Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.03.2022 04: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 16251112 kB
MemAvailable 31530208 kB
Buffers 20 kB
Cached 15362252 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 04:37:15
DTS_END                         2022-03-31 04:37:15.3920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(226:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56540
TRN_ID                          228978
ATT_ID                          608
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: 9139.83 19482 1096779
customer order: creation 178.93 451 21472
customer order: refuse 15.32 372 1838
order to supplier: creation 46.83 1373 5619
order to supplier: removal 3.40 143365 408
invoice (draft): creation 47.37 3199 5684
invoice (draft): removal 50.77 979 6092
invoice accept: apply 43.25 24818 5190
invoice accept: cancel 45.73 1227 5488
customer reserve: creation 3987.33 239 478480
customer reserve: removal 4425.94 87 531113
realization accept: apply 84.53 260 10143
realization accept: cancel 75.69 159 9083
payment from customer: creation 61.53 166 7383
payment from customer: removal 5.93 133 711
payment to supplier: creation 50.94 190 6113
payment to supplier: removal 3.61 114 433
service: total inventory turnovers 3.88 3463 465
service: total monetary turnovers 7.90 270 948
service: aggregate perf. data 0.93 14017 111
service: refresh index statistics 0.04 194755 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-03-2022 04:38:38 to 31-03-2022 04:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8473
TEST_TIME 2 6547
TEST_TIME 3 6544
TEST_TIME 4 6175
TEST_TIME 5 5986
TEST_TIME 6 6020
TEST_TIME 7 5727
TEST_TIME 8 6000
TEST_TIME 9 6096
TEST_TIME 10 6578
TEST_TIME 11 6848
TEST_TIME 12 7105
TEST_TIME 13 7312
TEST_TIME 14 7606
TEST_TIME 15 7575
TEST_TIME 16 7768
TEST_TIME 17 8066
TEST_TIME 18 8540
TEST_TIME 19 8763
TEST_TIME 20 8952
TEST_TIME 21 9003
TEST_TIME 22 9045
TEST_TIME 23 9083
TEST_TIME 24 9371
TEST_TIME 25 9598
TEST_TIME 26 10015
TEST_TIME 27 9895
TEST_TIME 28 10105
TEST_TIME 29 10240
TEST_TIME 30 10280
TEST_TIME 31 10806
TEST_TIME 32 10656
TEST_TIME 33 11172
TEST_TIME 34 11311
TEST_TIME 35 11262
TEST_TIME 36 11169
TEST_TIME 37 11132
TEST_TIME 38 10949
TEST_TIME 39 10788
TEST_TIME 40 10802
TEST_TIME 41 11004
TEST_TIME 42 10794
TEST_TIME 43 10568
TEST_TIME 44 10364
TEST_TIME 45 10301
TEST_TIME 46 10258
TEST_TIME 47 10259
TEST_TIME 48 10203
TEST_TIME 49 10164
TEST_TIME 50 10158
TEST_TIME 51 10021
TEST_TIME 52 9908
TEST_TIME 53 9903
TEST_TIME 54 9801
TEST_TIME 55 9691
TEST_TIME 56 9699
TEST_TIME 57 9619
TEST_TIME 58 9642
TEST_TIME 59 9676
TEST_TIME 60 9632
TEST_TIME 61 9612
TEST_TIME 62 9678
TEST_TIME 63 9665
TEST_TIME 64 9685
TEST_TIME 65 9646
TEST_TIME 66 9740
TEST_TIME 67 9785
TEST_TIME 68 9775
TEST_TIME 69 9712
TEST_TIME 70 9805
TEST_TIME 71 9782
TEST_TIME 72 9779
TEST_TIME 73 9759
TEST_TIME 74 9685
TEST_TIME 75 9578
TEST_TIME 76 9639
TEST_TIME 77 9568
TEST_TIME 78 9507
TEST_TIME 79 9470
TEST_TIME 80 9424
TEST_TIME 81 9341
TEST_TIME 82 9297
TEST_TIME 83 9299
TEST_TIME 84 9272
TEST_TIME 85 9300
TEST_TIME 86 9301
TEST_TIME 87 9256
TEST_TIME 88 9311
TEST_TIME 89 9270
TEST_TIME 90 9246
TEST_TIME 91 9211
TEST_TIME 92 9263
TEST_TIME 93 9274
TEST_TIME 94 9372
TEST_TIME 95 9380
TEST_TIME 96 9336
TEST_TIME 97 9282
TEST_TIME 98 9282
TEST_TIME 99 9252
TEST_TIME 100 9228
TEST_TIME 101 9206
TEST_TIME 102 9163
TEST_TIME 103 9127
TEST_TIME 104 9142
TEST_TIME 105 9115
TEST_TIME 106 9087
TEST_TIME 107 9121
TEST_TIME 108 9110
TEST_TIME 109 9146
TEST_TIME 110 9115
TEST_TIME 111 9092
TEST_TIME 112 9075
TEST_TIME 113 9083
TEST_TIME 114 9097
TEST_TIME 115 9190
TEST_TIME 116 9116
TEST_TIME 117 9250
TEST_TIME 118 9227
TEST_TIME 119 9238
TEST_TIME 120 9164

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-03-2022 04:38:42 to 31-03-2022 04:38:45.

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 1537980 1537980 0 0.00 0 15222 13 0 0
doc_list_biud 1537980 1537980 0 0.00 0 40704 36 0 0
sp_add_invoice_to_stock 5294 5190 104 1.96 41 195516 24818 0 104
sp_cancel_adding_invoice 5488 5488 0 0.00 7 83858 1228 0 0
sp_cancel_client_order 1838 1838 0 0.00 6 7910 373 0 0
sp_cancel_customer_reserve 531113 531113 0 0.00 1 36269 87 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 4629 134 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 3385 115 0 0
sp_cancel_supplier_invoice 6098 6092 6 0.09 2 40731 979 0 6
sp_cancel_supplier_order 408 408 0 0.00 10 1289452 143366 0 0
sp_cancel_write_off 9083 9083 0 0.00 2 12933 160 0 0
sp_client_order 21472 21472 0 0.00 6 24781 452 0 0
sp_customer_reserve 478480 478480 0 0.00 1 118758 239 0 0
sp_fill_shopping_cart 32768 32768 0 0.00 2 12708 78 0 0
sp_fill_shopping_cart_clo_res 478436 478436 0 0.00 0 16798 18 0 0
sp_get_clo_for_invoice 5146 5146 0 0.00 0 19456 252 0 0
sp_kill_cost_storno 15715 15715 0 0.00 0 6134 67 0 0
sp_kill_qstorno_ret_qs2qd 1077809 1077809 0 0.00 0 40701 39 0 0
sp_kill_qty_storno 569309 569309 0 0.00 0 40703 90 0 0
sp_lock_selected_doc 21259 21259 0 0.00 0 7670 6 0 0
sp_make_cost_storno 28783 28783 0 0.00 0 13714 83 0 0
sp_multiply_rows_for_pdistr 28783 28783 0 0.00 0 7432 33 0 0
sp_multiply_rows_for_qdistr 21472 21472 0 0.00 0 16207 165 0 0
sp_pay_from_customer 7383 7383 0 0.00 6 10644 166 0 0
sp_pay_to_supplier 6113 6113 0 0.00 7 13449 191 0 0
sp_payment_common 13496 13496 0 0.00 2 8465 48 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 1 7888 303 0 0
sp_qd_handle_on_invoice_upd_sts 10632 10632 0 0.00 0 31254 482 0 0
sp_qd_handle_on_reserve_upd_sts 10143 10143 0 0.00 0 19835 57 0 0
sp_reserve_write_off 10143 10143 0 0.00 8 19920 261 0 0
sp_supplier_invoice 5684 5684 0 0.00 10 87263 3199 0 0
sp_supplier_order 5619 5619 0 0.00 8 32461 1374 0 0
srv_aggregate_perf_data 112 111 1 0.89 2416 150392 14018 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 839 134472 36065 0 0
srv_find_qd_qs_mism 1046349 1046349 0 0.00 0 14212 11 0 0
srv_make_invnt_saldo 465 465 0 0.00 44 55902 3463 0 0
srv_make_money_saldo 948 948 0 0.00 1 9741 270 0 0
srv_recalc_idx_stat 5 5 0 0.00 74816 360715 194755 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 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 5190 5190 0 0.00 1 12830 836 0 0
t$perf-norm:sp_cancel_adding_invoice 462 462 0 0.00 1 6267 441 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 1438 23 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 2062 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 3891 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 2427 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 2321 31 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 82408 5936 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 696 14 0 0
t$perf-norm:sp_client_order 21472 21472 0 0.00 0 9744 32 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 678 13 0 0
t$perf-norm:sp_pay_from_customer 7383 7383 0 0.00 0 11601 17 0 0
t$perf-norm:sp_pay_to_supplier 6113 6113 0 0.00 0 13945 18 0 0
t$perf-norm:sp_reserve_write_off 10143 10143 0 0.00 0 4338 20 0 0
t$perf-norm:sp_supplier_invoice 5684 5684 0 0.00 0 5146 27 0 0
t$perf-norm:sp_supplier_order 5619 5619 0 0.00 0 11815 30 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 371 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 807 20 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 311 6 0 0
t$perf-norm:srv_make_money_saldo 948 948 0 0.00 0 1603 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 178 54 0 0
v_add_invoice_to_stock 10494 10286 208 1.98 0 10233 22 0 208
v_all_customers 39190 39190 0 0.00 0 72 3 0 0
v_all_suppliers 31812 31812 0 0.00 0 161 4 0 0
v_all_wares 42942 42942 0 0.00 0 127 3 0 0
v_cancel_adding_invoice 924 924 0 0.00 0 2364 15 0 0
v_cancel_client_order 3676 3676 0 0.00 0 4642 12 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 3139 13 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 0 3062 12 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 2538 13 0 0
v_cancel_supplier_invoice 892 880 12 1.34 0 812 20 0 12
v_cancel_supplier_order 816 816 0 0.00 0 953 10 0 0
v_cancel_write_off 2084 2084 0 0.00 0 824 19 0 0
v_max_id_clo_ord 5618 5618 0 0.00 0 547 5 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 4031 16 0 0
v_max_id_ord_sup 5679 5679 0 0.00 0 4783 8 0 0
v_max_non_paid_invoice 1504 1504 0 0.00 0 6006 12 0 0
v_max_non_paid_realizn 3933 3933 0 0.00 0 1118 7 0 0
v_min_id_clo_ord 5618 5618 0 0.00 0 1441 7 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 2288 11 0 0
v_min_id_ord_sup 5679 5679 0 0.00 0 7645 13 0 0
v_min_non_paid_invoice 6113 6113 0 0.00 0 5558 22 0 0
v_min_non_paid_realizn 7383 7383 0 0.00 0 7501 17 0 0
v_our_firm 10652 10652 0 0.00 0 67 4 0 0
v_reserve_write_off 20286 20286 0 0.00 0 5511 9 0 0
x_lock_depdocs_on_canc_invoice 11140 11140 0 0.00 0 19379 122 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 4324 312 0 0
x_make_qstorno_1000_1200 5619 5619 0 0.00 3 32409 1220 0 0
x_make_qstorno_1200_2000 5684 5684 0 0.00 5 81649 2982 0 0
x_make_qstorno_2100_3300 478480 478480 0 0.00 0 118754 210 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 31-03-2022 04:38:46 to 31-03-2022 04:38:51.

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
02:07:19 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 42534 8192000000 9738165008 10041053184 814028256 9.038 100 95 190 1 845560960 8309360 23223344
02:10:05 23308 8192000000 9768828096 10080858112 842221968 9.322 100 91 180 0 879315936 15471296 21622672
02:11:43 12879 8192000000 9824750208 10148741120 879629168 9.696 100 79 158 2 929422720 31811600 17981952
02:13:00 8629 8192000000 9869360064 10206752768 928544448 10.180 100 77 153 1 989709952 44746624 16418880
02:14:10 3677 8192000000 9930754976 10275876864 970711824 10.594 100 89 174 0 1047424992 58338944 18374224
02:15:16 2348 8192000000 9968453872 10326335488 1010386608 10.979 100 93 185 2 1093496528 64025504 19084416
02:16:21 1132 8192000000 10018348592 10382684160 1031930880 11.187 100 92 178 1 1142915136 93182672 17801584
02:17:23 851 8192000000 10024712096 10397376512 1057359120 11.431 100 94 183 1 1147406560 71412816 18634624
02:18:25 1118 8192000000 10026231056 10403475456 1060630144 11.463 100 94 177 5 1145210944 67637648 16943152
02:19:27 1122 8192000000 10043188224 10419564544 1069855024 11.551 100 93 173 1 1158959872 71284624 17820224
02:20:29 1036 8192000000 10048684464 10431361024 1077265216 11.621 100 88 167 2 1166996848 71817424 17914208
02:21:31 7019 8192000000 10037195552 10431770624 1082617008 11.672 100 73 136 1 1162150976 65419616 14114352
02:22:40 1150 8192000000 10077733040 10463653888 1092083456 11.762 100 97 191 0 1195782320 84659760 19039104
02:23:42 1169 8192000000 10063993408 10453585920 1093572752 11.777 100 99 193 0 1184490064 71424160 19493152
02:24:44 1549 8192000000 10088271840 10477977600 1093617440 11.777 100 91 172 0 1209573968 98730320 17226208
02:25:46 1211 8192000000 10097617008 10489094144 1095936864 11.799 100 95 183 1 1216266528 101133120 19196544
02:26:48 1103 8192000000 10066205312 10454736896 1088251584 11.726 99 95 179 1 1189568176 82654976 18661616
02:27:52 1140 8192000000 10063429616 10452520960 1103437568 11.870 100 96 182 1 1189525840 67090448 18997824
02:28:55 1537 8192000000 10049991280 10436608000 1095174496 11.792 100 96 189 2 1166911200 52673520 19063184
02:29:57 1320 8192000000 10055126480 10444255232 1092125568 11.763 100 91 175 4 1180079664 69412048 18542048
02:31:00 1200 8192000000 10028804464 10411229184 1066316032 11.517 100 98 182 4 1152312224 67087328 18908864
02:32:02 1270 8192000000 9985634672 10358435840 1046413136 11.326 100 94 174 1 1108633072 43131072 19088864
02:33:05 1253 8192000000 10004667584 10380288000 1040300368 11.268 99 94 177 1 1128564416 70725072 17538976
02:34:08 1270 8192000000 10008111344 10383544320 1042546208 11.289 100 87 165 0 1129953744 68637184 18770352
02:35:10 1102 8192000000 9996307168 10368200704 1039170672 11.257 100 93 182 0 1115076304 57240368 18665264
02:36:12 1163 8192000000 9983721328 10361987072 1044985040 11.313 100 83 151 0 1106749024 45861360 15902624
02:37:14 35584 8192000000 10013535264 10400591872 1065395792 11.508 100 57 99 0 1132456400 57931488 9129120
02:39:03 2174 8192000000 10036542544 10417229824 1064594688 11.500 99 86 168 0 1166117072 82815984 18706400
02:40:10 909 8192000000 10065658064 10444177408 1088974544 11.733 100 96 171 2 1180983632 75397840 16611248
02:41:14 19473 8192000000 10137295376 10537193472 1112397104 11.955 100 67 129 0 1247524864 121745056 13382704
02:42:40 6610 8192000000 10141725136 10539454464 1123721728 12.062 100 73 136 2 1252048416 113393696 14932992
02:43:52 3895 8192000000 10186563952 10587398144 1132218480 12.142 100 68 119 2 1301412080 154465088 14728512
02:45:01 3395 8192000000 10242094816 10648207360 1149202208 12.302 100 78 150 2 1355283488 188508256 17573024
02:46:11 995 8192000000 10245076064 10649460736 1157228528 12.377 100 94 180 0 1359225344 181701232 20295584
02:47:16 1001 8192000000 10302317264 10714099712 1173935040 12.534 100 99 186 0 1417276192 221679584 21661568
02:48:21 854 8192000000 10330234176 10731823104 1171290800 12.509 100 98 191 2 1438508080 245588464 21628816
02:49:24 2667 8192000000 10363066880 10778836992 1179086576 12.582 100 85 170 0 1468591872 269209040 20296256
02:50:31 1736 8192000000 10361627168 10770804736 1181541856 12.605 100 92 179 2 1469125696 267539840 20044000
02:51:36 2306 8192000000 10383682192 10806992896 1190000512 12.683 100 89 173 0 1497034224 287062784 19970928
02:52:42 1224 8192000000 10396212016 10801106944 1175391040 12.547 100 93 180 0 1502618912 305816032 21411840
02:53:44 1208 8192000000 10391359152 10830528512 1174953152 12.543 100 90 177 2 1502165216 305292128 21919936
02:54:49 3587 8192000000 10376862880 10828267520 1154492864 12.352 99 68 136 0 1475887216 305209632 16184720
02:55:56 8567 8192000000 10334988352 10795683840 1168379584 12.482 100 37 74 0 1441044448 263200736 9464128
02:57:06 18310 8192000000 10342737792 10793328640 1160102304 12.404 100 34 68 0 1446348112 277917680 8328128
02:58:36 6811 8192000000 10323852976 10766233600 1167848720 12.477 100 53 103 1 1431386816 251645696 11892400
02:59:46 831 8192000000 10362491808 10776264704 1165218368 12.452 100 96 184 0 1467408848 279704704 22485776
03:00:49 882 8192000000 10336756800 10760159232 1145304320 12.265 99 93 178 0 1438982624 272996048 20682256
03:01:51 2621 8192000000 10322445744 10749665280 1148444496 12.295 100 67 130 0 1434290288 270200352 15645440
03:02:55 12278 8192000000 10283051904 10773688320 1140746336 12.223 100 40 80 2 1424742528 274111088 9885104
03:04:09 50956 8192000000 10278208176 10732650496 1134267872 12.162 99 33 66 2 1401115264 259215104 7632288
03:06:03 6074 8192000000 10264886608 10711224320 1133241872 12.152 100 51 100 2 1364375520 220343120 10790528
03:07:10 8336 8192000000 10252536944 10698354688 1134408880 12.163 100 60 118 1 1360249008 212049744 13790384
03:08:20 5328 8192000000 10218954336 10686443520 1145450912 12.267 99 45 85 0 1329034832 173698384 9885536
03:09:31 14578 8192000000 10234871344 10674692096 1152437184 12.332 100 29 56 0 1335534432 176058768 7038480
03:10:47 54162 8192000000 10105837744 10613788672 1135624112 12.174 99 10 20 0 1207426224 69742080 2060032
03:12:41 36630 8192000000 10082648880 10501509120 1138200800 12.199 99 44 77 2 1170868928 24114512 8553616
03:14:19 36993 8192000000 10093870304 10553978880 1113362672 11.964 97 19 38 0 1179767984 62012176 4393136
03:15:59 32956 8192000000 10143004416 10563473408 1128479376 12.107 99 55 109 0 1222934304 82472400 11982528
03:17:34 38504 8192000000 10066992256 10594709504 1127236960 12.095 100 13 26 0 1242392096 111720656 3434480
03:19:14 92227 8192000000 10064748336 10498437120 1103815712 11.874 97 25 49 0 1124060848 14561840 5683296
03:21:47 110235 8192000000 10112789344 10553520128 1106186672 11.896 97 45 88 4 1176517392 61027264 9303456
03:24:49 36034 8192000000 10102215232 10545057792 1096215952 11.802 97 33 68 1 1172944448 69489040 7239456
03:26:46 134472 8192000000 10092846000 10543833088 1116950000 11.998 98 15 30 2 1195083312 74354240 3779072
03:31:10 103274 8192000000 10101505328 10576523264 1133722672 12.156 100 19 38 1 1191127552 53202272 4202608
03:34:25 50431 8192000000 10134081152 10607525888 1112416960 11.955 98 15 28 0 1194856464 79479408 2960096
03:36:21 63300 8192000000 10093100240 10543788032 1108436448 11.918 98 13 25 0 1198826816 87719744 2670624
03:38:26 70972 8192000000 10113204352 10557292544 1089276032 11.736 95 17 34 0 1168650976 75991408 3383536
03:40:42 27123 8192000000 10073063088 10541707264 1121203936 12.038 99 40 77 1 1158882512 28449152 9229424
03:42:09 25936 8192000000 10125295344 10550829056 1113996688 11.970 99 37 74 0 1194909024 73410512 7501824
03:43:36 75487 8192000000 10089406512 10573504512 1122843504 12.054 99 19 38 0 1194877760 67877552 4156704
03:45:54 12521 8192000000 10113320032 10540118016 1130014768 12.122 99 61 121 4 1187631312 45617952 11998592
03:47:11 33204 8192000000 10128353696 10614956032 1112381024 11.955 98 47 92 2 1225958288 103692000 9885264
03:48:44 21590 8192000000 10125391536 10562027520 1127989488 12.102 100 51 101 3 1199766880 62266688 9510704
03:50:12 103137 8192000000 10099551568 10556002304 1120945728 12.036 99 34 68 0 1188426384 60520880 6959776
03:53:24 42631 8192000000 10032097824 10503307264 1095714624 11.797 98 11 22 0 1136108752 38272368 2121760
03:55:12 58578 8192000000 10051147664 10503815168 1121905152 12.045 100 20 40 0 1165550064 39627344 4017568
03:57:16 49949 8192000000 10060083200 10509418496 1088663824 11.730 97 17 34 0 1136084848 43931984 3489040
03:59:09 48367 8192000000 10087870224 10545545216 1109456864 11.927 99 31 58 0 1183627040 68524192 5645984
04:00:58 64555 8192000000 10090689744 10556567552 1126803120 12.091 100 14 28 0 1190923600 60463616 3656864
04:03:15 26131 8192000000 10077932608 10510274560 1120166768 12.029 99 23 43 0 1157970000 33691120 4112112
04:04:42 25635 8192000000 10093555328 10548645888 1102634128 11.863 98 45 90 0 1188379584 76423520 9321936
04:06:09 34772 8192000000 10120418112 10560126976 1113989024 11.970 98 32 64 0 1205832176 85316976 6526176
04:07:47 35979 8192000000 10160541600 10594111488 1131034784 12.131 100 34 68 1 1249164448 110440640 7689024
04:09:44 27282 8192000000 10152958432 10587021312 1128640736 12.109 99 32 64 1 1237375088 102109424 6624928
04:11:12 41585 8192000000 10054693952 10492116992 1093189008 11.773 97 38 73 1 1128572912 26718528 8665376
04:12:55 11326 8192000000 10069550016 10490769408 1103819152 11.874 99 60 119 0 1161347888 45095648 12433088
04:14:11 97811 8192000000 10045951440 10522435584 1113863344 11.969 100 37 74 0 1197469904 75869248 7737312
04:16:54 54681 8192000000 10059063312 10514255872 1127633616 12.099 100 11 22 0 1166407360 36627712 2146032
04:19:30 118103 8192000000 10083248576 10528960512 1057336704 11.431 93 12 24 1 1111162096 51463200 2362192
04:22:29 64894 8192000000 10113550720 10560471040 1109789296 11.930 98 26 51 1 1184422768 68612512 6020960
04:24:40 19966 8192000000 10112874960 10553987072 1136463072 12.182 100 27 54 0 1218068368 75538880 6066416
04:26:11 19714 8192000000 10174606608 10611851264 1146139616 12.273 99 44 88 2 1264067104 108439472 9488016
04:27:31 31965 8192000000 10202869056 10639773696 1158422400 12.388 100 40 78 1 1281910032 114571936 8915696
04:29:05 47246 8192000000 10225652336 10671206400 1123152128 12.057 97 27 54 0 1290505584 161181584 6171872
04:30:56 58724 8192000000 10271400880 10735251456 1121431456 12.041 98 26 52 1 1359398672 231070784 6896432
04:32:56 88986 8192000000 10070307904 10604019712 1126986832 12.093 100 14 28 0 1233905664 103663776 3255056
04:35:25 109123 8192000000 10029838800 10514374656 1038488224 11.250 93 14 28 0 1094437296 52767696 3181376

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 04:38:52 to 31-03-2022 04:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 104 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 31-03-2022 04:39:09 to 31-03-2022 04:39:11.

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          228977
MON$OLDEST_ACTIVE               229339
MON$OLDEST_SNAPSHOT             229339
MON$NEXT_TRANSACTION            229339
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                       2077152
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             794
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 ---
:249: 
:250: mscs-test-161.budzdorov.ru    Thu Mar 31 04:37:18 2022
:251:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:252: 
:253: 
:254: mscs-test-161.budzdorov.ru    Thu Mar 31 04:37:18 2022
:255:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:256: 
:257: 
:258: mscs-test-161.budzdorov.ru    Thu Mar 31 04:37:19 2022
:259:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:260: 
--- end of diff output ---

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

31.03.22 04:39:12. 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 04:39:15 No severe PSQL-related problems occured
31.03.22 04:39:16. End of report.