Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.05.2022 10: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 15795492 kB
MemAvailable 31491988 kB
Buffers 0 kB
Cached 15930704 kB
SwapCached 2652 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-05-15 10:37:14
DTS_END                         2022-05-15 10:37:14.5250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54592
TRN_ID                          206495
ATT_ID                          638
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: 8853.17 28342 1062380
customer order: creation 159.63 535 19156
customer order: refuse 12.95 529 1554
order to supplier: creation 41.64 1610 4997
order to supplier: removal 3.38 196848 406
invoice (draft): creation 43.33 3933 5200
invoice (draft): removal 51.63 1088 6196
invoice accept: apply 38.18 28157 4582
invoice accept: cancel 46.60 1429 5592
customer reserve: creation 3574.59 262 428951
customer reserve: removal 4608.56 113 553027
realization accept: apply 77.28 353 9273
realization accept: cancel 75.21 193 9025
payment from customer: creation 55.78 246 6693
payment from customer: removal 5.13 223 616
payment to supplier: creation 44.54 254 5345
payment to supplier: removal 3.37 265 404
service: total inventory turnovers 3.03 6081 363
service: total monetary turnovers 7.37 433 884
service: aggregate perf. data 0.93 20218 111
service: refresh index statistics 0.04 304077 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-05-2022 10:39:45 to 15-05-2022 10:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6211
TEST_TIME 2 5081
TEST_TIME 3 4986
TEST_TIME 4 5567
TEST_TIME 5 5259
TEST_TIME 6 5801
TEST_TIME 7 5936
TEST_TIME 8 6287
TEST_TIME 9 6237
TEST_TIME 10 6448
TEST_TIME 11 6428
TEST_TIME 12 6552
TEST_TIME 13 6620
TEST_TIME 14 6688
TEST_TIME 15 6766
TEST_TIME 16 6880
TEST_TIME 17 6791
TEST_TIME 18 6962
TEST_TIME 19 6907
TEST_TIME 20 7262
TEST_TIME 21 7457
TEST_TIME 22 7555
TEST_TIME 23 7501
TEST_TIME 24 7645
TEST_TIME 25 7538
TEST_TIME 26 7781
TEST_TIME 27 8267
TEST_TIME 28 8334
TEST_TIME 29 8649
TEST_TIME 30 8796
TEST_TIME 31 8925
TEST_TIME 32 9193
TEST_TIME 33 9349
TEST_TIME 34 9574
TEST_TIME 35 9977
TEST_TIME 36 10048
TEST_TIME 37 10182
TEST_TIME 38 10453
TEST_TIME 39 10370
TEST_TIME 40 10527
TEST_TIME 41 10355
TEST_TIME 42 10188
TEST_TIME 43 10174
TEST_TIME 44 10153
TEST_TIME 45 10389
TEST_TIME 46 10452
TEST_TIME 47 10346
TEST_TIME 48 10258
TEST_TIME 49 10281
TEST_TIME 50 10208
TEST_TIME 51 10126
TEST_TIME 52 10015
TEST_TIME 53 9941
TEST_TIME 54 9803
TEST_TIME 55 9792
TEST_TIME 56 9768
TEST_TIME 57 9639
TEST_TIME 58 9603
TEST_TIME 59 9462
TEST_TIME 60 9369
TEST_TIME 61 9321
TEST_TIME 62 9297
TEST_TIME 63 9303
TEST_TIME 64 9262
TEST_TIME 65 9235
TEST_TIME 66 9217
TEST_TIME 67 9134
TEST_TIME 68 9078
TEST_TIME 69 9028
TEST_TIME 70 9030
TEST_TIME 71 8973
TEST_TIME 72 8977
TEST_TIME 73 8887
TEST_TIME 74 8898
TEST_TIME 75 8887
TEST_TIME 76 8866
TEST_TIME 77 8896
TEST_TIME 78 8857
TEST_TIME 79 8942
TEST_TIME 80 9017
TEST_TIME 81 9012
TEST_TIME 82 9027
TEST_TIME 83 9028
TEST_TIME 84 9036
TEST_TIME 85 9153
TEST_TIME 86 9158
TEST_TIME 87 9179
TEST_TIME 88 9094
TEST_TIME 89 9066
TEST_TIME 90 9077
TEST_TIME 91 9015
TEST_TIME 92 8987
TEST_TIME 93 8945
TEST_TIME 94 8862
TEST_TIME 95 8920
TEST_TIME 96 8898
TEST_TIME 97 8916
TEST_TIME 98 8888
TEST_TIME 99 8856
TEST_TIME 100 8826
TEST_TIME 101 8904
TEST_TIME 102 8906
TEST_TIME 103 8992
TEST_TIME 104 9024
TEST_TIME 105 9032
TEST_TIME 106 9051
TEST_TIME 107 9062
TEST_TIME 108 9099
TEST_TIME 109 9156
TEST_TIME 110 9127
TEST_TIME 111 9072
TEST_TIME 112 9036
TEST_TIME 113 9032
TEST_TIME 114 8973
TEST_TIME 115 8975
TEST_TIME 116 8969
TEST_TIME 117 8949
TEST_TIME 118 8957
TEST_TIME 119 8932
TEST_TIME 120 8865

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-05-2022 10:39:49 to 15-05-2022 10:39:52.

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 1458115 1458115 0 0.00 0 24589 14 0 0
doc_list_biud 1458106 1458106 0 0.00 0 47525 49 0 0
sp_add_invoice_to_stock 4696 4582 114 2.42 76 209346 28158 0 114
sp_cancel_adding_invoice 5592 5592 0 0.00 6 97451 1430 0 0
sp_cancel_client_order 1554 1554 0 0.00 6 18908 530 0 0
sp_cancel_customer_reserve 553027 553027 0 0.00 1 35730 113 0 0
sp_cancel_pay_from_customer 616 616 0 0.00 3 18692 224 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 16709 266 0 0
sp_cancel_supplier_invoice 6207 6196 11 0.17 3 47534 1088 0 11
sp_cancel_supplier_order 406 406 0 0.00 9 1516014 196848 0 0
sp_cancel_write_off 9025 9025 0 0.00 1 24591 193 0 0
sp_client_order 19156 19156 0 0.00 6 30871 535 0 0
sp_customer_reserve 428951 428951 0 0.00 1 50489 263 0 0
sp_fill_shopping_cart 29345 29345 0 0.00 1 33117 98 0 0
sp_fill_shopping_cart_clo_res 428921 428921 0 0.00 0 11968 20 0 0
sp_get_clo_for_invoice 4550 4550 0 0.00 0 37285 354 0 0
sp_kill_cost_storno 15637 15637 0 0.00 0 24587 90 0 0
sp_kill_qstorno_ret_qs2qd 1121681 1121681 0 0.00 0 47520 48 0 0
sp_kill_qty_storno 589623 589623 0 0.00 0 47522 113 0 0
sp_lock_selected_doc 19031 19031 0 0.00 0 7358 9 0 0
sp_make_cost_storno 25861 25861 0 0.00 0 19380 97 0 0
sp_multiply_rows_for_pdistr 25861 25861 0 0.00 0 19905 46 0 0
sp_multiply_rows_for_qdistr 19155 19155 0 0.00 0 23649 169 0 0
sp_pay_from_customer 6693 6693 0 0.00 6 22127 247 0 0
sp_pay_to_supplier 5345 5345 0 0.00 7 45364 254 0 0
sp_payment_common 12038 12038 0 0.00 2 11503 57 0 0
sp_qd_handle_on_cancel_clo 1554 1554 0 0.00 0 11945 433 0 0
sp_qd_handle_on_invoice_upd_sts 10142 10142 0 0.00 0 29374 633 0 0
sp_qd_handle_on_reserve_upd_sts 9273 9273 0 0.00 0 23761 90 0 0
sp_reserve_write_off 9273 9273 0 0.00 8 23786 354 0 0
sp_supplier_invoice 5200 5200 0 0.00 10 115753 3933 0 0
sp_supplier_order 4997 4997 0 0.00 8 40696 1610 0 0
srv_aggregate_perf_data 112 111 1 0.89 2482 160301 20218 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 788 133986 33691 0 0
srv_find_qd_qs_mism 1017348 1017348 0 0.00 0 17859 10 0 0
srv_make_invnt_saldo 363 363 0 0.00 50 139644 6082 0 0
srv_make_money_saldo 884 884 0 0.00 1 17463 434 0 0
srv_recalc_idx_stat 5 5 0 0.00 48569 421651 304077 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4584 4584 0 0.00 2 11054 1142 0 0
t$perf-norm:sp_cancel_adding_invoice 359 359 0 0.00 0 8518 631 0 0
t$perf-norm:sp_cancel_client_order 1554 1554 0 0.00 0 3904 32 0 0
t$perf-norm:sp_cancel_customer_reserve 614 614 0 0.00 0 854 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 616 616 0 0.00 0 942 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 12122 49 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 2695 61 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 132437 8730 0 0
t$perf-norm:sp_cancel_write_off 893 893 0 0.00 0 1741 22 0 0
t$perf-norm:sp_client_order 19157 19157 0 0.00 0 7151 44 0 0
t$perf-norm:sp_customer_reserve 814 814 0 0.00 0 928 16 0 0
t$perf-norm:sp_pay_from_customer 6693 6693 0 0.00 0 6364 22 0 0
t$perf-norm:sp_pay_to_supplier 5345 5345 0 0.00 0 7647 22 0 0
t$perf-norm:sp_reserve_write_off 9273 9273 0 0.00 0 20297 29 0 0
t$perf-norm:sp_supplier_invoice 5200 5200 0 0.00 0 5964 38 0 0
t$perf-norm:sp_supplier_order 4997 4997 0 0.00 0 8040 43 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 437 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1377 42 0 0
t$perf-norm:srv_make_invnt_saldo 363 363 0 0.00 0 6252 48 0 0
t$perf-norm:srv_make_money_saldo 884 884 0 0.00 0 678 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 198 82 0 0
v_add_invoice_to_stock 9318 9090 228 2.44 0 13017 31 0 228
v_all_customers 35554 35554 0 0.00 0 124 4 0 0
v_all_suppliers 28308 28308 0 0.00 0 125 5 0 0
v_all_wares 38310 38310 0 0.00 0 128 4 0 0
v_cancel_adding_invoice 718 718 0 0.00 0 393 15 0 0
v_cancel_client_order 3108 3108 0 0.00 0 12637 20 0 0
v_cancel_customer_prepayment 1232 1232 0 0.00 0 18079 45 0 0
v_cancel_customer_reserve 1228 1228 0 0.00 0 15570 36 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 16666 63 0 0
v_cancel_supplier_invoice 756 734 22 2.91 0 2684 27 0 22
v_cancel_supplier_order 812 812 0 0.00 0 8178 25 0 0
v_cancel_write_off 1786 1786 0 0.00 0 16256 35 0 0
v_max_id_clo_ord 4995 4995 0 0.00 0 3561 6 0 0
v_max_id_clo_res 814 814 0 0.00 0 12161 27 0 0
v_max_id_ord_sup 5195 5195 0 0.00 0 4823 8 0 0
v_max_non_paid_invoice 1380 1380 0 0.00 0 5916 12 0 0
v_max_non_paid_realizn 3261 3261 0 0.00 0 1365 9 0 0
v_min_id_clo_ord 4995 4995 0 0.00 0 4625 9 0 0
v_min_id_clo_res 814 814 0 0.00 0 5640 18 0 0
v_min_id_ord_sup 5195 5195 0 0.00 0 5044 9 0 0
v_min_non_paid_invoice 5345 5345 0 0.00 0 11349 23 0 0
v_min_non_paid_realizn 6693 6693 0 0.00 0 11483 28 0 0
v_our_firm 9620 9620 0 0.00 0 67 5 0 0
v_reserve_write_off 18546 18546 0 0.00 0 18447 18 0 0
x_lock_depdocs_on_canc_invoice 11421 11421 0 0.00 0 12174 153 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 9753 496 0 0
x_make_qstorno_1000_1200 4997 4997 0 0.00 3 40678 1409 0 0
x_make_qstorno_1200_2000 5200 5200 0 0.00 5 112946 3661 0 0
x_make_qstorno_2100_3300 428949 428949 0 0.00 0 47120 229 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 15-05-2022 10:39:53 to 15-05-2022 10:39:58.

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
08:07:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:19 34203 8192000000 9721902320 10022039552 807261264 8.970 100 91 182 0 837330896 7709744 22359888
08:10:05 24302 8192000000 9759782768 10070786048 836642336 9.266 100 84 168 0 869886896 12974592 20269968
08:11:33 21601 8192000000 9821991760 10144374784 881363696 9.713 100 86 173 0 923536176 22708912 19463568
08:13:05 11656 8192000000 9882258272 10222112768 924986656 10.145 100 76 150 1 991070560 50126832 15957072
08:14:27 9248 8192000000 9917047664 10266214400 966945216 10.557 100 81 157 2 1033031616 48515600 17570800
08:15:40 2179 8192000000 9967795776 10323783680 996579312 10.845 100 92 178 0 1084192176 69806192 17806672
08:16:44 1355 8192000000 10008346176 10374426624 1017792848 11.051 100 89 172 0 1130404080 95995600 16615632
08:17:48 945 8192000000 10006299584 10375512064 1042759392 11.291 100 95 188 1 1127767696 66792672 18215632
08:18:50 1519 8192000000 10025634480 10396143616 1052153120 11.381 100 93 182 1 1138364272 66813328 19397824
08:19:53 908 8192000000 10037309024 10412658688 1063949888 11.494 100 97 190 0 1154980704 70734784 20296032
08:20:58 1429 8192000000 10018547936 10400083968 1068826128 11.541 100 74 132 1 1140588528 59780752 11981648
08:22:01 1410 8192000000 10038310448 10420584448 1076533248 11.614 100 93 178 3 1159946192 65234736 18178208
08:23:04 1532 8192000000 10048722160 10440302592 1080339792 11.651 100 83 162 0 1170726048 73481440 16904816
08:24:06 1119 8192000000 10053337040 10442223616 1084008624 11.686 100 91 178 2 1173213888 71149536 18055728
08:25:08 1825 8192000000 10058534400 10451476480 1093052448 11.772 100 84 165 2 1184233520 74374272 16806800
08:26:12 921 8192000000 10062288304 10451709952 1096529568 11.805 100 90 171 0 1181519920 67166768 17823584
08:27:14 1282 8192000000 10075715024 10468806656 1102784640 11.864 100 88 175 3 1204129680 82194640 19150400
08:28:16 1094 8192000000 10064460496 10455687168 1103115776 11.867 100 97 180 0 1186436080 63562880 19757424
08:29:18 1295 8192000000 10050261760 10439987200 1098790528 11.826 100 81 157 1 1175629616 61059712 15779376
08:30:21 1062 8192000000 10058042384 10450399232 1093965328 11.780 100 90 164 1 1178658960 67548224 17145408
08:31:23 2244 8192000000 10061842656 10457346048 1091968848 11.761 100 71 134 1 1183420480 77596000 13855632
08:32:27 1041 8192000000 10028720016 10413498368 1068042368 11.533 100 88 170 0 1154619440 68572816 18004256
08:33:29 1331 8192000000 10016640768 10390659072 1048770080 11.349 100 94 184 1 1142762016 75560640 18431296
08:34:31 871 8192000000 9992599744 10365931520 1042771632 11.291 100 83 165 1 1112175600 52037216 17366752
08:35:33 837 8192000000 10009697760 10389868544 1049550576 11.356 100 86 170 0 1135268640 67101632 18616432
08:36:35 1115 8192000000 10011619152 10386018304 1041987264 11.284 100 86 155 0 1135749664 77299552 16462848
08:37:38 1970 8192000000 9999009184 10375266304 1066147008 11.515 100 87 161 0 1119023232 36366864 16509360
08:38:48 1870 8192000000 10026979344 10404024320 1060124032 11.458 100 90 171 0 1153777712 75346656 18307024
08:39:58 8490 8192000000 10048475216 10435096576 1080251488 11.650 100 75 143 2 1166410384 70761328 15397568
08:41:09 2695 8192000000 10061652560 10450018304 1092960320 11.771 100 70 138 0 1184265504 76865968 14439216
08:42:21 18572 8192000000 10102367808 10498822144 1110536208 11.937 100 77 149 0 1217811776 91805104 15470464
08:43:43 3938 8192000000 10134987824 10530979840 1120233328 12.029 100 70 132 2 1252177408 117703408 14240672
08:44:51 1027 8192000000 10184120224 10571038720 1133006192 12.150 100 95 182 0 1297736672 145071104 19659376
08:45:58 1558 8192000000 10196956480 10590343168 1141780880 12.232 100 90 171 1 1312326000 151119504 19425616
08:47:05 1797 8192000000 10210158448 10617376768 1154745744 12.354 100 89 176 1 1324228144 150577728 18904672
08:48:10 1454 8192000000 10268198544 10663993344 1165610640 12.456 100 88 171 0 1378552976 194024368 18917968
08:49:13 1237 8192000000 10286856192 10684690432 1180664448 12.596 100 94 183 1 1397955584 197922720 19368416
08:50:17 1176 8192000000 10320918688 10719678464 1174463648 12.539 100 96 185 0 1435417472 239333856 21619968
08:51:23 1386 8192000000 10374943296 10783461376 1191044288 12.693 100 93 185 1 1487498784 274039840 22414656
08:52:28 888 8192000000 10414965520 10815651840 1193231888 12.713 100 98 197 0 1527546128 312045152 22269088
08:53:32 781 8192000000 10459924912 10870927360 1202220976 12.797 100 95 189 0 1570097120 344971872 22904272
08:54:37 3860 8192000000 10459424752 10876854272 1189509888 12.679 100 81 156 2 1562428640 355030928 17887824
08:55:46 1389 8192000000 10493076176 10906890240 1204391856 12.817 100 89 166 0 1598875424 374756784 19726784
08:56:51 1510 8192000000 10511697936 10966872064 1207716960 12.848 100 90 173 1 1620387648 391810800 20859888
08:57:56 878 8192000000 10545145520 10996035584 1215639696 12.921 100 97 189 0 1649619312 409965808 24013808
08:59:01 1622 8192000000 10536209840 10968268800 1210290368 12.872 100 89 177 0 1638516048 407014512 21211168
09:00:07 1232 8192000000 10570715120 10980003840 1212028816 12.888 100 100 197 0 1676564224 441169968 23365440
09:01:12 2438 8192000000 10615600208 11041894400 1201576992 12.791 100 98 187 1 1719946416 494732864 23636560
09:02:19 1551 8192000000 10597397984 11050582016 1209494288 12.864 100 92 180 0 1698160928 466951392 21715248
09:03:25 1027 8192000000 10650646368 11122077696 1204105616 12.814 100 100 196 0 1753899792 523663232 26130944
09:04:29 8627 8192000000 10597494864 11086123008 1199477024 12.771 100 59 118 0 1712569232 497370320 15721888
09:05:42 1518 8192000000 10596000144 11072294912 1203468768 12.809 100 71 137 0 1701708848 480751472 17488608
09:06:46 10024 8192000000 10548302080 11009327104 1188003712 12.665 100 47 94 0 1652271904 451917264 12350928
09:08:13 31873 8192000000 10500151808 10978070528 1167535536 12.474 99 34 68 0 1610929104 434457312 8936256
09:09:46 23530 8192000000 10441808512 10904150016 1156703504 12.372 99 31 62 2 1549765584 385484400 7577680
09:11:12 18801 8192000000 10377234064 10850324480 1163363456 12.435 100 37 74 0 1512978240 340897520 8717264
09:12:32 20430 8192000000 10312917904 10807631872 1152272480 12.331 100 36 72 1 1430459584 269063008 9124096
09:13:54 849 8192000000 10285493008 10730344448 1165244016 12.452 100 96 187 1 1384290448 198891552 20154880
09:14:56 8765 8192000000 10250002720 10726871040 1161472864 12.417 100 52 102 1 1344107024 171194768 11439392
09:16:10 19261 8192000000 10219867536 10686296064 1153597632 12.343 99 38 74 2 1350515200 187791776 9125792
09:17:32 1330 8192000000 10278271280 10689626112 1166131744 12.461 100 77 145 2 1375793520 193147264 16514512
09:18:36 67642 8192000000 10295403952 10733674496 1169683184 12.494 100 51 96 1 1387517408 206014832 11819392
09:20:50 57781 8192000000 10304258368 10807988224 1144186528 12.255 99 21 42 2 1389517808 239784016 5547264
09:22:49 39958 8192000000 10115335760 10624552960 1126105424 12.085 98 15 30 0 1282049040 151808240 4135376
09:24:30 65638 8192000000 10084353648 10538844160 1127753984 12.100 99 35 68 1 1203991584 68905184 7332416
09:26:38 99219 8192000000 10104776560 10574893056 1129113328 12.113 99 14 29 0 1181134496 49403248 2617920
09:29:30 133986 8192000000 10124104656 10587545600 1099487984 11.833 96 21 41 1 1158086064 54057376 4540704
09:33:12 101966 8192000000 10053980368 10482155520 1113242160 11.963 98 33 63 0 1130544656 10430736 6871760
09:35:57 65308 8192000000 10077888656 10522357760 1141346032 12.228 100 29 58 0 1179100464 31938672 5815760
09:38:04 23780 8192000000 10125720384 10567065600 1154355584 12.350 100 58 115 0 1230723104 64418240 11949280
09:39:32 43693 8192000000 10131440000 10587426816 1137287520 12.190 98 34 68 0 1221677808 77092144 7298144
09:41:39 36480 8192000000 10107852992 10585202688 1131487392 12.135 98 11 22 0 1202456752 68771392 2197968
09:44:10 28090 8192000000 10062690288 10507755520 1113579456 11.966 99 18 36 0 1150652224 33671072 3401696
09:45:39 87161 8192000000 10078631296 10547068928 1129545776 12.117 99 22 44 1 1184715648 50433152 4736720
09:48:07 36461 8192000000 10085827232 10564894720 1125705952 12.081 99 11 22 0 1193062096 64856384 2499760
09:49:43 78414 8192000000 10088131456 10541690880 1111213808 11.944 98 8 16 0 1141019280 28472048 1333424
09:52:02 49666 8192000000 10126916688 10586865664 1135652976 12.175 99 20 40 1 1216424368 76735024 4036368
09:53:52 46872 8192000000 10156162896 10612518912 1157004592 12.375 100 18 36 2 1260475488 99021376 4449520
09:55:42 64157 8192000000 10157384992 10631393280 1131399824 12.135 98 37 74 0 1274404608 135146448 7858336
09:57:51 51318 8192000000 10127543200 10583773184 1139927424 12.215 99 25 49 2 1233852688 88222384 5702880
10:00:00 24065 8192000000 10149872480 10579087360 1128493216 12.107 98 48 96 0 1225068192 86022224 10552752
10:01:25 77076 8192000000 10101090160 10629337088 1116591520 11.995 98 11 22 1 1200463872 81348496 2523856
10:03:42 113397 8192000000 10077365840 10514169856 1110951600 11.941 98 18 34 1 1157314720 42501840 3861280
10:06:46 59217 8192000000 10090124080 10583412736 1133747760 12.157 100 31 62 0 1209168224 69171040 6249424
10:08:47 55436 8192000000 10097616432 10553442304 1113936368 11.970 98 24 48 0 1161705408 42796768 4972272
10:10:42 40095 8192000000 10114798400 10578321408 1121936624 12.045 98 12 24 1 1208289152 83852688 2499840
10:14:47 27415 8192000000 10096305200 10557116416 1129975344 12.121 98 18 35 0 1179478912 45644256 3859312
10:16:21 69656 8192000000 10164512480 10606329856 1144702400 12.260 99 22 44 0 1253763648 104248048 4813200
10:18:32 63546 8192000000 10205940176 10668122112 1127066544 12.094 98 20 40 1 1256716448 125365040 4284864
10:20:37 63798 8192000000 10118252000 10611294208 1145501648 12.267 100 12 24 1 1244122240 96003856 2616736
10:22:47 14589 8192000000 10143966192 10569220096 1153233840 12.340 100 52 101 0 1227348096 63776368 10337888
10:24:05 53750 8192000000 10132489008 10617311232 1148901584 12.299 99 34 68 1 1278311760 122527920 6882256
10:26:04 95128 8192000000 10100122560 10528735232 1088446880 11.728 95 20 40 2 1154261760 61201536 4613344
10:28:41 110330 8192000000 10076005536 10515005440 1103955408 11.875 97 40 77 0 1147514624 35547328 8011888
10:31:41 17377 8192000000 10113083040 10545295360 1138984528 12.206 100 63 122 0 1213452528 61812384 12655616
10:33:00 61057 8192000000 10050750208 10513494016 1109302528 11.926 98 15 30 0 1174620688 61761616 3556544
10:35:11 58718 8192000000 10083551296 10515697664 1122772160 12.053 99 34 69 3 1160766912 31021616 6973136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 15-05-2022 10:39:59 to 15-05-2022 10:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 114 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-05-2022 10:40:17 to 15-05-2022 10:40:19.

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          205383
MON$OLDEST_ACTIVE               207285
MON$OLDEST_SNAPSHOT             207285
MON$NEXT_TRANSACTION            207285
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                       2126656
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             752
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:167: 
:168: mscs-test-161.budzdorov.ru    Sun May 15 10:37:52 2022
:169:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:170: 
:171: 
:172: mscs-test-161.budzdorov.ru    Sun May 15 10:37:53 2022
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:174: 
--- end of diff output ---

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

15.05.22 10:40:20. 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-05-15 10:40:22 No severe PSQL-related problems occured
15.05.22 10:40:23. End of report.