Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.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 15653364 kB
MemAvailable 31059964 kB
Buffers 0 kB
Cached 16024564 kB
SwapCached 2492 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-17 07:37:17
DTS_END                         2022-07-17 07:37:17.8930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55042
TRN_ID                          228620
ATT_ID                          639
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: 9255.99 22385 1110719
customer order: creation 178.65 443 21438
customer order: refuse 14.93 375 1791
order to supplier: creation 45.86 1311 5503
order to supplier: removal 3.24 165564 389
invoice (draft): creation 47.58 3037 5709
invoice (draft): removal 50.10 934 6012
invoice accept: apply 43.34 24405 5201
invoice accept: cancel 45.59 1197 5471
customer reserve: creation 4074.05 228 488886
customer reserve: removal 4449.98 95 533998
realization accept: apply 85.37 287 10244
realization accept: cancel 82.35 163 9882
payment from customer: creation 61.76 173 7411
payment from customer: removal 6.29 112 755
payment to supplier: creation 50.00 206 6000
payment to supplier: removal 3.84 118 461
service: total inventory turnovers 4.47 3505 536
service: total monetary turnovers 7.68 365 922
service: aggregate perf. data 0.88 16237 106
service: refresh index statistics 0.03 228936 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10935
TEST_TIME 2 7904
TEST_TIME 3 8197
TEST_TIME 4 7894
TEST_TIME 5 7959
TEST_TIME 6 8898
TEST_TIME 7 8409
TEST_TIME 8 8442
TEST_TIME 9 8307
TEST_TIME 10 8226
TEST_TIME 11 8491
TEST_TIME 12 8284
TEST_TIME 13 8176
TEST_TIME 14 8044
TEST_TIME 15 7905
TEST_TIME 16 7880
TEST_TIME 17 7822
TEST_TIME 18 7969
TEST_TIME 19 8097
TEST_TIME 20 8224
TEST_TIME 21 8185
TEST_TIME 22 8256
TEST_TIME 23 8520
TEST_TIME 24 8739
TEST_TIME 25 9206
TEST_TIME 26 9403
TEST_TIME 27 9589
TEST_TIME 28 9652
TEST_TIME 29 9743
TEST_TIME 30 9808
TEST_TIME 31 10039
TEST_TIME 32 10783
TEST_TIME 33 10878
TEST_TIME 34 11391
TEST_TIME 35 11433
TEST_TIME 36 11410
TEST_TIME 37 11280
TEST_TIME 38 11161
TEST_TIME 39 11058
TEST_TIME 40 11002
TEST_TIME 41 10844
TEST_TIME 42 10782
TEST_TIME 43 10859
TEST_TIME 44 10995
TEST_TIME 45 10874
TEST_TIME 46 10653
TEST_TIME 47 10510
TEST_TIME 48 10480
TEST_TIME 49 10354
TEST_TIME 50 10333
TEST_TIME 51 10269
TEST_TIME 52 10221
TEST_TIME 53 10183
TEST_TIME 54 10103
TEST_TIME 55 10117
TEST_TIME 56 10118
TEST_TIME 57 10142
TEST_TIME 58 10104
TEST_TIME 59 10017
TEST_TIME 60 9879
TEST_TIME 61 9986
TEST_TIME 62 9999
TEST_TIME 63 9998
TEST_TIME 64 9939
TEST_TIME 65 9932
TEST_TIME 66 9879
TEST_TIME 67 10055
TEST_TIME 68 9990
TEST_TIME 69 9992
TEST_TIME 70 9931
TEST_TIME 71 10017
TEST_TIME 72 10146
TEST_TIME 73 10136
TEST_TIME 74 10099
TEST_TIME 75 10088
TEST_TIME 76 10050
TEST_TIME 77 9987
TEST_TIME 78 9923
TEST_TIME 79 9852
TEST_TIME 80 9795
TEST_TIME 81 9807
TEST_TIME 82 9826
TEST_TIME 83 9797
TEST_TIME 84 9726
TEST_TIME 85 9682
TEST_TIME 86 9681
TEST_TIME 87 9663
TEST_TIME 88 9643
TEST_TIME 89 9597
TEST_TIME 90 9586
TEST_TIME 91 9563
TEST_TIME 92 9626
TEST_TIME 93 9673
TEST_TIME 94 9687
TEST_TIME 95 9712
TEST_TIME 96 9697
TEST_TIME 97 9803
TEST_TIME 98 9755
TEST_TIME 99 9752
TEST_TIME 100 9749
TEST_TIME 101 9692
TEST_TIME 102 9654
TEST_TIME 103 9619
TEST_TIME 104 9577
TEST_TIME 105 9554
TEST_TIME 106 9514
TEST_TIME 107 9484
TEST_TIME 108 9447
TEST_TIME 109 9420
TEST_TIME 110 9393
TEST_TIME 111 9371
TEST_TIME 112 9330
TEST_TIME 113 9381
TEST_TIME 114 9366
TEST_TIME 115 9371
TEST_TIME 116 9374
TEST_TIME 117 9379
TEST_TIME 118 9343
TEST_TIME 119 9320
TEST_TIME 120 9277

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-07-2022 07:38:37 to 17-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 1560370 1560370 0 0.00 0 21004 13 0 0
doc_list_biud 1560355 1560355 0 0.00 0 52173 38 0 0
sp_add_invoice_to_stock 5348 5201 147 2.74 58 188854 24405 0 147
sp_cancel_adding_invoice 5471 5471 0 0.00 7 81750 1198 0 0
sp_cancel_client_order 1791 1791 0 0.00 5 12031 375 0 0
sp_cancel_customer_reserve 533998 533998 0 0.00 1 27343 95 0 0
sp_cancel_pay_from_customer 755 755 0 0.00 4 2238 112 0 0
sp_cancel_pay_to_supplier 461 461 0 0.00 3 4278 118 0 0
sp_cancel_supplier_invoice 6027 6012 15 0.24 2 52192 935 0 15
sp_cancel_supplier_order 389 389 0 0.00 15 1354229 165564 0 0
sp_cancel_write_off 9882 9882 0 0.00 2 12390 163 0 0
sp_client_order 21438 21438 0 0.00 5 28803 444 0 0
sp_customer_reserve 488886 488886 0 0.00 0 134564 229 0 0
sp_fill_shopping_cart 32639 32639 0 0.00 1 14537 86 0 0
sp_fill_shopping_cart_clo_res 488851 488851 0 0.00 0 16671 17 0 0
sp_get_clo_for_invoice 5159 5159 0 0.00 0 40228 258 0 0
sp_kill_cost_storno 16569 16569 0 0.00 0 11437 65 0 0
sp_kill_qstorno_ret_qs2qd 1084279 1084279 0 0.00 0 52170 41 0 0
sp_kill_qty_storno 572945 572945 0 0.00 0 52172 94 0 0
sp_lock_selected_doc 21266 21266 0 0.00 0 11104 8 0 0
sp_make_cost_storno 28814 28814 0 0.00 0 15471 80 0 0
sp_multiply_rows_for_pdistr 28814 28814 0 0.00 0 5145 36 0 0
sp_multiply_rows_for_qdistr 21438 21438 0 0.00 0 11678 162 0 0
sp_pay_from_customer 7411 7411 0 0.00 5 8645 174 0 0
sp_pay_to_supplier 6000 6000 0 0.00 6 16155 206 0 0
sp_payment_common 13410 13410 0 0.00 2 7415 50 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 12021 301 0 0
sp_qd_handle_on_invoice_upd_sts 10630 10630 0 0.00 0 18233 495 0 0
sp_qd_handle_on_reserve_upd_sts 10243 10243 0 0.00 0 17731 71 0 0
sp_reserve_write_off 10244 10244 0 0.00 9 17768 288 0 0
sp_supplier_invoice 5709 5709 0 0.00 9 109261 3037 0 0
sp_supplier_order 5503 5503 0 0.00 8 30697 1311 0 0
srv_aggregate_perf_data 106 106 0 0.00 2438 157277 16238 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 765 149325 37980 0 0
srv_find_qd_qs_mism 1059356 1059356 0 0.00 0 21004 10 0 0
srv_make_invnt_saldo 536 536 0 0.00 41 64972 3506 0 0
srv_make_money_saldo 922 922 0 0.00 1 13662 366 0 0
srv_recalc_idx_stat 4 4 0 0.00 46909 362729 228936 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5203 5203 0 0.00 1 11242 962 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 1 11278 563 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 1857 27 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 704 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 755 755 0 0.00 0 11144 32 0 0
t$perf-norm:sp_cancel_pay_to_supplier 461 461 0 0.00 0 1287 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 704 30 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 77264 6699 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 1803 19 0 0
t$perf-norm:sp_client_order 21439 21439 0 0.00 0 7227 37 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 587 14 0 0
t$perf-norm:sp_pay_from_customer 7411 7411 0 0.00 0 7572 22 0 0
t$perf-norm:sp_pay_to_supplier 6000 6000 0 0.00 0 8109 23 0 0
t$perf-norm:sp_reserve_write_off 10244 10244 0 0.00 0 8009 23 0 0
t$perf-norm:sp_supplier_invoice 5709 5709 0 0.00 0 6045 33 0 0
t$perf-norm:sp_supplier_order 5503 5503 0 0.00 0 4630 34 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 283 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 273 14 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 710 13 0 0
t$perf-norm:srv_make_money_saldo 922 922 0 0.00 0 637 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 205 82 0 0
v_add_invoice_to_stock 10606 10312 294 2.77 0 7267 25 0 294
v_all_customers 38922 38922 0 0.00 0 81 4 0 0
v_all_suppliers 31030 31030 0 0.00 0 71 5 0 0
v_all_wares 42868 42868 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 668 16 0 0
v_cancel_client_order 3582 3582 0 0.00 0 1668 10 0 0
v_cancel_customer_prepayment 1510 1510 0 0.00 0 1905 12 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 1095 11 0 0
v_cancel_payment_to_supplier 922 922 0 0.00 0 1411 13 0 0
v_cancel_supplier_invoice 826 796 30 3.63 0 2140 25 0 30
v_cancel_supplier_order 778 778 0 0.00 0 1719 12 0 0
v_cancel_write_off 1932 1932 0 0.00 0 3373 25 0 0
v_max_id_clo_ord 5497 5497 0 0.00 0 192 5 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 3102 17 0 0
v_max_id_ord_sup 5700 5700 0 0.00 0 6835 8 0 0
v_max_non_paid_invoice 1681 1681 0 0.00 0 97 8 0 0
v_max_non_paid_realizn 3922 3922 0 0.00 0 3384 9 0 0
v_min_id_clo_ord 5497 5497 0 0.00 0 1495 8 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 2483 11 0 0
v_min_id_ord_sup 5700 5700 0 0.00 0 4526 11 0 0
v_min_non_paid_invoice 5999 5999 0 0.00 0 6266 20 0 0
v_min_non_paid_realizn 7410 7410 0 0.00 0 7392 21 0 0
v_our_firm 10921 10921 0 0.00 0 61 5 0 0
v_reserve_write_off 20486 20486 0 0.00 0 3738 11 0 0
x_lock_depdocs_on_canc_invoice 11085 11085 0 0.00 0 20116 131 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 7976 363 0 0
x_make_qstorno_1000_1200 5503 5503 0 0.00 2 30629 1140 0 0
x_make_qstorno_1200_2000 5709 5709 0 0.00 4 109205 2802 0 0
x_make_qstorno_2100_3300 488883 488883 0 0.00 0 134561 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-07-2022 07:38:41 to 17-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:21 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:22 29172 8192000000 9724462176 10027679744 805553392 8.953 100 88 176 0 834024784 7545280 20926112
05:09:58 20663 8192000000 9773391376 10085351424 848770688 9.388 100 89 178 0 882987104 13455616 20760800
05:11:36 9448 8192000000 9813820000 10136866816 880466144 9.704 100 85 169 0 927692208 28294976 18931088
05:12:52 5749 8192000000 9874564272 10208034816 921717264 10.113 100 92 179 0 991402576 50532432 19152880
05:14:00 3324 8192000000 9911949824 10255360000 952744304 10.418 100 85 166 0 1033038288 63082368 17211616
05:15:05 3297 8192000000 9951924848 10308259840 983277264 10.716 100 78 155 0 1067095776 68740672 15077840
05:16:11 924 8192000000 10004564464 10368311296 1011903120 10.994 100 91 176 1 1124414128 94222512 18288496
05:17:14 990 8192000000 10007196256 10377764864 1043922368 11.302 100 92 177 1 1130056096 67556336 18577392
05:18:16 1209 8192000000 10050546608 10426851328 1060410256 11.460 100 94 173 0 1166600480 89722768 16467456
05:19:19 1039 8192000000 10021782240 10399744000 1070980720 11.561 100 94 178 2 1148512000 58110256 19421024
05:20:21 994 8192000000 10036310288 10420494336 1076542624 11.615 100 89 170 1 1160333568 66898816 16892128
05:21:23 2074 8192000000 10014119744 10405576704 1079850064 11.646 100 81 153 2 1136926144 41626816 15449264
05:22:25 1205 8192000000 10041001568 10426785792 1083555808 11.681 100 93 185 1 1163297280 60098352 19643120
05:23:28 1342 8192000000 10049949680 10438656000 1090632496 11.749 100 87 167 1 1168461568 59994672 17834400
05:24:29 3520 8192000000 10057334688 10448457728 1091991136 11.762 100 81 157 2 1174722160 67477040 15253984
05:25:34 1568 8192000000 10052140240 10444857344 1096915536 11.808 100 79 151 3 1175418816 63191504 15311776
05:26:36 940 8192000000 10061721840 10452885504 1103310208 11.869 100 81 156 0 1184175216 64184368 16680640
05:27:38 1059 8192000000 10042286176 10431717376 1097013568 11.809 100 93 178 3 1165293728 49018240 19261920
05:28:41 864 8192000000 10045727312 10439995392 1097411520 11.813 100 86 160 0 1173377680 58266608 17699552
05:29:42 1149 8192000000 10062694608 10448625664 1091129536 11.753 100 97 187 1 1184966640 74549776 19287328
05:30:45 970 8192000000 10011199232 10388803584 1046763392 11.330 99 94 171 4 1133984960 68566544 18655024
05:31:47 989 8192000000 10003589184 10377478144 1036221264 11.228 99 96 183 3 1128160896 72368096 19571536
05:32:50 1003 8192000000 9996403856 10369589248 1030217984 11.171 98 88 161 4 1119860944 74004320 15638640
05:33:51 954 8192000000 9972879648 10349424640 1028462992 11.154 99 81 152 1 1096197568 52780096 14954480
05:34:53 2944 8192000000 9989602976 10365845504 1026533280 11.135 99 68 123 3 1103186160 63978880 12674000
05:35:57 5068 8192000000 10006553312 10387361792 1045343648 11.316 100 69 134 0 1120225584 60559264 14322672
05:37:05 1514 8192000000 9950015280 10323693568 1037438864 11.240 100 76 130 3 1071095712 21702976 11953872
05:38:11 1884 8192000000 10045178800 10426261504 1069465680 11.547 100 88 156 4 1163133008 78699392 14967936
05:39:19 3696 8192000000 10041970768 10437804032 1083877056 11.684 100 72 140 0 1160434304 61964048 14593200
05:40:25 1575 8192000000 10059244256 10452029440 1093833664 11.779 100 92 181 1 1184672304 70047504 20791136
05:41:31 10954 8192000000 10093796688 10490458112 1110403744 11.936 100 72 135 1 1203486400 79402000 13680656
05:42:47 1215 8192000000 10121832432 10515857408 1127816112 12.101 100 96 182 0 1243573008 95377136 20379760
05:43:50 975 8192000000 10108856000 10507186176 1130577280 12.127 100 76 140 2 1224074944 78698896 14798768
05:44:57 3104 8192000000 10147317744 10557591552 1145271424 12.265 100 68 132 2 1254829440 95255792 14302224
05:46:03 892 8192000000 10191958272 10590777344 1152862592 12.336 100 96 187 0 1308634800 135210800 20561408
05:47:07 2535 8192000000 10211593328 10613538816 1163102048 12.432 100 79 157 1 1326367024 146405408 16859568
05:48:12 1804 8192000000 10233901392 10650877952 1168685760 12.485 100 89 170 1 1350908192 162591808 19630624
05:49:18 2454 8192000000 10271064864 10679001088 1177506112 12.567 100 77 148 0 1383754992 189141696 17107184
05:50:25 1874 8192000000 10284815392 10689064960 1182692288 12.615 100 86 157 3 1398384496 198479632 17212576
05:51:30 5064 8192000000 10352027504 10758770688 1189085728 12.675 100 79 150 0 1463738800 257654832 16998240
05:52:39 2726 8192000000 10381972752 10789752832 1203373312 12.808 100 90 175 0 1491422448 267258000 20791136
05:53:47 757 8192000000 10417859360 10821992448 1201090720 12.786 100 95 188 4 1527319312 304351744 21876848
05:54:50 1001 8192000000 10425541600 10852134912 1185340576 12.640 100 96 186 1 1533148320 324965520 22842224
05:55:55 859 8192000000 10465407552 10882883584 1191325296 12.696 100 96 189 1 1565913296 353046960 21541040
05:56:59 1391 8192000000 10508203824 10923778048 1194449216 12.725 100 98 189 0 1608657872 391916224 22292432
05:58:02 962 8192000000 10521002064 10930831360 1176780416 12.560 100 97 186 0 1627884176 428587088 22516672
05:59:06 841 8192000000 10560274112 10976641024 1190697776 12.690 100 98 194 0 1665477856 452204384 22575696
06:00:10 761 8192000000 10533175792 10985418752 1163921616 12.440 100 90 176 2 1637683184 451596432 22165136
06:01:13 928 8192000000 10549233440 10984919040 1175131264 12.545 100 85 162 2 1663132576 468158720 19842592
06:02:16 4984 8192000000 10513632128 11000201216 1158070432 12.385 99 70 134 1 1608855392 432999888 17785072
06:03:25 9890 8192000000 10474557952 10917437440 1157615648 12.381 100 51 100 0 1578266048 407354176 13296224
06:04:50 7215 8192000000 10489383296 10940289024 1178777008 12.579 100 50 99 2 1600060000 408465392 12817600
06:05:58 12990 8192000000 10503851984 10971729920 1170856608 12.505 100 43 86 0 1593186688 411599520 10730560
06:07:36 27876 8192000000 10459949424 10960814080 1125879424 12.083 98 28 56 0 1582344032 449069568 7395040
06:09:57 62554 8192000000 10267795296 10725621760 1134976944 12.168 99 29 53 0 1372446912 232256992 5212976
06:12:00 33567 8192000000 10040674144 10492186624 1092000096 11.762 96 40 69 1 1141079872 42288176 6791600
06:13:43 86436 8192000000 10081604256 10544316416 1124697088 12.071 100 24 41 3 1154050752 24899552 4454112
06:16:14 50442 8192000000 10082249232 10525032448 1126586304 12.089 99 43 73 3 1167599760 34454720 6558736
06:18:11 36752 8192000000 10113175648 10537930752 1143154112 12.245 100 35 62 2 1202205728 51809376 7242240
06:19:50 149324 8192000000 10067095776 10558935040 1087171056 11.716 95 13 26 0 1179841408 89900512 2769840
06:23:23 94315 8192000000 10088701184 10537549824 1124812272 12.072 99 33 64 1 1183534048 52253952 6467824
06:26:06 83195 8192000000 10086757440 10532474880 1121437696 12.041 99 31 59 0 1170507248 42835232 6234320
06:28:30 29986 8192000000 10103516960 10540589056 1113858192 11.969 99 48 94 0 1193430128 70344688 9227248
06:30:09 18545 8192000000 10148139392 10603229184 1127035680 12.093 99 45 89 0 1229658944 93745552 8877712
06:31:30 97343 8192000000 10072221824 10562871296 1085065056 11.696 96 23 44 0 1178132528 87938816 5128656
06:34:08 144684 8192000000 10101780800 10577682432 1104031056 11.876 98 20 40 0 1179545712 71057776 4456880
06:37:39 75305 8192000000 10074273168 10623246336 1116502896 11.994 99 12 24 0 1233882128 114711552 2667680
06:39:55 62284 8192000000 10050772016 10543763456 1105496800 11.890 98 17 32 0 1152546448 43540848 3508800
06:42:00 32748 8192000000 10113363184 10555740160 1110262336 11.935 98 25 50 0 1173872736 58808752 4801648
06:43:33 34600 8192000000 10152770256 10592366592 1101454688 11.851 98 48 93 0 1211646320 100149744 10041888
06:45:13 66014 8192000000 10115512528 10580836352 1125454736 12.078 99 27 52 1 1180682320 49839088 5388496
06:47:19 28646 8192000000 10172155280 10613993472 1141042384 12.225 100 45 90 0 1257684224 107046544 9595296
06:48:55 119706 8192000000 10072056688 10543931392 1077052864 11.619 95 9 18 0 1125938144 47342848 1542432
06:51:56 74729 8192000000 10075460720 10525118464 1083298064 11.679 97 29 54 0 1131506768 43101296 5107408
06:54:11 126607 8192000000 10050108368 10530172928 1077320928 11.622 96 21 41 0 1135685504 54014064 4350512
06:57:19 47106 8192000000 10130512400 10566619136 1100966768 11.847 99 48 95 0 1203140544 92462896 9710880
06:59:12 47471 8192000000 10054273648 10545516544 1096378496 11.803 99 14 28 0 1158935200 59013552 3543152
07:01:02 73441 8192000000 10084147040 10530697216 1103094256 11.867 99 13 26 0 1156691168 51126544 2470368
07:03:18 69517 8192000000 10043314768 10536755200 1098125264 11.820 98 11 22 0 1151367888 51053056 2189568
07:05:27 44298 8192000000 10072622896 10517991424 1125722864 12.081 100 28 52 1 1167523744 36390256 5410624
07:07:28 46644 8192000000 10138045232 10584850432 1118411472 12.012 98 17 34 0 1204416160 82233936 3770752
07:09:22 46864 8192000000 10098312528 10575917056 1131375648 12.134 100 19 38 0 1235608288 99588960 4643680
07:11:13 47473 8192000000 10127956912 10616172544 1128007440 12.103 99 22 44 0 1223119552 90682752 4429360
07:13:01 28440 8192000000 10130489056 10572570624 1124306976 12.068 98 41 80 0 1212400304 80031456 8061872
07:14:36 69566 8192000000 10083853648 10517540864 1095941424 11.799 96 20 40 0 1136875552 37214080 3720048
07:16:48 29941 8192000000 10104363072 10563465216 1123384656 12.059 99 40 80 0 1216107440 85003840 7718944
07:18:19 89849 8192000000 10074689472 10537046016 1133767680 12.157 100 22 42 0 1175006464 36987472 4251312
07:20:49 46709 8192000000 10088943696 10546204672 1139453456 12.210 100 32 64 0 1183884832 37565072 6866304
07:22:36 57249 8192000000 10084324960 10549985280 1139864368 12.214 100 11 22 0 1209108496 66536144 2707984
07:24:34 42577 8192000000 10051708384 10514632704 1093015936 11.771 96 24 49 0 1134654496 35892864 5745696
07:26:17 12892 8192000000 10054268512 10540007424 1121616256 12.042 100 12 24 1 1176156064 52007040 2532768
07:27:30 65468 8192000000 10078227856 10558296064 1097849840 11.817 97 18 36 0 1158145456 56250384 4045232
07:29:36 50557 8192000000 10080934656 10562027520 1116171792 11.991 99 24 48 0 1168989072 47752384 5064896
07:31:28 38948 8192000000 10064516304 10518585344 1117992848 12.008 99 26 52 0 1158036944 34727936 5316160
07:33:10 27331 8192000000 10075570784 10504163328 1102260256 11.859 98 43 84 0 1171629888 60653872 8715760
07:34:39 30633 8192000000 10121107296 10583674880 1132148320 12.142 100 32 62 0 1207796784 69061600 6586864
07:36:10 26284 8192000000 10168032400 10608775168 1134187552 12.161 99 32 64 0 1252476896 111094224 7195120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-07-2022 07:39:04 to 17-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          223694
MON$OLDEST_ACTIVE               228769
MON$OLDEST_SNAPSHOT             228769
MON$NEXT_TRANSACTION            228769
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                       2080416
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Sun Jul 17 07:37:31 2022
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Sun Jul 17 07:37:31 2022
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
--- end of diff output ---

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

17.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-17 07:39:09 No severe PSQL-related problems occured
17.07.22 07:39:11. End of report.