Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.03.2023 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15228372 kB
MemAvailable 30417300 kB
Buffers 0 kB
Cached 16485012 kB
SwapCached 3028 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-06 19:38:39
DTS_END                         2023-03-06 19:38:39.6830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38838
TRN_ID                          236773
ATT_ID                          716
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9485.21 23347 1138225
customer order: creation 167.63 386 20115
customer order: refuse 14.51 318 1741
order to supplier: creation 43.38 1232 5205
order to supplier: removal 3.43 99848 411
invoice (draft): creation 45.11 3429 5413
invoice (draft): removal 56.52 703 6782
invoice accept: apply 40.08 16474 4810
invoice accept: cancel 51.21 709 6145
customer reserve: creation 3725.33 162 447040
customer reserve: removal 5043.33 53 605200
realization accept: apply 80.78 188 9694
realization accept: cancel 86.39 108 10367
payment from customer: creation 58.79 140 7055
payment from customer: removal 5.84 85 701
payment to supplier: creation 47.58 176 5710
payment to supplier: removal 3.29 133 395
service: total inventory turnovers 4.87 916 584
service: total monetary turnovers 6.43 23 772
service: aggregate perf. data 0.68 27491 81
service: refresh index statistics 0.03 314371 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-03-2023 19:40:02 to 06-03-2023 19:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3209
TEST_TIME 2 1892
TEST_TIME 3 2789
TEST_TIME 4 2796
TEST_TIME 5 3742
TEST_TIME 6 4188
TEST_TIME 7 4190
TEST_TIME 8 4625
TEST_TIME 9 4930
TEST_TIME 10 5129
TEST_TIME 11 5287
TEST_TIME 12 5662
TEST_TIME 13 5829
TEST_TIME 14 5758
TEST_TIME 15 6075
TEST_TIME 16 6093
TEST_TIME 17 6101
TEST_TIME 18 7115
TEST_TIME 19 7222
TEST_TIME 20 7341
TEST_TIME 21 7632
TEST_TIME 22 8232
TEST_TIME 23 8509
TEST_TIME 24 8583
TEST_TIME 25 8770
TEST_TIME 26 9205
TEST_TIME 27 10610
TEST_TIME 28 10671
TEST_TIME 29 10511
TEST_TIME 30 10619
TEST_TIME 31 10602
TEST_TIME 32 10460
TEST_TIME 33 10394
TEST_TIME 34 10140
TEST_TIME 35 10006
TEST_TIME 36 10197
TEST_TIME 37 10193
TEST_TIME 38 10098
TEST_TIME 39 10226
TEST_TIME 40 10109
TEST_TIME 41 10005
TEST_TIME 42 9929
TEST_TIME 43 9877
TEST_TIME 44 10008
TEST_TIME 45 9860
TEST_TIME 46 9913
TEST_TIME 47 9845
TEST_TIME 48 9757
TEST_TIME 49 9744
TEST_TIME 50 9726
TEST_TIME 51 9623
TEST_TIME 52 9552
TEST_TIME 53 9617
TEST_TIME 54 9667
TEST_TIME 55 9644
TEST_TIME 56 9700
TEST_TIME 57 9742
TEST_TIME 58 9756
TEST_TIME 59 9783
TEST_TIME 60 9742
TEST_TIME 61 9789
TEST_TIME 62 9838
TEST_TIME 63 9942
TEST_TIME 64 10036
TEST_TIME 65 9950
TEST_TIME 66 9886
TEST_TIME 67 9887
TEST_TIME 68 9820
TEST_TIME 69 9803
TEST_TIME 70 9818
TEST_TIME 71 9753
TEST_TIME 72 9711
TEST_TIME 73 9680
TEST_TIME 74 9579
TEST_TIME 75 9636
TEST_TIME 76 9713
TEST_TIME 77 9675
TEST_TIME 78 9719
TEST_TIME 79 9694
TEST_TIME 80 9648
TEST_TIME 81 9651
TEST_TIME 82 9716
TEST_TIME 83 9781
TEST_TIME 84 9739
TEST_TIME 85 9820
TEST_TIME 86 9807
TEST_TIME 87 9855
TEST_TIME 88 9831
TEST_TIME 89 9752
TEST_TIME 90 9738
TEST_TIME 91 9699
TEST_TIME 92 9661
TEST_TIME 93 9602
TEST_TIME 94 9635
TEST_TIME 95 9631
TEST_TIME 96 9613
TEST_TIME 97 9611
TEST_TIME 98 9603
TEST_TIME 99 9625
TEST_TIME 100 9725
TEST_TIME 101 9730
TEST_TIME 102 9708
TEST_TIME 103 9747
TEST_TIME 104 9764
TEST_TIME 105 9762
TEST_TIME 106 9758
TEST_TIME 107 9801
TEST_TIME 108 9816
TEST_TIME 109 9751
TEST_TIME 110 9682
TEST_TIME 111 9684
TEST_TIME 112 9634
TEST_TIME 113 9616
TEST_TIME 114 9592
TEST_TIME 115 9574
TEST_TIME 116 9569
TEST_TIME 117 9539
TEST_TIME 118 9510
TEST_TIME 119 9463
TEST_TIME 120 9487

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-03-2023 19:40:06 to 06-03-2023 19:40:09.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1553615 1553615 0 0.00 0 28139 6 0 0
doc_list_biud 1553615 1553615 0 0.00 0 88788 26 0 0
sp_add_invoice_to_stock 4893 4810 83 1.69 53 160626 16474 0 83
sp_cancel_adding_invoice 6145 6145 0 0.00 5 71650 710 0 0
sp_cancel_client_order 1741 1741 0 0.00 4 8953 319 0 0
sp_cancel_customer_reserve 605200 605200 0 0.00 0 37217 54 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 6145 86 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 13720 134 0 0
sp_cancel_supplier_invoice 6796 6782 14 0.20 2 88796 703 0 14
sp_cancel_supplier_order 411 411 0 0.00 6 1136421 99849 0 0
sp_cancel_write_off 10367 10367 0 0.00 1 19180 108 0 0
sp_client_order 20115 20115 0 0.00 3 17341 386 0 0
sp_customer_reserve 447040 447040 0 0.00 0 43868 162 0 0
sp_fill_shopping_cart 30731 30731 0 0.00 1 26083 41 0 0
sp_fill_shopping_cart_clo_res 447036 447036 0 0.00 0 20061 15 0 0
sp_get_clo_for_invoice 4805 4805 0 0.00 0 20143 284 0 0
sp_kill_cost_storno 17608 17608 0 0.00 0 13712 51 0 0
sp_kill_qstorno_ret_qs2qd 1227960 1227960 0 0.00 0 88786 29 0 0
sp_kill_qty_storno 645145 645145 0 0.00 0 88788 62 0 0
sp_lock_selected_doc 20237 20237 0 0.00 0 8860 7 0 0
sp_make_cost_storno 27264 27264 0 0.00 0 28126 72 0 0
sp_multiply_rows_for_pdistr 27264 27264 0 0.00 0 12042 28 0 0
sp_multiply_rows_for_qdistr 20115 20115 0 0.00 0 16631 172 0 0
sp_pay_from_customer 7055 7055 0 0.00 4 25809 140 0 0
sp_pay_to_supplier 5710 5710 0 0.00 4 28171 176 0 0
sp_payment_common 12765 12765 0 0.00 1 13754 48 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 0 8735 277 0 0
sp_qd_handle_on_invoice_upd_sts 10949 10949 0 0.00 0 34348 285 0 0
sp_qd_handle_on_reserve_upd_sts 9694 9694 0 0.00 0 12733 55 0 0
sp_reserve_write_off 9694 9694 0 0.00 6 22810 188 0 0
sp_supplier_invoice 5413 5413 0 0.00 7 96712 3430 0 0
sp_supplier_order 5205 5205 0 0.00 5 35942 1233 0 0
srv_aggregate_perf_data 81 81 0 0.00 2811 522656 27491 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 882 120425 48109 0 0
srv_find_qd_qs_mism 1092452 1092452 0 0.00 0 12403 5 0 0
srv_make_invnt_saldo 584 584 0 0.00 44 43752 916 0 0
srv_make_money_saldo 772 772 0 0.00 0 513 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 123047 508239 314371 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4810 4810 0 0.00 0 10929 158 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 3096 87 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 1154 11 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 28828 67 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 1872 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 7088 45 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 718 11 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 40154 1626 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 5086 13 0 0
t$perf-norm:sp_client_order 20115 20115 0 0.00 0 34856 19 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 1650 6 0 0
t$perf-norm:sp_pay_from_customer 7055 7055 0 0.00 0 6364 10 0 0
t$perf-norm:sp_pay_to_supplier 5710 5710 0 0.00 0 28092 21 0 0
t$perf-norm:sp_reserve_write_off 9694 9694 0 0.00 0 7935 11 0 0
t$perf-norm:sp_supplier_invoice 5413 5413 0 0.00 0 6639 22 0 0
t$perf-norm:sp_supplier_order 5205 5205 0 0.00 0 8536 16 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 237 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 84 2 0 0
t$perf-norm:srv_make_invnt_saldo 584 584 0 0.00 0 3373 21 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 804 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 284 72 0 0
v_add_invoice_to_stock 9774 9608 166 1.69 0 4976 17 0 166
v_all_customers 36886 36886 0 0.00 0 15 0 0 0
v_all_suppliers 29696 29696 0 0.00 0 67 1 0 0
v_all_wares 40230 40230 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 3456 10 0 0
v_cancel_client_order 3482 3482 0 0.00 0 8909 9 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 1217 5 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 8986 20 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 6572 11 0 0
v_cancel_supplier_invoice 810 782 28 3.45 0 3469 17 0 28
v_cancel_supplier_order 822 822 0 0.00 0 7306 23 0 0
v_cancel_write_off 2050 2050 0 0.00 0 4447 15 0 0
v_max_id_clo_ord 5205 5205 0 0.00 0 3512 3 0 0
v_max_id_clo_res 992 992 0 0.00 0 4318 24 0 0
v_max_id_ord_sup 5411 5411 0 0.00 0 4984 5 0 0
v_max_non_paid_invoice 1478 1478 0 0.00 0 1989 7 0 0
v_max_non_paid_realizn 3688 3688 0 0.00 0 3376 5 0 0
v_min_id_clo_ord 5205 5205 0 0.00 0 4344 9 0 0
v_min_id_clo_res 992 992 0 0.00 0 8401 17 0 0
v_min_id_ord_sup 5411 5411 0 0.00 0 12662 6 0 0
v_min_non_paid_invoice 5710 5710 0 0.00 0 13552 35 0 0
v_min_non_paid_realizn 7055 7055 0 0.00 0 13744 42 0 0
v_our_firm 10078 10078 0 0.00 0 17 1 0 0
v_reserve_write_off 19388 19388 0 0.00 0 11223 7 0 0
x_lock_depdocs_on_canc_invoice 12536 12536 0 0.00 0 20792 112 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 10075 401 0 0
x_make_qstorno_1000_1200 5205 5205 0 0.00 1 35912 1154 0 0
x_make_qstorno_1200_2000 5413 5413 0 0.00 4 96700 3279 0 0
x_make_qstorno_2100_3300 447040 447040 0 0.00 0 43868 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-03-2023 19:40:10 to 06-03-2023 19:40:14.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:43 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:45 19054 8192000000 9898681664 10206699520 980552832 10.690 100 88 177 0 1010037856 6145104 23339920
17:11:10 17809 8192000000 9949877744 10271948800 1029283920 11.162 100 87 174 0 1059856592 8543008 22029664
17:12:33 17623 8192000000 9983294752 10310344704 1060905872 11.465 100 89 178 0 1093606016 10373504 22326640
17:13:57 9974 8192000000 10009589568 10352226304 1096434480 11.804 100 76 151 1 1125052512 10561664 18056368
17:15:09 6146 8192000000 10062799232 10413867008 1147041472 12.282 100 78 156 0 1177721088 12968032 17711584
17:16:17 3453 8192000000 10099164112 10462388224 1193957616 12.720 100 75 150 0 1223000528 11950976 17091936
17:17:23 2681 8192000000 10147915376 10520027136 1233984048 13.091 100 81 160 0 1265200368 12800624 18415696
17:18:27 7823 8192000000 10176754176 10556092416 1269106560 13.413 100 81 162 0 1299100080 12777264 17216256
17:19:38 1419 8192000000 10203265120 10588954624 1293813824 13.639 100 85 169 1 1325312272 12365168 19133280
17:20:40 9602 8192000000 10217827968 10605871104 1302840832 13.721 100 85 166 2 1334039584 11623792 19574960
17:21:51 4983 8192000000 10220479456 10615406592 1315662576 13.837 100 52 104 0 1335089552 7432608 11994368
17:22:57 8230 8192000000 10221122336 10632814592 1327696528 13.946 100 28 56 0 1352833152 18579712 6556912
17:24:08 9923 8192000000 10211690688 10628820992 1329048640 13.959 100 18 36 0 1338444656 5390528 4005488
17:25:18 46656 8192000000 10222920272 10644553728 1331987568 13.985 100 38 74 1 1347492480 6615328 8889584
17:27:05 9541 8192000000 10229934432 10654703616 1338401472 14.043 100 29 58 2 1352486016 7223968 6860576
17:28:15 9396 8192000000 10235087856 10656849920 1339899568 14.057 100 20 40 0 1355458272 11182592 4376112
17:29:29 63070 8192000000 10207971056 10613792768 1234439040 13.095 93 29 59 1 1246042400 5003936 6599424
17:31:39 4736 8192000000 10151940800 10549678080 1226018704 13.017 97 31 62 2 1238828304 6051136 6758464
17:32:47 6561 8192000000 10147616896 10545901568 1253371424 13.269 100 17 34 0 1262702464 5378416 3952624
17:33:54 18218 8192000000 10182386496 10582306816 1281171008 13.524 100 18 36 1 1290413232 5145312 4096912
17:35:13 22815 8192000000 10195090128 10606936064 1293404288 13.635 100 27 51 1 1314315904 15554864 5356752
17:36:36 9555 8192000000 10203830192 10617331712 1310007840 13.786 100 24 49 0 1321586400 6349936 5228624
17:37:45 10748 8192000000 10200366160 10624593920 1319283136 13.870 100 19 38 0 1333072320 10071552 3717632
17:39:07 37990 8192000000 10257395808 10666098688 1343620544 14.090 100 34 64 0 1359626640 9033456 6972640
17:40:51 47392 8192000000 10258826720 10685452288 1362416192 14.259 100 36 72 0 1381577776 10943648 8217936
17:42:45 18129 8192000000 10287180336 10709487616 1375755360 14.379 100 41 82 0 1402294704 16367616 10171728
17:44:08 21784 8192000000 10306213472 10726608896 1389649920 14.503 100 49 98 1 1420759040 19848720 11260400
17:45:33 7127 8192000000 10311419328 10742571008 1400347296 14.598 100 38 77 0 1428541200 18466192 9727712
17:46:43 2877 8192000000 10335206288 10762215424 1410500960 14.688 100 53 101 1 1447944880 25614384 11829536
17:47:48 5329 8192000000 10349046128 10777194496 1416762192 14.744 100 48 94 0 1458544544 29963072 11819280
17:48:59 6034 8192000000 10356109984 10784493568 1423730816 14.806 100 47 92 1 1466202800 31882720 10589264
17:50:09 8199 8192000000 10353458080 10782392320 1416367008 14.740 100 49 96 0 1463359984 35099504 11893472
17:51:22 6468 8192000000 10353871536 10786918400 1423037056 14.800 100 43 84 0 1467848896 33941776 10870064
17:52:37 9714 8192000000 10370371088 10797338624 1424133904 14.809 100 46 90 1 1475232528 39596112 11502512
17:53:53 3643 8192000000 10373149056 10790789120 1408966688 14.675 99 73 147 1 1465834752 39867408 17000656
17:55:02 2517 8192000000 10362696960 10771267584 1394698880 14.548 100 81 164 0 1464170656 49146512 20325264
17:56:09 881 8192000000 10335731600 10743709696 1362980416 14.264 99 70 140 3 1439530672 58350352 18199904
17:57:15 4553 8192000000 10328958480 10743615488 1372413168 14.349 100 61 124 0 1434230480 45690224 16127088
17:58:24 16389 8192000000 10345903088 10769825792 1378895616 14.407 99 44 87 1 1445239200 54904416 11439168
17:59:47 8581 8192000000 10323989600 10744188928 1369667456 14.324 100 42 79 0 1430739216 49856304 11215456
18:01:00 35860 8192000000 10293257248 10725699584 1332235168 13.987 97 25 49 0 1383067728 43560928 7271632
18:02:52 15610 8192000000 10317470960 10750865408 1355928784 14.201 99 32 64 0 1413267248 48424272 8914192
18:04:45 14547 8192000000 10301962304 10736570368 1354543280 14.188 100 22 44 0 1405877984 45174304 6160400
18:06:30 48837 8192000000 10273791984 10715496448 1351196208 14.158 99 16 28 1 1369847808 15830240 2821360
18:08:21 56530 8192000000 10293468592 10731950080 1370867184 14.335 100 20 41 0 1389467248 14145808 4454256
18:10:30 96592 8192000000 10301272688 10748792832 1354418720 14.187 98 26 50 0 1374976320 14991760 5565840
18:13:36 66234 8192000000 10296742768 10754396160 1357572896 14.216 98 17 34 1 1380668480 19101632 3993952
18:15:43 33193 8192000000 10296662432 10737258496 1364524128 14.278 99 14 28 1 1378313328 10470224 3318976
18:17:20 59905 8192000000 10285564640 10745372672 1385493488 14.466 100 27 54 0 1405251712 13357616 6400608
18:19:21 56865 8192000000 10306973264 10762772480 1393012544 14.533 100 19 38 0 1410559088 13306656 4239888
18:21:19 56479 8192000000 10302373280 10760593408 1398875392 14.585 100 16 32 0 1419264976 16350992 4038592
18:23:17 98005 8192000000 10305250416 10772135936 1400023840 14.595 100 22 44 0 1419664624 13927712 5713072
18:26:04 56787 8192000000 10300568208 10763350016 1401456032 14.608 100 9 18 0 1415846720 12501680 1889008
18:28:13 47481 8192000000 10296598688 10776776704 1402181328 14.614 100 13 26 0 1416783456 11641200 2960928
18:30:08 27141 8192000000 10309617520 10758508544 1377180400 14.391 98 31 63 0 1391581664 7589376 6811888
18:31:45 49835 8192000000 10314229504 10782380032 1410195648 14.686 100 12 24 0 1423387904 10521696 2670560
18:33:41 58508 8192000000 10310372176 10773204992 1392194416 14.525 99 7 14 0 1404498544 10713184 1590944
18:35:39 65117 8192000000 10313214464 10760282112 1357561360 14.215 96 22 44 0 1376971376 14296496 5113520
18:38:00 34715 8192000000 10294108192 10745536512 1361977744 14.255 97 23 46 0 1374652368 8059872 4614752
18:39:35 37341 8192000000 10294223408 10743791616 1369523632 14.323 99 26 52 0 1387290272 12136704 5629936
18:41:13 76352 8192000000 10269203728 10726522880 1316660576 13.846 95 9 18 0 1337338688 18614288 2063824
18:43:30 45047 8192000000 10294020432 10733568000 1341746064 14.073 97 29 58 0 1362190544 13986128 6458352
18:45:24 79806 8192000000 10267950768 10714468352 1348898976 14.138 98 25 50 0 1368680096 13769296 6011824
18:48:54 47196 8192000000 10235473328 10690584576 1320897056 13.885 98 8 16 0 1331581248 8920432 1763760
18:50:53 72361 8192000000 10254101776 10702041088 1324633536 13.919 98 21 42 0 1348625984 18884960 5107488
18:53:07 68757 8192000000 10238504816 10692620288 1312583808 13.810 97 21 42 0 1338746112 21384592 4777712
18:55:36 72540 8192000000 10246882224 10702147584 1317273408 13.852 97 21 42 0 1332568496 10403376 4891712
18:58:24 43809 8192000000 10288064384 10728366080 1364068000 14.274 100 34 68 0 1390173712 18243536 7862176
19:00:15 87191 8192000000 10287105552 10740883456 1377531312 14.394 100 13 25 0 1394449248 13953168 2964768
19:02:49 14129 8192000000 10291645056 10723418112 1371373184 14.339 100 38 71 1 1388209616 9311376 7525056
19:04:11 120425 8192000000 10280529280 10724356096 1336979232 14.030 97 19 38 0 1351746496 10395104 4372160
19:07:19 98069 8192000000 10280267248 10736037888 1369953248 14.327 99 28 56 0 1382977536 6855120 6169168
19:10:06 59425 8192000000 10296473552 10753843200 1384503856 14.457 99 27 54 0 1402461584 11708656 6249072
19:12:05 77149 8192000000 10288724224 10747842560 1394637872 14.547 100 16 32 0 1411014848 12647920 3729056
19:14:23 73933 8192000000 10305368896 10767228928 1388176816 14.490 99 13 24 0 1403739968 12897456 2665696
19:16:38 65654 8192000000 10310678224 10765967360 1371310960 14.339 97 15 30 0 1390512832 15633024 3568848
19:18:44 79255 8192000000 10309834688 10766585856 1371069936 14.337 98 8 16 0 1388087408 14903344 2114128
19:21:04 77120 8192000000 10316846288 10775592960 1381202464 14.427 98 25 50 0 1400723824 13585152 5936208
19:23:24 55322 8192000000 10307681248 10754322432 1334261872 14.006 95 18 36 1 1345967584 7862720 3842992
19:25:22 51789 8192000000 10312683920 10756947968 1365155984 14.284 98 25 50 2 1386426576 15737568 5533024
19:27:36 98900 8192000000 10286682208 10745106432 1360822880 14.245 97 23 46 0 1379105760 12867680 5415200
19:30:16 87326 8192000000 10278920960 10735206400 1306241392 13.752 94 9 18 0 1325028576 16545792 2241392
19:32:56 50872 8192000000 10255952928 10707386368 1329087328 13.959 97 13 26 0 1345968224 13665872 3215024
19:34:48 73432 8192000000 10245295408 10699563008 1306427152 13.754 96 20 39 0 1320954672 10094656 4432864
19:37:03 83638 8192000000 10248782368 10708086784 1303473344 13.727 96 12 24 1 1320787120 14836528 2477248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-03-2023 19:40:16 to 06-03-2023 19:40:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-03-2023 19:40:32 to 06-03-2023 19:40:34.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          236343
MON$OLDEST_ACTIVE               236929
MON$OLDEST_SNAPSHOT             236929
MON$NEXT_TRANSACTION            236929
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2032288
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:1095: 
:1096: mscs-test-161.budzdorov.ru    Mon Mar  6 19:38:44 2023
:1097:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1098: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

06.03.23 19:40:35. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-03-06 19:40:38 No severe PSQL-related problems occured
06.03.23 19:40:39. End of report.