Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.07.2022 07:38
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 15986524 kB
MemAvailable 31101844 kB
Buffers 0 kB
Cached 15701452 kB
SwapCached 2520 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2022-07-10 07:37:18
DTS_END                         2022-07-10 07:37:18.5100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34476
TRN_ID                          221127
ATT_ID                          591
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: 8962.91 26368 1075549
customer order: creation 170.38 469 20445
customer order: refuse 14.89 380 1787
order to supplier: creation 46.01 1326 5521
order to supplier: removal 3.29 167149 395
invoice (draft): creation 47.60 3386 5712
invoice (draft): removal 48.44 1026 5813
invoice accept: apply 43.18 26698 5181
invoice accept: cancel 44.20 1302 5304
customer reserve: creation 3996.55 254 479586
customer reserve: removal 4249.02 105 509882
realization accept: apply 84.83 270 10179
realization accept: cancel 83.21 157 9985
payment from customer: creation 59.79 174 7175
payment from customer: removal 5.91 119 709
payment to supplier: creation 48.79 202 5855
payment to supplier: removal 3.67 152 440
service: total inventory turnovers 4.28 3619 514
service: total monetary turnovers 7.88 397 945
service: aggregate perf. data 0.98 14763 117
service: refresh index statistics 0.03 305404 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-07-2022 07:38:33 to 10-07-2022 07:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8751
TEST_TIME 2 6732
TEST_TIME 3 7230
TEST_TIME 4 7047
TEST_TIME 5 6843
TEST_TIME 6 6789
TEST_TIME 7 6989
TEST_TIME 8 6872
TEST_TIME 9 6862
TEST_TIME 10 6789
TEST_TIME 11 7157
TEST_TIME 12 7523
TEST_TIME 13 7954
TEST_TIME 14 7963
TEST_TIME 15 7861
TEST_TIME 16 8351
TEST_TIME 17 8317
TEST_TIME 18 8382
TEST_TIME 19 8280
TEST_TIME 20 8305
TEST_TIME 21 8717
TEST_TIME 22 8781
TEST_TIME 23 8831
TEST_TIME 24 8939
TEST_TIME 25 8977
TEST_TIME 26 9015
TEST_TIME 27 9213
TEST_TIME 28 9278
TEST_TIME 29 9527
TEST_TIME 30 10258
TEST_TIME 31 10245
TEST_TIME 32 10615
TEST_TIME 33 10797
TEST_TIME 34 10880
TEST_TIME 35 10762
TEST_TIME 36 10561
TEST_TIME 37 10375
TEST_TIME 38 10226
TEST_TIME 39 10093
TEST_TIME 40 10099
TEST_TIME 41 9970
TEST_TIME 42 10044
TEST_TIME 43 9952
TEST_TIME 44 9889
TEST_TIME 45 9878
TEST_TIME 46 9780
TEST_TIME 47 9733
TEST_TIME 48 9618
TEST_TIME 49 9709
TEST_TIME 50 9618
TEST_TIME 51 9593
TEST_TIME 52 9584
TEST_TIME 53 9536
TEST_TIME 54 9627
TEST_TIME 55 9628
TEST_TIME 56 9577
TEST_TIME 57 9875
TEST_TIME 58 9847
TEST_TIME 59 9801
TEST_TIME 60 9908
TEST_TIME 61 9804
TEST_TIME 62 9693
TEST_TIME 63 9627
TEST_TIME 64 9557
TEST_TIME 65 9539
TEST_TIME 66 9457
TEST_TIME 67 9372
TEST_TIME 68 9334
TEST_TIME 69 9295
TEST_TIME 70 9349
TEST_TIME 71 9302
TEST_TIME 72 9246
TEST_TIME 73 9220
TEST_TIME 74 9187
TEST_TIME 75 9158
TEST_TIME 76 9138
TEST_TIME 77 9212
TEST_TIME 78 9294
TEST_TIME 79 9343
TEST_TIME 80 9465
TEST_TIME 81 9440
TEST_TIME 82 9554
TEST_TIME 83 9504
TEST_TIME 84 9469
TEST_TIME 85 9400
TEST_TIME 86 9322
TEST_TIME 87 9288
TEST_TIME 88 9244
TEST_TIME 89 9196
TEST_TIME 90 9148
TEST_TIME 91 9093
TEST_TIME 92 9096
TEST_TIME 93 9109
TEST_TIME 94 9170
TEST_TIME 95 9167
TEST_TIME 96 9173
TEST_TIME 97 9153
TEST_TIME 98 9160
TEST_TIME 99 9163
TEST_TIME 100 9179
TEST_TIME 101 9246
TEST_TIME 102 9262
TEST_TIME 103 9214
TEST_TIME 104 9148
TEST_TIME 105 9116
TEST_TIME 106 9141
TEST_TIME 107 9118
TEST_TIME 108 9082
TEST_TIME 109 9044
TEST_TIME 110 9032
TEST_TIME 111 9007
TEST_TIME 112 9018
TEST_TIME 113 8987
TEST_TIME 114 8979
TEST_TIME 115 8957
TEST_TIME 116 8939
TEST_TIME 117 8949
TEST_TIME 118 9030
TEST_TIME 119 9040
TEST_TIME 120 8992

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-07-2022 07:38:37 to 10-07-2022 07:38:40.

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 1516568 1516568 0 0.00 0 41805 14 0 0
doc_list_biud 1516560 1516560 0 0.00 0 31418 42 0 0
sp_add_invoice_to_stock 5293 5181 112 2.11 35 207668 26698 0 112
sp_cancel_adding_invoice 5304 5304 0 0.00 6 94580 1302 0 0
sp_cancel_client_order 1787 1787 0 0.00 5 8319 381 0 0
sp_cancel_customer_reserve 509882 509882 0 0.00 1 29893 105 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 3 6738 120 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 4 6054 153 0 0
sp_cancel_supplier_invoice 5823 5813 10 0.17 3 42760 1026 0 10
sp_cancel_supplier_order 395 395 0 0.00 10 1261534 167149 0 0
sp_cancel_write_off 9985 9985 0 0.00 2 18439 158 0 0
sp_client_order 20445 20445 0 0.00 5 27924 469 0 0
sp_customer_reserve 479586 479586 0 0.00 1 136363 255 0 0
sp_fill_shopping_cart 31668 31668 0 0.00 1 19995 78 0 0
sp_fill_shopping_cart_clo_res 479552 479552 0 0.00 0 22198 20 0 0
sp_get_clo_for_invoice 5135 5135 0 0.00 0 23219 279 0 0
sp_kill_cost_storno 16438 16438 0 0.00 0 11823 63 0 0
sp_kill_qstorno_ret_qs2qd 1035957 1035957 0 0.00 0 31415 47 0 0
sp_kill_qty_storno 548476 548476 0 0.00 0 31417 107 0 0
sp_lock_selected_doc 21240 21240 0 0.00 0 7833 7 0 0
sp_make_cost_storno 28342 28342 0 0.00 0 22322 88 0 0
sp_multiply_rows_for_pdistr 28341 28341 0 0.00 0 7139 36 0 0
sp_multiply_rows_for_qdistr 20444 20444 0 0.00 0 23927 201 0 0
sp_pay_from_customer 7175 7175 0 0.00 5 12219 174 0 0
sp_pay_to_supplier 5855 5855 0 0.00 6 19579 202 0 0
sp_payment_common 13029 13029 0 0.00 2 8899 49 0 0
sp_qd_handle_on_cancel_clo 1787 1787 0 0.00 0 6471 307 0 0
sp_qd_handle_on_invoice_upd_sts 10435 10435 0 0.00 0 28153 506 0 0
sp_qd_handle_on_reserve_upd_sts 10179 10179 0 0.00 0 18778 65 0 0
sp_reserve_write_off 10179 10179 0 0.00 9 18837 270 0 0
sp_supplier_invoice 5712 5712 0 0.00 7 143659 3386 0 0
sp_supplier_order 5521 5521 0 0.00 7 22584 1327 0 0
srv_aggregate_perf_data 117 117 0 0.00 2296 131795 14763 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 743 197001 33025 0 0
srv_find_qd_qs_mism 1025442 1025442 0 0.00 0 41804 11 0 0
srv_make_invnt_saldo 514 514 0 0.00 59 50283 3620 0 0
srv_make_money_saldo 945 945 0 0.00 1 24701 398 0 0
srv_recalc_idx_stat 4 4 0 0.00 211816 408129 305405 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5184 5184 0 0.00 1 13590 937 0 0
t$perf-norm:sp_cancel_adding_invoice 459 459 0 0.00 2 14943 438 0 0
t$perf-norm:sp_cancel_client_order 1787 1787 0 0.00 0 4653 29 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 3146 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 1784 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 712 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 455 455 0 0.00 0 711 32 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 101267 6579 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 18179 34 0 0
t$perf-norm:sp_client_order 20446 20446 0 0.00 0 12715 36 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 866 13 0 0
t$perf-norm:sp_pay_from_customer 7175 7175 0 0.00 0 24505 20 0 0
t$perf-norm:sp_pay_to_supplier 5855 5855 0 0.00 0 24168 22 0 0
t$perf-norm:sp_reserve_write_off 10179 10179 0 0.00 0 9775 23 0 0
t$perf-norm:sp_supplier_invoice 5712 5712 0 0.00 0 8043 30 0 0
t$perf-norm:sp_supplier_order 5521 5521 0 0.00 0 5975 32 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 5746 70 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 100 4 0 0
t$perf-norm:srv_make_invnt_saldo 514 514 0 0.00 0 732 13 0 0
t$perf-norm:srv_make_money_saldo 945 945 0 0.00 0 931 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 413 115 0 0
v_add_invoice_to_stock 10484 10260 224 2.13 0 4967 24 0 224
v_all_customers 37679 37679 0 0.00 0 76 4 0 0
v_all_suppliers 30872 30872 0 0.00 0 83 4 0 0
v_all_wares 40888 40888 0 0.00 0 117 4 0 0
v_cancel_adding_invoice 918 918 0 0.00 0 1021 16 0 0
v_cancel_client_order 3574 3574 0 0.00 0 2147 10 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 435 10 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 786 11 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 2406 14 0 0
v_cancel_supplier_invoice 930 910 20 2.15 0 960 26 0 20
v_cancel_supplier_order 790 790 0 0.00 0 421 10 0 0
v_cancel_write_off 2044 2044 0 0.00 0 2700 24 0 0
v_max_id_clo_ord 5518 5518 0 0.00 0 1703 5 0 0
v_max_id_clo_res 995 995 0 0.00 0 2473 19 0 0
v_max_id_ord_sup 5705 5705 0 0.00 0 6956 9 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 797 8 0 0
v_max_non_paid_realizn 3683 3683 0 0.00 0 6966 11 0 0
v_min_id_clo_ord 5518 5518 0 0.00 0 1070 7 0 0
v_min_id_clo_res 995 995 0 0.00 0 3664 13 0 0
v_min_id_ord_sup 5705 5705 0 0.00 0 6455 10 0 0
v_min_non_paid_invoice 5855 5855 0 0.00 0 7545 23 0 0
v_min_non_paid_realizn 7174 7174 0 0.00 0 8890 19 0 0
v_our_firm 10190 10190 0 0.00 0 69 4 0 0
v_reserve_write_off 20358 20358 0 0.00 0 2862 10 0 0
x_lock_depdocs_on_canc_invoice 10662 10662 0 0.00 0 20573 119 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 9518 333 0 0
x_make_qstorno_1000_1200 5521 5521 0 0.00 2 22539 1168 0 0
x_make_qstorno_1200_2000 5712 5712 0 0.00 3 143348 3169 0 0
x_make_qstorno_2100_3300 479586 479586 0 0.00 0 136345 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-07-2022 07:38:41 to 10-07-2022 07:38:45.

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
05:07:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:22 20957 8192000000 9718245856 10018103296 797357456 8.870 100 91 183 0 827360144 8221216 21781472
05:09:46 20462 8192000000 9757830112 10065002496 834901184 9.249 100 90 178 0 869011616 12899216 21211216
05:11:14 13588 8192000000 9799070288 10118737920 863275920 9.533 100 84 166 0 904220880 21246176 19698784
05:12:34 8945 8192000000 9848639152 10178420736 893299856 9.832 100 85 170 1 959853520 47036880 19516784
05:13:53 5621 8192000000 9904465376 10246000640 942725664 10.320 100 84 163 0 1021469360 60816704 17926992
05:15:02 4311 8192000000 9955398864 10311688192 990345072 10.785 100 77 155 0 1072761040 66501200 15914768
05:16:09 1759 8192000000 10008550000 10372046848 1025789328 11.128 100 97 193 0 1124441248 79664176 18987744
05:17:14 1179 8192000000 10015667472 10390831104 1043687808 11.300 100 94 184 1 1134787008 72689488 18409712
05:18:16 1059 8192000000 10014338208 10391711744 1060511200 11.461 100 98 189 0 1138054304 58808288 18734816
05:19:19 1154 8192000000 10045946032 10424520704 1065954816 11.513 100 96 186 3 1167320912 82379312 18986784
05:20:23 1910 8192000000 10040668000 10419957760 1070535648 11.557 100 93 165 1 1162522816 76130752 15856416
05:21:26 981 8192000000 10051679360 10433404928 1082441840 11.671 100 97 182 0 1175651696 74802832 18407024
05:22:28 1097 8192000000 10044537632 10428813312 1084391472 11.689 100 98 188 3 1159715072 56913664 18409936
05:23:31 1446 8192000000 10049972416 10436333568 1086474304 11.709 100 97 188 2 1176022736 69619536 19928896
05:24:33 1193 8192000000 10050086208 10436743168 1082746784 11.674 99 93 181 2 1171362864 70558208 18057872
05:25:36 1232 8192000000 10060896016 10453114880 1098140272 11.820 100 97 188 0 1188677920 70610864 19926784
05:26:38 1579 8192000000 10066626096 10461212672 1099113808 11.829 100 84 155 3 1191877408 76059632 16703968
05:27:41 3973 8192000000 10053591520 10446508032 1100557920 11.843 100 66 124 0 1179312256 66435696 12318640
05:28:46 2873 8192000000 10051509808 10444226560 1098125136 11.820 100 70 135 1 1163609664 50890608 14593920
05:29:52 4254 8192000000 10029370688 10427084800 1085823184 11.703 100 65 126 0 1151835984 52501680 13511120
05:30:59 883 8192000000 10070117616 10461511680 1077647360 11.625 100 83 164 0 1195228480 100843920 16737200
05:32:00 2030 8192000000 10055436000 10440699904 1071462784 11.566 100 92 176 1 1175423712 85831920 18129008
05:33:03 1990 8192000000 9992126080 10376429568 1043333104 11.297 100 81 138 6 1112455376 54140192 14982080
05:34:06 1072 8192000000 9998407744 10376609792 1046989856 11.332 100 93 169 3 1123162512 57080384 19092272
05:35:09 973 8192000000 10005654688 10381623296 1037761808 11.243 100 95 178 1 1129426800 72464704 19200288
05:36:11 1193 8192000000 10018596576 10389372928 1025702576 11.127 100 99 188 0 1135702288 90771872 19227840
05:37:14 1188 8192000000 10007209376 10386202624 1045061552 11.313 100 89 171 3 1127765568 65600144 17103872
05:38:25 8551 8192000000 9995813488 10380853248 1057509264 11.433 100 68 130 1 1116713888 46773696 12430928
05:39:37 1161 8192000000 10054713392 10434600960 1068739360 11.540 100 96 182 0 1173237776 85621056 18877360
05:40:41 9560 8192000000 10059609344 10455625728 1085763328 11.702 100 64 127 0 1182995088 83821152 13410608
05:41:53 4587 8192000000 10093377152 10481737728 1097668864 11.816 100 76 151 0 1214834128 101230368 15934896
05:43:01 1646 8192000000 10128131344 10526863360 1107290960 11.907 100 64 124 0 1249179904 128659904 13229040
05:44:05 2207 8192000000 10133490096 10526330880 1107426080 11.908 100 86 157 3 1249299248 125559840 16313328
05:45:13 1476 8192000000 10161451808 10560270336 1122897552 12.054 100 97 184 2 1278841728 133149600 22794576
05:46:18 1107 8192000000 10214767056 10608963584 1131827184 12.139 100 94 181 1 1332997888 180816672 20354032
05:47:24 3210 8192000000 10219772976 10618380288 1145145232 12.264 100 78 148 0 1337361408 176258816 15957360
05:48:32 1582 8192000000 10281930432 10689028096 1158767952 12.392 100 88 170 0 1390612352 213403184 18441216
05:49:37 978 8192000000 10262415984 10677854208 1160106320 12.404 100 97 182 2 1379708544 199641152 19961072
05:50:40 1287 8192000000 10248253440 10666684416 1166140896 12.461 100 86 155 0 1360043472 178179088 15723488
05:51:45 1219 8192000000 10276408416 10678972416 1163490608 12.436 100 91 177 2 1389507920 205417136 20600176
05:52:50 2036 8192000000 10273573952 10717999104 1179634016 12.587 100 87 168 2 1382852592 185855776 17362800
05:53:55 1602 8192000000 10301520768 10711724032 1178460304 12.576 100 87 168 1 1407874960 211219424 18195232
05:55:00 1365 8192000000 10322827408 10737553408 1163096336 12.432 100 93 179 0 1430742256 245393280 22252640
05:56:05 980 8192000000 10346891312 10747973632 1167065952 12.469 100 96 187 0 1453763424 264847040 21850432
05:57:08 908 8192000000 10341271360 10779262976 1157205520 12.377 100 94 182 2 1448391984 269210656 21975808
05:58:12 2224 8192000000 10366953344 10810998784 1168144352 12.479 100 94 183 0 1472032272 281506016 22381904
05:59:17 3161 8192000000 10373417424 10808479744 1175784208 12.551 100 65 127 2 1479417424 287682448 15950768
06:00:28 736 8192000000 10374021552 10796089344 1172227824 12.518 100 81 152 2 1478483568 287592144 18663600
06:01:32 4836 8192000000 10431663168 10854154240 1190191472 12.685 100 70 138 0 1533054112 326872352 15990288
06:02:43 5689 8192000000 10399398832 10821591040 1175681248 12.550 100 59 117 0 1511929360 322808368 13439744
06:04:10 9136 8192000000 10408511792 10855387136 1176847184 12.561 100 49 96 1 1525255088 336113200 12294704
06:05:27 18628 8192000000 10375765232 10869338112 1157777264 12.382 100 31 62 0 1496147712 329662528 8707920
06:06:49 13692 8192000000 10262200320 10804690944 1149116784 12.301 100 38 71 0 1391209376 232953968 9138624
06:08:05 47313 8192000000 10209370080 10695143424 1116853584 11.997 98 18 36 0 1298379936 177140704 4385648
06:09:53 67611 8192000000 10100876688 10541793280 1106524496 11.900 97 25 50 1 1185466576 73430832 5511248
06:12:09 61364 8192000000 10108297344 10547093504 1135441248 12.173 100 18 36 0 1197533136 58803360 3288528
06:14:12 131099 8192000000 10069017824 10525945856 1112231616 11.954 98 25 47 0 1188366768 70251344 5883808
06:17:26 31489 8192000000 10104360352 10565074944 1139448000 12.210 99 33 63 1 1217297504 69856768 7992736
06:19:35 29170 8192000000 10108483392 10548981760 1128225040 12.105 99 46 85 0 1199279568 62524288 8530240
06:21:21 22174 8192000000 10100537104 10578776064 1121294368 12.039 99 14 28 0 1201177504 76753072 3130064
06:22:44 197001 8192000000 10112208592 10565857280 1083318560 11.679 94 9 18 0 1144859392 59463312 2077520
06:27:10 35286 8192000000 10115459280 10554990592 1147759936 12.288 100 24 44 0 1218477072 65755040 4962096
06:28:46 20770 8192000000 10152243120 10603618304 1150047280 12.310 100 28 56 0 1250377200 94049248 6280672
06:30:18 28928 8192000000 10176638112 10619322368 1141275200 12.228 99 34 68 0 1269004880 120641936 7087744
06:31:59 13286 8192000000 10177815792 10602975232 1132255712 12.143 98 52 101 1 1259336448 116285792 10794944
06:33:24 60711 8192000000 10121823392 10628382720 1101080432 11.848 97 20 40 0 1290946464 184159056 5706976
06:35:31 11228 8192000000 10079427904 10497458176 1106687760 11.901 98 58 111 1 1150045440 32314560 11043120
06:36:45 34570 8192000000 10087314368 10527035392 1115679136 11.986 100 35 70 0 1227308608 104522000 7107472
06:38:23 106310 8192000000 10083858592 10530553856 1121260432 12.039 100 42 84 0 1178781648 49136576 8384640
06:41:10 43724 8192000000 10084265648 10548252672 1132998048 12.150 100 26 49 0 1196309568 58078064 5233456
06:43:10 50736 8192000000 10069316944 10521251840 1123501536 12.060 99 14 26 0 1161309680 34932144 2876000
06:45:37 67856 8192000000 10098097440 10574331904 1114472752 11.975 98 12 24 0 1186333136 69008768 2851616
06:47:46 52632 8192000000 10092508112 10542956544 1117580128 12.004 98 23 46 1 1178280560 55854128 4846304
06:49:40 23713 8192000000 10146279264 10583654400 1131862160 12.139 99 34 67 1 1239986512 100337376 7786976
06:51:09 23535 8192000000 10198891840 10638807040 1157820944 12.383 100 27 54 0 1298371968 134013760 6537264
06:52:34 25568 8192000000 10239066352 10696273920 1166092256 12.460 100 45 90 1 1342526384 164389696 12044432
06:54:06 21667 8192000000 10254646512 10728816640 1131702016 12.137 98 44 86 0 1350174320 208795616 9676688
06:55:38 57098 8192000000 10182803344 10630823936 1130095680 12.122 99 18 36 0 1291614176 157259408 4259088
06:57:43 70988 8192000000 10139206976 10585350144 1115747872 11.987 98 37 72 0 1206021200 82986992 7286336
06:59:55 30538 8192000000 10081333664 10516840448 1141016784 12.225 100 28 56 0 1178876176 32048032 5811360
07:01:33 87104 8192000000 10064510880 10524823552 1118404720 12.012 99 31 62 0 1172821312 47859504 6557088
07:04:23 97054 8192000000 10095364464 10564419584 1125865488 12.082 100 27 52 1 1213528368 82265024 5397856
07:07:00 78900 8192000000 10108220656 10571862016 1114759344 11.977 98 13 27 0 1213218368 95465744 2993280
07:09:40 77639 8192000000 10127401584 10604527616 1106519632 11.899 97 18 36 0 1205109680 94480224 4109824
07:12:05 43368 8192000000 10074321024 10535026688 1114358464 11.974 98 26 50 1 1161552288 41698288 5495536
07:13:49 21749 8192000000 10090793008 10524200960 1126681136 12.090 100 34 66 1 1175790512 42461408 6647968
07:15:13 9843 8192000000 10075787264 10503135232 1115683536 11.986 99 60 114 0 1166158272 38458512 12016224
07:16:26 27810 8192000000 10108799136 10565107712 1115976864 11.989 100 38 76 0 1209150976 85269184 7904928
07:17:54 32802 8192000000 10133697856 10557665280 1119076400 12.018 99 34 58 0 1197062992 71706576 6280016
07:19:31 58976 8192000000 10078152720 10525638656 1084095456 11.686 96 28 56 0 1146636656 56948160 5593040
07:21:32 82082 8192000000 10080103888 10501242880 1077848672 11.627 96 39 75 1 1172375136 86780752 7745712
07:24:03 45286 8192000000 10029128784 10479083520 1076953216 11.618 97 12 24 0 1125588160 46131584 2503360
07:25:50 76664 8192000000 10051056096 10562129920 1106358544 11.898 100 11 22 0 1154766800 46229408 2178848
07:28:07 30627 8192000000 10096222416 10539855872 1117464352 12.003 99 19 38 0 1170670176 49383440 3822384
07:29:39 33450 8192000000 10126793888 10571972608 1134684192 12.165 99 29 57 0 1206580752 65615520 6281040
07:31:18 8523 8192000000 10168119184 10607284224 1142069360 12.235 100 47 89 0 1266589040 114760560 9759120
07:32:29 24935 8192000000 10206430080 10641854464 1149942048 12.309 100 42 84 0 1301425024 142521504 8961472
07:34:01 35508 8192000000 10216059520 10656473088 1141135056 12.226 99 32 64 0 1312810848 164446144 7229648
07:35:42 35262 8192000000 10190491536 10629611520 1142783680 12.242 100 42 82 0 1301618320 149765680 9068960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-07-2022 07:38:47 to 10-07-2022 07:39:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-07-2022 07:39:04 to 10-07-2022 07:39:06.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          218849
MON$OLDEST_ACTIVE               226564
MON$OLDEST_SNAPSHOT             226564
MON$NEXT_TRANSACTION            226564
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-30 07:30:08.6030 GMT
MON$PAGES                       2048544
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:189: 
:190: mscs-test-161.budzdorov.ru    Sun Jul 10 07:37:20 2022
:191:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:192: 
:193: 
:194: mscs-test-161.budzdorov.ru    Sun Jul 10 07:37:29 2022
:195:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:196: 
--- end of diff output ---

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

10.07.22 07:39:07. There are 3 logs in /var/tmp/logs-oltp40 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
2022-07-10 07:39:10 No severe PSQL-related problems occured
10.07.22 07:39:11. End of report.