Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.03.2022 10:38
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 11135836 kB
MemAvailable 31688784 kB
Buffers 0 kB
Cached 20401120 kB
SwapCached 2216 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-21 10:37:14
DTS_END                         2022-03-21 10:37:14.3210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40512
TRN_ID                          205753
ATT_ID                          615
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: 8534.63 32129 1024156
customer order: creation 164.01 489 19681
customer order: refuse 14.25 435 1710
order to supplier: creation 44.85 1621 5382
order to supplier: removal 3.13 159470 376
invoice (draft): creation 44.54 3422 5345
invoice (draft): removal 46.46 979 5575
invoice accept: apply 40.25 27482 4830
invoice accept: cancel 41.88 1304 5025
customer reserve: creation 3791.80 255 455016
customer reserve: removal 4060.75 98 487290
realization accept: apply 81.16 309 9739
realization accept: cancel 77.58 161 9310
payment from customer: creation 56.38 184 6766
payment from customer: removal 5.69 135 683
payment to supplier: creation 46.60 202 5592
payment to supplier: removal 3.27 110 392
service: total inventory turnovers 3.58 4861 430
service: total monetary turnovers 7.55 377 906
service: aggregate perf. data 0.87 16459 104
service: refresh index statistics 0.03 424220 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-03-2022 10:38:49 to 21-03-2022 10:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6929
TEST_TIME 2 6844
TEST_TIME 3 6766
TEST_TIME 4 6599
TEST_TIME 5 6211
TEST_TIME 6 5844
TEST_TIME 7 5752
TEST_TIME 8 6159
TEST_TIME 9 6300
TEST_TIME 10 6471
TEST_TIME 11 6505
TEST_TIME 12 6581
TEST_TIME 13 7103
TEST_TIME 14 7462
TEST_TIME 15 7641
TEST_TIME 16 7942
TEST_TIME 17 8127
TEST_TIME 18 8490
TEST_TIME 19 8794
TEST_TIME 20 8742
TEST_TIME 21 8802
TEST_TIME 22 8808
TEST_TIME 23 8784
TEST_TIME 24 9007
TEST_TIME 25 9202
TEST_TIME 26 9361
TEST_TIME 27 9365
TEST_TIME 28 9596
TEST_TIME 29 9438
TEST_TIME 30 9716
TEST_TIME 31 9779
TEST_TIME 32 9686
TEST_TIME 33 10238
TEST_TIME 34 10198
TEST_TIME 35 9959
TEST_TIME 36 10019
TEST_TIME 37 10056
TEST_TIME 38 10128
TEST_TIME 39 9982
TEST_TIME 40 10148
TEST_TIME 41 10241
TEST_TIME 42 10094
TEST_TIME 43 10126
TEST_TIME 44 10097
TEST_TIME 45 9944
TEST_TIME 46 9913
TEST_TIME 47 9847
TEST_TIME 48 9672
TEST_TIME 49 9672
TEST_TIME 50 9554
TEST_TIME 51 9432
TEST_TIME 52 9344
TEST_TIME 53 9467
TEST_TIME 54 9407
TEST_TIME 55 9384
TEST_TIME 56 9349
TEST_TIME 57 9292
TEST_TIME 58 9161
TEST_TIME 59 9106
TEST_TIME 60 9053
TEST_TIME 61 8961
TEST_TIME 62 9058
TEST_TIME 63 9019
TEST_TIME 64 8943
TEST_TIME 65 8863
TEST_TIME 66 8836
TEST_TIME 67 8815
TEST_TIME 68 8934
TEST_TIME 69 8877
TEST_TIME 70 8855
TEST_TIME 71 8803
TEST_TIME 72 8798
TEST_TIME 73 8887
TEST_TIME 74 8921
TEST_TIME 75 8882
TEST_TIME 76 8984
TEST_TIME 77 8956
TEST_TIME 78 8933
TEST_TIME 79 8941
TEST_TIME 80 8947
TEST_TIME 81 9021
TEST_TIME 82 9033
TEST_TIME 83 9008
TEST_TIME 84 8996
TEST_TIME 85 8917
TEST_TIME 86 8922
TEST_TIME 87 8852
TEST_TIME 88 8860
TEST_TIME 89 8846
TEST_TIME 90 8833
TEST_TIME 91 8783
TEST_TIME 92 8715
TEST_TIME 93 8690
TEST_TIME 94 8703
TEST_TIME 95 8701
TEST_TIME 96 8676
TEST_TIME 97 8687
TEST_TIME 98 8672
TEST_TIME 99 8700
TEST_TIME 100 8726
TEST_TIME 101 8737
TEST_TIME 102 8700
TEST_TIME 103 8752
TEST_TIME 104 8719
TEST_TIME 105 8826
TEST_TIME 106 8799
TEST_TIME 107 8793
TEST_TIME 108 8916
TEST_TIME 109 8869
TEST_TIME 110 8823
TEST_TIME 111 8771
TEST_TIME 112 8743
TEST_TIME 113 8749
TEST_TIME 114 8721
TEST_TIME 115 8696
TEST_TIME 116 8664
TEST_TIME 117 8666
TEST_TIME 118 8627
TEST_TIME 119 8613
TEST_TIME 120 8573

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-03-2022 10:38:53 to 21-03-2022 10:38:56.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1440597 1440597 0 0.00 0 27089 14 0 0
doc_list_biud 1440584 1440584 0 0.00 0 39172 39 0 0
sp_add_invoice_to_stock 4988 4830 158 3.16 52 190275 27483 0 158
sp_cancel_adding_invoice 5025 5025 0 0.00 5 66006 1305 0 0
sp_cancel_client_order 1710 1710 0 0.00 6 29242 435 0 0
sp_cancel_customer_reserve 487290 487290 0 0.00 1 39210 98 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 4 5561 136 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 3710 110 0 0
sp_cancel_supplier_invoice 5584 5575 9 0.16 2 23073 979 0 9
sp_cancel_supplier_order 376 376 0 0.00 11 953776 159470 0 0
sp_cancel_write_off 9310 9310 0 0.00 2 18540 162 0 0
sp_client_order 19681 19681 0 0.00 5 32219 490 0 0
sp_customer_reserve 455016 455016 0 0.00 1 60709 256 0 0
sp_fill_shopping_cart 30402 30402 0 0.00 2 17934 130 0 0
sp_fill_shopping_cart_clo_res 454979 454979 0 0.00 0 17109 20 0 0
sp_get_clo_for_invoice 4781 4781 0 0.00 0 47105 519 0 0
sp_kill_cost_storno 15410 15410 0 0.00 0 20032 78 0 0
sp_kill_qstorno_ret_qs2qd 989841 989841 0 0.00 0 32097 44 0 0
sp_kill_qty_storno 523803 523803 0 0.00 0 39166 101 0 0
sp_lock_selected_doc 20189 20189 0 0.00 0 8797 7 0 0
sp_make_cost_storno 26875 26875 0 0.00 0 27066 90 0 0
sp_multiply_rows_for_pdistr 26873 26873 0 0.00 0 8041 40 0 0
sp_multiply_rows_for_qdistr 19681 19681 0 0.00 0 15468 164 0 0
sp_pay_from_customer 6766 6766 0 0.00 5 19809 185 0 0
sp_pay_to_supplier 5592 5592 0 0.00 6 13877 203 0 0
sp_payment_common 12355 12355 0 0.00 2 11042 46 0 0
sp_qd_handle_on_cancel_clo 1710 1710 0 0.00 0 29203 369 0 0
sp_qd_handle_on_invoice_upd_sts 9803 9803 0 0.00 0 32030 535 0 0
sp_qd_handle_on_reserve_upd_sts 9739 9739 0 0.00 0 31928 89 0 0
sp_reserve_write_off 9739 9739 0 0.00 8 32208 310 0 0
sp_supplier_invoice 5345 5345 0 0.00 10 73574 3423 0 0
sp_supplier_order 5382 5382 0 0.00 8 39347 1621 0 0
srv_aggregate_perf_data 105 104 1 0.95 2841 102543 16459 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 1126 107290 37105 0 0
srv_find_qd_qs_mism 975492 975492 0 0.00 0 18389 11 0 0
srv_make_invnt_saldo 430 430 0 0.00 51 123769 4861 0 0
srv_make_money_saldo 906 906 0 0.00 1 17160 378 0 0
srv_recalc_idx_stat 4 4 0 0.00 220611 722212 424221 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 4841 4841 0 0.00 1 15385 893 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 8549 489 0 0
t$perf-norm:sp_cancel_client_order 1710 1710 0 0.00 0 6240 33 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 2879 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 6608 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 331 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1729 37 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 106914 7027 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 3449 23 0 0
t$perf-norm:sp_client_order 19681 19681 0 0.00 0 5662 33 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 1255 15 0 0
t$perf-norm:sp_pay_from_customer 6767 6767 0 0.00 0 13972 23 0 0
t$perf-norm:sp_pay_to_supplier 5592 5592 0 0.00 0 4984 18 0 0
t$perf-norm:sp_reserve_write_off 9739 9739 0 0.00 0 4360 20 0 0
t$perf-norm:sp_supplier_invoice 5345 5345 0 0.00 0 3975 30 0 0
t$perf-norm:sp_supplier_order 5382 5382 0 0.00 0 6062 31 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 407 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 403 21 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 263 8 0 0
t$perf-norm:srv_make_money_saldo 906 906 0 0.00 0 6062 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 54 18 0 0
v_add_invoice_to_stock 9868 9552 316 3.20 0 5151 26 0 316
v_all_customers 36184 36184 0 0.00 0 59 3 0 0
v_all_suppliers 29990 29990 0 0.00 0 434 4 0 0
v_all_wares 39354 39354 0 0.00 0 88 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 3191 21 0 0
v_cancel_client_order 3420 3420 0 0.00 0 1281 8 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 3772 13 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 4273 16 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 715 10 0 0
v_cancel_supplier_invoice 776 758 18 2.31 0 3683 28 0 18
v_cancel_supplier_order 752 752 0 0.00 0 4164 17 0 0
v_cancel_write_off 2070 2070 0 0.00 0 5823 26 0 0
v_max_id_clo_ord 5376 5376 0 0.00 0 252 5 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 7832 19 0 0
v_max_id_ord_sup 5343 5343 0 0.00 0 10706 11 0 0
v_max_non_paid_invoice 1314 1314 0 0.00 0 138 7 0 0
v_max_non_paid_realizn 3429 3429 0 0.00 0 1717 7 0 0
v_min_id_clo_ord 5376 5376 0 0.00 0 3464 13 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 2424 12 0 0
v_min_id_ord_sup 5343 5343 0 0.00 0 5082 13 0 0
v_min_non_paid_invoice 5590 5590 0 0.00 0 4542 16 0 0
v_min_non_paid_realizn 6764 6764 0 0.00 0 11034 22 0 0
v_our_firm 9840 9840 0 0.00 0 80 4 0 0
v_reserve_write_off 19476 19476 0 0.00 0 4274 9 0 0
x_lock_depdocs_on_canc_invoice 10221 10221 0 0.00 0 14219 147 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 10216 386 0 0
x_make_qstorno_1000_1200 5382 5382 0 0.00 3 39319 1202 0 0
x_make_qstorno_1200_2000 5345 5345 0 0.00 5 73554 3175 0 0
x_make_qstorno_2100_3300 455016 455016 0 0.00 0 60624 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-03-2022 10:38:57 to 21-03-2022 10:39:01.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:13:17 10234 8192000000 9774870720 10087825408 849963568 9.400 100 85 170 0 887716208 18102496 19650144
08:14:31 14286 8192000000 9837564928 10165436416 886886592 9.768 100 75 150 0 947015968 43240128 16889248
08:15:50 10250 8192000000 9904813360 10246324224 946623264 10.358 100 76 152 0 1015732320 52836720 16272336
08:17:02 5221 8192000000 9937413200 10288328704 984442160 10.727 100 82 164 1 1050283584 48849904 16991520
08:18:12 2162 8192000000 9988616624 10347466752 1014414560 11.018 100 93 178 1 1109407856 77270480 17722816
08:19:15 3707 8192000000 10019128512 10383372288 1029324704 11.162 100 88 173 2 1141547616 95112400 17110512
08:20:22 1442 8192000000 10017520224 10390794240 1047775904 11.339 100 98 185 3 1133124768 67303536 18045328
08:21:26 2074 8192000000 10014636480 10385784832 1057594960 11.433 100 94 183 5 1132158464 56243136 18320368
08:22:30 1838 8192000000 10019257968 10404696064 1061072160 11.467 100 63 123 0 1158896368 85995872 11828336
08:23:35 2342 8192000000 10034148000 10413494272 1069404960 11.546 100 89 181 0 1161702784 74081680 18216144
08:24:39 3225 8192000000 10060186416 10443616256 1074681616 11.597 100 97 185 1 1178692512 85009968 19000928
08:25:43 2489 8192000000 10067291152 10453258240 1082695440 11.673 100 98 193 0 1188305696 85602112 20008144
08:26:46 2387 8192000000 10060322720 10444701696 1083887472 11.685 100 92 177 3 1180316928 78235408 18194048
08:27:50 2201 8192000000 10051445760 10434519040 1085030336 11.695 100 95 188 1 1171243392 68183696 18029360
08:28:53 2278 8192000000 10044473536 10431131648 1088247120 11.726 100 96 186 0 1165149872 58740208 18162544
08:29:56 2344 8192000000 10080751568 10466529280 1093086480 11.772 100 96 187 1 1202166560 90391392 18688688
08:30:59 2293 8192000000 10063871008 10450595840 1098395632 11.822 100 84 162 0 1180415184 65726800 16292752
08:32:02 2635 8192000000 10063043392 10447851520 1092960448 11.771 100 100 193 0 1182475312 70135632 19379232
08:33:06 2466 8192000000 10049351376 10431336448 1079384944 11.642 100 97 195 1 1171163184 72353632 19424608
08:34:09 2353 8192000000 10042979152 10427764736 1079499728 11.643 100 95 186 1 1168826208 69119440 20207040
08:35:12 2363 8192000000 10053932080 10437001216 1072750128 11.578 100 99 192 2 1174497072 81910816 19836128
08:36:16 2482 8192000000 10019331408 10396762112 1061280640 11.469 100 93 178 2 1135776736 57385840 17110256
08:37:22 15171 8192000000 10026013984 10407432192 1057907920 11.436 100 77 145 1 1146638512 73420944 15309648
08:38:44 13178 8192000000 10023176320 10402275328 1050386864 11.364 99 67 125 1 1154679648 91429328 12863456
08:40:01 2346 8192000000 10046285712 10423848960 1068037712 11.533 100 98 182 3 1167065296 79517424 19510160
08:41:07 3249 8192000000 10053820816 10430337024 1064243856 11.497 99 81 157 1 1170184208 88388144 17552208
08:42:14 15820 8192000000 10088040656 10473156608 1057304368 11.431 99 71 140 0 1195516464 123444352 14767744
08:43:39 4012 8192000000 10100419488 10481930240 1056573664 11.424 99 70 133 0 1224628144 153531568 14522912
08:44:49 1798 8192000000 10152067664 10538172416 1084682752 11.692 100 81 151 3 1270212272 169165984 16363536
08:45:56 2151 8192000000 10169897200 10554953728 1091071984 11.753 99 83 159 1 1289088176 180960656 17055536
08:47:01 3186 8192000000 10204698480 10587361280 1096620912 11.806 99 84 164 1 1311664896 197187104 17856880
08:48:11 1763 8192000000 10255554816 10646073344 1119537792 12.023 100 97 180 2 1371026176 230570000 20918384
08:49:15 1118 8192000000 10271828656 10661580800 1125571584 12.080 100 89 172 1 1383435888 238191792 19672512
08:50:18 1708 8192000000 10310143888 10705293312 1141970432 12.234 100 91 179 0 1423127968 261099744 20057792
08:51:21 2084 8192000000 10266913456 10705526784 1146365376 12.275 100 96 188 3 1376441520 208354608 21721536
08:52:27 1254 8192000000 10286072960 10678788096 1143961024 12.253 100 95 191 0 1396514752 230329088 22224640
08:53:32 1205 8192000000 10296537808 10738630656 1159229392 12.396 100 95 193 1 1404771952 223990832 21551728
08:54:35 1509 8192000000 10302762608 10725285888 1164987488 12.450 100 97 191 1 1408846096 222017824 21840784
08:55:39 1533 8192000000 10331585792 10740318208 1158522496 12.389 99 84 162 2 1438957376 260913120 19521760
08:56:44 16202 8192000000 10270005264 10701631488 1162600544 12.428 100 38 73 3 1390399424 218290752 9508128
08:58:06 15044 8192000000 10313748448 10760294400 1163975072 12.440 99 45 89 0 1417215520 242551616 10688832
08:59:24 11176 8192000000 10320252704 10753806336 1173056224 12.525 100 38 74 1 1428007872 245957120 8994528
09:00:39 11747 8192000000 10331022224 10782978048 1153225440 12.340 99 50 92 2 1443741152 277623568 12892144
09:01:56 2394 8192000000 10344918752 10774806528 1166147536 12.461 100 66 126 2 1446033520 265303952 14582032
09:03:10 6108 8192000000 10315387984 10767814656 1175115440 12.545 100 56 110 2 1417605600 229753840 12736320
09:04:21 21596 8192000000 10347258928 10795282432 1152821232 12.336 98 47 92 1 1427617600 263959136 10837232
09:05:50 38536 8192000000 10351464768 10790719488 1142907552 12.243 99 22 45 2 1419649152 271282368 5459232
09:07:30 30199 8192000000 10285918864 10737709056 1128174080 12.104 98 36 70 1 1364789024 227427216 9187728
09:09:06 2969 8192000000 10280554064 10779037696 1162336608 12.425 100 86 168 3 1379019504 196928896 19754000
09:10:09 1762 8192000000 10286724944 10767327232 1153423616 12.342 99 94 182 1 1380882432 206956288 20502528
09:11:13 82151 8192000000 10279656832 10727989248 1161241952 12.415 100 38 75 0 1399891856 229975984 8673920
09:13:38 16279 8192000000 10278052560 10727440384 1157913984 12.384 100 38 76 0 1373849120 207254896 8680240
09:15:17 97409 8192000000 10182557936 10727944192 1139148496 12.208 99 18 38 0 1350886528 206708944 5029088
09:17:55 46791 8192000000 10119303264 10619785216 1141925424 12.234 100 23 46 0 1261735168 114110912 5698832
09:19:45 64426 8192000000 10153004976 10630926336 1144259120 12.256 99 29 58 0 1254470896 103638288 6573488
09:21:52 107289 8192000000 10123452128 10582818816 1121297952 12.039 97 20 40 0 1209872224 84521200 4053072
09:26:13 71553 8192000000 10070902336 10509627392 1142409184 12.238 100 39 75 2 1184967632 34768480 7789968
09:28:25 58853 8192000000 10151789056 10590130176 1140131408 12.217 99 30 61 1 1235376720 88658192 6587120
09:30:29 73325 8192000000 10103461888 10623193088 1124977776 12.074 99 10 20 0 1216539392 89664032 1897584
09:32:44 51013 8192000000 10062130224 10524905472 1120701808 12.034 99 15 31 1 1171225040 47450592 3072640
09:34:40 80177 8192000000 10065141200 10510135296 1123451360 12.060 99 37 67 1 1161226560 30295920 7479280
09:37:04 101375 8192000000 10094208288 10561073152 1103577744 11.872 97 30 60 1 1187311632 77098800 6635088
09:39:50 51435 8192000000 10077457216 10529976320 1128412480 12.106 99 18 34 3 1180677440 48429648 3835312
09:42:08 42582 8192000000 10129444800 10577510400 1132192096 12.142 99 21 42 1 1208021280 71019600 4809584
09:43:57 91188 8192000000 10123847408 10610081792 1132208928 12.142 99 21 42 0 1252518208 115282640 5026640
09:46:30 36097 8192000000 10043517024 10483081216 1123373424 12.059 100 44 84 5 1164583120 31547920 9661776
09:48:06 51637 8192000000 10080223296 10517098496 1124239232 12.067 99 33 66 1 1169149856 38159600 6751024
09:49:58 85189 8192000000 10061151808 10551693312 1090074688 11.743 96 16 32 1 1157272096 64032736 3164672
09:52:24 32322 8192000000 10100405856 10532622336 1115808496 11.987 99 39 76 1 1185351696 62196576 7346624
09:53:57 42095 8192000000 10047933648 10472144896 1100743008 11.845 97 31 63 0 1126422976 18883344 6796624
09:55:47 5010 8192000000 10100755040 10524712960 1130917984 12.130 100 60 116 1 1199539472 57340448 11281040
09:56:53 12331 8192000000 10129268400 10558758912 1128518736 12.107 100 67 130 2 1205090448 64144384 12427328
09:58:08 47610 8192000000 10116576176 10604605440 1140226384 12.218 100 38 76 1 1225408800 77308688 7873728
09:59:59 27052 8192000000 10119201776 10544181248 1110697120 11.939 97 52 101 1 1179029152 57869888 10462144
10:01:28 95125 8192000000 10107172672 10561011712 1141942624 12.234 100 41 81 1 1193632560 42616288 9073648
10:04:23 31749 8192000000 10092595392 10524958720 1118876544 12.016 99 40 80 0 1159711280 32493008 8341728
10:06:01 43614 8192000000 10041243712 10485182464 1096742800 11.807 98 13 26 1 1119880080 20446768 2690512
10:07:53 46831 8192000000 10043864272 10493964288 1127167040 12.095 100 15 29 2 1157518864 27039712 3312112
10:09:55 46051 8192000000 10071332592 10523271168 1107773408 11.911 98 15 31 2 1165083248 54072416 3237424
10:11:55 89564 8192000000 10031254800 10479661056 1108662960 11.920 99 20 39 0 1165531392 52402336 4466096
10:14:25 40233 8192000000 10080728400 10532507648 1117660752 12.005 99 45 90 0 1198179456 71450608 9068096
10:16:22 63822 8192000000 10091275360 10528178176 1110076464 11.933 98 29 60 2 1176968448 60955824 5936160
10:18:34 43828 8192000000 10096070688 10534338560 1114394448 11.974 98 32 64 1 1180153216 59307776 6450992
10:20:22 76902 8192000000 10114738992 10547167232 1100371936 11.841 98 43 86 0 1198066592 89584096 8110560
10:23:49 44836 8192000000 10110377696 10557652992 1094222528 11.783 97 39 78 2 1207269536 104571168 8475840
10:25:38 83809 8192000000 10038319648 10498994176 1060225984 11.459 95 9 18 0 1101965216 40043024 1696208
10:28:36 42259 8192000000 10060182784 10506342400 1106183120 11.896 99 28 56 1 1152488144 40491904 5813120
10:30:19 48708 8192000000 10059400320 10525016064 1090996592 11.752 97 21 43 0 1146073984 50908304 4169088
10:32:07 69786 8192000000 10054154144 10515087360 1121074656 12.037 100 20 39 0 1150349872 25166368 4108848
10:34:26 58282 8192000000 10069557888 10545012736 1104074304 11.876 98 14 28 0 1142582944 35660400 2848240
10:36:24 28552 8192000000 10117400080 10551504896 1108527232 11.918 99 40 80 0 1198044608 81292736 8224640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-03-2022 10:39:03 to 21-03-2022 10:39:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 158 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-03-2022 10:39:20 to 21-03-2022 10:39:22.

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          203138
MON$OLDEST_ACTIVE               205950
MON$OLDEST_SNAPSHOT             205950
MON$NEXT_TRANSACTION            205950
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                       2037792
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             756
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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


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

21.03.22 10:45:47. 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-21 10:45:50 No severe PSQL-related problems occured
21.03.22 10:45:51. End of report.