Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.04.2022 10:37
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 16276576 kB
MemAvailable 31492492 kB
Buffers 0 kB
Cached 15389156 kB
SwapCached 2504 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-04-25 10:37:15
DTS_END                         2022-04-25 10:37:15.4530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34212
TRN_ID                          218451
ATT_ID                          648
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: 9297.55 21433 1115706
customer order: creation 172.08 448 20650
customer order: refuse 14.66 365 1759
order to supplier: creation 44.77 1339 5372
order to supplier: removal 3.50 168722 420
invoice (draft): creation 45.77 2916 5492
invoice (draft): removal 53.27 991 6392
invoice accept: apply 40.99 27077 4919
invoice accept: cancel 48.43 1145 5811
customer reserve: creation 3878.98 255 465477
customer reserve: removal 4686.13 97 562336
realization accept: apply 84.06 267 10087
realization accept: cancel 96.07 150 11528
payment from customer: creation 59.19 187 7103
payment from customer: removal 5.43 128 652
payment to supplier: creation 48.06 192 5767
payment to supplier: removal 3.54 88 425
service: total inventory turnovers 4.01 4024 481
service: total monetary turnovers 7.67 350 920
service: aggregate perf. data 0.92 15467 110
service: refresh index statistics 0.04 204450 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-04-2022 10:38:01 to 25-04-2022 10:38:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7168
TEST_TIME 2 5545
TEST_TIME 3 5893
TEST_TIME 4 6072
TEST_TIME 5 5947
TEST_TIME 6 5776
TEST_TIME 7 5934
TEST_TIME 8 6171
TEST_TIME 9 6503
TEST_TIME 10 6547
TEST_TIME 11 6987
TEST_TIME 12 6976
TEST_TIME 13 7124
TEST_TIME 14 7178
TEST_TIME 15 7127
TEST_TIME 16 7200
TEST_TIME 17 7234
TEST_TIME 18 7171
TEST_TIME 19 7301
TEST_TIME 20 7211
TEST_TIME 21 7291
TEST_TIME 22 7767
TEST_TIME 23 8174
TEST_TIME 24 8369
TEST_TIME 25 8481
TEST_TIME 26 8512
TEST_TIME 27 8973
TEST_TIME 28 9569
TEST_TIME 29 9666
TEST_TIME 30 10009
TEST_TIME 31 10727
TEST_TIME 32 10938
TEST_TIME 33 11480
TEST_TIME 34 11355
TEST_TIME 35 11286
TEST_TIME 36 11101
TEST_TIME 37 10946
TEST_TIME 38 10910
TEST_TIME 39 10940
TEST_TIME 40 11153
TEST_TIME 41 11124
TEST_TIME 42 10960
TEST_TIME 43 10797
TEST_TIME 44 10573
TEST_TIME 45 10479
TEST_TIME 46 10319
TEST_TIME 47 10417
TEST_TIME 48 10521
TEST_TIME 49 10374
TEST_TIME 50 10425
TEST_TIME 51 10339
TEST_TIME 52 10451
TEST_TIME 53 10388
TEST_TIME 54 10329
TEST_TIME 55 10233
TEST_TIME 56 10276
TEST_TIME 57 10261
TEST_TIME 58 10251
TEST_TIME 59 10229
TEST_TIME 60 10212
TEST_TIME 61 10187
TEST_TIME 62 10071
TEST_TIME 63 10091
TEST_TIME 64 9982
TEST_TIME 65 9925
TEST_TIME 66 9946
TEST_TIME 67 9943
TEST_TIME 68 9876
TEST_TIME 69 9842
TEST_TIME 70 9890
TEST_TIME 71 9897
TEST_TIME 72 9943
TEST_TIME 73 9962
TEST_TIME 74 9960
TEST_TIME 75 9954
TEST_TIME 76 9945
TEST_TIME 77 9927
TEST_TIME 78 9915
TEST_TIME 79 9866
TEST_TIME 80 9808
TEST_TIME 81 9718
TEST_TIME 82 9669
TEST_TIME 83 9647
TEST_TIME 84 9627
TEST_TIME 85 9551
TEST_TIME 86 9499
TEST_TIME 87 9486
TEST_TIME 88 9581
TEST_TIME 89 9559
TEST_TIME 90 9576
TEST_TIME 91 9543
TEST_TIME 92 9542
TEST_TIME 93 9495
TEST_TIME 94 9552
TEST_TIME 95 9613
TEST_TIME 96 9572
TEST_TIME 97 9664
TEST_TIME 98 9630
TEST_TIME 99 9647
TEST_TIME 100 9640
TEST_TIME 101 9661
TEST_TIME 102 9574
TEST_TIME 103 9518
TEST_TIME 104 9511
TEST_TIME 105 9528
TEST_TIME 106 9475
TEST_TIME 107 9461
TEST_TIME 108 9408
TEST_TIME 109 9384
TEST_TIME 110 9346
TEST_TIME 111 9328
TEST_TIME 112 9359
TEST_TIME 113 9326
TEST_TIME 114 9362
TEST_TIME 115 9358
TEST_TIME 116 9375
TEST_TIME 117 9412
TEST_TIME 118 9402
TEST_TIME 119 9381
TEST_TIME 120 9325

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-04-2022 10:38:05 to 25-04-2022 10:38:08.

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 1544917 1544917 0 0.00 0 16007 14 0 0
doc_list_biud 1544904 1544904 0 0.00 0 66383 41 0 0
sp_add_invoice_to_stock 5013 4919 94 1.87 70 209612 27078 0 94
sp_cancel_adding_invoice 5811 5811 0 0.00 5 89165 1145 0 0
sp_cancel_client_order 1759 1759 0 0.00 6 8505 365 0 0
sp_cancel_customer_reserve 562336 562336 0 0.00 1 66387 98 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 4 6573 128 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 4 2322 88 0 0
sp_cancel_supplier_invoice 6396 6392 4 0.06 2 41955 991 0 4
sp_cancel_supplier_order 420 420 0 0.00 12 1740013 168722 0 0
sp_cancel_write_off 11528 11528 0 0.00 2 20019 150 0 0
sp_client_order 20650 20650 0 0.00 6 30197 449 0 0
sp_customer_reserve 465477 465477 0 0.00 1 76423 256 0 0
sp_fill_shopping_cart 31498 31498 0 0.00 1 16681 77 0 0
sp_fill_shopping_cart_clo_res 465450 465450 0 0.00 0 13423 17 0 0
sp_get_clo_for_invoice 4881 4881 0 0.00 0 33391 273 0 0
sp_kill_cost_storno 18416 18416 0 0.00 0 15035 70 0 0
sp_kill_qstorno_ret_qs2qd 1143012 1143012 0 0.00 0 66378 42 0 0
sp_kill_qty_storno 603211 603211 0 0.00 0 66383 96 0 0
sp_lock_selected_doc 20771 20771 0 0.00 0 8751 8 0 0
sp_make_cost_storno 27837 27837 0 0.00 0 15613 83 0 0
sp_multiply_rows_for_pdistr 27835 27835 0 0.00 0 6117 35 0 0
sp_multiply_rows_for_qdistr 20648 20648 0 0.00 0 22117 162 0 0
sp_pay_from_customer 7103 7103 0 0.00 5 25938 187 0 0
sp_pay_to_supplier 5767 5767 0 0.00 7 25427 193 0 0
sp_payment_common 12868 12868 0 0.00 2 6834 46 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 4383 289 0 0
sp_qd_handle_on_invoice_upd_sts 10691 10691 0 0.00 0 27973 496 0 0
sp_qd_handle_on_reserve_upd_sts 10085 10085 0 0.00 0 13075 54 0 0
sp_reserve_write_off 10087 10087 0 0.00 9 30753 268 0 0
sp_supplier_invoice 5492 5492 0 0.00 9 83452 2917 0 0
sp_supplier_order 5372 5372 0 0.00 9 30554 1340 0 0
srv_aggregate_perf_data 110 110 0 0.00 2659 146913 15467 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 802 165124 36788 0 0
srv_find_qd_qs_mism 1067102 1067102 0 0.00 0 11359 11 0 0
srv_make_invnt_saldo 481 481 0 0.00 63 105369 4025 0 0
srv_make_money_saldo 920 920 0 0.00 1 14463 351 0 0
srv_recalc_idx_stat 5 5 0 0.00 86408 385478 204450 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4924 4924 0 0.00 1 10164 892 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 1 7651 394 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 943 21 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 964 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 1894 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 3446 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 3419 45 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 89022 6967 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 1763 16 0 0
t$perf-norm:sp_client_order 20650 20650 0 0.00 0 10386 34 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 5106 18 0 0
t$perf-norm:sp_pay_from_customer 7103 7103 0 0.00 0 7056 19 0 0
t$perf-norm:sp_pay_to_supplier 5767 5767 0 0.00 0 6336 18 0 0
t$perf-norm:sp_reserve_write_off 10087 10087 0 0.00 0 8191 22 0 0
t$perf-norm:sp_supplier_invoice 5492 5492 0 0.00 0 8702 32 0 0
t$perf-norm:sp_supplier_order 5372 5372 0 0.00 0 5267 31 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 204 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 321 8 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 936 14 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 5872 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 9 635 245 0 0
v_add_invoice_to_stock 9942 9754 188 1.89 0 26130 29 0 188
v_all_customers 38050 38050 0 0.00 0 124 3 0 0
v_all_suppliers 30680 30680 0 0.00 0 65 4 0 0
v_all_wares 41283 41283 0 0.00 0 54 4 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 22391 105 0 0
v_cancel_client_order 3518 3518 0 0.00 0 1797 9 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 6525 18 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 1887 10 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 1088 9 0 0
v_cancel_supplier_invoice 810 802 8 0.98 0 2364 23 0 8
v_cancel_supplier_order 840 840 0 0.00 0 3035 15 0 0
v_cancel_write_off 2062 2062 0 0.00 0 19773 29 0 0
v_max_id_clo_ord 5365 5365 0 0.00 0 425 5 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 2564 14 0 0
v_max_id_ord_sup 5486 5486 0 0.00 0 5746 9 0 0
v_max_non_paid_invoice 1276 1276 0 0.00 0 49 7 0 0
v_max_non_paid_realizn 3556 3556 0 0.00 0 1738 7 0 0
v_min_id_clo_ord 5365 5365 0 0.00 0 2246 8 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 570 7 0 0
v_min_id_ord_sup 5486 5486 0 0.00 0 6394 12 0 0
v_min_non_paid_invoice 5765 5765 0 0.00 0 6805 18 0 0
v_min_non_paid_realizn 7103 7103 0 0.00 0 5689 20 0 0
v_our_firm 10326 10326 0 0.00 0 60 4 0 0
v_reserve_write_off 20170 20170 0 0.00 0 30528 13 0 0
x_lock_depdocs_on_canc_invoice 11802 11802 0 0.00 0 14120 129 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 16129 400 0 0
x_make_qstorno_1000_1200 5371 5371 0 0.00 3 30526 1182 0 0
x_make_qstorno_1200_2000 5492 5492 0 0.00 4 78189 2710 0 0
x_make_qstorno_2100_3300 465477 465477 0 0.00 0 76422 227 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 25-04-2022 10:38:09 to 25-04-2022 10:38:14.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:19 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:20 15929 8192000000 9717399792 10015019008 798800512 8.884 100 93 186 0 829578288 8198800 22578976
08:09:41 19184 8192000000 9761965856 10071576576 835676224 9.256 100 87 174 0 868068752 11492352 20900176
08:11:12 13727 8192000000 9805883696 10124681216 873905648 9.639 100 88 176 0 913283024 19275328 20102048
08:12:31 14334 8192000000 9868130480 10205462528 923598656 10.132 100 84 164 2 975367200 34384832 17383712
08:13:54 6696 8192000000 9934309600 10280415232 952175680 10.412 100 83 164 1 1042876272 72685024 18015568
08:15:07 3852 8192000000 9957076736 10309029888 989571600 10.777 100 82 163 0 1082162064 75925584 16664880
08:16:14 1197 8192000000 9997072656 10356981760 1014543696 11.019 100 93 185 1 1117904112 84285408 19075008
08:17:18 1150 8192000000 10030464928 10398920704 1035039024 11.217 100 95 183 1 1145213968 91836112 18338832
08:18:21 1006 8192000000 10022288480 10397200384 1053995600 11.399 100 98 187 2 1142693616 69884848 18813168
08:19:23 1091 8192000000 10037753696 10414022656 1063332080 11.488 100 95 182 1 1158830384 77755360 17742944
08:20:26 1477 8192000000 10045024352 10422009856 1068903872 11.542 100 91 180 3 1164095104 77232480 17958752
08:21:29 1165 8192000000 10028926960 10413817856 1069407440 11.546 100 90 176 2 1149567680 60877696 19282544
08:22:31 1089 8192000000 10032404432 10413457408 1077316000 11.622 100 93 186 1 1151748288 55829984 18602304
08:23:33 3013 8192000000 10023921216 10410430464 1080618832 11.653 100 77 142 0 1142290816 47462080 14209904
08:24:37 3887 8192000000 10054139040 10449772544 1086167056 11.706 100 68 129 0 1173443616 75409984 11866576
08:25:44 1019 8192000000 10053555072 10443665408 1093434064 11.775 100 86 170 2 1175488784 64384800 17669920
08:26:46 1037 8192000000 10054984048 10446225408 1086650720 11.711 99 94 180 0 1179299360 72954256 19694384
08:27:49 1101 8192000000 10044367632 10433449984 1093423344 11.775 100 96 189 0 1165836272 52729824 19683104
08:28:50 1654 8192000000 10030465488 10418909184 1075422544 11.604 99 83 158 1 1149517168 57063824 17030800
08:29:53 1204 8192000000 10068677360 10450997248 1077437648 11.623 100 96 188 1 1188066928 92186592 18442688
08:30:55 4218 8192000000 10032561376 10420920320 1069504592 11.547 99 68 134 0 1152607616 69412016 13691008
08:32:00 1821 8192000000 10006008528 10389884928 1064210240 11.497 100 72 141 0 1128210528 49931952 14068336
08:33:02 892 8192000000 9991861504 10369904640 1054066944 11.400 100 92 165 2 1115297552 43637808 17592800
08:34:04 1532 8192000000 9943803488 10309443584 1011526480 10.990 98 89 165 2 1069349408 40294000 17528928
08:35:06 4376 8192000000 9979739440 10361057280 1028655184 11.155 99 55 109 1 1091509440 51709120 11145136
08:36:13 988 8192000000 10001518480 10378661888 1047449440 11.336 100 87 170 3 1129024816 63961520 17613856
08:37:15 4394 8192000000 10019833552 10404507648 1053029280 11.390 100 73 136 2 1137619552 71117392 13472880
08:38:26 5584 8192000000 10036056352 10418487296 1074746288 11.597 100 94 184 1 1161796032 67892880 19156864
08:39:36 5121 8192000000 10052206656 10435297280 1082283504 11.669 100 77 142 1 1171384960 75876416 13225040
08:40:45 21065 8192000000 10067974144 10462285824 1093552944 11.776 100 69 133 2 1177736512 70691920 13491648
08:42:10 16400 8192000000 10098956272 10494152704 1101486656 11.852 99 62 117 3 1211970512 97461376 13022480
08:43:30 1006 8192000000 10137653664 10523680768 1119080672 12.018 100 96 180 1 1259733168 119809536 20842960
08:44:35 1857 8192000000 10211359744 10599567360 1133793008 12.157 100 94 188 1 1332135904 178823024 19519872
08:45:40 2450 8192000000 10204372720 10603520000 1140737936 12.222 100 73 141 1 1318688240 162834464 15115840
08:46:49 1094 8192000000 10228197504 10623541248 1150667648 12.316 100 79 154 0 1339880480 172541456 16671376
08:47:53 1443 8192000000 10286088400 10687692800 1164576112 12.446 100 86 162 1 1393061952 210550048 17935792
08:48:57 1062 8192000000 10301639168 10701611008 1172399808 12.519 100 94 178 1 1415496208 222412320 20684080
08:50:01 1745 8192000000 10356204736 10753261568 1179384400 12.584 100 93 180 0 1462697472 264039488 19273584
08:51:05 1195 8192000000 10382293584 10789462016 1191297552 12.695 100 96 187 0 1490433584 277199392 21936640
08:52:09 1431 8192000000 10433915824 10845478912 1205586160 12.828 100 92 182 2 1544308656 316583296 22139200
08:53:16 794 8192000000 10474085152 10890084352 1212492528 12.892 100 94 185 3 1582885936 348247392 22146016
08:54:21 787 8192000000 10521606368 10931748864 1219227216 12.955 100 95 187 0 1628267808 387039424 22001168
08:55:24 1035 8192000000 10551171632 10959245312 1204228208 12.816 100 97 192 0 1655888112 428537616 23122288
08:56:30 1321 8192000000 10610972336 11035299840 1211137872 12.880 100 96 186 0 1714318288 479861536 23318880
08:57:35 1103 8192000000 10656350800 11068674048 1212795744 12.895 100 95 181 1 1759798768 523936368 23066656
08:58:39 934 8192000000 10631534432 11102539776 1215505360 12.920 100 86 171 0 1737489792 500430208 21554224
08:59:45 2139 8192000000 10653868880 11124289536 1205173760 12.824 100 75 144 1 1761940448 537576032 19190656
09:00:53 7210 8192000000 10608655424 11071496192 1208340864 12.854 100 56 111 0 1727189008 504057456 14790688
09:02:10 4756 8192000000 10605040272 11068276736 1199376864 12.771 100 60 118 0 1711660720 497538480 14745376
09:03:17 7488 8192000000 10637895984 11083907072 1188835040 12.673 100 53 106 1 1737519456 534675328 14009088
09:04:26 14973 8192000000 10547651936 11021819904 1169267488 12.490 99 52 104 1 1654242032 471232336 13742208
09:05:51 25614 8192000000 10473879696 10938585088 1153017568 12.338 99 40 78 0 1560866064 397068864 10779632
09:07:25 34939 8192000000 10331823424 10876076032 1145038272 12.263 100 26 52 0 1511659328 359876304 6744752
09:09:05 35763 8192000000 10228052384 10754441216 1119401920 12.021 99 28 56 1 1363398560 237613888 6382752
09:10:47 108883 8192000000 10073410496 10534985728 1084802336 11.693 95 16 30 1 1124158992 36206944 3149712
09:13:38 23679 8192000000 10110893968 10536312832 1136969888 12.187 100 58 116 2 1201304944 52263968 12071088
09:15:03 10177 8192000000 10140180320 10561957888 1145848032 12.271 100 50 97 1 1235622096 78944528 10829536
09:16:17 71760 8192000000 10114065232 10622140416 1111915024 11.951 97 12 24 1 1177590960 63157488 2518448
09:18:30 86019 8192000000 10041878528 10472816640 1061576528 11.472 94 30 60 1 1093507808 24821344 7109936
09:20:59 95254 8192000000 10155205904 10592423936 1121446032 12.041 99 22 44 0 1212238048 85378384 5413632
09:24:09 165124 8192000000 10135227120 10578903040 1087183776 11.716 95 25 53 0 1246714880 153645184 5885920
09:27:55 32202 8192000000 10145869776 10578059264 1129792912 12.119 100 37 74 1 1230094384 92446240 7855232
09:29:30 12686 8192000000 10145590192 10597892096 1140943296 12.224 100 59 116 3 1238898656 85146112 12809248
09:30:55 38286 8192000000 10174434288 10617511936 1145217872 12.265 99 64 119 4 1258498320 99444416 13836032
09:32:33 68910 8192000000 10180391824 10668130304 1112298736 11.954 96 22 44 1 1260968496 143276912 5392848
09:34:55 123196 8192000000 10113614320 10598014976 1126202112 12.086 99 17 30 1 1236265536 105962368 4101056
09:38:14 43650 8192000000 10085795296 10558181376 1114349776 11.974 98 13 26 0 1181786688 64857136 2579776
09:40:25 74146 8192000000 10042560896 10487111680 1128136976 12.104 99 14 28 0 1176779120 45549824 3092320
09:42:39 97604 8192000000 10069477264 10520420352 1108511952 11.918 96 12 23 0 1167059344 56415968 2131424
09:45:18 9830 8192000000 10096854464 10534121472 1155879440 12.365 100 55 97 1 1194345328 25688864 12777024
09:46:38 25715 8192000000 10173735392 10632228864 1162715040 12.429 100 48 96 2 1269877392 97476112 9686240
09:48:14 26872 8192000000 10150522112 10591731712 1154963136 12.356 100 45 88 0 1232238928 67218016 10057776
09:49:44 106466 8192000000 10083635280 10532483072 1100322512 11.841 95 24 48 0 1158327408 53234272 4770624
09:52:34 44209 8192000000 10125393312 10583461888 1121691968 12.043 98 31 62 0 1205016736 76940688 6384080
09:54:25 35555 8192000000 10086887504 10510884864 1143587712 12.249 100 43 86 0 1186319680 33064432 9667536
09:56:01 101147 8192000000 10084989296 10546593792 1147658336 12.288 100 26 52 0 1217009472 63797024 5554112
09:58:42 33304 8192000000 10094864576 10538237952 1145581152 12.268 100 30 60 0 1191725968 39851392 6293424
10:00:17 72359 8192000000 10083522032 10570903552 1116323008 11.992 98 21 42 0 1198244816 77360816 4560992
10:02:30 73118 8192000000 10131943408 10585714688 1127531104 12.098 99 16 32 0 1225018384 93764496 3722784
10:05:03 81836 8192000000 10058471984 10542403584 1136364416 12.181 100 10 19 1 1258688432 119775152 2548864
10:07:28 39462 8192000000 10072933600 10512785408 1101633552 11.853 97 16 32 0 1144012128 39545184 2833392
10:09:32 18369 8192000000 10100410624 10540892160 1130058528 12.122 99 34 68 1 1183958320 47230896 6668896
10:10:54 46235 8192000000 10116414288 10582794240 1114591056 11.976 98 40 80 0 1212599552 89511776 8496720
10:12:45 22977 8192000000 10076073552 10520543232 1123290480 12.058 99 25 50 0 1165260176 36836976 5132720
10:14:42 35460 8192000000 10060452288 10499670016 1068177696 11.535 95 15 30 0 1127406112 56212704 3015712
10:16:19 26029 8192000000 10069002864 10503258112 1117909696 12.007 99 37 74 1 1174365232 49082816 7372720
10:17:49 31009 8192000000 10151594912 10612248576 1131172896 12.132 99 35 70 0 1202044112 63865984 7005232
10:19:22 95441 8192000000 10122108944 10578554880 1140820224 12.223 100 29 56 2 1230136832 83344032 5972576
10:21:58 69415 8192000000 10060060912 10552397824 1121707392 12.043 99 8 16 0 1156048272 32782336 1558544
10:24:13 47458 8192000000 10049388368 10542534656 1115921344 11.988 99 5 10 0 1162646160 45685184 1039632
10:26:06 47530 8192000000 10074098544 10523893760 1110050688 11.933 98 12 24 0 1159009408 46605392 2353328
10:27:54 42174 8192000000 10107752560 10569560064 1138733584 12.204 100 19 38 0 1214949136 71967344 4248208
10:29:40 32462 8192000000 10073027536 10505146368 1089264592 11.736 96 35 68 2 1144683344 48130256 7288496
10:31:14 52114 8192000000 10103803056 10564952064 1123757824 12.062 100 13 26 0 1195698480 69202528 2738128
10:33:06 33314 8192000000 10121040160 10547937280 1127091168 12.094 100 39 79 0 1196177456 60283328 8802960
10:34:41 71125 8192000000 10075385088 10509275136 1080502480 11.652 95 35 70 1 1153487392 66132336 6852576
10:36:53 34383 8192000000 9165765792 9448603648 298074592 3.510 27 8 10 3 322968272 23337952 1555728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 25-04-2022 10:38:15 to 25-04-2022 10:38:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-04-2022 10:38:32 to 25-04-2022 10:38:34.

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          221781
MON$OLDEST_ACTIVE               221782
MON$OLDEST_SNAPSHOT             221782
MON$NEXT_TRANSACTION            221782
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                       2065888
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.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:623: 
:624: mscs-test-161.budzdorov.ru    Mon Apr 25 10:37:32 2022
:625:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:626: 
:627: 
:628: mscs-test-161.budzdorov.ru    Mon Apr 25 10:37:36 2022
:629:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:630: 
:631: 
:632: mscs-test-161.budzdorov.ru    Mon Apr 25 10:37:36 2022
:633:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:634: 
:635: 
:636: mscs-test-161.budzdorov.ru    Mon Apr 25 10:37:36 2022
:637:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:638: 
--- end of diff output ---

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

25.04.22 10:38:35. 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-04-25 10:38:38 No severe PSQL-related problems occured
25.04.22 10:38:39. End of report.