Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.04.2022 22:40
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 16628288 kB
MemAvailable 31546128 kB
Buffers 0 kB
Cached 15113756 kB
SwapCached 2688 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-14 22:38:10
DTS_END                         2022-04-14 22:38:10.8200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57010
TRN_ID                          235650
ATT_ID                          679
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: 9483.58 18039 1138029
customer order: creation 170.68 369 20481
customer order: refuse 14.44 345 1733
order to supplier: creation 43.73 1360 5248
order to supplier: removal 3.68 99897 442
invoice (draft): creation 44.10 3268 5292
invoice (draft): removal 55.50 700 6660
invoice accept: apply 40.24 18596 4829
invoice accept: cancel 50.50 826 6060
customer reserve: creation 3811.21 180 457345
customer reserve: removal 4953.57 59 594428
realization accept: apply 82.62 208 9914
realization accept: cancel 86.03 121 10323
payment from customer: creation 58.17 132 6980
payment from customer: removal 5.66 98 679
payment to supplier: creation 47.93 125 5751
payment to supplier: removal 3.54 112 425
service: total inventory turnovers 4.88 1064 586
service: total monetary turnovers 6.23 22 747
service: aggregate perf. data 0.85 18478 102
service: refresh index statistics 0.03 214817 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-04-2022 22:40:49 to 14-04-2022 22:40:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7271
TEST_TIME 2 6017
TEST_TIME 3 7212
TEST_TIME 4 7158
TEST_TIME 5 6324
TEST_TIME 6 5809
TEST_TIME 7 5938
TEST_TIME 8 6603
TEST_TIME 9 6752
TEST_TIME 10 7151
TEST_TIME 11 7255
TEST_TIME 12 7111
TEST_TIME 13 7277
TEST_TIME 14 7374
TEST_TIME 15 7369
TEST_TIME 16 7438
TEST_TIME 17 7875
TEST_TIME 18 8124
TEST_TIME 19 8101
TEST_TIME 20 8553
TEST_TIME 21 8779
TEST_TIME 22 8614
TEST_TIME 23 8945
TEST_TIME 24 8962
TEST_TIME 25 9725
TEST_TIME 26 9825
TEST_TIME 27 10408
TEST_TIME 28 10726
TEST_TIME 29 11086
TEST_TIME 30 10908
TEST_TIME 31 11254
TEST_TIME 32 10982
TEST_TIME 33 10703
TEST_TIME 34 10459
TEST_TIME 35 10877
TEST_TIME 36 10652
TEST_TIME 37 10591
TEST_TIME 38 10497
TEST_TIME 39 10427
TEST_TIME 40 10382
TEST_TIME 41 10462
TEST_TIME 42 10398
TEST_TIME 43 10303
TEST_TIME 44 10225
TEST_TIME 45 10212
TEST_TIME 46 10122
TEST_TIME 47 10113
TEST_TIME 48 10079
TEST_TIME 49 9999
TEST_TIME 50 9921
TEST_TIME 51 9863
TEST_TIME 52 9764
TEST_TIME 53 9696
TEST_TIME 54 9708
TEST_TIME 55 9679
TEST_TIME 56 9697
TEST_TIME 57 9685
TEST_TIME 58 9736
TEST_TIME 59 9904
TEST_TIME 60 9957
TEST_TIME 61 9899
TEST_TIME 62 10043
TEST_TIME 63 10049
TEST_TIME 64 10043
TEST_TIME 65 10065
TEST_TIME 66 10106
TEST_TIME 67 10157
TEST_TIME 68 10205
TEST_TIME 69 10089
TEST_TIME 70 10034
TEST_TIME 71 9931
TEST_TIME 72 9969
TEST_TIME 73 9934
TEST_TIME 74 9842
TEST_TIME 75 9835
TEST_TIME 76 9816
TEST_TIME 77 9799
TEST_TIME 78 9777
TEST_TIME 79 9735
TEST_TIME 80 9748
TEST_TIME 81 9764
TEST_TIME 82 9745
TEST_TIME 83 9785
TEST_TIME 84 9837
TEST_TIME 85 9869
TEST_TIME 86 9832
TEST_TIME 87 9869
TEST_TIME 88 9878
TEST_TIME 89 9934
TEST_TIME 90 9946
TEST_TIME 91 9855
TEST_TIME 92 9821
TEST_TIME 93 9785
TEST_TIME 94 9728
TEST_TIME 95 9670
TEST_TIME 96 9713
TEST_TIME 97 9666
TEST_TIME 98 9661
TEST_TIME 99 9642
TEST_TIME 100 9640
TEST_TIME 101 9651
TEST_TIME 102 9661
TEST_TIME 103 9641
TEST_TIME 104 9709
TEST_TIME 105 9721
TEST_TIME 106 9697
TEST_TIME 107 9661
TEST_TIME 108 9799
TEST_TIME 109 9786
TEST_TIME 110 9803
TEST_TIME 111 9784
TEST_TIME 112 9745
TEST_TIME 113 9719
TEST_TIME 114 9661
TEST_TIME 115 9654
TEST_TIME 116 9628
TEST_TIME 117 9587
TEST_TIME 118 9584
TEST_TIME 119 9540
TEST_TIME 120 9490

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-04-2022 22:40:53 to 14-04-2022 22:40:56.

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 1561184 1561184 0 0.00 0 13951 6 0 0
doc_list_biud 1561182 1561182 0 0.00 0 56725 28 0 0
sp_add_invoice_to_stock 4939 4829 110 2.22 52 215296 18597 0 110
sp_cancel_adding_invoice 6060 6060 0 0.00 4 84330 827 0 0
sp_cancel_client_order 1733 1733 0 0.00 4 15898 346 0 0
sp_cancel_customer_reserve 594428 594428 0 0.00 1 56727 60 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 2 6319 99 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 7831 113 0 0
sp_cancel_supplier_invoice 6668 6660 8 0.11 2 18829 700 0 8
sp_cancel_supplier_order 442 442 0 0.00 4 940352 99897 0 0
sp_cancel_write_off 10323 10323 0 0.00 1 21086 122 0 0
sp_client_order 20481 20481 0 0.00 4 16584 370 0 0
sp_customer_reserve 457345 457345 0 0.00 0 64749 181 0 0
sp_fill_shopping_cart 31008 31008 0 0.00 1 8377 34 0 0
sp_fill_shopping_cart_clo_res 457336 457336 0 0.00 0 16862 13 0 0
sp_get_clo_for_invoice 4820 4820 0 0.00 0 26298 265 0 0
sp_kill_cost_storno 17487 17487 0 0.00 0 13949 56 0 0
sp_kill_qstorno_ret_qs2qd 1206281 1206281 0 0.00 0 56722 32 0 0
sp_kill_qty_storno 634380 634380 0 0.00 0 56724 69 0 0
sp_lock_selected_doc 20459 20459 0 0.00 0 7521 7 0 0
sp_make_cost_storno 27465 27465 0 0.00 0 12055 64 0 0
sp_multiply_rows_for_pdistr 27465 27465 0 0.00 0 9120 28 0 0
sp_multiply_rows_for_qdistr 20481 20481 0 0.00 0 14987 162 0 0
sp_pay_from_customer 6980 6980 0 0.00 4 11832 132 0 0
sp_pay_to_supplier 5751 5751 0 0.00 4 9507 125 0 0
sp_payment_common 12731 12731 0 0.00 2 9442 27 0 0
sp_qd_handle_on_cancel_clo 1733 1733 0 0.00 0 15889 310 0 0
sp_qd_handle_on_invoice_upd_sts 10880 10880 0 0.00 0 27413 317 0 0
sp_qd_handle_on_reserve_upd_sts 9914 9914 0 0.00 0 11024 65 0 0
sp_reserve_write_off 9914 9914 0 0.00 6 14431 209 0 0
sp_supplier_invoice 5292 5292 0 0.00 6 85593 3268 0 0
sp_supplier_order 5248 5248 0 0.00 5 66906 1360 0 0
srv_aggregate_perf_data 102 102 0 0.00 2509 151452 18478 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1444 149748 45680 0 0
srv_find_qd_qs_mism 1090871 1090871 0 0.00 0 11338 5 0 0
srv_make_invnt_saldo 586 586 0 0.00 31 21410 1064 0 0
srv_make_money_saldo 747 747 0 0.00 1 1053 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 124528 409608 214818 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4829 4829 0 0.00 0 16209 174 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 3883 113 0 0
t$perf-norm:sp_cancel_client_order 1733 1733 0 0.00 0 4453 14 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 1046 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 6578 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 1613 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1747 13 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 19710 1509 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 8103 30 0 0
t$perf-norm:sp_client_order 20481 20481 0 0.00 0 24260 22 0 0
t$perf-norm:sp_customer_reserve 945 945 0 0.00 0 6851 17 0 0
t$perf-norm:sp_pay_from_customer 6980 6980 0 0.00 0 6615 12 0 0
t$perf-norm:sp_pay_to_supplier 5751 5751 0 0.00 0 10048 11 0 0
t$perf-norm:sp_reserve_write_off 9914 9914 0 0.00 0 25576 18 0 0
t$perf-norm:sp_supplier_invoice 5292 5292 0 0.00 0 25542 29 0 0
t$perf-norm:sp_supplier_order 5248 5248 0 0.00 0 25155 24 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 446 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 1247 26 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 558 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9852 9632 220 2.23 0 13247 20 0 220
v_all_customers 37760 37760 0 0.00 0 19 1 0 0
v_all_suppliers 29806 29806 0 0.00 0 13 1 0 0
v_all_wares 40960 40960 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 2778 11 0 0
v_cancel_client_order 3466 3466 0 0.00 0 3803 7 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 6312 12 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 9053 12 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 3411 13 0 0
v_cancel_supplier_invoice 766 750 16 2.08 0 2680 17 0 16
v_cancel_supplier_order 884 884 0 0.00 0 2555 7 0 0
v_cancel_write_off 2036 2036 0 0.00 0 2475 13 0 0
v_max_id_clo_ord 5243 5243 0 0.00 0 1821 2 0 0
v_max_id_clo_res 945 945 0 0.00 0 7362 16 0 0
v_max_id_ord_sup 5283 5283 0 0.00 0 3096 3 0 0
v_max_non_paid_invoice 1374 1374 0 0.00 0 336 3 0 0
v_max_non_paid_realizn 3508 3508 0 0.00 0 1613 4 0 0
v_min_id_clo_ord 5243 5243 0 0.00 0 6894 11 0 0
v_min_id_clo_res 945 945 0 0.00 0 9228 32 0 0
v_min_id_ord_sup 5283 5283 0 0.00 0 3211 4 0 0
v_min_non_paid_invoice 5751 5751 0 0.00 0 9353 17 0 0
v_min_non_paid_realizn 6980 6980 0 0.00 0 9436 21 0 0
v_our_firm 10144 10144 0 0.00 0 15 1 0 0
v_reserve_write_off 19828 19828 0 0.00 0 9088 8 0 0
x_lock_depdocs_on_canc_invoice 12345 12345 0 0.00 0 20485 115 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 20105 375 0 0
x_make_qstorno_1000_1200 5248 5248 0 0.00 2 66824 1276 0 0
x_make_qstorno_1200_2000 5292 5292 0 0.00 2 85519 3156 0 0
x_make_qstorno_2100_3300 457345 457345 0 0.00 0 64748 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-04-2022 22:40:57 to 14-04-2022 22:41:01.

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
20:08:14 14 8192000000 8800952752 8813051904 5315664 0.064 2 1 2 0 5415264 56816 42784
20:09:15 27143 8192000000 9891383200 10200662016 966502688 10.553 100 85 170 0 995561600 5577696 23481216
20:10:49 12007 8192000000 9936892112 10257780736 1023192624 11.103 100 87 174 0 1053456784 7837792 22426368
20:12:05 8006 8192000000 9966073552 10297704448 1056328208 11.421 100 83 166 0 1086204784 9417856 20458720
20:13:17 16992 8192000000 10032080896 10374819840 1111306368 11.945 100 87 174 0 1142172864 10733216 20133280
20:14:37 13982 8192000000 10081879264 10435674112 1162148704 12.423 100 85 171 0 1194047104 12473808 19424592
20:15:54 7004 8192000000 10111934640 10474946560 1194469456 12.725 100 80 160 0 1225213888 13047600 17696832
20:17:04 4012 8192000000 10157821152 10534711296 1247513552 13.215 100 89 174 1 1278558880 12238624 18806704
20:18:09 3946 8192000000 10191989696 10570276864 1278010240 13.495 100 91 182 0 1313906352 15899312 19996800
20:19:15 1575 8192000000 10212968944 10597355520 1293671984 13.638 100 95 191 0 1328267408 13928048 20667376
20:20:18 17740 8192000000 10210949776 10609741824 1307538112 13.764 100 52 104 1 1326461520 6503056 12420352
20:21:38 12115 8192000000 10210614352 10619883520 1314224624 13.824 100 31 62 0 1332333936 11391248 6718064
20:22:54 5950 8192000000 10212099328 10628313088 1325255200 13.924 100 32 63 1 1340287664 7972736 7059728
20:24:01 7392 8192000000 10208514016 10627481600 1326149280 13.932 100 18 36 0 1336521504 6476416 3895808
20:25:09 14150 8192000000 10227350912 10642206720 1333357232 13.997 100 39 78 3 1349839808 6416432 10066144
20:26:26 31313 8192000000 10229411568 10645504000 1319898720 13.876 99 25 50 0 1331322736 6014592 5409424
20:27:59 6772 8192000000 10225952704 10635898880 1324477152 13.917 100 34 68 2 1337110880 4409008 8224720
20:29:06 6356 8192000000 10165857056 10564677632 1220290336 12.964 95 11 23 1 1228147776 5225344 2632096
20:30:12 7584 8192000000 10164010224 10567086080 1255812928 13.292 99 20 40 0 1269910816 9887360 4210528
20:31:23 26532 8192000000 10133727920 10528993280 1192946640 12.711 95 35 70 2 1206367616 6024128 7396848
20:32:51 5430 8192000000 10136971104 10538074112 1228124528 13.037 98 41 82 0 1244472624 5527888 10820208
20:33:59 8765 8192000000 10169883888 10580201472 1279897296 13.512 100 26 49 1 1291758976 6477472 5384208
20:35:08 9419 8192000000 10189951040 10603270144 1294401088 13.644 100 29 56 1 1307008544 5295728 7311728
20:36:18 51052 8192000000 10195334352 10610364416 1303266656 13.725 100 29 58 2 1315545632 5886640 6392336
20:38:11 32418 8192000000 10229979856 10639826944 1329614880 13.964 100 38 77 2 1344174432 5911072 8648480
20:39:49 18575 8192000000 10256228128 10668228608 1336942816 14.030 99 53 106 1 1359930592 10471872 12515904
20:41:16 14121 8192000000 10267528816 10683564032 1359560448 14.233 100 49 97 1 1382192992 11604624 11027920
20:42:33 41163 8192000000 10270766448 10697420800 1363072032 14.265 99 37 69 2 1379586704 8855216 7659456
20:44:23 9831 8192000000 10304925680 10726477824 1387929616 14.487 100 52 104 3 1416483632 17176144 11377872
20:45:37 10064 8192000000 10303415328 10732105728 1394141328 14.543 100 40 76 1 1416562288 12912112 9508848
20:46:49 1442 8192000000 10339547360 10754830336 1402580544 14.618 100 77 155 1 1441559776 20409776 18569456
20:47:55 13990 8192000000 10314438992 10746077184 1401979728 14.613 100 46 93 1 1433008112 19281552 11746832
20:49:13 5450 8192000000 10334399040 10755039232 1406240880 14.651 100 67 135 0 1443524800 20658048 16625872
20:50:23 14211 8192000000 10341919792 10761396224 1407893920 14.665 100 67 134 0 1450917120 26675968 16347232
20:51:47 9620 8192000000 10341625328 10764173312 1405466304 14.644 100 58 116 0 1450648032 31149856 14031872
20:53:06 6935 8192000000 10338001040 10747490304 1386520736 14.475 99 76 152 0 1438539056 33756480 18261840
20:54:17 3625 8192000000 10370480416 10779541504 1411602608 14.698 100 84 168 1 1473548416 40995136 20950672
20:55:30 2288 8192000000 10366002096 10780626944 1404366864 14.634 100 69 138 0 1471078304 48391856 18319584
20:56:36 5978 8192000000 10365062160 10781782016 1402696912 14.619 100 59 118 2 1466944096 48841536 15405648
20:57:52 1713 8192000000 10395052496 10801106944 1415244816 14.731 100 90 180 0 1495636176 56739568 23651792
20:58:56 11808 8192000000 10372221552 10812354560 1416473152 14.741 100 41 80 1 1481760560 53990480 11296928
21:00:11 8317 8192000000 10367057824 10793119744 1399355744 14.589 99 49 98 2 1465585952 52290080 13940128
21:01:22 10679 8192000000 10351486976 10784518144 1387731280 14.486 100 40 80 2 1460605168 61556640 11317248
21:02:37 18442 8192000000 10347649040 10778095616 1375735152 14.378 99 30 60 0 1445445904 60776080 8934672
21:03:59 24228 8192000000 10309416096 10747760640 1339429840 14.052 99 22 44 0 1400384560 54384448 6570272
21:06:10 46760 8192000000 10274092784 10705567744 1294560880 13.646 97 17 34 1 1349172944 49346336 5265728
21:07:59 45507 8192000000 10254809120 10681696256 1283093520 13.541 96 15 30 1 1304167856 17800256 3274080
21:09:45 49783 8192000000 10267101840 10689515520 1327963120 13.949 98 37 74 2 1345133024 9096464 8073440
21:11:57 105700 8192000000 10272545392 10743529472 1369749504 14.325 100 33 66 0 1397410928 20362064 7299360
21:14:50 24387 8192000000 10279096608 10727522304 1364714112 14.280 100 24 48 2 1385919136 15475520 5729504
21:16:17 23403 8192000000 10288900192 10741219328 1378789248 14.406 100 19 38 1 1395361280 12215280 4356752
21:17:42 44665 8192000000 10306077408 10748284928 1375226032 14.374 99 48 93 0 1402547856 15131696 12190128
21:19:30 47378 8192000000 10289088864 10749661184 1392260848 14.526 100 13 26 0 1404028896 8651056 3116992
21:21:24 47415 8192000000 10312393360 10764910592 1384428704 14.456 99 15 30 0 1402487024 14904672 3153648
21:23:13 36352 8192000000 10303653600 10767773696 1385333088 14.464 99 8 16 0 1404275152 16776880 2165184
21:25:04 88463 8192000000 10302140352 10765606912 1387931520 14.487 99 13 26 1 1404195936 13516944 2747472
21:28:25 54077 8192000000 10296979312 10760421376 1380293984 14.419 98 15 30 0 1392998944 9333040 3371920
21:30:28 57494 8192000000 10293215232 10765041664 1388190512 14.490 99 21 42 0 1401957808 8139584 5627712
21:33:30 32309 8192000000 10316865424 10780360704 1406784512 14.655 99 29 59 1 1425668976 12400864 6483600
21:35:08 30404 8192000000 10335005040 10792321024 1409101632 14.676 99 28 56 1 1430512224 14842016 6568576
21:36:39 60189 8192000000 10302301200 10762559488 1341724944 14.073 95 12 24 0 1358677920 14608016 2344960
21:38:42 54944 8192000000 10307101360 10747666432 1367256528 14.302 98 26 52 0 1382992976 10235440 5501008
21:40:43 72165 8192000000 10290582800 10748559360 1321452128 13.890 94 20 40 1 1341043216 15200416 4390672
21:43:32 51773 8192000000 10303656528 10755715072 1369157376 14.319 99 12 24 0 1390191808 18794720 2239712
21:45:35 72364 8192000000 10312307040 10767396864 1379489008 14.412 99 15 30 1 1400588544 17749264 3350272
21:47:50 96013 8192000000 10301770752 10758729728 1382129872 14.436 99 18 36 0 1393972768 7548288 4294608
21:50:30 56562 8192000000 10276491568 10747621376 1383374016 14.447 100 16 32 0 1399057520 11845040 3838464
21:52:43 87837 8192000000 10293965856 10762661888 1384291776 14.455 99 17 34 0 1400516944 12241632 3983536
21:55:27 81414 8192000000 10270363600 10733309952 1317164464 13.851 95 13 26 0 1333480032 13599728 2715840
21:57:49 50635 8192000000 10243857264 10694320128 1265065792 13.376 93 11 22 0 1281264096 13466064 2732240
21:59:41 73320 8192000000 10245661904 10688532480 1282861072 13.539 95 24 46 3 1306065744 17858448 5346224
22:01:55 58349 8192000000 10264943696 10705166336 1324473152 13.917 98 11 22 0 1338666944 11948624 2245168
22:03:53 36271 8192000000 10259474000 10696646656 1314551120 13.827 97 27 54 0 1329107104 8634992 5920992
22:05:33 33775 8192000000 10281142304 10716028928 1333434960 13.998 98 33 64 1 1351749808 11215408 7099440
22:07:07 81248 8192000000 10280084448 10738712576 1342892320 14.083 98 8 16 0 1359529072 15385712 1251040
22:09:28 85206 8192000000 10291766048 10743435264 1370444144 14.331 99 29 59 1 1383817280 6502768 6870368
22:12:00 83055 8192000000 10295361936 10760261632 1398292720 14.580 100 14 28 0 1413078560 11161984 3623856
22:14:24 53812 8192000000 10295610896 10762096640 1398067104 14.578 100 8 15 1 1411485344 11544416 1873824
22:16:28 62885 8192000000 10304624640 10765279232 1383951984 14.452 98 15 30 0 1394837776 7181344 3704448
22:18:34 43656 8192000000 10299226944 10760474624 1386420480 14.474 99 17 34 0 1400828432 10547760 3860192
22:20:27 67750 8192000000 10305594096 10762395648 1384127824 14.453 99 18 36 0 1400060080 11339936 4592320
22:22:35 40428 8192000000 10308485648 10759761920 1398982336 14.586 100 18 36 0 1412748448 9562784 4203328
22:24:16 37537 8192000000 10310649664 10763350016 1382690560 14.441 99 24 48 2 1403587696 14439936 6457200
22:26:02 47605 8192000000 10283694176 10752802816 1374872528 14.371 100 24 48 2 1405615520 24757136 5985856
22:27:57 85969 8192000000 10299053360 10756661248 1385667664 14.467 100 19 38 0 1401972096 12179856 4124576
22:30:28 149747 8192000000 10273583264 10724167680 1330957520 13.976 96 33 66 3 1347087072 8959728 7169824
22:33:59 80446 8192000000 10276786528 10734538752 1356438416 14.205 98 10 20 0 1365931104 7334864 2157824
22:36:28 119500 8192000000 9721933168 10100002816 851881600 9.419 61 13 26 0 863730192 8398320 3450272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-04-2022 22:41:03 to 14-04-2022 22:41:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-04-2022 22:41:19 to 14-04-2022 22:41:21.

Content of mon$database and FB version

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


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
--- end of diff output ---

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

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