Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.11.2021 13: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 16860168 kB
MemAvailable 31617392 kB
Buffers 0 kB
Cached 14866460 kB
SwapCached 0 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.1
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 2021-11-23 13:37:08
DTS_END                         2021-11-23 13:37:08.7790
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/43104
TRN_ID                          222586
ATT_ID                          590
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: 9148.83 22492 1097859
customer order: creation 158.49 472 19019
customer order: refuse 13.86 340 1663
order to supplier: creation 41.85 1492 5022
order to supplier: removal 3.55 119828 426
invoice (draft): creation 42.52 3504 5102
invoice (draft): removal 54.12 968 6494
invoice accept: apply 37.94 19258 4553
invoice accept: cancel 48.78 862 5854
customer reserve: creation 3611.88 185 433425
customer reserve: removal 4865.14 69 583817
realization accept: apply 77.13 207 9256
realization accept: cancel 76.78 136 9213
payment from customer: creation 53.83 113 6460
payment from customer: removal 5.47 103 656
payment to supplier: creation 44.61 130 5353
payment to supplier: removal 3.25 70 390
service: total inventory turnovers 3.44 3740 413
service: total monetary turnovers 5.28 557 634
service: aggregate perf. data 0.88 13447 105
service: refresh index statistics 0.03 284352 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-11-2021 13:39:42 to 23-11-2021 13:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4366
TEST_TIME 2 5192
TEST_TIME 3 5221
TEST_TIME 4 5166
TEST_TIME 5 5237
TEST_TIME 6 4985
TEST_TIME 7 4860
TEST_TIME 8 5096
TEST_TIME 9 5162
TEST_TIME 10 5541
TEST_TIME 11 5733
TEST_TIME 12 6231
TEST_TIME 13 6501
TEST_TIME 14 6645
TEST_TIME 15 6797
TEST_TIME 16 7039
TEST_TIME 17 7180
TEST_TIME 18 7651
TEST_TIME 19 8101
TEST_TIME 20 8376
TEST_TIME 21 8997
TEST_TIME 22 9206
TEST_TIME 23 9267
TEST_TIME 24 9431
TEST_TIME 25 10018
TEST_TIME 26 11010
TEST_TIME 27 10903
TEST_TIME 28 10627
TEST_TIME 29 10524
TEST_TIME 30 10516
TEST_TIME 31 10303
TEST_TIME 32 10042
TEST_TIME 33 9961
TEST_TIME 34 10118
TEST_TIME 35 10004
TEST_TIME 36 9849
TEST_TIME 37 9784
TEST_TIME 38 9736
TEST_TIME 39 9720
TEST_TIME 40 9605
TEST_TIME 41 9453
TEST_TIME 42 9341
TEST_TIME 43 9281
TEST_TIME 44 9269
TEST_TIME 45 9213
TEST_TIME 46 9666
TEST_TIME 47 9613
TEST_TIME 48 9527
TEST_TIME 49 9611
TEST_TIME 50 9661
TEST_TIME 51 9535
TEST_TIME 52 9481
TEST_TIME 53 9522
TEST_TIME 54 9854
TEST_TIME 55 9836
TEST_TIME 56 9936
TEST_TIME 57 9884
TEST_TIME 58 9852
TEST_TIME 59 9728
TEST_TIME 60 9671
TEST_TIME 61 9631
TEST_TIME 62 9701
TEST_TIME 63 9595
TEST_TIME 64 9555
TEST_TIME 65 9515
TEST_TIME 66 9528
TEST_TIME 67 9595
TEST_TIME 68 9590
TEST_TIME 69 9533
TEST_TIME 70 9490
TEST_TIME 71 9510
TEST_TIME 72 9620
TEST_TIME 73 9623
TEST_TIME 74 9707
TEST_TIME 75 9733
TEST_TIME 76 9763
TEST_TIME 77 9754
TEST_TIME 78 9806
TEST_TIME 79 9697
TEST_TIME 80 9645
TEST_TIME 81 9548
TEST_TIME 82 9534
TEST_TIME 83 9525
TEST_TIME 84 9556
TEST_TIME 85 9486
TEST_TIME 86 9449
TEST_TIME 87 9388
TEST_TIME 88 9427
TEST_TIME 89 9424
TEST_TIME 90 9402
TEST_TIME 91 9454
TEST_TIME 92 9457
TEST_TIME 93 9492
TEST_TIME 94 9471
TEST_TIME 95 9483
TEST_TIME 96 9494
TEST_TIME 97 9491
TEST_TIME 98 9510
TEST_TIME 99 9482
TEST_TIME 100 9463
TEST_TIME 101 9377
TEST_TIME 102 9344
TEST_TIME 103 9323
TEST_TIME 104 9253
TEST_TIME 105 9224
TEST_TIME 106 9238
TEST_TIME 107 9247
TEST_TIME 108 9214
TEST_TIME 109 9208
TEST_TIME 110 9205
TEST_TIME 111 9201
TEST_TIME 112 9216
TEST_TIME 113 9229
TEST_TIME 114 9206
TEST_TIME 115 9326
TEST_TIME 116 9316
TEST_TIME 117 9320
TEST_TIME 118 9257
TEST_TIME 119 9224
TEST_TIME 120 9151

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-11-2021 13:39:45 to 23-11-2021 13:39:48.

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 1498237 1498237 0 0.00 0 20966 6 0 0
doc_list_biud 1498233 1498233 0 0.00 0 55784 34 0 0
sp_add_invoice_to_stock 4668 4553 115 2.46 30 185876 19258 0 115
sp_cancel_adding_invoice 5854 5854 0 0.00 5 78910 863 0 0
sp_cancel_client_order 1663 1663 0 0.00 4 13276 341 0 0
sp_cancel_customer_reserve 583817 583817 0 0.00 1 55786 70 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 10724 103 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 3078 71 0 0
sp_cancel_supplier_invoice 6501 6494 7 0.10 2 16375 968 0 7
sp_cancel_supplier_order 426 426 0 0.00 6 1023839 119829 0 0
sp_cancel_write_off 9213 9213 0 0.00 1 9095 137 0 0
sp_client_order 19019 19019 0 0.00 4 26813 473 0 0
sp_customer_reserve 433425 433425 0 0.00 0 80030 185 0 0
sp_fill_shopping_cart 29130 29130 0 0.00 1 12351 42 0 0
sp_fill_shopping_cart_clo_res 433422 433422 0 0.00 0 12664 18 0 0
sp_get_clo_for_invoice 4550 4550 0 0.00 0 23972 419 0 0
sp_kill_cost_storno 16113 16113 0 0.00 0 9697 65 0 0
sp_kill_qstorno_ret_qs2qd 1183767 1183767 0 0.00 0 55782 38 0 0
sp_kill_qty_storno 621272 621272 0 0.00 0 55784 81 0 0
sp_lock_selected_doc 19287 19287 0 0.00 0 7407 5 0 0
sp_make_cost_storno 25619 25619 0 0.00 0 20926 58 0 0
sp_multiply_rows_for_pdistr 25618 25618 0 0.00 0 8719 34 0 0
sp_multiply_rows_for_qdistr 19019 19019 0 0.00 0 26800 256 0 0
sp_pay_from_customer 6460 6460 0 0.00 4 8882 114 0 0
sp_pay_to_supplier 5353 5353 0 0.00 5 17025 130 0 0
sp_payment_common 11812 11812 0 0.00 1 8508 29 0 0
sp_qd_handle_on_cancel_clo 1663 1663 0 0.00 0 13265 296 0 0
sp_qd_handle_on_invoice_upd_sts 10404 10404 0 0.00 0 32939 367 0 0
sp_qd_handle_on_reserve_upd_sts 9255 9255 0 0.00 0 10241 65 0 0
sp_reserve_write_off 9256 9256 0 0.00 7 20977 207 0 0
sp_supplier_invoice 5102 5102 0 0.00 6 90679 3504 0 0
sp_supplier_order 5022 5022 0 0.00 5 34475 1493 0 0
srv_aggregate_perf_data 105 105 0 0.00 2912 139593 13448 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1542 161634 49014 0 0
srv_find_qd_qs_mism 1053332 1053332 0 0.00 0 20063 5 0 0
srv_make_invnt_saldo 413 413 0 0.00 115 138516 3741 0 0
srv_make_money_saldo 634 634 0 0.00 1 14129 557 0 0
srv_recalc_idx_stat 4 4 0 0.00 208683 361237 284352 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4553 4553 0 0.00 0 21241 188 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 3366 85 0 0
t$perf-norm:sp_cancel_client_order 1663 1663 0 0.00 0 4734 14 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 910 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 1631 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 108 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 2151 26 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 22794 1613 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 5437 18 0 0
t$perf-norm:sp_client_order 19019 19019 0 0.00 0 14154 20 0 0
t$perf-norm:sp_customer_reserve 935 935 0 0.00 0 636 5 0 0
t$perf-norm:sp_pay_from_customer 6460 6460 0 0.00 0 21304 14 0 0
t$perf-norm:sp_pay_to_supplier 5353 5353 0 0.00 0 5575 8 0 0
t$perf-norm:sp_reserve_write_off 9256 9256 0 0.00 0 24568 16 0 0
t$perf-norm:sp_supplier_invoice 5102 5102 0 0.00 0 16665 17 0 0
t$perf-norm:sp_supplier_order 5022 5022 0 0.00 0 5868 18 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 419 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 96 3 0 0
t$perf-norm:srv_make_invnt_saldo 413 413 0 0.00 0 1103 9 0 0
t$perf-norm:srv_make_money_saldo 634 634 0 0.00 0 279 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9328 9098 230 2.46 0 11123 20 0 230
v_all_customers 34742 34742 0 0.00 0 162 1 0 0
v_all_suppliers 28074 28074 0 0.00 0 99 1 0 0
v_all_wares 38030 38030 0 0.00 0 139 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 5076 12 0 0
v_cancel_client_order 3326 3326 0 0.00 0 4967 9 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 4259 13 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 3272 10 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 3067 12 0 0
v_cancel_supplier_invoice 806 792 14 1.73 0 3927 20 0 14
v_cancel_supplier_order 852 852 0 0.00 0 942 5 0 0
v_cancel_write_off 1846 1846 0 0.00 0 5087 17 0 0
v_max_id_clo_ord 5021 5021 0 0.00 0 2359 2 0 0
v_max_id_clo_res 935 935 0 0.00 0 1992 11 0 0
v_max_id_ord_sup 5094 5094 0 0.00 0 5820 5 0 0
v_max_non_paid_invoice 1431 1431 0 0.00 0 814 2 0 0
v_max_non_paid_realizn 3315 3315 0 0.00 0 6872 6 0 0
v_min_id_clo_ord 5021 5021 0 0.00 0 1271 4 0 0
v_min_id_clo_res 935 935 0 0.00 0 5064 15 0 0
v_min_id_ord_sup 5094 5094 0 0.00 0 8843 11 0 0
v_min_non_paid_invoice 5353 5353 0 0.00 0 8501 23 0 0
v_min_non_paid_realizn 6459 6459 0 0.00 0 7847 19 0 0
v_our_firm 9576 9576 0 0.00 0 14 1 0 0
v_reserve_write_off 18510 18510 0 0.00 0 5045 7 0 0
x_lock_depdocs_on_canc_invoice 11952 11952 0 0.00 0 12055 99 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 30285 359 0 0
x_make_qstorno_1000_1200 5022 5022 0 0.00 2 34462 1421 0 0
x_make_qstorno_1200_2000 5102 5102 0 0.00 3 90670 3340 0 0
x_make_qstorno_2100_3300 433425 433425 0 0.00 0 80002 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-11-2021 13:39:49 to 23-11-2021 13:39:53.

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
11:07:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 25244 8192000000 9727376096 10025467904 796380160 8.860 100 78 155 1 822297664 6611248 19306256
11:09:43 16163 8192000000 9772520304 10085216256 852284080 9.423 100 81 162 0 881403664 9836016 19283568
11:11:04 18593 8192000000 9822365632 10144231424 891465408 9.814 100 78 156 0 923955936 15134336 17356192
11:12:27 16261 8192000000 9878491392 10221416448 938361504 10.277 100 80 160 0 995672464 39847696 17463264
11:13:45 10556 8192000000 9931464480 10281811968 984586512 10.729 100 79 157 0 1045445296 44440208 16418576
11:14:58 5284 8192000000 9976085680 10338144256 1013306576 11.007 100 86 168 1 1090662336 60317120 17038640
11:16:06 2409 8192000000 10009580448 10373869568 1032340512 11.191 100 87 172 2 1115251296 65429200 17481584
11:17:11 2327 8192000000 10018352736 10387148800 1051363584 11.374 100 84 166 1 1127385968 59076592 16945792
11:18:15 1756 8192000000 10033718768 10407481344 1061961792 11.475 100 94 186 2 1146771872 65061888 19748192
11:19:19 29577 8192000000 10004312240 10387648512 1068907536 11.542 100 53 102 0 1116370224 35839568 11623120
11:20:49 3077 8192000000 10009087984 10404585472 1081709424 11.664 100 56 112 0 1125669088 31764208 12195456
11:21:55 46669 8192000000 10008709712 10417774592 1088986192 11.733 100 40 82 1 1110694224 13085040 8622992
11:23:44 5310 8192000000 10003306544 10406432768 1092488464 11.766 100 51 103 1 1111434544 7732080 11214000
11:24:54 8902 8192000000 10009748384 10408648704 1097234608 11.811 100 41 85 3 1127333024 21366496 8731920
11:26:04 55126 8192000000 10000259104 10404524032 1074544608 11.595 98 43 85 1 1097834864 13919792 9370464
11:28:16 4594 8192000000 9984634112 10373570560 1054822032 11.407 98 48 92 2 1080386944 16301024 9263888
11:29:23 3519 8192000000 9943045536 10322321408 1017276576 11.046 98 39 77 2 1042344880 15722016 9346288
11:30:30 5630 8192000000 9949378656 10335195136 1022445520 11.096 98 38 71 3 1041466864 10556320 8465024
11:31:36 24221 8192000000 9937955808 10336935936 960840720 10.497 92 22 44 1 998600704 33004224 4755760
11:33:01 7709 8192000000 9967246000 10359988224 1050247264 11.363 100 45 90 0 1076906288 16534928 10124096
11:34:10 6185 8192000000 9964344656 10349121536 1050450928 11.365 100 54 110 2 1076659680 12917968 13290784
11:35:17 73616 8192000000 9980225936 10384543744 1066685552 11.520 100 30 60 0 1088156048 14910784 6559712
11:37:34 9450 8192000000 10024186368 10413297664 1097079968 11.810 100 57 115 0 1124763792 15355568 12328256
11:38:48 19875 8192000000 10079692176 10478166016 1113990960 11.970 100 62 123 2 1190172288 63584032 12597296
11:40:11 12845 8192000000 10073741888 10484555776 1126216000 12.086 100 36 72 0 1182210064 47792800 8201264
11:41:29 16117 8192000000 10140057760 10554470400 1142903952 12.243 100 55 108 0 1256140416 101991040 11245424
11:42:54 28448 8192000000 10159145696 10576560128 1149724336 12.307 100 40 80 2 1270931056 112271664 8935056
11:44:38 22056 8192000000 10215515712 10629050368 1161281472 12.415 100 40 80 0 1305869552 134937456 9650624
11:46:06 17002 8192000000 10265446976 10673520640 1180942656 12.599 100 54 108 1 1357720704 163394368 13383680
11:47:25 8540 8192000000 10295522416 10712625152 1190207680 12.685 100 67 132 2 1401604448 196100384 15296384
11:48:38 6206 8192000000 10336253200 10745147392 1195598480 12.735 100 71 142 1 1436319584 224251776 16469328
11:49:48 4315 8192000000 10327976448 10765017088 1205448960 12.827 100 62 124 2 1432084176 212165488 14469728
11:50:56 5596 8192000000 10394307728 10802180096 1206563888 12.837 99 73 146 1 1490783904 267880528 16339488
11:52:08 2385 8192000000 10427081552 10829877248 1203584272 12.810 100 88 176 1 1515023200 291350176 20088752
11:53:21 1538 8192000000 10446627184 10864099328 1185012288 12.637 99 90 179 1 1542470480 337257168 20201024
11:54:25 2461 8192000000 10490530960 10899632128 1195676208 12.736 100 69 137 4 1582941296 370954928 16310160
11:55:33 4221 8192000000 10500223936 10918629376 1196565440 12.744 100 71 139 3 1597515616 383870544 17079632
11:56:41 6258 8192000000 10442406720 10882187264 1179606304 12.587 100 61 122 0 1537790432 342820368 15363760
11:57:51 10969 8192000000 10428424064 10897534976 1167640576 12.475 100 35 70 1 1546481696 369062304 9778816
11:59:07 10117 8192000000 10384891696 10812878848 1143105376 12.245 98 43 86 0 1459373184 305296288 10971520
12:00:41 25015 8192000000 10349356624 10850865152 1151519536 12.324 100 22 44 0 1478896160 321534416 5842208
12:02:11 35966 8192000000 10298016512 10775568384 1107486416 11.909 98 11 22 0 1406038528 295095792 3456320
12:03:51 16198 8192000000 10073340736 10486272000 1115182384 11.981 99 47 90 1 1155143568 30476144 9485040
12:05:10 81034 8192000000 10113432352 10559508480 1113945712 11.970 98 37 74 1 1209633600 87799392 7888496
12:07:41 121400 8192000000 10100140384 10540544000 1117905664 12.007 98 29 58 0 1193138688 68714144 6518880
12:10:45 113316 8192000000 10046699024 10476437504 1080514720 11.652 96 29 58 1 1167166224 80412384 6239120
12:13:39 32915 8192000000 10058845984 10482982912 1104453312 11.880 99 38 74 1 1145066304 32341328 8271664
12:15:12 26283 8192000000 10090482112 10517749760 1111877248 11.950 100 36 72 0 1184016096 64436896 7701952
12:16:40 39898 8192000000 10111323648 10550120448 1118811632 12.016 100 36 70 2 1187542032 60964496 7765904
12:18:32 64790 8192000000 10184978672 10631122944 1145224576 12.265 100 20 40 1 1287483680 137533200 4725904
12:21:06 63294 8192000000 10203422032 10660528128 1134529760 12.164 99 30 60 1 1294680192 152793920 7356512
12:23:10 105934 8192000000 10100111760 10606252032 1104801456 11.883 97 13 26 2 1150486752 42850656 2834640
12:25:56 33793 8192000000 10114530864 10580135936 1140458272 12.220 100 41 82 1 1215683184 66650864 8574048
12:27:33 56988 8192000000 10111816912 10534457344 1095810256 11.798 96 19 37 3 1118591712 17609712 5171744
12:29:31 77038 8192000000 10175370192 10614607872 1140846848 12.223 99 22 44 1 1298316656 152454000 5015808
12:31:53 92761 8192000000 10135801488 10587815936 1136527552 12.183 99 13 26 0 1186863248 47987104 2348592
12:34:28 161634 8192000000 10168828816 10660720640 1142720832 12.241 99 18 36 0 1234037680 87496208 3820640
12:38:23 40208 8192000000 10134101024 10616352768 1131306944 12.134 98 19 38 0 1226789840 91566064 3916832
12:40:31 47560 8192000000 10056014768 10524991488 1115795664 11.987 97 6 12 0 1154678752 37840992 1042096
12:42:21 51981 8192000000 10092899280 10558500864 1125764736 12.081 98 19 38 0 1177318000 47819456 3733808
12:44:16 66617 8192000000 10052127536 10504347648 1084403056 11.689 95 15 30 0 1139733456 51738368 3592032
12:46:33 42365 8192000000 10137135680 10578444288 1136263376 12.180 99 28 54 2 1212101984 70012304 5826304
12:48:22 43217 8192000000 10153344064 10621460480 1127118512 12.094 99 29 58 0 1259347312 125437536 6791264
12:50:13 71911 8192000000 10106817904 10575917056 1109560400 11.928 97 17 34 0 1199021024 85938992 3521632
12:52:25 85663 8192000000 10059980976 10520887296 1071340992 11.565 94 17 34 1 1118830992 43674464 3815536
12:54:51 70861 8192000000 10052007632 10565361664 1072978096 11.581 96 19 38 1 1163466272 86712912 3775264
12:57:02 83645 8192000000 10051637952 10478612480 1112475168 11.956 99 24 48 1 1129558880 11887104 5196608
12:59:26 67480 8192000000 10115489568 10613907456 1121009600 12.037 99 12 23 0 1223005264 99734064 2261600
13:01:34 56656 8192000000 10070448832 10560647168 1097014416 11.809 97 7 14 0 1170194512 71302704 1877392
13:03:48 47409 8192000000 10049200688 10501054464 1091926832 11.761 97 10 20 0 1138459088 44483792 2048464
13:05:46 35226 8192000000 10053951952 10493816832 1084074512 11.686 96 12 24 2 1108087472 21569200 2443760
13:07:21 98730 8192000000 10068635952 10536677376 1079016816 11.638 96 13 26 0 1139394384 57838112 2539456
13:10:03 80902 8192000000 10062339184 10488377344 1093900576 11.780 97 18 36 0 1139460432 42172528 3387328
13:12:26 58460 8192000000 10068926944 10535280640 1075008192 11.600 96 33 66 0 1150570336 68920896 6641248
13:14:25 65139 8192000000 10068570752 10508840960 1076439472 11.614 96 27 54 0 1128389968 46719600 5230896
13:16:31 28037 8192000000 10077548816 10496761856 1116175792 11.991 99 48 96 0 1161978432 35714912 10087728
13:18:15 66564 8192000000 10082138528 10523566080 1118462048 12.012 99 43 86 0 1168949664 41253360 9234256
13:20:24 51921 8192000000 10054289744 10523820032 1118419056 12.012 99 7 14 0 1152458976 32766560 1273360
13:22:52 72854 8192000000 10074601136 10537885696 1110012416 11.933 98 18 36 1 1170186720 55596640 4577664
13:25:05 58280 8192000000 10093377040 10539053056 1117353984 12.002 98 24 48 2 1179355648 56896832 5104832
13:27:12 81104 8192000000 10129295328 10571010048 1109910800 11.932 97 25 47 1 1171621040 56425824 5284416
13:29:35 48551 8192000000 10118839104 10562281472 1101239904 11.849 97 36 72 2 1199686144 91150176 7296064
13:31:27 65737 8192000000 10070907616 10611421184 1105407328 11.889 98 20 39 3 1198801808 88542976 4851504
13:33:35 127804 8192000000 10068416656 10536984576 1114287920 11.973 99 22 44 0 1173421200 55026320 4106960
13:36:45 36251 8192000000 9389420480 9725640704 521971120 5.990 46 9 18 0 543255888 19523216 1761552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-11-2021 13:39:55 to 23-11-2021 13:40:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-11-2021 13:40:11 to 23-11-2021 13:40:13.

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          223762
MON$OLDEST_ACTIVE               223763
MON$OLDEST_SNAPSHOT             223763
MON$NEXT_TRANSACTION            223763
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                       2030112
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 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 ---
--- end of diff output ---

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

23.11.21 13:40:14. 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
2021-11-23 13:40:16 No severe PSQL-related problems occured
23.11.21 13:40:17. End of report.