Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.06.2022 04:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15938172 kB
MemAvailable 31219528 kB
Buffers 20 kB
Cached 15678232 kB
SwapCached 2452 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-06-14 04:37:18
DTS_END                         2022-06-14 04:37:18.8490
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/59034
TRN_ID                          215684
ATT_ID                          578
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: 8926.83 29601 1071220
customer order: creation 167.79 473 20135
customer order: refuse 15.28 482 1834
order to supplier: creation 44.58 1546 5349
order to supplier: removal 3.52 159997 422
invoice (draft): creation 45.55 3354 5466
invoice (draft): removal 49.28 1019 5914
invoice accept: apply 41.62 28805 4994
invoice accept: cancel 44.72 1444 5366
customer reserve: creation 3867.10 271 464052
customer reserve: removal 4358.61 102 523033
realization accept: apply 83.41 310 10009
realization accept: cancel 76.64 190 9197
payment from customer: creation 58.37 189 7004
payment from customer: removal 5.18 122 621
payment to supplier: creation 48.68 207 5842
payment to supplier: removal 3.60 134 432
service: total inventory turnovers 3.73 4823 448
service: total monetary turnovers 8.11 415 973
service: aggregate perf. data 1.03 15175 124
service: refresh index statistics 0.04 372968 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-06-2022 04:38:02 to 14-06-2022 04:38:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5530
TEST_TIME 2 4417
TEST_TIME 3 5275
TEST_TIME 4 5639
TEST_TIME 5 5454
TEST_TIME 6 5467
TEST_TIME 7 5718
TEST_TIME 8 6094
TEST_TIME 9 5950
TEST_TIME 10 6050
TEST_TIME 11 6230
TEST_TIME 12 6244
TEST_TIME 13 6450
TEST_TIME 14 6366
TEST_TIME 15 6443
TEST_TIME 16 6453
TEST_TIME 17 6499
TEST_TIME 18 6610
TEST_TIME 19 6721
TEST_TIME 20 6718
TEST_TIME 21 7098
TEST_TIME 22 7168
TEST_TIME 23 7515
TEST_TIME 24 7632
TEST_TIME 25 7721
TEST_TIME 26 7899
TEST_TIME 27 8045
TEST_TIME 28 8516
TEST_TIME 29 8709
TEST_TIME 30 8884
TEST_TIME 31 9074
TEST_TIME 32 9277
TEST_TIME 33 9233
TEST_TIME 34 9578
TEST_TIME 35 10015
TEST_TIME 36 10384
TEST_TIME 37 10594
TEST_TIME 38 10435
TEST_TIME 39 10322
TEST_TIME 40 10242
TEST_TIME 41 10042
TEST_TIME 42 10146
TEST_TIME 43 10035
TEST_TIME 44 10028
TEST_TIME 45 9846
TEST_TIME 46 9812
TEST_TIME 47 9810
TEST_TIME 48 9678
TEST_TIME 49 9550
TEST_TIME 50 9629
TEST_TIME 51 9555
TEST_TIME 52 9626
TEST_TIME 53 9536
TEST_TIME 54 9445
TEST_TIME 55 9475
TEST_TIME 56 9557
TEST_TIME 57 9670
TEST_TIME 58 9723
TEST_TIME 59 9634
TEST_TIME 60 9600
TEST_TIME 61 9490
TEST_TIME 62 9418
TEST_TIME 63 9423
TEST_TIME 64 9389
TEST_TIME 65 9316
TEST_TIME 66 9355
TEST_TIME 67 9362
TEST_TIME 68 9352
TEST_TIME 69 9356
TEST_TIME 70 9358
TEST_TIME 71 9410
TEST_TIME 72 9418
TEST_TIME 73 9371
TEST_TIME 74 9526
TEST_TIME 75 9511
TEST_TIME 76 9436
TEST_TIME 77 9390
TEST_TIME 78 9348
TEST_TIME 79 9265
TEST_TIME 80 9227
TEST_TIME 81 9218
TEST_TIME 82 9168
TEST_TIME 83 9169
TEST_TIME 84 9117
TEST_TIME 85 9099
TEST_TIME 86 9142
TEST_TIME 87 9137
TEST_TIME 88 9125
TEST_TIME 89 9132
TEST_TIME 90 9144
TEST_TIME 91 9172
TEST_TIME 92 9200
TEST_TIME 93 9199
TEST_TIME 94 9191
TEST_TIME 95 9248
TEST_TIME 96 9205
TEST_TIME 97 9278
TEST_TIME 98 9227
TEST_TIME 99 9152
TEST_TIME 100 9104
TEST_TIME 101 9106
TEST_TIME 102 9057
TEST_TIME 103 9064
TEST_TIME 104 9016
TEST_TIME 105 8971
TEST_TIME 106 8950
TEST_TIME 107 8911
TEST_TIME 108 8922
TEST_TIME 109 8894
TEST_TIME 110 8889
TEST_TIME 111 8869
TEST_TIME 112 8924
TEST_TIME 113 8912
TEST_TIME 114 8948
TEST_TIME 115 8938
TEST_TIME 116 8914
TEST_TIME 117 8958
TEST_TIME 118 9001
TEST_TIME 119 8992
TEST_TIME 120 8947

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-06-2022 04:38:06 to 14-06-2022 04:38:09.

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 1498186 1498186 0 0.00 0 21232 15 0 0
doc_list_biud 1498171 1498171 0 0.00 0 42014 42 0 0
sp_add_invoice_to_stock 5116 4994 122 2.38 59 184587 28805 0 122
sp_cancel_adding_invoice 5366 5366 0 0.00 7 106943 1445 0 0
sp_cancel_client_order 1834 1834 0 0.00 6 13027 483 0 0
sp_cancel_customer_reserve 523033 523033 0 0.00 1 26048 102 0 0
sp_cancel_pay_from_customer 621 621 0 0.00 4 2478 123 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 3071 134 0 0
sp_cancel_supplier_invoice 5918 5914 4 0.06 3 42059 1019 0 4
sp_cancel_supplier_order 422 422 0 0.00 11 1655517 159997 0 0
sp_cancel_write_off 9197 9197 0 0.00 2 15844 190 0 0
sp_client_order 20135 20135 0 0.00 6 19837 473 0 0
sp_customer_reserve 464052 464052 0 0.00 1 110516 272 0 0
sp_fill_shopping_cart 30942 30942 0 0.00 2 9291 88 0 0
sp_fill_shopping_cart_clo_res 464021 464021 0 0.00 0 20324 20 0 0
sp_get_clo_for_invoice 4954 4954 0 0.00 0 23526 330 0 0
sp_kill_cost_storno 15616 15616 0 0.00 0 10152 86 0 0
sp_kill_qstorno_ret_qs2qd 1061599 1061599 0 0.00 0 42011 45 0 0
sp_kill_qty_storno 560728 560728 0 0.00 0 42013 104 0 0
sp_lock_selected_doc 20744 20744 0 0.00 0 5900 7 0 0
sp_make_cost_storno 27809 27809 0 0.00 0 14081 91 0 0
sp_multiply_rows_for_pdistr 27807 27807 0 0.00 0 9002 42 0 0
sp_multiply_rows_for_qdistr 20134 20134 0 0.00 0 15380 181 0 0
sp_pay_from_customer 7004 7004 0 0.00 5 14158 190 0 0
sp_pay_to_supplier 5842 5842 0 0.00 7 11101 207 0 0
sp_payment_common 12843 12843 0 0.00 2 10833 52 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 1 13019 398 0 0
sp_qd_handle_on_invoice_upd_sts 10319 10319 0 0.00 1 32035 595 0 0
sp_qd_handle_on_reserve_upd_sts 10008 10008 0 0.00 0 23253 80 0 0
sp_reserve_write_off 10009 10009 0 0.00 9 23299 311 0 0
sp_supplier_invoice 5466 5466 0 0.00 9 85695 3354 0 0
sp_supplier_order 5349 5349 0 0.00 8 117714 1546 0 0
srv_aggregate_perf_data 125 124 1 0.80 2623 233657 15176 0 1
srv_fill_mon_cache_memory 75 75 0 0.00 714 131164 31714 0 0
srv_find_qd_qs_mism 1022516 1022516 0 0.00 0 16637 12 0 0
srv_make_invnt_saldo 448 448 0 0.00 55 87422 4824 0 0
srv_make_money_saldo 973 973 0 0.00 1 23664 415 0 0
srv_recalc_idx_stat 5 5 0 0.00 248294 508410 372968 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4997 4997 0 0.00 0 13727 1060 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 2 6824 538 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 4282 38 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 885 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 621 621 0 0.00 0 358 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 1765 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 4043 52 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 116563 7228 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 4346 29 0 0
t$perf-norm:sp_client_order 20139 20139 0 0.00 0 6705 42 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 4614 22 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 4393 21 0 0
t$perf-norm:sp_pay_to_supplier 5842 5842 0 0.00 0 4393 22 0 0
t$perf-norm:sp_reserve_write_off 10009 10009 0 0.00 0 3994 24 0 0
t$perf-norm:sp_supplier_invoice 5466 5466 0 0.00 0 5304 35 0 0
t$perf-norm:sp_supplier_order 5349 5349 0 0.00 0 4499 36 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 592 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 2889 53 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 2004 19 0 0
t$perf-norm:srv_make_money_saldo 973 973 0 0.00 0 4658 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 221 113 0 0
v_add_invoice_to_stock 10144 9900 244 2.40 0 12349 30 0 244
v_all_customers 37182 37182 0 0.00 0 85 4 0 0
v_all_suppliers 30240 30240 0 0.00 0 223 5 0 0
v_all_wares 40260 40260 0 0.00 0 97 4 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 3052 20 0 0
v_cancel_client_order 3668 3668 0 0.00 0 3268 13 0 0
v_cancel_customer_prepayment 1242 1242 0 0.00 0 565 11 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 808 11 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 2318 17 0 0
v_cancel_supplier_invoice 738 730 8 1.08 0 399 22 0 8
v_cancel_supplier_order 844 844 0 0.00 0 1822 11 0 0
v_cancel_write_off 1924 1924 0 0.00 0 513 24 0 0
v_max_id_clo_ord 5345 5345 0 0.00 0 6736 7 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 1736 14 0 0
v_max_id_ord_sup 5465 5465 0 0.00 0 5656 8 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 270 8 0 0
v_max_non_paid_realizn 3499 3499 0 0.00 0 5239 10 0 0
v_min_id_clo_ord 5345 5345 0 0.00 0 5017 13 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 4178 15 0 0
v_min_id_ord_sup 5465 5465 0 0.00 0 4719 9 0 0
v_min_non_paid_invoice 5841 5841 0 0.00 0 4899 21 0 0
v_min_non_paid_realizn 7002 7002 0 0.00 0 6063 19 0 0
v_our_firm 10084 10084 0 0.00 0 62 5 0 0
v_reserve_write_off 20016 20016 0 0.00 0 3331 11 0 0
x_lock_depdocs_on_canc_invoice 10915 10915 0 0.00 0 16800 131 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 4291 341 0 0
x_make_qstorno_1000_1200 5349 5349 0 0.00 3 115576 1345 0 0
x_make_qstorno_1200_2000 5466 5466 0 0.00 5 84147 3137 0 0
x_make_qstorno_2100_3300 464052 464052 0 0.00 0 110483 238 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:22 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 32202 8192000000 9713155424 10007732224 785450576 8.749 100 92 184 0 816324336 7672096 23201664
02:10:09 28245 8192000000 9754684800 10060189696 824400288 9.143 100 92 184 0 860050944 13311440 22339216
02:11:42 13516 8192000000 9797757328 10118049792 864078400 9.541 100 74 148 1 904204304 23083456 17042448
02:12:58 14523 8192000000 9872133872 10206040064 918537216 10.082 100 78 153 1 979703296 44746432 16419648
02:14:17 5854 8192000000 9920506016 10268991488 955470208 10.445 100 88 173 1 1039135680 64850064 18815408
02:15:28 2433 8192000000 9963030880 10317225984 995755808 10.837 100 87 170 1 1075909104 62772160 17381136
02:16:33 1147 8192000000 9994549728 10353463296 1019028368 11.063 100 97 193 0 1113464368 74635808 19800192
02:17:37 1516 8192000000 10030854416 10400247808 1044390384 11.307 100 92 171 1 1148554016 87514784 16648848
02:18:41 698 8192000000 10046739456 10417709056 1057659360 11.434 100 96 176 1 1165948240 90678336 17610544
02:19:45 1014 8192000000 10042509312 10421829632 1062127680 11.477 100 92 182 1 1165768528 85401344 18239504
02:20:47 1773 8192000000 10045727744 10424881152 1068229280 11.535 100 96 185 2 1168135200 80329248 19576672
02:21:52 1011 8192000000 10051997776 10436521984 1074578752 11.596 100 91 180 0 1173766000 81142240 18045008
02:22:54 936 8192000000 10067356704 10452860928 1076530912 11.614 100 98 191 0 1188647616 92287376 19829328
02:23:56 1388 8192000000 10050225472 10432073728 1078716960 11.635 100 97 191 1 1168990768 70215472 20058336
02:25:01 1200 8192000000 10059348384 10442993664 1082999936 11.676 100 95 187 0 1175249552 72782160 19467456
02:26:03 1174 8192000000 10033390192 10416017408 1073679440 11.587 99 93 179 0 1156726128 65217584 17829104
02:27:05 1273 8192000000 10063786656 10451439616 1084951792 11.695 100 95 191 2 1184971328 80324592 19694944
02:28:08 1401 8192000000 10061612736 10456395776 1094552784 11.786 100 97 193 0 1186807776 73093168 19161824
02:29:11 1810 8192000000 10064280752 10454876160 1093169488 11.773 100 87 172 1 1187484720 75931088 18384144
02:30:17 1005 8192000000 10053785536 10440536064 1079354080 11.641 100 93 184 1 1177299280 80145536 17799664
02:31:19 1084 8192000000 10031469920 10412883968 1069307808 11.545 100 96 187 2 1156915808 68508416 19099584
02:32:22 1026 8192000000 10029292304 10412855296 1067732656 11.530 99 83 161 2 1147429472 64027904 15668912
02:33:25 1322 8192000000 10026824160 10412929024 1065051008 11.505 100 84 157 3 1158322464 76800912 16470544
02:34:29 1314 8192000000 10003511264 10381611008 1052072112 11.381 100 98 187 0 1126565152 55368960 19124080
02:35:31 1658 8192000000 10002514864 10376839168 1044261680 11.306 100 98 187 4 1127148848 63353552 19533616
02:36:35 1283 8192000000 10002286624 10379268096 1054563600 11.404 100 94 184 2 1120321072 47794240 17963232
02:37:45 4129 8192000000 9974252624 10355326976 1042550256 11.289 100 69 136 2 1100485328 44318352 13616720
02:38:54 4144 8192000000 10020720992 10405122048 1060948064 11.466 100 67 129 1 1140538112 66574432 13015616
02:40:04 5875 8192000000 10038613424 10431500288 1072302224 11.574 100 72 140 1 1162501776 74677088 15522464
02:41:20 4777 8192000000 10043011744 10437365760 1084918608 11.694 100 66 123 0 1162047248 64074608 13054032
02:42:31 1740 8192000000 10084643120 10471391232 1094095024 11.782 100 89 165 0 1205615520 94479456 17041040
02:43:36 1277 8192000000 10149132032 10534715392 1109868960 11.931 100 95 183 0 1271631680 140099088 21663632
02:44:41 1960 8192000000 10170482848 10570133504 1118589376 12.014 100 78 150 0 1286915200 152496768 15829056
02:45:47 2798 8192000000 10197401168 10600456192 1128769264 12.110 100 94 181 1 1316251152 168241712 19240176
02:47:01 2271 8192000000 10244814528 10646802432 1149964672 12.309 100 85 164 1 1362878960 194937344 17976944
02:48:12 2416 8192000000 10262142272 10667798528 1161923760 12.421 100 80 148 0 1376298512 199351664 15023088
02:49:19 986 8192000000 10293730992 10694549504 1166946336 12.468 100 92 174 0 1406314608 220058288 19309984
02:50:26 2454 8192000000 10326050384 10742775808 1176634384 12.559 100 89 172 1 1435389296 240488592 18266320
02:51:31 3344 8192000000 10353146672 10758004736 1186551376 12.651 100 91 172 2 1465162848 258676576 19934896
02:52:39 901 8192000000 10403716624 10810744832 1185189600 12.639 100 94 181 0 1521162016 314775328 21197088
02:53:42 1079 8192000000 10415212336 10819325952 1188865952 12.673 100 95 185 1 1522727328 313347920 20513456
02:54:47 706 8192000000 10447208464 10848948224 1174026208 12.534 99 97 192 1 1558495168 361542880 22926080
02:55:50 773 8192000000 10473324592 10892115968 1186595872 12.652 100 98 187 0 1579971888 371522688 21853328
02:56:55 1419 8192000000 10504022848 10919198720 1180559408 12.595 100 91 180 0 1605436464 403237904 21639152
02:57:59 1149 8192000000 10543692960 10983940096 1198049600 12.758 100 97 194 0 1649811872 428833616 22928656
02:59:03 813 8192000000 10545371840 10981498880 1204127664 12.815 100 97 191 2 1650453136 424664608 21660864
03:00:06 803 8192000000 10534875024 10993672192 1187135392 12.657 100 88 174 0 1640099520 433015200 19948928
03:01:09 2848 8192000000 10546447216 10996854784 1196712416 12.746 100 79 147 2 1647759728 432171184 18876128
03:02:14 1981 8192000000 10561152560 10989166592 1194848064 12.728 100 66 125 1 1674556560 465069648 14638848
03:03:21 840 8192000000 10515505344 10994831360 1168610992 12.484 100 88 168 3 1626835824 437519296 20705536
03:04:26 1458 8192000000 10510815088 10995494912 1170847888 12.505 100 95 191 1 1613540736 420741968 21950880
03:05:29 2875 8192000000 10522333808 10989600768 1171730128 12.513 100 79 156 2 1624045312 434147168 18168016
03:06:39 5186 8192000000 10425860400 10879565824 1158678816 12.391 100 49 96 1 1529038160 358499680 11859664
03:08:08 6536 8192000000 10435200016 10860113920 1162992288 12.431 100 58 114 1 1546063232 369164096 13906848
03:09:16 11878 8192000000 10401442272 10869092352 1140766256 12.223 99 30 60 2 1526604256 378115728 7722272
03:10:48 18530 8192000000 10371107792 10880389120 1139611056 12.212 100 22 44 2 1491722544 346213936 5897552
03:12:21 32222 8192000000 10201148608 10708709376 1131957936 12.140 100 20 40 0 1339060768 202057792 5045040
03:13:54 57577 8192000000 10057631936 10544099328 1114742672 11.977 99 6 12 0 1185990944 69680624 1567648
03:15:57 4713 8192000000 10057711792 10484310016 1125542848 12.079 100 66 116 1 1153620000 14892000 13185152
03:17:08 50213 8192000000 10123652080 10601062400 1129297168 12.115 100 12 24 0 1212018048 79762752 2958128
03:19:21 68147 8192000000 10103400160 10567618560 1125787552 12.082 99 32 62 1 1187060768 54203872 7069344
03:21:35 78208 8192000000 10079114672 10522279936 1132332160 12.143 100 44 84 1 1174175680 32041888 9801632
03:24:03 31103 8192000000 10118926176 10556100608 1137822816 12.195 100 36 61 0 1221684576 77691104 6170656
03:25:44 60322 8192000000 10125611264 10549800960 1137499328 12.192 100 50 95 2 1202138944 53989456 10650160
03:27:46 7094 8192000000 10172513776 10606338048 1159828672 12.402 100 56 109 2 1280964832 109025232 12110928
03:28:59 15784 8192000000 10225768992 10682171392 1162498384 12.427 100 48 96 2 1309876848 136973968 10404496
03:30:28 18510 8192000000 10179987520 10673496064 1141259056 12.227 98 21 41 0 1268487408 122308928 4919424
03:31:55 72164 8192000000 10171492976 10681970688 1140722992 12.222 98 22 45 0 1300068192 153817056 5528144
03:34:10 41625 8192000000 10145178896 10650562560 1156470640 12.370 100 11 22 0 1253376352 94257376 2648336
03:35:52 59656 8192000000 10129206864 10642124800 1160387520 12.407 100 14 28 1 1232948512 69440928 3120064
03:37:54 87841 8192000000 10109657632 10610642944 1123745568 12.062 97 11 22 0 1186584896 60711200 2128128
03:40:26 65971 8192000000 10069968368 10520006656 1126164176 12.085 99 16 30 1 1162954512 34195200 2595136
03:42:37 30149 8192000000 10104238992 10548350976 1124330528 12.068 98 44 86 1 1180361760 46922688 9108544
03:44:10 42511 8192000000 10121150736 10590220288 1142623184 12.240 100 27 54 1 1223583872 74539056 6421632
03:45:53 51170 8192000000 10125284352 10571603968 1131608752 12.137 99 26 51 0 1210001648 73349024 5043872
03:47:45 57365 8192000000 10115145824 10584252416 1109980752 11.932 97 29 58 2 1216716000 100735616 5999632
03:49:49 18631 8192000000 10080739568 10513813504 1118664448 12.014 99 53 100 0 1168064752 39090160 10310144
03:51:09 61901 8192000000 10097681248 10622918656 1113756896 11.968 98 23 46 1 1233498672 114546320 5195456
03:53:13 9897 8192000000 10087712544 10510946304 1137932112 12.196 100 60 120 4 1176861920 24907840 14021968
03:54:26 128460 8192000000 10049763056 10489356288 1091985152 11.762 96 23 46 1 1133930656 37009184 4936320
03:57:35 53397 8192000000 10083980064 10543775744 1106803504 11.902 98 13 26 0 1165312352 55996704 2512144
03:59:31 59240 8192000000 10111011632 10579722240 1129699888 12.119 99 17 32 2 1196577168 63848496 3028784
04:01:31 68146 8192000000 10115359168 10579001344 1131819504 12.139 100 24 47 1 1198333488 61767488 4746496
04:03:40 41036 8192000000 10067724512 10497982464 1085159504 11.697 96 28 54 2 1128502304 37567408 5775392
04:05:21 52667 8192000000 10106509840 10543951872 1126728896 12.091 99 30 60 1 1190936432 57870240 6337296
04:07:20 109960 8192000000 10097338704 10572632064 1088705520 11.730 96 18 36 2 1123628128 31080256 3842352
04:10:11 30676 8192000000 10082496064 10543636480 1132266224 12.143 100 41 81 1 1192719760 52271472 8182064
04:11:42 56801 8192000000 10146101440 10614722560 1130339312 12.125 99 28 56 1 1240315392 104063296 5912784
04:13:41 16458 8192000000 10128270496 10558672896 1131685024 12.137 99 49 94 2 1210747808 69000432 10062352
04:15:04 131160 8192000000 10104513856 10536886272 1148385120 12.294 100 58 106 1 1195578896 36965040 10228736
04:18:17 77789 8192000000 10118654448 10581438464 1131162912 12.132 98 15 30 0 1199527456 65241936 3122608
04:20:35 35587 8192000000 10058744000 10552025088 1116055872 11.990 98 11 23 1 1148494304 30178304 2260128
04:22:11 62376 8192000000 10091058624 10552315904 1127976864 12.102 99 20 40 0 1181673088 49372400 4323824
04:24:16 83238 8192000000 10074596896 10536091648 1118990112 12.017 98 19 36 2 1166420800 43954752 3475936
04:26:39 49685 8192000000 10081519968 10553737216 1129689072 12.118 99 17 33 0 1178064944 45051200 3324672
04:28:35 46760 8192000000 10113788656 10576375808 1119760624 12.025 98 39 78 1 1211665776 83870400 8034752
04:30:22 32828 8192000000 10126505248 10582978560 1145623520 12.268 100 37 72 0 1223212560 69896384 7692656
04:32:20 31587 8192000000 10125803056 10554695680 1131482400 12.135 99 43 86 1 1193064656 53228256 8354000
04:33:56 51347 8192000000 10142575760 10633900032 1130215664 12.123 99 45 90 1 1242977792 102838144 9923984
04:35:48 7224 8192000000 10070774256 10503155712 1121632656 12.042 99 52 100 2 1167282832 35150192 10499984
04:36:57 31119 8192000000 9012513792 9256783872 166263216 1.989 15 3 6 0 209396160 42603488 529456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-06-2022 04:38:16 to 14-06-2022 04:38:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 122 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-06-2022 04:38:33 to 14-06-2022 04:38:35.

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          218740
MON$OLDEST_ACTIVE               218741
MON$OLDEST_SNAPSHOT             218741
MON$NEXT_TRANSACTION            218741
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                       2066016
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2776 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2776 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2776 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2776 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 ---
:185: 
:186: mscs-test-161.budzdorov.ru    Tue Jun 14 04:37:36 2022
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:188: 
:189: 
:190: mscs-test-161.budzdorov.ru    Tue Jun 14 04:37:36 2022
:191:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:192: 
--- end of diff output ---

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

14.06.22 04:38:36. 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-06-14 04:38:39 No severe PSQL-related problems occured
14.06.22 04:38:40. End of report.