Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.04.2022 13:40
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 16274200 kB
MemAvailable 31565036 kB
Buffers 0 kB
Cached 15462948 kB
SwapCached 2336 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-04-11 13:37:16
DTS_END                         2022-04-11 13:37:16.3020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(526:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48036
TRN_ID                          217445
ATT_ID                          633
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: 8741.97 32674 1049036
customer order: creation 159.97 463 19196
customer order: refuse 13.91 401 1669
order to supplier: creation 43.87 1328 5264
order to supplier: removal 3.38 141101 406
invoice (draft): creation 43.79 3524 5255
invoice (draft): removal 48.34 929 5801
invoice accept: apply 41.12 26390 4934
invoice accept: cancel 43.57 1179 5228
customer reserve: creation 3802.34 252 456281
customer reserve: removal 4266.91 88 512029
realization accept: apply 77.87 274 9344
realization accept: cancel 72.49 146 8699
payment from customer: creation 56.93 178 6831
payment from customer: removal 5.41 123 649
payment to supplier: creation 46.56 211 5587
payment to supplier: removal 3.52 111 422
service: total inventory turnovers 3.83 3929 460
service: total monetary turnovers 7.35 300 882
service: aggregate perf. data 0.79 18170 95
service: refresh index statistics 0.03 454388 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-04-2022 13:40:29 to 11-04-2022 13:40:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6045
TEST_TIME 2 6020
TEST_TIME 3 6675
TEST_TIME 4 7805
TEST_TIME 5 7328
TEST_TIME 6 7284
TEST_TIME 7 6894
TEST_TIME 8 6961
TEST_TIME 9 6896
TEST_TIME 10 6822
TEST_TIME 11 7058
TEST_TIME 12 7036
TEST_TIME 13 7103
TEST_TIME 14 7376
TEST_TIME 15 7721
TEST_TIME 16 7979
TEST_TIME 17 8176
TEST_TIME 18 8184
TEST_TIME 19 8189
TEST_TIME 20 8451
TEST_TIME 21 8499
TEST_TIME 22 8778
TEST_TIME 23 9024
TEST_TIME 24 9463
TEST_TIME 25 9285
TEST_TIME 26 9140
TEST_TIME 27 9357
TEST_TIME 28 10184
TEST_TIME 29 10302
TEST_TIME 30 10468
TEST_TIME 31 10530
TEST_TIME 32 10800
TEST_TIME 33 10914
TEST_TIME 34 10912
TEST_TIME 35 10736
TEST_TIME 36 10784
TEST_TIME 37 11293
TEST_TIME 38 11279
TEST_TIME 39 11050
TEST_TIME 40 10836
TEST_TIME 41 10931
TEST_TIME 42 10773
TEST_TIME 43 10665
TEST_TIME 44 10520
TEST_TIME 45 10403
TEST_TIME 46 10376
TEST_TIME 47 10281
TEST_TIME 48 10123
TEST_TIME 49 9956
TEST_TIME 50 9891
TEST_TIME 51 9799
TEST_TIME 52 9691
TEST_TIME 53 9606
TEST_TIME 54 9567
TEST_TIME 55 9536
TEST_TIME 56 9510
TEST_TIME 57 9399
TEST_TIME 58 9413
TEST_TIME 59 9352
TEST_TIME 60 9300
TEST_TIME 61 9223
TEST_TIME 62 9222
TEST_TIME 63 9162
TEST_TIME 64 9116
TEST_TIME 65 9126
TEST_TIME 66 9144
TEST_TIME 67 9085
TEST_TIME 68 9097
TEST_TIME 69 9184
TEST_TIME 70 9290
TEST_TIME 71 9288
TEST_TIME 72 9322
TEST_TIME 73 9282
TEST_TIME 74 9360
TEST_TIME 75 9359
TEST_TIME 76 9324
TEST_TIME 77 9224
TEST_TIME 78 9194
TEST_TIME 79 9162
TEST_TIME 80 9107
TEST_TIME 81 9117
TEST_TIME 82 9043
TEST_TIME 83 9057
TEST_TIME 84 8985
TEST_TIME 85 8932
TEST_TIME 86 8951
TEST_TIME 87 8911
TEST_TIME 88 8915
TEST_TIME 89 8942
TEST_TIME 90 8937
TEST_TIME 91 8884
TEST_TIME 92 8898
TEST_TIME 93 8977
TEST_TIME 94 8985
TEST_TIME 95 8973
TEST_TIME 96 9053
TEST_TIME 97 9114
TEST_TIME 98 9106
TEST_TIME 99 9061
TEST_TIME 100 8994
TEST_TIME 101 8965
TEST_TIME 102 8959
TEST_TIME 103 8923
TEST_TIME 104 8874
TEST_TIME 105 8834
TEST_TIME 106 8810
TEST_TIME 107 8853
TEST_TIME 108 8842
TEST_TIME 109 8817
TEST_TIME 110 8790
TEST_TIME 111 8818
TEST_TIME 112 8855
TEST_TIME 113 8850
TEST_TIME 114 8854
TEST_TIME 115 8858
TEST_TIME 116 8890
TEST_TIME 117 8882
TEST_TIME 118 8878
TEST_TIME 119 8813
TEST_TIME 120 8762

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-04-2022 13:40:33 to 11-04-2022 13:40:36.

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 1468007 1468007 0 0.00 0 13717 13 0 0
doc_list_biud 1467998 1467998 0 0.00 0 45443 37 0 0
sp_add_invoice_to_stock 5041 4934 107 2.12 29 212200 26391 0 107
sp_cancel_adding_invoice 5228 5228 0 0.00 5 69466 1179 0 0
sp_cancel_client_order 1669 1669 0 0.00 5 10700 401 0 0
sp_cancel_customer_reserve 512029 512029 0 0.00 1 44065 88 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 4 4921 124 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 4 1002 112 0 0
sp_cancel_supplier_invoice 5804 5801 3 0.05 2 45455 929 0 3
sp_cancel_supplier_order 406 406 0 0.00 8 1247898 141101 0 0
sp_cancel_write_off 8699 8699 0 0.00 2 16991 147 0 0
sp_client_order 19196 19196 0 0.00 5 32515 464 0 0
sp_customer_reserve 456281 456281 0 0.00 1 68488 253 0 0
sp_fill_shopping_cart 29715 29715 0 0.00 1 28475 94 0 0
sp_fill_shopping_cart_clo_res 456255 456255 0 0.00 0 17396 20 0 0
sp_get_clo_for_invoice 4893 4893 0 0.00 0 24470 302 0 0
sp_kill_cost_storno 14998 14998 0 0.00 0 11767 68 0 0
sp_kill_qstorno_ret_qs2qd 1038964 1038964 0 0.00 0 45439 38 0 0
sp_kill_qty_storno 548069 548069 0 0.00 0 45441 89 0 0
sp_lock_selected_doc 19817 19817 0 0.00 0 5395 7 0 0
sp_make_cost_storno 26655 26655 0 0.00 0 10511 82 0 0
sp_multiply_rows_for_pdistr 26655 26655 0 0.00 0 12087 39 0 0
sp_multiply_rows_for_qdistr 19196 19196 0 0.00 0 26282 167 0 0
sp_pay_from_customer 6831 6831 0 0.00 5 15231 179 0 0
sp_pay_to_supplier 5587 5587 0 0.00 6 13103 212 0 0
sp_payment_common 12418 12418 0 0.00 2 11212 54 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 1 10692 328 0 0
sp_qd_handle_on_invoice_upd_sts 10121 10121 0 0.00 0 41753 520 0 0
sp_qd_handle_on_reserve_upd_sts 9344 9344 0 0.00 0 9509 64 0 0
sp_reserve_write_off 9344 9344 0 0.00 9 19218 275 0 0
sp_supplier_invoice 5255 5255 0 0.00 8 120827 3524 0 0
sp_supplier_order 5264 5264 0 0.00 9 31087 1329 0 0
srv_aggregate_perf_data 96 95 1 1.04 2030 143991 18171 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 824 141395 41177 0 0
srv_find_qd_qs_mism 1001300 1001300 0 0.00 0 13712 10 0 0
srv_make_invnt_saldo 460 460 0 0.00 56 81961 3930 0 0
srv_make_money_saldo 882 882 0 0.00 1 11818 300 0 0
srv_recalc_idx_stat 4 4 0 0.00 362262 619863 454389 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 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 4937 4937 0 0.00 1 17338 989 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 7216 501 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 3020 26 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 1140 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 1192 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 1071 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1090 36 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 118360 6247 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 5143 23 0 0
t$perf-norm:sp_client_order 19196 19196 0 0.00 0 14887 37 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 8023 29 0 0
t$perf-norm:sp_pay_from_customer 6831 6831 0 0.00 0 5282 19 0 0
t$perf-norm:sp_pay_to_supplier 5587 5587 0 0.00 0 5276 21 0 0
t$perf-norm:sp_reserve_write_off 9344 9344 0 0.00 0 11339 25 0 0
t$perf-norm:sp_supplier_invoice 5255 5255 0 0.00 0 5754 31 0 0
t$perf-norm:sp_supplier_order 5264 5264 0 0.00 0 3953 31 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 126 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 2745 55 0 0
t$perf-norm:srv_make_invnt_saldo 460 460 0 0.00 0 2406 15 0 0
t$perf-norm:srv_make_money_saldo 882 882 0 0.00 0 1039 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 183 50 0 0
v_add_invoice_to_stock 10000 9786 214 2.14 0 15184 27 0 214
v_all_customers 35136 35136 0 0.00 0 66 4 0 0
v_all_suppliers 29100 29100 0 0.00 0 75 4 0 0
v_all_wares 38392 38392 0 0.00 0 69 4 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 446 14 0 0
v_cancel_client_order 3338 3338 0 0.00 0 926 10 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 4886 17 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 1123 10 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 884 12 0 0
v_cancel_supplier_invoice 814 808 6 0.73 0 996 28 0 6
v_cancel_supplier_order 812 812 0 0.00 0 2191 13 0 0
v_cancel_write_off 1954 1954 0 0.00 0 16933 34 0 0
v_max_id_clo_ord 5263 5263 0 0.00 0 1316 5 0 0
v_max_id_clo_res 964 964 0 0.00 0 1327 12 0 0
v_max_id_ord_sup 5255 5255 0 0.00 0 7484 7 0 0
v_max_non_paid_invoice 1555 1555 0 0.00 0 108 8 0 0
v_max_non_paid_realizn 3578 3578 0 0.00 0 888 8 0 0
v_min_id_clo_ord 5263 5263 0 0.00 0 5120 10 0 0
v_min_id_clo_res 964 964 0 0.00 0 9448 31 0 0
v_min_id_ord_sup 5255 5255 0 0.00 0 5333 9 0 0
v_min_non_paid_invoice 5587 5587 0 0.00 0 10552 24 0 0
v_min_non_paid_realizn 6831 6831 0 0.00 0 11197 25 0 0
v_our_firm 9762 9762 0 0.00 0 67 4 0 0
v_reserve_write_off 18686 18686 0 0.00 0 16925 14 0 0
x_lock_depdocs_on_canc_invoice 10625 10625 0 0.00 0 12683 117 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 3226 287 0 0
x_make_qstorno_1000_1200 5264 5264 0 0.00 3 31074 1134 0 0
x_make_qstorno_1200_2000 5255 5255 0 0.00 4 120809 3261 0 0
x_make_qstorno_2100_3300 456281 456281 0 0.00 0 68485 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-04-2022 13:40:37 to 11-04-2022 13:40:41.

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
11:07:20 98 8192000000 8833710528 8858103808 24037280 0.292 11 3 2 0 24157104 84048 35776
11:08:21 40831 8192000000 9739731104 10041946112 814554768 9.044 100 85 170 1 842215904 7666304 19994832
11:10:12 12646 8192000000 9774632928 10085253120 841408624 9.314 100 88 176 1 881778352 19016096 21353632
11:11:29 18888 8192000000 9828116144 10152120320 887530704 9.775 100 83 166 0 932200080 26229600 18439776
11:13:02 12775 8192000000 9888244448 10226294784 927752544 10.173 100 80 160 1 988749968 43683728 17313696
11:14:18 3776 8192000000 9945174960 10293051392 973938816 10.625 100 95 188 0 1067383968 74209712 19235440
11:15:25 1724 8192000000 9970875536 10327027712 1002449968 10.902 100 90 176 0 1093909648 73031344 18428336
11:16:29 921 8192000000 10016112016 10380525568 1026993200 11.139 100 97 186 1 1130796064 85261808 18541056
11:17:32 1239 8192000000 10027056784 10399928320 1045613920 11.319 100 95 185 2 1144180320 80536080 18030320
11:18:34 1115 8192000000 10033017760 10409496576 1062258176 11.478 100 94 188 0 1154115392 73003120 18854096
11:19:35 1109 8192000000 10062250352 10444419072 1068624960 11.539 100 97 190 1 1181677408 94294400 18758048
11:20:38 1209 8192000000 10042672672 10425942016 1075512656 11.605 100 97 187 2 1163173216 68969440 18691120
11:21:39 1016 8192000000 10046266384 10435379200 1083291328 11.679 100 85 163 0 1170320272 68945072 18083872
11:22:42 1346 8192000000 10062532448 10450395136 1088058368 11.724 100 96 179 1 1181777696 75008672 18710656
11:23:43 1489 8192000000 10056675856 10442502144 1089890128 11.742 100 85 166 0 1172164640 66111392 16163120
11:24:45 3019 8192000000 10052740208 10439712768 1091827280 11.760 100 76 152 2 1176840096 69637600 15375216
11:25:49 974 8192000000 10052416784 10448691200 1093716080 11.778 100 78 153 1 1176509760 65741200 17052480
11:26:51 9112 8192000000 10058860880 10456367104 1102947584 11.866 100 66 128 0 1177224336 61685488 12591264
11:28:01 1179 8192000000 10073297936 10462720000 1103071056 11.867 100 96 185 1 1197389856 75111552 19207248
11:29:04 1201 8192000000 10082838656 10475008000 1091572496 11.758 100 90 177 3 1201742944 91424656 18745792
11:30:06 1281 8192000000 10059716656 10451230720 1087028448 11.714 100 93 175 0 1181304544 74542144 19733952
11:31:08 1487 8192000000 10022664640 10401103872 1061258224 11.469 100 94 178 2 1145689408 66118320 18312864
11:32:10 5467 8192000000 9959259584 10343206912 1026583328 11.136 98 60 113 0 1095211504 57313632 11314544
11:33:17 1343 8192000000 10006880992 10385645568 1051680880 11.377 100 89 169 1 1130828992 61340576 17807536
11:34:19 1221 8192000000 10011853296 10390482944 1045677376 11.319 100 96 178 1 1132618368 68095248 18845744
11:35:22 2257 8192000000 9997660480 10372952064 1036695744 11.233 100 81 157 2 1115298112 63212320 15390048
11:36:25 1463 8192000000 10015947440 10396676096 1047565776 11.337 100 86 161 4 1144677712 78813712 18298224
11:37:29 24512 8192000000 9974021008 10358775808 1047709360 11.339 100 64 120 0 1112835856 53727248 11399248
11:38:58 1597 8192000000 10044125584 10428669952 1071926048 11.570 100 85 150 2 1168034816 80329408 15779360
11:40:04 1255 8192000000 10063419008 10443612160 1070029392 11.552 100 80 153 0 1181800864 96491392 15280080
11:41:07 1010 8192000000 10067260096 10450960384 1069277872 11.545 99 95 183 2 1184867136 95866288 19722976
11:42:10 3837 8192000000 10125944128 10511413248 1097524400 11.814 100 80 160 2 1241382112 127349712 16508000
11:43:19 2378 8192000000 10111991984 10504552448 1105048240 11.886 100 75 139 2 1233997296 113843824 15105232
11:44:25 16603 8192000000 10162797824 10554814464 1111512816 11.947 100 79 148 1 1277710064 151241232 14956016
11:45:51 3555 8192000000 10163565136 10559614976 1126498224 12.088 100 73 131 2 1277353216 136981264 13873728
11:47:02 3044 8192000000 10239132368 10632040448 1133548288 12.155 100 82 160 1 1346570368 196017856 17004224
11:48:07 1037 8192000000 10268973232 10669096960 1156500464 12.370 100 94 184 4 1383153168 205736832 20915872
11:49:11 2075 8192000000 10287400544 10683666432 1166220016 12.461 100 85 164 1 1398604976 214596128 17788832
11:50:17 1446 8192000000 10327688400 10732589056 1171094480 12.507 100 91 177 5 1444274160 252575280 20604400
11:51:20 2084 8192000000 10323884384 10752491520 1171598032 12.512 100 88 169 0 1438292368 247450032 19244304
11:52:25 872 8192000000 10356290608 10767204352 1183602112 12.624 100 98 189 1 1467619024 262510752 21506160
11:53:28 1496 8192000000 10376683664 10780008448 1174104528 12.535 99 95 179 2 1479370480 284308688 20957264
11:54:34 908 8192000000 10411811824 10830245888 1176376496 12.556 100 98 194 0 1522979104 323768016 22834592
11:55:37 854 8192000000 10414001088 10817626112 1168142352 12.479 99 95 184 2 1520819984 330551008 22126624
11:56:40 1052 8192000000 10445020336 10866728960 1177490992 12.567 100 97 189 0 1554885344 354856960 22537392
11:57:43 813 8192000000 10415180144 10847395840 1168294576 12.481 100 96 185 0 1521586784 329821280 23470928
11:58:46 6699 8192000000 10426696048 10896674816 1174262464 12.537 100 63 124 1 1540148144 349817520 16068160
12:00:00 6229 8192000000 10454381488 10907095040 1176620224 12.559 100 65 128 0 1556155360 363272256 16262880
12:01:09 6030 8192000000 10433237072 10872909824 1176864928 12.561 100 55 108 1 1559956672 369329696 13762048
12:02:24 17801 8192000000 10435436512 10877706240 1170308528 12.500 100 34 65 1 1532136720 352763280 9064912
12:03:44 26613 8192000000 10493789408 10952273920 1162494048 12.427 100 27 54 0 1606569872 436457872 7617952
12:05:29 40661 8192000000 10292606064 10757619712 1127995472 12.102 98 28 54 2 1423107168 288127184 6984512
12:07:14 28328 8192000000 10257322272 10762977280 1132577936 12.146 98 37 72 2 1333262160 192095856 8588368
12:08:45 28399 8192000000 10234674400 10686099456 1126786544 12.091 98 26 49 1 1326802032 193807312 6208176
12:10:17 27837 8192000000 10166722736 10642726912 1138197280 12.199 99 11 22 1 1303518368 162552944 2768144
12:11:45 55591 8192000000 10265216320 10689032192 1143875456 12.252 99 38 71 0 1353421840 201161776 8384608
12:13:42 33132 8192000000 10153558400 10686042112 1093506880 11.776 97 29 58 1 1309101600 208590288 7004432
12:15:17 91848 8192000000 10126318880 10566275072 1130090624 12.122 100 17 31 1 1220880384 87310576 3479184
12:17:54 141395 8192000000 10084402704 10580213760 1121368464 12.040 98 13 26 0 1187407440 63385088 2653888
12:21:16 78245 8192000000 10086848272 10535993344 1141164848 12.226 100 12 25 0 1185368544 41931616 2272080
12:23:48 70108 8192000000 10059975616 10510692352 1132214608 12.142 99 19 36 2 1161223648 24968400 4040640
12:26:00 101832 8192000000 10073522688 10551005184 1114048592 11.971 97 18 36 0 1170572880 52976528 3547760
12:28:44 69897 8192000000 10075939296 10524209152 1133850800 12.158 99 30 60 1 1175462544 34907984 6703760
12:30:54 66436 8192000000 10115234032 10584264704 1137154896 12.189 99 26 52 1 1215228784 72429840 5644048
12:33:01 80802 8192000000 10142742080 10633023488 1154331968 12.350 100 19 38 0 1246859744 88192176 4335600
12:35:23 58522 8192000000 10078007552 10573840384 1148296640 12.294 100 17 32 1 1201882624 50433312 3152672
12:37:37 3896 8192000000 10092552080 10532687872 1155498800 12.361 100 50 94 1 1200373744 33645152 11229792
12:38:42 95442 8192000000 10084086336 10578358272 1118861984 12.016 98 14 29 0 1170632704 48596496 3174224
12:41:18 17828 8192000000 10099436064 10528641024 1122774768 12.053 100 45 88 1 1174219936 42857680 8587488
12:42:36 64923 8192000000 10071563696 10529177600 1083535184 11.681 96 34 68 1 1145443504 55273424 6634896
12:44:48 25413 8192000000 10122932896 10581831680 1128156208 12.104 100 37 74 1 1239117680 103100304 7861168
12:46:53 70743 8192000000 10075550432 10583056384 1097370560 11.813 96 21 42 2 1150407088 48628704 4407824
12:50:07 56711 8192000000 10093951536 10529959936 1086376736 11.708 96 19 38 2 1140804928 50772752 3655440
12:52:59 90730 8192000000 10065195632 10511106048 1081798656 11.665 96 31 62 0 1124190192 36507328 5884208
12:55:52 43421 8192000000 10064641968 10491838464 1071105696 11.563 95 45 89 1 1128435968 48245952 9084320
12:58:33 79419 8192000000 10091234880 10548961280 1123990560 12.065 100 14 29 0 1184712384 56892144 3829680
13:00:57 67904 8192000000 10068770672 10559385600 1125525824 12.079 100 9 18 1 1196710880 69452240 1732816
13:03:13 81334 8192000000 10065763584 10549587968 1090007216 11.743 97 4 8 0 1122555264 31722352 825696
13:05:39 53522 8192000000 10088482624 10536509440 1107448464 11.908 99 17 34 0 1160796528 49574912 3773152
13:07:37 50002 8192000000 10117239136 10546065408 1103885952 11.874 98 39 76 1 1186851808 74163280 8802576
13:09:29 81599 8192000000 10102843168 10592763904 1099999104 11.838 98 25 50 1 1204148400 98510928 5638368
13:11:58 34585 8192000000 10129621568 10571177984 1125799680 12.082 100 35 69 0 1199439488 66804512 6835296
13:13:51 121420 8192000000 10042641408 10496548864 1073215104 11.583 95 8 16 0 1122630304 47924448 1490752
13:16:58 88233 8192000000 10085457456 10533335040 1113731120 11.968 99 40 80 1 1173041200 51356336 7953744
13:19:36 42864 8192000000 10075467632 10539925504 1121747552 12.043 99 13 26 1 1167665200 43434000 2483648
13:21:19 61282 8192000000 10057617504 10519207936 1127029824 12.093 100 5 10 0 1164920064 37074016 816224
13:23:32 88521 8192000000 10067122912 10576269312 1109485312 11.928 97 18 34 1 1174400240 61381360 3533568
13:26:07 11767 8192000000 10062181856 10509217792 1131776880 12.138 99 52 88 3 1164357392 22372512 10208000
13:27:24 45977 8192000000 10158098048 10602479616 1129970192 12.121 98 43 86 0 1237556528 98839872 8746464
13:29:12 35775 8192000000 10066835360 10531733504 1108467488 11.918 97 10 20 0 1165894112 55022624 2404000
13:30:51 46110 8192000000 10110937216 10559229952 1107588128 11.910 98 53 106 1 1185982720 67629216 10765376
13:32:38 53205 8192000000 10099600688 10564239360 1108344016 11.917 98 41 83 0 1182445200 66108096 7993088
13:34:31 11424 8192000000 10100809936 10525466624 1117878848 12.007 99 54 108 1 1181524096 53180800 10464448
13:35:45 127717 8192000000 9056207424 9361321984 209439536 2.492 19 10 20 1 251878576 40126288 2312752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-04-2022 13:40:43 to 11-04-2022 13:40:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 107 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-04-2022 13:41:00 to 11-04-2022 13:41:02.

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          217848
MON$OLDEST_ACTIVE               217849
MON$OLDEST_SNAPSHOT             217849
MON$NEXT_TRANSACTION            217849
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                       2085792
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

11.04.22 13:41:03. 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-04-11 13:41:06 No severe PSQL-related problems occured
11.04.22 13:41:07. End of report.