Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.11.2021 10: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 16663844 kB
MemAvailable 31643364 kB
Buffers 0 kB
Cached 15059324 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-18 10:37:08
DTS_END                         2021-11-18 10:37:08.3980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(195:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47798
TRN_ID                          238480
ATT_ID                          707
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: 8948.48 22327 1073817
customer order: creation 173.18 387 20781
customer order: refuse 15.13 377 1815
order to supplier: creation 46.10 1436 5532
order to supplier: removal 3.33 102780 400
invoice (draft): creation 46.38 3617 5565
invoice (draft): removal 49.86 925 5983
invoice accept: apply 42.07 17572 5048
invoice accept: cancel 44.98 846 5398
customer reserve: creation 3837.67 175 460520
customer reserve: removal 4400.42 60 528050
realization accept: apply 83.02 191 9962
realization accept: cancel 77.31 124 9277
payment from customer: creation 60.01 120 7201
payment from customer: removal 5.37 106 644
payment to supplier: creation 49.22 146 5906
payment to supplier: removal 3.35 64 402
service: total inventory turnovers 3.96 3513 475
service: total monetary turnovers 6.28 492 753
service: aggregate perf. data 0.84 17218 101
service: refresh index statistics 0.03 296385 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-11-2021 10:39:23 to 18-11-2021 10:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2884
TEST_TIME 2 3087
TEST_TIME 3 3459
TEST_TIME 4 3983
TEST_TIME 5 3902
TEST_TIME 6 4484
TEST_TIME 7 4838
TEST_TIME 8 5377
TEST_TIME 9 5544
TEST_TIME 10 5949
TEST_TIME 11 6370
TEST_TIME 12 6922
TEST_TIME 13 6972
TEST_TIME 14 6963
TEST_TIME 15 7190
TEST_TIME 16 7721
TEST_TIME 17 7943
TEST_TIME 18 8004
TEST_TIME 19 8258
TEST_TIME 20 8531
TEST_TIME 21 9016
TEST_TIME 22 9030
TEST_TIME 23 9198
TEST_TIME 24 9340
TEST_TIME 25 9767
TEST_TIME 26 10387
TEST_TIME 27 10357
TEST_TIME 28 10426
TEST_TIME 29 10370
TEST_TIME 30 10295
TEST_TIME 31 10077
TEST_TIME 32 10101
TEST_TIME 33 9973
TEST_TIME 34 9855
TEST_TIME 35 9720
TEST_TIME 36 9734
TEST_TIME 37 10022
TEST_TIME 38 10022
TEST_TIME 39 10037
TEST_TIME 40 9900
TEST_TIME 41 10016
TEST_TIME 42 10070
TEST_TIME 43 10125
TEST_TIME 44 9996
TEST_TIME 45 9782
TEST_TIME 46 9716
TEST_TIME 47 9643
TEST_TIME 48 9605
TEST_TIME 49 9510
TEST_TIME 50 9582
TEST_TIME 51 9653
TEST_TIME 52 9582
TEST_TIME 53 9466
TEST_TIME 54 9395
TEST_TIME 55 9361
TEST_TIME 56 9430
TEST_TIME 57 9357
TEST_TIME 58 9350
TEST_TIME 59 9263
TEST_TIME 60 9294
TEST_TIME 61 9346
TEST_TIME 62 9332
TEST_TIME 63 9346
TEST_TIME 64 9377
TEST_TIME 65 9346
TEST_TIME 66 9391
TEST_TIME 67 9347
TEST_TIME 68 9472
TEST_TIME 69 9534
TEST_TIME 70 9574
TEST_TIME 71 9529
TEST_TIME 72 9432
TEST_TIME 73 9348
TEST_TIME 74 9345
TEST_TIME 75 9321
TEST_TIME 76 9268
TEST_TIME 77 9197
TEST_TIME 78 9145
TEST_TIME 79 9141
TEST_TIME 80 9112
TEST_TIME 81 9115
TEST_TIME 82 9097
TEST_TIME 83 9081
TEST_TIME 84 9048
TEST_TIME 85 9092
TEST_TIME 86 9064
TEST_TIME 87 9136
TEST_TIME 88 9168
TEST_TIME 89 9302
TEST_TIME 90 9272
TEST_TIME 91 9267
TEST_TIME 92 9247
TEST_TIME 93 9231
TEST_TIME 94 9182
TEST_TIME 95 9149
TEST_TIME 96 9139
TEST_TIME 97 9078
TEST_TIME 98 9012
TEST_TIME 99 9016
TEST_TIME 100 9064
TEST_TIME 101 9053
TEST_TIME 102 9070
TEST_TIME 103 9127
TEST_TIME 104 9087
TEST_TIME 105 9116
TEST_TIME 106 9200
TEST_TIME 107 9216
TEST_TIME 108 9185
TEST_TIME 109 9188
TEST_TIME 110 9223
TEST_TIME 111 9263
TEST_TIME 112 9281
TEST_TIME 113 9215
TEST_TIME 114 9152
TEST_TIME 115 9151
TEST_TIME 116 9124
TEST_TIME 117 9072
TEST_TIME 118 9058
TEST_TIME 119 9005
TEST_TIME 120 8951

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-11-2021 10:39:26 to 18-11-2021 10:39:30.

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 1500753 1500753 0 0.00 0 26803 7 0 0
doc_list_biud 1500715 1500715 0 0.00 0 28818 28 0 0
sp_add_invoice_to_stock 5162 5048 114 2.20 24 204618 17573 0 114
sp_cancel_adding_invoice 5398 5398 0 0.00 4 93722 847 0 0
sp_cancel_client_order 1815 1815 0 0.00 4 20121 377 0 0
sp_cancel_customer_reserve 528050 528050 0 0.00 1 28825 61 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 3 6029 106 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 2530 65 0 0
sp_cancel_supplier_invoice 5989 5983 6 0.10 2 20618 925 0 6
sp_cancel_supplier_order 400 400 0 0.00 10 1175214 102780 0 0
sp_cancel_write_off 9277 9277 0 0.00 1 20774 125 0 0
sp_client_order 20781 20781 0 0.00 4 21830 387 0 0
sp_customer_reserve 460520 460520 0 0.00 0 130008 175 0 0
sp_fill_shopping_cart 31869 31869 0 0.00 1 25130 47 0 0
sp_fill_shopping_cart_clo_res 460518 460518 0 0.00 0 27767 14 0 0
sp_get_clo_for_invoice 5046 5046 0 0.00 0 21542 329 0 0
sp_kill_cost_storno 15721 15721 0 0.00 0 6228 58 0 0
sp_kill_qstorno_ret_qs2qd 1071760 1071760 0 0.00 0 28775 33 0 0
sp_kill_qty_storno 565911 565911 0 0.00 0 28812 72 0 0
sp_lock_selected_doc 20682 20682 0 0.00 0 9643 6 0 0
sp_make_cost_storno 28115 28115 0 0.00 0 14049 57 0 0
sp_multiply_rows_for_pdistr 28078 28078 0 0.00 0 16877 32 0 0
sp_multiply_rows_for_qdistr 20781 20781 0 0.00 0 12953 184 0 0
sp_pay_from_customer 7201 7201 0 0.00 4 29676 121 0 0
sp_pay_to_supplier 5906 5906 0 0.00 5 18388 147 0 0
sp_payment_common 13090 13090 0 0.00 1 29663 25 0 0
sp_qd_handle_on_cancel_clo 1815 1815 0 0.00 0 20114 336 0 0
sp_qd_handle_on_invoice_upd_sts 10435 10435 0 0.00 0 16892 350 0 0
sp_qd_handle_on_reserve_upd_sts 9951 9951 0 0.00 0 19456 54 0 0
sp_reserve_write_off 9962 9962 0 0.00 6 19520 191 0 0
sp_supplier_invoice 5565 5565 0 0.00 7 59746 3618 0 0
sp_supplier_order 5532 5532 0 0.00 6 41098 1437 0 0
srv_aggregate_perf_data 102 101 1 0.98 2704 142474 17218 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2626 134925 46336 0 0
srv_find_qd_qs_mism 1026251 1026251 0 0.00 0 26802 6 0 0
srv_make_invnt_saldo 475 475 0 0.00 68 95837 3514 0 0
srv_make_money_saldo 753 753 0 0.00 1 11281 492 0 0
srv_recalc_idx_stat 4 4 0 0.00 193311 474678 296386 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 5048 5048 0 0.00 0 23094 172 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 21885 153 0 0
t$perf-norm:sp_cancel_client_order 1815 1815 0 0.00 0 3901 16 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 4414 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 3061 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 820 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 570 9 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 31594 1668 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 738 5 0 0
t$perf-norm:sp_client_order 20781 20781 0 0.00 0 22484 21 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 1261 6 0 0
t$perf-norm:sp_pay_from_customer 7201 7201 0 0.00 0 9260 13 0 0
t$perf-norm:sp_pay_to_supplier 5906 5906 0 0.00 0 5303 11 0 0
t$perf-norm:sp_reserve_write_off 9962 9962 0 0.00 0 16162 16 0 0
t$perf-norm:sp_supplier_invoice 5565 5565 0 0.00 0 19846 22 0 0
t$perf-norm:sp_supplier_order 5532 5532 0 0.00 0 6485 19 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 330 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 407 13 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 14902 56 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 1459 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 20 6 0 0
v_add_invoice_to_stock 10302 10074 228 2.21 0 6046 18 0 228
v_all_customers 37978 37978 0 0.00 0 42 1 0 0
v_all_suppliers 30748 30748 0 0.00 0 20 1 0 0
v_all_wares 41556 41556 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1800 12 0 0
v_cancel_client_order 3630 3630 0 0.00 0 12140 10 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 3303 9 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 1948 4 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 506 4 0 0
v_cancel_supplier_invoice 772 760 12 1.55 0 1089 14 0 12
v_cancel_supplier_order 800 800 0 0.00 0 1495 7 0 0
v_cancel_write_off 1940 1940 0 0.00 0 20033 42 0 0
v_max_id_clo_ord 5531 5531 0 0.00 0 239 1 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 1733 11 0 0
v_max_id_ord_sup 5560 5560 0 0.00 0 6776 10 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 235 2 0 0
v_max_non_paid_realizn 3756 3756 0 0.00 0 1489 4 0 0
v_min_id_clo_ord 5531 5531 0 0.00 0 5479 7 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 2570 8 0 0
v_min_id_ord_sup 5560 5560 0 0.00 0 5882 7 0 0
v_min_non_paid_invoice 5901 5901 0 0.00 0 7587 16 0 0
v_min_non_paid_realizn 7189 7189 0 0.00 0 29656 19 0 0
v_our_firm 10444 10444 0 0.00 0 19 1 0 0
v_reserve_write_off 19902 19902 0 0.00 0 16643 9 0 0
x_lock_depdocs_on_canc_invoice 11001 11001 0 0.00 0 22817 115 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 5603 346 0 0
x_make_qstorno_1000_1200 5532 5532 0 0.00 2 41090 1336 0 0
x_make_qstorno_1200_2000 5565 5565 0 0.00 3 59539 3449 0 0
x_make_qstorno_2100_3300 460520 460520 0 0.00 0 129997 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-11-2021 10:39:31 to 18-11-2021 10:39:35.

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:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 29302 8192000000 9723596880 10020540416 797068784 8.867 100 95 189 3 829730144 8549584 24111776
08:09:51 26216 8192000000 9767547728 10075238400 839283904 9.293 100 89 178 0 874660224 14114352 21261968
08:11:22 16934 8192000000 9805032640 10124124160 869397968 9.594 100 85 170 0 913573760 24399616 19776176
08:12:45 4883 8192000000 9854561824 10184990720 911129536 10.008 100 80 160 0 975395472 46918272 17347664
08:13:54 8618 8192000000 9907021856 10247991296 949571376 10.387 100 76 152 0 1023323472 57994032 15758064
08:15:05 5772 8192000000 9966691504 10318446592 986041920 10.743 100 91 182 0 1084603504 79044608 19516976
08:16:13 4228 8192000000 9983547200 10341560320 1015590496 11.029 100 81 162 0 1096385280 64605184 16189600
08:17:19 1511 8192000000 10003030576 10366332928 1031246768 11.180 100 81 161 0 1114500528 67357632 15896128
08:18:22 2280 8192000000 10056472032 10428313600 1064076304 11.495 100 90 180 2 1169470352 86817424 18576624
08:19:25 9231 8192000000 10009033152 10415939584 1064773504 11.502 100 52 103 2 1126816288 50790144 11252640
08:20:36 7775 8192000000 9979640208 10381627392 1072891264 11.580 100 35 68 3 1111298960 30865264 7542432
08:21:49 4912 8192000000 10009884560 10404999168 1083395600 11.680 100 47 91 2 1116935280 22863248 10676432
08:22:59 58781 8192000000 10002651856 10400899072 1090669552 11.749 100 35 71 1 1109042688 11500672 6872464
08:24:59 5119 8192000000 10011131904 10415575040 1097609296 11.815 100 34 68 1 1129446176 24697280 7139600
08:26:06 4376 8192000000 10032193600 10429399040 1099406752 11.832 100 56 112 3 1139729264 28206448 12116064
08:27:11 10582 8192000000 9998689024 10404691968 1082460496 11.671 99 21 40 1 1117702480 30535088 4706896
08:28:23 46897 8192000000 9978727504 10393157632 1018174528 11.054 93 25 48 0 1044338288 20989472 5174288
08:30:10 7591 8192000000 9962261216 10349469696 1014051312 11.015 97 37 74 0 1056200000 34193056 7955632
08:31:18 5994 8192000000 9946390224 10322006016 1006506736 10.942 97 46 93 2 1037608112 21548464 9552912
08:32:25 4700 8192000000 9945990848 10320408576 1010756528 10.983 99 51 98 3 1039015456 16620320 11638608
08:33:34 30171 8192000000 9956404512 10353537024 1031971952 11.187 99 21 42 1 1060378416 23779712 4626752
08:35:06 6843 8192000000 9969621168 10364919808 1059260528 11.449 100 39 79 2 1087049440 19411664 8377248
08:36:14 5708 8192000000 9991477296 10392825856 1071626208 11.568 100 40 80 1 1099744944 19495536 8623200
08:37:24 120142 8192000000 10029527488 10426806272 1089678736 11.740 100 45 88 1 1125702576 26653280 9370560
08:40:29 8719 8192000000 10076924816 10476457984 1113134976 11.962 100 47 94 0 1183473856 59968752 10370128
08:41:44 37525 8192000000 10109453616 10517561344 1127063872 12.094 100 34 68 0 1207459984 72796688 7599424
08:43:25 14041 8192000000 10167113200 10584932352 1132728720 12.147 100 50 100 0 1276851728 133820336 10302672
08:44:50 25241 8192000000 10156113440 10577022976 1135309792 12.171 100 47 93 1 1267532816 122050240 10172784
08:46:24 10546 8192000000 10177181504 10600681472 1146498512 12.277 100 57 114 0 1287845184 128935312 12411360
08:47:41 2625 8192000000 10211466720 10627751936 1157179584 12.377 100 86 167 0 1313475504 139576896 16719024
08:48:51 2705 8192000000 10212521840 10622709760 1151121376 12.320 100 59 118 1 1319277056 155281264 12874416
08:49:58 3171 8192000000 10217625568 10622697472 1133028896 12.150 99 66 131 2 1316666368 169386288 14251184
08:51:06 16835 8192000000 10294095216 10690437120 1128359120 12.106 99 80 161 2 1384684496 238746816 17578560
08:52:26 3573 8192000000 10330352752 10732183552 1162241984 12.424 100 81 163 0 1428704304 247374656 19087664
08:53:33 2862 8192000000 10322235872 10723569664 1144039136 12.254 98 74 147 0 1411933568 250192128 17702304
08:54:40 3166 8192000000 10351218640 10744360960 1160788720 12.411 100 91 182 0 1443934000 262453920 20691360
08:55:48 7308 8192000000 10370266320 10788147200 1162418336 12.426 100 56 112 5 1477479392 300971952 14089104
08:57:00 7136 8192000000 10364422816 10790068224 1152430016 12.332 100 46 92 2 1460975344 296913040 11632288
08:58:12 13979 8192000000 10308872864 10746998784 1155446016 12.361 100 40 81 1 1419542944 254212704 9884224
08:59:35 21453 8192000000 10332234560 10796048384 1153401008 12.341 100 26 52 0 1434144480 273617968 7125504
09:00:59 10107 8192000000 10283490512 10730663936 1135152288 12.170 100 35 70 0 1397846528 252578192 10116048
09:02:11 10096 8192000000 10299847408 10734706688 1133345824 12.153 100 37 70 2 1395677216 253660368 8671024
09:03:31 29236 8192000000 10131973616 10553221120 1146498832 12.277 100 42 79 0 1228774272 73276848 8998592
09:05:01 36027 8192000000 10110324256 10601590784 1116383984 11.993 98 23 44 0 1179067584 58124416 4559184
09:06:41 82320 8192000000 10073356496 10562277376 1085080944 11.696 96 10 21 0 1129472560 42147360 2244256
09:09:09 40876 8192000000 10057521600 10486575104 1113204992 11.963 99 23 46 0 1143581664 25426384 4950288
09:10:52 45466 8192000000 10103941280 10546003968 1134827632 12.167 100 16 32 0 1192542624 53662640 4052352
09:13:05 101767 8192000000 10065053744 10570207232 1112295856 11.954 98 13 26 3 1222344112 106745584 3302672
09:15:48 92470 8192000000 10072119520 10525933568 1097090496 11.810 97 5 10 1 1138286960 40728608 467856
09:18:20 116903 8192000000 10107035184 10557214720 1149683760 12.307 100 13 26 1 1238080032 85638944 2757328
09:21:21 134313 8192000000 10098120192 10533670912 1130585520 12.127 98 31 61 0 1167054832 30134464 6334848
09:24:49 113393 8192000000 10138059744 10588020736 1139178592 12.208 98 34 66 1 1196138016 49637920 7321504
09:27:53 49342 8192000000 10110666672 10568351744 1130034320 12.122 98 22 44 0 1181907328 46638368 5234640
09:30:32 62029 8192000000 10109518160 10563260416 1100549936 11.843 95 16 32 0 1175469424 71413248 3506240
09:32:34 78018 8192000000 10080640144 10580410368 1147661456 12.288 100 15 30 0 1212160880 61862896 2636528
09:34:55 37643 8192000000 10056138848 10507313152 1093714768 11.778 96 21 42 0 1126006336 27975168 4316400
09:36:37 61941 8192000000 10071127952 10542231552 1085368992 11.699 95 23 46 0 1152867968 62337344 5161632
09:38:41 41990 8192000000 10016772448 10453266432 1065741760 11.511 95 11 22 1 1088506672 20567504 2197408
09:40:30 28115 8192000000 10076164432 10511269888 1107984688 11.913 99 25 50 1 1167405344 54368528 5052128
09:42:00 30523 8192000000 10106117504 10539548672 1107115952 11.905 98 26 52 0 1181272400 68599840 5556608
09:43:31 54691 8192000000 10128672224 10580742144 1107226352 11.906 98 21 42 0 1203441728 91730144 4485232
09:45:31 28103 8192000000 10100220048 10535522304 1121351744 12.040 99 42 84 2 1177203536 47026416 8825376
09:47:11 30937 8192000000 10077330048 10540056576 1088185904 11.725 97 27 54 2 1143654048 49862368 5605776
09:48:50 88293 8192000000 10043757744 10473328640 1101629648 11.853 98 28 56 0 1123306336 15832864 5843824
09:51:19 28828 8192000000 10069408512 10524999680 1112896736 11.960 99 17 34 0 1158090512 41814528 3379248
09:52:51 51089 8192000000 10045302224 10486157312 1112878128 11.960 99 16 31 2 1160364896 43817376 3669392
09:54:43 60251 8192000000 10083267312 10548768768 1130017600 12.122 100 11 21 1 1188715600 56129744 2568256
09:57:11 43755 8192000000 10054463888 10512408576 1113991712 11.970 98 15 28 1 1142711696 25175760 3544224
09:58:57 38247 8192000000 10046089664 10478116864 1098032688 11.819 97 29 56 1 1131777088 27899200 5845200
10:00:42 40016 8192000000 10086387664 10516946944 1075563840 11.605 95 26 52 0 1140395664 59490880 5340944
10:02:26 72993 8192000000 10156172992 10622115840 1126408256 12.087 100 25 50 0 1264588208 132198736 5981216
10:05:07 88103 8192000000 10115128560 10582700032 1130865376 12.130 99 17 34 0 1241264400 107089504 3309520
10:07:38 8614 8192000000 10085144368 10513985536 1132892192 12.149 100 56 110 0 1181844080 36529136 12422752
10:08:54 19958 8192000000 10082102176 10512478208 1128446864 12.107 100 56 109 1 1172600128 32767136 11386128
10:10:15 107177 8192000000 10060727920 10518470656 1109483824 11.928 98 29 58 1 1177366320 61939040 5943456
10:13:20 63936 8192000000 10047280912 10512613376 1118459200 12.012 99 11 23 0 1168033392 47252480 2321712
10:15:27 94490 8192000000 10134449120 10628767744 1126453264 12.088 99 14 28 0 1199806208 70216944 3136000
10:18:02 42566 8192000000 10100796752 10557816832 1109081632 11.924 98 14 29 0 1164610048 52431280 3097136
10:20:35 70690 8192000000 10135152752 10578812928 1127326624 12.096 99 19 38 1 1182670544 50540768 4803152
10:22:48 73126 8192000000 10075645024 10540736512 1099993776 11.838 97 10 20 0 1179617200 77688656 1934768
10:25:03 17615 8192000000 10068068304 10489028608 1127451248 12.097 100 44 87 2 1154707712 18807632 8448832
10:26:25 40632 8192000000 10136377744 10593017856 1121076832 12.037 99 38 76 0 1210088848 81403952 7608064
10:28:08 30300 8192000000 10094073856 10529447936 1082448064 11.671 96 29 54 1 1143972720 55932336 5592320
10:29:40 134925 8192000000 10045356384 10479005696 1080637072 11.654 97 20 40 0 1130204080 45301296 4265712
10:32:56 40669 8192000000 10034853904 10493083648 1099924480 11.837 99 16 32 1 1136113568 32570544 3618544
10:34:40 36113 8192000000 10022705456 10508808192 1106212960 11.897 100 10 20 1 1156362752 47747280 2402512
10:36:25 55752 8192000000 9409550656 9744715776 521265712 5.982 47 9 18 0 557886976 34687984 1933280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-11-2021 10:39:36 to 18-11-2021 10:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 114 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-11-2021 10:39:52 to 18-11-2021 10:39:54.

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          238780
MON$OLDEST_ACTIVE               238781
MON$OLDEST_SNAPSHOT             238781
MON$NEXT_TRANSACTION            238781
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                       2058464
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2667 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2667 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2667 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2667 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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Thu Nov 18 10:37:29 2021
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Thu Nov 18 10:37:29 2021
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Thu Nov 18 10:37:30 2021
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Thu Nov 18 10:37:30 2021
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
--- end of diff output ---

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

18.11.21 10:39:55. 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-18 10:39:57 No severe PSQL-related problems occured
18.11.21 10:39:59. End of report.