Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.03.2022 07: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 16394508 kB
MemAvailable 31636248 kB
Buffers 0 kB
Cached 15347588 kB
SwapCached 2184 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-03-31 07:37:10
DTS_END                         2022-03-31 07:37:10.3640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(601:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58320
TRN_ID                          209654
ATT_ID                          575
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: 8719.86 30314 1046383
customer order: creation 154.26 492 18511
customer order: refuse 13.24 384 1589
order to supplier: creation 42.53 1377 5103
order to supplier: removal 3.19 182132 383
invoice (draft): creation 42.30 3680 5076
invoice (draft): removal 51.20 1012 6144
invoice accept: apply 37.98 27073 4557
invoice accept: cancel 46.13 1301 5536
customer reserve: creation 3546.55 253 425586
customer reserve: removal 4515.06 101 541807
realization accept: apply 75.49 310 9059
realization accept: cancel 74.48 172 8938
payment from customer: creation 52.93 191 6352
payment from customer: removal 5.00 198 600
payment to supplier: creation 44.73 212 5367
payment to supplier: removal 3.07 116 368
service: total inventory turnovers 3.73 4686 448
service: total monetary turnovers 7.14 400 857
service: aggregate perf. data 0.81 19473 97
service: refresh index statistics 0.04 362709 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-03-2022 07:40:18 to 31-03-2022 07:40:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8302
TEST_TIME 2 7792
TEST_TIME 3 6898
TEST_TIME 4 7053
TEST_TIME 5 7079
TEST_TIME 6 7375
TEST_TIME 7 7578
TEST_TIME 8 7886
TEST_TIME 9 7592
TEST_TIME 10 7295
TEST_TIME 11 7214
TEST_TIME 12 7242
TEST_TIME 13 7200
TEST_TIME 14 7316
TEST_TIME 15 7793
TEST_TIME 16 7841
TEST_TIME 17 7947
TEST_TIME 18 8167
TEST_TIME 19 8391
TEST_TIME 20 8401
TEST_TIME 21 8467
TEST_TIME 22 8478
TEST_TIME 23 8700
TEST_TIME 24 8698
TEST_TIME 25 8596
TEST_TIME 26 9391
TEST_TIME 27 9481
TEST_TIME 28 9334
TEST_TIME 29 9392
TEST_TIME 30 10102
TEST_TIME 31 10240
TEST_TIME 32 10446
TEST_TIME 33 10622
TEST_TIME 34 11225
TEST_TIME 35 11378
TEST_TIME 36 11066
TEST_TIME 37 10875
TEST_TIME 38 10866
TEST_TIME 39 10738
TEST_TIME 40 10598
TEST_TIME 41 10474
TEST_TIME 42 10340
TEST_TIME 43 10204
TEST_TIME 44 10163
TEST_TIME 45 10091
TEST_TIME 46 10170
TEST_TIME 47 10009
TEST_TIME 48 9879
TEST_TIME 49 9860
TEST_TIME 50 9699
TEST_TIME 51 9699
TEST_TIME 52 9620
TEST_TIME 53 9645
TEST_TIME 54 9553
TEST_TIME 55 9518
TEST_TIME 56 9407
TEST_TIME 57 9281
TEST_TIME 58 9253
TEST_TIME 59 9164
TEST_TIME 60 9208
TEST_TIME 61 9155
TEST_TIME 62 9117
TEST_TIME 63 9212
TEST_TIME 64 9163
TEST_TIME 65 9114
TEST_TIME 66 9130
TEST_TIME 67 9143
TEST_TIME 68 9171
TEST_TIME 69 9277
TEST_TIME 70 9357
TEST_TIME 71 9311
TEST_TIME 72 9260
TEST_TIME 73 9157
TEST_TIME 74 9165
TEST_TIME 75 9086
TEST_TIME 76 8996
TEST_TIME 77 8956
TEST_TIME 78 8983
TEST_TIME 79 8959
TEST_TIME 80 9066
TEST_TIME 81 8999
TEST_TIME 82 8982
TEST_TIME 83 8977
TEST_TIME 84 9055
TEST_TIME 85 9068
TEST_TIME 86 9054
TEST_TIME 87 9001
TEST_TIME 88 9044
TEST_TIME 89 9101
TEST_TIME 90 9056
TEST_TIME 91 8994
TEST_TIME 92 8929
TEST_TIME 93 9041
TEST_TIME 94 8975
TEST_TIME 95 8917
TEST_TIME 96 8890
TEST_TIME 97 8833
TEST_TIME 98 8803
TEST_TIME 99 8774
TEST_TIME 100 8754
TEST_TIME 101 8756
TEST_TIME 102 8775
TEST_TIME 103 8752
TEST_TIME 104 8740
TEST_TIME 105 8743
TEST_TIME 106 8771
TEST_TIME 107 8802
TEST_TIME 108 8777
TEST_TIME 109 8825
TEST_TIME 110 8816
TEST_TIME 111 8850
TEST_TIME 112 8994
TEST_TIME 113 8947
TEST_TIME 114 8983
TEST_TIME 115 8959
TEST_TIME 116 8909
TEST_TIME 117 8892
TEST_TIME 118 8847
TEST_TIME 119 8811
TEST_TIME 120 8750

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-03-2022 07:40:22 to 31-03-2022 07:40:25.

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 1437960 1437960 0 0.00 0 24506 14 0 0
doc_list_biud 1437945 1437945 0 0.00 0 49581 44 0 0
sp_add_invoice_to_stock 4667 4557 110 2.35 37 225300 27074 0 110
sp_cancel_adding_invoice 5536 5536 0 0.00 8 124227 1302 0 0
sp_cancel_client_order 1589 1589 0 0.00 6 11122 385 0 0
sp_cancel_customer_reserve 541807 541807 0 0.00 1 49584 101 0 0
sp_cancel_pay_from_customer 600 600 0 0.00 4 16665 199 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 4 5484 116 0 0
sp_cancel_supplier_invoice 6157 6144 13 0.21 3 32745 1013 0 13
sp_cancel_supplier_order 383 383 0 0.00 16 1435128 182132 0 0
sp_cancel_write_off 8938 8938 0 0.00 2 11807 172 0 0
sp_client_order 18511 18511 0 0.00 6 45431 493 0 0
sp_customer_reserve 425586 425586 0 0.00 1 71686 253 0 0
sp_fill_shopping_cart 28676 28676 0 0.00 2 16535 94 0 0
sp_fill_shopping_cart_clo_res 425551 425551 0 0.00 0 16244 20 0 0
sp_get_clo_for_invoice 4509 4509 0 0.00 0 35237 346 0 0
sp_kill_cost_storno 15442 15442 0 0.00 0 16656 76 0 0
sp_kill_qstorno_ret_qs2qd 1099079 1099079 0 0.00 0 34925 43 0 0
sp_kill_qty_storno 577960 577960 0 0.00 0 49580 101 0 0
sp_lock_selected_doc 18748 18748 0 0.00 0 7504 7 0 0
sp_make_cost_storno 25286 25286 0 0.00 0 24504 92 0 0
sp_multiply_rows_for_pdistr 25284 25284 0 0.00 0 8324 40 0 0
sp_multiply_rows_for_qdistr 18509 18509 0 0.00 0 45379 182 0 0
sp_pay_from_customer 6352 6352 0 0.00 6 24511 192 0 0
sp_pay_to_supplier 5367 5367 0 0.00 6 14587 212 0 0
sp_payment_common 11719 11719 0 0.00 2 8958 47 0 0
sp_qd_handle_on_cancel_clo 1589 1589 0 0.00 0 11089 311 0 0
sp_qd_handle_on_invoice_upd_sts 10042 10042 0 0.00 0 35239 581 0 0
sp_qd_handle_on_reserve_upd_sts 9057 9057 0 0.00 0 19649 72 0 0
sp_reserve_write_off 9059 9059 0 0.00 8 19798 310 0 0
sp_supplier_invoice 5076 5076 0 0.00 9 102426 3680 0 0
sp_supplier_order 5103 5103 0 0.00 6 55770 1378 0 0
srv_aggregate_perf_data 98 97 1 1.02 3010 146034 19474 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 716 125716 40874 0 0
srv_find_qd_qs_mism 1001632 1001632 0 0.00 0 15239 10 0 0
srv_make_invnt_saldo 448 448 0 0.00 65 147787 4686 0 0
srv_make_money_saldo 857 857 0 0.00 1 22548 400 0 0
srv_recalc_idx_stat 5 5 0 0.00 87882 483510 362709 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 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4562 4562 0 0.00 1 11303 1061 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 1 9034 556 0 0
t$perf-norm:sp_cancel_client_order 1589 1589 0 0.00 0 18003 44 0 0
t$perf-norm:sp_cancel_customer_reserve 573 573 0 0.00 0 911 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 600 600 0 0.00 0 6584 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 209 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 3445 48 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 1 117150 7713 0 0
t$perf-norm:sp_cancel_write_off 925 925 0 0.00 0 595 19 0 0
t$perf-norm:sp_client_order 18511 18511 0 0.00 0 8144 38 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 1290 14 0 0
t$perf-norm:sp_pay_from_customer 6352 6352 0 0.00 0 6510 22 0 0
t$perf-norm:sp_pay_to_supplier 5367 5367 0 0.00 0 6936 24 0 0
t$perf-norm:sp_reserve_write_off 9060 9060 0 0.00 0 8150 23 0 0
t$perf-norm:sp_supplier_invoice 5076 5076 0 0.00 0 7506 35 0 0
t$perf-norm:sp_supplier_order 5103 5103 0 0.00 0 7692 36 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 395 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 82 4 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 480 13 0 0
t$perf-norm:srv_make_money_saldo 857 857 0 0.00 0 432 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 210 69 0 0
v_add_invoice_to_stock 9216 8996 220 2.38 0 15339 34 0 220
v_all_customers 33964 33964 0 0.00 0 66 4 0 0
v_all_suppliers 28494 28494 0 0.00 0 69 4 0 0
v_all_wares 37013 37013 0 0.00 0 67 4 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 817 14 0 0
v_cancel_client_order 3178 3178 0 0.00 0 1928 10 0 0
v_cancel_customer_prepayment 1200 1200 0 0.00 0 3168 16 0 0
v_cancel_customer_reserve 1146 1146 0 0.00 0 14155 23 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 257 10 0 0
v_cancel_supplier_invoice 774 748 26 3.35 0 3772 35 0 26
v_cancel_supplier_order 766 766 0 0.00 0 13422 35 0 0
v_cancel_write_off 1850 1850 0 0.00 0 5811 25 0 0
v_max_id_clo_ord 5098 5098 0 0.00 0 2358 6 0 0
v_max_id_clo_res 932 932 0 0.00 0 1359 12 0 0
v_max_id_ord_sup 5069 5069 0 0.00 0 8036 11 0 0
v_max_non_paid_invoice 1286 1286 0 0.00 0 74 8 0 0
v_max_non_paid_realizn 3295 3295 0 0.00 0 818 7 0 0
v_min_id_clo_ord 5098 5098 0 0.00 0 4673 12 0 0
v_min_id_clo_res 932 932 0 0.00 0 2917 15 0 0
v_min_id_ord_sup 5069 5069 0 0.00 0 4097 13 0 0
v_min_non_paid_invoice 5367 5367 0 0.00 0 8948 17 0 0
v_min_non_paid_realizn 6352 6352 0 0.00 0 6845 16 0 0
v_our_firm 9160 9160 0 0.00 0 95 5 0 0
v_reserve_write_off 18112 18112 0 0.00 0 13877 14 0 0
x_lock_depdocs_on_canc_invoice 11306 11306 0 0.00 0 15298 130 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 15073 399 0 0
x_make_qstorno_1000_1200 5103 5103 0 0.00 2 50919 1184 0 0
x_make_qstorno_1200_2000 5076 5076 0 0.00 4 96565 3430 0 0
x_make_qstorno_2100_3300 425584 425584 0 0.00 0 71684 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-03-2022 07:40:26 to 31-03-2022 07:40:30.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:15 11115 8192000000 9715732992 10015776768 803899520 8.936 100 79 158 0 829470016 6944080 18626416
05:09:31 14513 8192000000 9755198112 10065408000 834965232 9.249 100 83 166 2 866103664 11478576 19659856
05:10:53 21816 8192000000 9800509280 10118627328 868800560 9.588 100 88 176 0 905899696 16906688 20192448
05:12:18 2342 8192000000 9854220720 10182184960 906389312 9.962 100 86 172 0 962067952 36550160 19128480
05:13:25 4897 8192000000 9917848992 10261180416 948859472 10.380 100 86 166 0 1026382320 60253216 17269632
05:14:34 2465 8192000000 9943196304 10295685120 991373744 10.795 100 89 172 1 1065422256 57119696 16928816
05:15:39 1484 8192000000 9990375776 10350444544 1020496496 11.077 100 96 185 2 1109721344 70455344 18769504
05:16:42 1887 8192000000 10016476800 10383093760 1039063968 11.256 100 93 185 1 1140496048 82698528 18733552
05:17:46 1144 8192000000 10036319328 10408144896 1057702272 11.434 100 97 180 2 1156167232 79798192 18666768
05:18:48 1114 8192000000 10043390560 10420432896 1070867440 11.560 100 94 181 0 1166441376 77008752 18565184
05:19:51 1118 8192000000 10042369648 10420596736 1072651632 11.577 100 85 163 1 1162774352 72857360 17265360
05:20:52 5199 8192000000 10041358784 10431225856 1079479680 11.643 100 77 145 3 1175950384 81696928 14773776
05:21:59 1120 8192000000 10067554688 10454458368 1086851648 11.713 100 90 175 0 1189311888 85459040 17001200
05:23:01 1032 8192000000 10059605440 10449166336 1092274240 11.764 100 86 167 2 1182629584 74237984 16117360
05:24:03 1227 8192000000 10077113344 10465402880 1097785680 11.817 100 98 190 0 1195798320 78266528 19746112
05:25:05 963 8192000000 10048935536 10440884224 1096446464 11.804 100 88 176 0 1171460352 56709136 18304752
05:26:07 1210 8192000000 10065821456 10454196224 1103794944 11.874 100 97 185 0 1189586848 66675712 19116192
05:27:09 1307 8192000000 10034173296 10423087104 1093464384 11.776 100 87 166 1 1154167552 43479408 17223760
05:28:11 1258 8192000000 10048875376 10436640768 1085609072 11.701 100 95 173 2 1163821728 60476640 17736016
05:29:13 1185 8192000000 10029097408 10415587328 1063038160 11.486 99 96 178 1 1139947792 58131728 18777904
05:30:15 4964 8192000000 10035735520 10421485568 1055856896 11.417 98 74 143 2 1152124416 81809664 14457856
05:31:21 2417 8192000000 10022573952 10420170752 1069057728 11.543 100 72 138 1 1148824000 63909712 15856560
05:32:24 12522 8192000000 9971012960 10347782144 1031362080 11.182 98 60 115 0 1098476432 54260432 12853920
05:33:38 7857 8192000000 10004409440 10385436672 1042684304 11.290 99 67 131 1 1110073696 54390480 12998912
05:34:47 1111 8192000000 10013448000 10396835840 1057558880 11.433 100 89 161 4 1138783472 64746160 16478432
05:35:49 1156 8192000000 10000398112 10374324224 1034577856 11.213 100 94 175 3 1130092912 74302912 21212144
05:36:51 1271 8192000000 10012166080 10387947520 1041954288 11.283 100 98 187 3 1130529280 69538592 19036400
05:37:58 3267 8192000000 10028879456 10404036608 1069581952 11.548 100 89 165 1 1147505808 63601600 14322256
05:39:04 714 8192000000 10052561456 10432339968 1072526176 11.576 99 90 171 0 1168707152 77950816 18230160
05:40:11 6478 8192000000 10080787792 10479157248 1094212080 11.783 100 56 110 0 1199197536 93378960 11606496
05:41:29 895 8192000000 10102596544 10492669952 1103558864 11.871 100 95 180 2 1217611328 95623344 18429120
05:42:36 3319 8192000000 10105464768 10508656640 1117618480 12.004 100 88 174 3 1236998288 99911424 19468384
05:43:43 1894 8192000000 10141358704 10536361984 1127790000 12.101 100 85 166 0 1256766016 111481056 17494960
05:44:47 1054 8192000000 10186741456 10603241472 1147441648 12.285 100 92 177 1 1300259472 134322992 18494832
05:45:52 2358 8192000000 10169500592 10571112448 1157300240 12.378 100 71 137 0 1284392112 112349520 14742352
05:46:56 1249 8192000000 10211529296 10614210560 1164754368 12.448 100 80 150 1 1320862256 140283776 15824112
05:48:03 1268 8192000000 10269274544 10666729472 1167370176 12.472 100 89 171 0 1379761792 194116400 18275216
05:49:08 2566 8192000000 10314895424 10709549056 1173759568 12.532 100 91 179 0 1423377008 228588160 21029280
05:50:14 1075 8192000000 10344044512 10745565184 1181970768 12.609 100 97 195 1 1454652496 250833728 21848000
05:51:17 1144 8192000000 10389401328 10797903872 1180101648 12.591 100 95 187 0 1498032320 295336176 22594496
05:52:22 1170 8192000000 10382359120 10809458688 1179299904 12.584 100 95 189 2 1494813904 292989392 22524608
05:53:25 1552 8192000000 10407236256 10825179136 1192382928 12.706 100 98 191 0 1512806048 297778992 22644128
05:54:29 1183 8192000000 10416534928 10833186816 1173528176 12.530 99 94 185 1 1519617872 324929104 21160592
05:55:32 844 8192000000 10446423008 10882629632 1189001968 12.674 100 98 189 0 1553496048 341026784 23467296
05:56:36 1128 8192000000 10467836544 10891776000 1197964032 12.757 100 94 185 0 1574434464 354258832 22211600
05:57:41 858 8192000000 10498054496 10923696128 1192759312 12.709 100 85 165 1 1603932288 390036368 21136608
05:58:50 710 8192000000 10537511616 10956726272 1199589088 12.773 100 96 189 0 1643128416 421679424 21859904
05:59:52 2127 8192000000 10519230352 10957647872 1184418016 12.631 100 91 173 3 1621977744 415428448 22131280
06:00:57 2338 8192000000 10531669632 10973696000 1194749312 12.728 100 64 128 1 1635494848 425013600 15731936
06:02:02 1180 8192000000 10577119392 11018461184 1190685120 12.690 100 88 175 1 1679489664 467729392 21075152
06:03:08 2572 8192000000 10607810992 11070361600 1194112544 12.722 100 75 142 1 1709150016 496724704 18312768
06:04:32 29297 8192000000 10504386896 10979409920 1179596624 12.586 99 34 68 0 1595429152 406864384 8968144
06:06:04 15731 8192000000 10487156688 10961661952 1161672480 12.419 100 44 86 1 1606817632 432866224 12278928
06:07:27 19070 8192000000 10383594544 10858614784 1148925712 12.299 98 38 76 2 1462114160 304054128 9134320
06:08:56 17787 8192000000 10359681552 10855923712 1149037680 12.300 99 23 46 1 1475191776 320575136 5578960
06:10:33 33878 8192000000 10240032912 10732838912 1131037168 12.131 99 20 40 0 1370063568 234179520 4846880
06:12:08 120952 8192000000 10097164768 10540748800 1106424352 11.899 97 27 54 1 1171455280 58490656 6540272
06:15:10 65762 8192000000 10096543728 10567147520 1136893488 12.186 100 33 64 0 1199763008 56472272 6397248
06:17:22 96576 8192000000 10106426800 10579480576 1143890400 12.252 100 17 33 1 1214304512 67241680 3172432
06:21:44 104586 8192000000 10100358736 10618728448 1145989440 12.272 99 21 42 0 1213229472 62969536 4270496
06:24:45 119232 8192000000 10125608784 10597183488 1115081232 11.980 97 33 64 0 1204105440 81307104 7717104
06:28:01 24029 8192000000 10087110448 10569191424 1132643872 12.146 99 32 62 1 1183421728 43568896 7208960
06:29:29 22218 8192000000 10092718176 10549547008 1148350800 12.294 100 33 67 0 1203405184 47233760 7820624
06:31:04 27495 8192000000 10064086240 10546581504 1124229968 12.067 98 16 30 1 1173559344 45596416 3732960
06:32:32 86485 8192000000 10053869744 10517848064 1114625712 11.976 98 15 30 0 1166683184 49144672 2912800
06:35:05 43257 8192000000 10058716464 10559746048 1099431008 11.832 98 14 28 1 1178210704 75966432 2813264
06:36:49 53229 8192000000 10054859776 10548047872 1111850288 11.950 99 22 44 1 1169258208 52722912 4685008
06:38:49 28129 8192000000 10101268016 10556489728 1131960352 12.140 99 44 88 1 1203208624 62172704 9075568
06:40:22 80079 8192000000 10072585248 10530217984 1097792192 11.817 97 24 48 0 1147547472 44831120 4924160
06:42:45 35736 8192000000 10121658272 10564526080 1115356736 11.983 98 34 68 1 1215136048 92062896 7716416
06:44:31 80873 8192000000 10169523552 10641215488 1113273216 11.963 97 30 59 0 1208186320 88950768 5962336
06:46:53 125716 8192000000 10076127536 10518052864 1074507008 11.595 94 10 20 1 1112248832 35373424 2368400
06:50:00 123354 8192000000 10077269760 10526121984 1092785120 11.769 96 38 74 1 1198194624 96754112 8655392
06:53:04 115298 8192000000 10198372192 10680156160 1134807824 12.167 99 23 45 0 1300957728 160514144 5635760
06:56:16 100242 8192000000 10063892720 10560225280 1112517168 11.956 98 11 22 0 1174053424 58895392 2640864
06:59:01 36490 8192000000 10152230608 10605166592 1152967904 12.337 100 50 96 2 1240213216 77226560 10018752
07:00:38 85431 8192000000 10100738160 10577678336 1110783936 11.940 97 13 26 1 1202837280 88736288 3317056
07:03:11 64446 8192000000 10107391600 10548355072 1117235120 12.001 98 26 52 0 1172849360 50442512 5171728
07:05:16 123509 8192000000 10027475888 10456006656 1017006224 11.043 89 17 33 1 1084242960 63982896 3253840
07:08:24 110360 8192000000 10102498384 10614493184 1120380800 12.031 100 31 62 0 1257786176 130727456 6677920
07:11:34 33002 8192000000 10025843264 10476154880 1089105952 11.734 97 10 21 0 1137186096 46297280 1782864
07:13:07 56129 8192000000 10060578816 10514415616 1125659632 12.080 100 12 24 0 1161217632 33117040 2440960
07:15:04 59494 8192000000 10063135360 10534518784 1133773088 12.157 100 16 33 2 1185843696 48737408 3333200
07:17:03 89017 8192000000 10098320752 10597478400 1136586240 12.183 99 11 22 1 1205975344 66811392 2577712
07:19:44 39326 8192000000 10104140608 10549313536 1125480512 12.079 98 20 40 1 1179137280 49499168 4157600
07:21:24 41273 8192000000 10133539856 10582216704 1129199104 12.114 99 24 48 0 1222539856 87890864 5449888
07:23:05 47271 8192000000 10128882304 10600431616 1118899280 12.017 98 33 65 0 1228875792 102677520 7298992
07:24:53 51519 8192000000 10160148848 10610909184 1141768768 12.232 99 22 44 1 1247541936 100574080 5199088
07:27:11 34935 8192000000 10157226400 10582405120 1118836112 12.016 98 45 87 1 1237668944 109140720 9692112
07:28:49 48599 8192000000 10135738368 10644455424 1118465184 12.012 99 31 61 2 1286588304 160954272 7168848
07:30:38 1863 8192000000 10109988496 10537242624 1132867328 12.148 100 72 119 4 1200950496 56409616 11673552
07:33:03 63192 8192000000 10060880400 10509762560 1104332320 11.879 97 32 64 1 1159402272 48746000 6323952
07:35:22 99579 8192000000 10069419248 10530254848 1101862752 11.855 97 12 24 0 1136874480 32489504 2522224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-03-2022 07:40:32 to 31-03-2022 07:40:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 110 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-03-2022 07:40:49 to 31-03-2022 07:40:51.

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          209905
MON$OLDEST_ACTIVE               209906
MON$OLDEST_SNAPSHOT             209906
MON$NEXT_TRANSACTION            209906
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                       2077600
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             757
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:266: 
:267: mscs-test-161.budzdorov.ru    Thu Mar 31 07:37:22 2022
:268:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:269: 
:270: 
:271: mscs-test-161.budzdorov.ru    Thu Mar 31 07:37:22 2022
:272:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:273: 
:274: 
:275: mscs-test-161.budzdorov.ru    Thu Mar 31 07:37:50 2022
:276:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:277: 
--- end of diff output ---

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

31.03.22 07:40:52. 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-03-31 07:40:54 No severe PSQL-related problems occured
31.03.22 07:40:56. End of report.