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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16674088 kB
MemAvailable 31489344 kB
Buffers 0 kB
Cached 15030756 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.9
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-10 22:37:57
DTS_END                         2021-12-10 22:37:57.2880
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(57:17)
IP                              ::1/46358
TRN_ID                          237526
ATT_ID                          647
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: 9227.04 24104 1107245
customer order: creation 169.59 384 20351
customer order: refuse 14.12 380 1694
order to supplier: creation 45.22 1339 5426
order to supplier: removal 3.53 106720 424
invoice (draft): creation 45.33 3546 5439
invoice (draft): removal 53.62 849 6434
invoice accept: apply 40.85 17804 4902
invoice accept: cancel 48.50 779 5820
customer reserve: creation 3807.19 174 456863
customer reserve: removal 4705.84 62 564701
realization accept: apply 81.72 230 9807
realization accept: cancel 84.19 121 10103
payment from customer: creation 57.36 160 6883
payment from customer: removal 5.90 86 708
payment to supplier: creation 48.88 174 5866
payment to supplier: removal 3.17 87 380
service: total inventory turnovers 4.43 1495 531
service: total monetary turnovers 6.73 46 807
service: aggregate perf. data 0.85 21819 102
service: refresh index statistics 0.03 325824 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-12-2021 22:40:06 to 10-12-2021 22:40:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2511
TEST_TIME 2 3503
TEST_TIME 3 3743
TEST_TIME 4 3693
TEST_TIME 5 3319
TEST_TIME 6 4500
TEST_TIME 7 5112
TEST_TIME 8 5266
TEST_TIME 9 5542
TEST_TIME 10 5689
TEST_TIME 11 5939
TEST_TIME 12 5915
TEST_TIME 13 6285
TEST_TIME 14 6313
TEST_TIME 15 6285
TEST_TIME 16 6571
TEST_TIME 17 6548
TEST_TIME 18 6910
TEST_TIME 19 7287
TEST_TIME 20 7500
TEST_TIME 21 8131
TEST_TIME 22 8361
TEST_TIME 23 8596
TEST_TIME 24 8486
TEST_TIME 25 8659
TEST_TIME 26 8877
TEST_TIME 27 9666
TEST_TIME 28 9989
TEST_TIME 29 10827
TEST_TIME 30 10816
TEST_TIME 31 10794
TEST_TIME 32 10623
TEST_TIME 33 10343
TEST_TIME 34 10340
TEST_TIME 35 10307
TEST_TIME 36 10242
TEST_TIME 37 10217
TEST_TIME 38 10109
TEST_TIME 39 10001
TEST_TIME 40 9836
TEST_TIME 41 9691
TEST_TIME 42 9761
TEST_TIME 43 9659
TEST_TIME 44 9706
TEST_TIME 45 9976
TEST_TIME 46 10096
TEST_TIME 47 9978
TEST_TIME 48 9845
TEST_TIME 49 9774
TEST_TIME 50 9722
TEST_TIME 51 9656
TEST_TIME 52 9592
TEST_TIME 53 9520
TEST_TIME 54 9518
TEST_TIME 55 9452
TEST_TIME 56 9366
TEST_TIME 57 9398
TEST_TIME 58 9416
TEST_TIME 59 9440
TEST_TIME 60 9571
TEST_TIME 61 9549
TEST_TIME 62 9578
TEST_TIME 63 9677
TEST_TIME 64 9682
TEST_TIME 65 9784
TEST_TIME 66 9721
TEST_TIME 67 9736
TEST_TIME 68 9715
TEST_TIME 69 9694
TEST_TIME 70 9566
TEST_TIME 71 9521
TEST_TIME 72 9484
TEST_TIME 73 9551
TEST_TIME 74 9493
TEST_TIME 75 9434
TEST_TIME 76 9396
TEST_TIME 77 9417
TEST_TIME 78 9393
TEST_TIME 79 9310
TEST_TIME 80 9336
TEST_TIME 81 9288
TEST_TIME 82 9277
TEST_TIME 83 9254
TEST_TIME 84 9247
TEST_TIME 85 9263
TEST_TIME 86 9289
TEST_TIME 87 9367
TEST_TIME 88 9329
TEST_TIME 89 9330
TEST_TIME 90 9327
TEST_TIME 91 9454
TEST_TIME 92 9402
TEST_TIME 93 9439
TEST_TIME 94 9493
TEST_TIME 95 9450
TEST_TIME 96 9385
TEST_TIME 97 9361
TEST_TIME 98 9343
TEST_TIME 99 9295
TEST_TIME 100 9249
TEST_TIME 101 9208
TEST_TIME 102 9211
TEST_TIME 103 9180
TEST_TIME 104 9166
TEST_TIME 105 9185
TEST_TIME 106 9193
TEST_TIME 107 9176
TEST_TIME 108 9242
TEST_TIME 109 9216
TEST_TIME 110 9238
TEST_TIME 111 9195
TEST_TIME 112 9299
TEST_TIME 113 9266
TEST_TIME 114 9372
TEST_TIME 115 9350
TEST_TIME 116 9344
TEST_TIME 117 9296
TEST_TIME 118 9282
TEST_TIME 119 9255
TEST_TIME 120 9233

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-12-2021 22:40:10 to 10-12-2021 22:40:13.

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 1529451 1529451 0 0.00 0 41570 7 0 0
doc_list_biud 1529450 1529450 0 0.00 0 35120 29 0 0
sp_add_invoice_to_stock 5046 4902 144 2.85 45 204074 17804 0 144
sp_cancel_adding_invoice 5820 5820 0 0.00 5 72493 780 0 0
sp_cancel_client_order 1694 1694 0 0.00 4 8625 380 0 0
sp_cancel_customer_reserve 564701 564701 0 0.00 1 31122 63 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 5083 87 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 4299 87 0 0
sp_cancel_supplier_invoice 6447 6434 13 0.20 2 17414 849 0 13
sp_cancel_supplier_order 424 424 0 0.00 5 1069056 106720 0 0
sp_cancel_write_off 10103 10103 0 0.00 1 21023 121 0 0
sp_client_order 20351 20351 0 0.00 3 20211 385 0 0
sp_customer_reserve 456863 456863 0 0.00 0 92030 174 0 0
sp_fill_shopping_cart 31211 31211 0 0.00 1 29476 42 0 0
sp_fill_shopping_cart_clo_res 456853 456853 0 0.00 0 23953 14 0 0
sp_get_clo_for_invoice 4890 4890 0 0.00 0 28639 339 0 0
sp_kill_cost_storno 17011 17011 0 0.00 0 20679 59 0 0
sp_kill_qstorno_ret_qs2qd 1146363 1146363 0 0.00 0 21461 34 0 0
sp_kill_qty_storno 603873 603873 0 0.00 0 35119 73 0 0
sp_lock_selected_doc 20469 20469 0 0.00 0 6842 5 0 0
sp_make_cost_storno 27446 27446 0 0.00 0 41555 81 0 0
sp_multiply_rows_for_pdistr 27446 27446 0 0.00 0 27431 32 0 0
sp_multiply_rows_for_qdistr 20351 20351 0 0.00 0 17127 180 0 0
sp_pay_from_customer 6883 6883 0 0.00 4 28056 161 0 0
sp_pay_to_supplier 5866 5866 0 0.00 5 34491 174 0 0
sp_payment_common 12749 12749 0 0.00 2 34271 59 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 0 8597 344 0 0
sp_qd_handle_on_invoice_upd_sts 10710 10710 0 0.00 0 35119 329 0 0
sp_qd_handle_on_reserve_upd_sts 9807 9807 0 0.00 0 19500 68 0 0
sp_reserve_write_off 9807 9807 0 0.00 6 28533 230 0 0
sp_supplier_invoice 5439 5439 0 0.00 7 85153 3547 0 0
sp_supplier_order 5426 5426 0 0.00 5 30195 1339 0 0
srv_aggregate_perf_data 103 102 1 0.97 3137 314039 21819 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1082 137955 45129 0 0
srv_find_qd_qs_mism 1059748 1059748 0 0.00 0 22028 5 0 0
srv_make_invnt_saldo 531 531 0 0.00 50 104111 1495 0 0
srv_make_money_saldo 807 807 0 0.00 1 12133 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 266620 380882 325824 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 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 4902 4902 0 0.00 0 23109 184 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 4142 68 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 970 9 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 3269 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 3310 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 612 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 2482 32 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 20345 1645 0 0
t$perf-norm:sp_cancel_write_off 1050 1050 0 0.00 0 5538 13 0 0
t$perf-norm:sp_client_order 20351 20351 0 0.00 0 13656 17 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 1895 6 0 0
t$perf-norm:sp_pay_from_customer 6883 6883 0 0.00 0 14264 11 0 0
t$perf-norm:sp_pay_to_supplier 5866 5866 0 0.00 0 10192 14 0 0
t$perf-norm:sp_reserve_write_off 9807 9807 0 0.00 0 9089 10 0 0
t$perf-norm:sp_supplier_invoice 5439 5439 0 0.00 0 11657 19 0 0
t$perf-norm:sp_supplier_order 5426 5426 0 0.00 0 4309 15 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 10417 109 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 147 6 0 0
t$perf-norm:srv_make_invnt_saldo 531 531 0 0.00 0 3609 26 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 1762 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 5 0 0
v_add_invoice_to_stock 10068 9780 288 2.86 0 10965 22 0 288
v_all_customers 36942 36942 0 0.00 0 16 1 0 0
v_all_suppliers 30382 30382 0 0.00 0 24 1 0 0
v_all_wares 40698 40698 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1407 9 0 0
v_cancel_client_order 3388 3388 0 0.00 0 3030 7 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 2151 6 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 2382 5 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 1471 6 0 0
v_cancel_supplier_invoice 892 866 26 2.91 0 1810 18 0 26
v_cancel_supplier_order 848 848 0 0.00 0 1697 11 0 0
v_cancel_write_off 2100 2100 0 0.00 0 3252 14 0 0
v_max_id_clo_ord 5425 5425 0 0.00 0 452 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 2042 10 0 0
v_max_id_ord_sup 5436 5436 0 0.00 0 9248 8 0 0
v_max_non_paid_invoice 1536 1536 0 0.00 0 1183 3 0 0
v_max_non_paid_realizn 3768 3768 0 0.00 0 5038 5 0 0
v_min_id_clo_ord 5425 5425 0 0.00 0 6200 11 0 0
v_min_id_clo_res 985 985 0 0.00 0 8260 19 0 0
v_min_id_ord_sup 5436 5436 0 0.00 0 3485 7 0 0
v_min_non_paid_invoice 5866 5866 0 0.00 0 34269 57 0 0
v_min_non_paid_realizn 6883 6883 0 0.00 0 28031 44 0 0
v_our_firm 9986 9986 0 0.00 0 12 1 0 0
v_reserve_write_off 19614 19614 0 0.00 0 10862 6 0 0
x_lock_depdocs_on_canc_invoice 11821 11821 0 0.00 0 27537 128 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 8061 392 0 0
x_make_qstorno_1000_1200 5426 5426 0 0.00 1 30176 1257 0 0
x_make_qstorno_1200_2000 5439 5439 0 0.00 3 85109 3394 0 0
x_make_qstorno_2100_3300 456863 456863 0 0.00 0 91987 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2021 22:40:14 to 10-12-2021 22:40:18.

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
20:08:00 18 8192000000 8805741632 8819478528 10403072 0.126 4 1 2 0 10504032 58176 42784
20:09:01 18886 8192000000 9874056768 10180493312 956567568 10.455 100 86 172 0 985417456 5471840 23378048
20:10:26 23433 8192000000 9940810720 10261610496 1019698112 11.069 100 88 176 0 1049866496 7371696 22796688
20:11:53 18808 8192000000 9984893840 10318741504 1064109104 11.496 100 78 156 0 1092403024 9042400 19251520
20:13:15 15688 8192000000 10030970336 10377715712 1114737744 11.977 100 77 154 0 1142983088 9828464 18416880
20:14:48 9908 8192000000 10084641008 10446675968 1170915600 12.505 100 75 150 1 1199915888 11306848 17693440
20:16:01 6582 8192000000 10118606512 10490757120 1214124752 12.907 100 81 160 1 1244682720 12995040 17562928
20:17:10 3462 8192000000 10153632432 10532868096 1248548048 13.225 100 75 150 1 1277438032 12062864 16827120
20:18:15 2387 8192000000 10200045200 10580987904 1279070528 13.505 100 87 174 0 1312076976 13906864 19099584
20:19:20 1256 8192000000 10218540992 10604441600 1298655120 13.683 100 92 184 3 1333334720 13665184 21014416
20:20:22 10025 8192000000 10217292800 10610982912 1307174912 13.760 100 80 163 1 1337613248 12038080 18400256
20:21:44 7661 8192000000 10195650800 10611286016 1313018992 13.813 100 16 32 0 1322546720 6048192 3479536
20:22:56 11793 8192000000 10206007504 10624790528 1323345120 13.907 100 16 32 1 1332423552 5166336 3912096
20:24:08 15897 8192000000 10227913440 10637709312 1331937504 13.985 100 30 60 1 1345505424 5687872 7880048
20:25:24 19562 8192000000 10259249840 10658865152 1336954352 14.030 100 86 172 4 1363257184 6070432 20232400
20:26:44 9132 8192000000 10238447200 10657161216 1338754416 14.046 100 19 38 0 1348706272 6084240 3867616
20:27:55 4043 8192000000 10216071056 10628706304 1307808656 13.766 99 29 58 2 1320672192 6416912 6446624
20:28:59 64893 8192000000 10184714992 10598170624 1225087120 13.009 93 36 72 1 1239201072 4948768 9165184
20:31:05 5081 8192000000 10146691600 10548232192 1223665936 12.996 97 38 76 1 1244057008 12127264 8263808
20:32:11 7334 8192000000 10142208832 10544779264 1233145536 13.083 98 21 42 1 1243023296 5093184 4784576
20:33:24 63305 8192000000 10173952288 10581286912 1264946064 13.375 99 24 48 1 1277083504 5160928 6976512
20:35:32 5902 8192000000 10187986624 10596077568 1290110480 13.605 100 37 74 3 1306700928 8775408 7815040
20:36:40 7729 8192000000 10186145888 10601644032 1295340704 13.653 100 25 47 4 1306873408 6148576 5384128
20:37:48 54717 8192000000 10215391344 10625253376 1305775776 13.748 100 27 54 0 1318040416 5640992 6623648
20:39:47 41948 8192000000 10248182368 10658869248 1337297712 14.033 100 31 62 0 1353202464 8722032 7182720
20:41:33 67330 8192000000 10261086320 10680193024 1354689968 14.190 100 39 78 1 1374600416 11125328 8785120
20:43:47 23365 8192000000 10281923728 10697359360 1359297040 14.231 99 45 89 0 1385448768 16345424 9806304
20:45:14 14005 8192000000 10293049824 10711244800 1377723696 14.396 100 52 104 0 1406564688 16542000 12298992
20:46:30 14360 8192000000 10311857360 10734972928 1388793520 14.495 100 53 106 3 1420828592 19384672 12650400
20:47:49 5922 8192000000 10311928208 10732040192 1376551824 14.386 99 56 110 3 1411778112 22518592 12707696
20:48:59 9764 8192000000 10330140768 10755313664 1399321616 14.589 100 63 126 0 1440850528 26385824 15143088
20:50:15 4324 8192000000 10356164944 10776813568 1416695376 14.743 100 53 106 0 1462921664 32867632 13358656
20:51:23 4240 8192000000 10364086992 10786488320 1421875648 14.789 100 67 132 0 1470588768 32078704 16634416
20:52:37 4957 8192000000 10357868416 10770391040 1401530192 14.609 100 74 145 1 1462104512 41911424 18662896
20:53:54 2210 8192000000 10373087072 10778546176 1412255376 14.704 100 81 162 2 1472654592 40169904 20229312
20:55:00 1081 8192000000 10388135424 10792148992 1417248928 14.748 100 92 184 0 1486596576 46152512 23195136
20:56:08 1309 8192000000 10371233088 10773889024 1402890960 14.621 100 93 182 4 1474773808 49188080 22694768
20:57:13 13573 8192000000 10357455664 10775232512 1370833872 14.335 98 50 98 1 1440139472 54964240 14341360
20:58:31 11366 8192000000 10358759968 10793984000 1392325904 14.527 99 38 77 3 1456334656 53145936 10862816
20:59:48 8695 8192000000 10343155088 10768891904 1389338608 14.500 100 41 81 2 1448518992 48005008 11175376
21:01:02 21253 8192000000 10350261312 10778955776 1395997712 14.559 100 29 58 3 1455009520 50734832 8276976
21:02:24 22917 8192000000 10353463360 10792087552 1393349232 14.536 100 28 56 1 1459706480 58351072 8006176
21:04:03 29951 8192000000 10333036928 10793005056 1375792560 14.379 100 22 44 2 1449081488 66264720 7024208
21:05:36 19494 8192000000 10302892960 10742820864 1313279168 13.816 96 30 60 0 1367829440 46549440 8000832
21:07:03 27387 8192000000 10250211952 10693550080 1326253360 13.933 99 6 12 0 1341467296 13629328 1584608
21:08:32 112173 8192000000 10256941200 10699399168 1302898656 13.722 96 24 48 1 1322535392 14492240 5144496
21:12:08 15535 8192000000 10254291488 10695823360 1350358784 14.151 100 39 78 0 1367741232 8972864 8409584
21:13:25 65645 8192000000 10274950608 10724061184 1354680048 14.190 99 16 31 0 1367155104 9052384 3422672
21:15:41 27868 8192000000 10288727312 10730196992 1376990416 14.390 100 55 108 1 1404040144 14690160 12359568
21:17:10 52393 8192000000 10292528944 10739871744 1374737488 14.369 99 16 31 1 1388392672 9772112 3883072
21:19:06 9474 8192000000 10328414080 10755395584 1394024384 14.542 100 61 121 2 1420963232 12783968 14154880
21:20:20 21931 8192000000 10339600816 10779750400 1402167024 14.614 99 43 86 2 1433981680 20728960 11085696
21:21:43 48886 8192000000 10321418112 10781663232 1381702224 14.432 99 47 94 0 1428166672 34922400 11542048
21:23:40 137955 8192000000 10293793120 10749722624 1371117856 14.337 98 12 24 0 1388852000 14983280 2750864
21:27:39 50970 8192000000 10281595456 10743144448 1373364848 14.357 99 17 34 0 1387577440 10796320 3416272
21:29:31 66731 8192000000 10286398512 10746589184 1382188704 14.436 100 10 20 0 1398460176 13842960 2428512
21:31:47 39687 8192000000 10292073152 10759520256 1396540096 14.564 100 5 10 0 1406363296 8570144 1253056
21:33:27 50681 8192000000 10305125648 10756698112 1378747888 14.405 98 32 64 0 1395797504 9703616 7346000
21:35:32 115577 8192000000 10310377856 10781392896 1384841248 14.460 98 24 47 3 1403808832 13426816 5540768
21:38:29 32570 8192000000 10308710688 10752323584 1368190416 14.311 97 39 77 2 1383757808 6727152 8840240
21:40:02 37848 8192000000 10312833968 10773827584 1400625088 14.601 100 19 38 2 1421257264 16669936 3962240
21:42:08 101505 8192000000 10305486576 10746998784 1319937632 13.876 94 19 37 0 1334382880 10714496 3730752
21:44:55 18421 8192000000 10312284784 10749292544 1389575504 14.502 100 42 83 3 1408875024 9166864 10132656
21:46:20 115291 8192000000 10251607952 10699239424 1304220640 13.734 94 22 44 0 1319074000 9533568 5319792
21:49:21 29032 8192000000 10266157536 10717257728 1356539904 14.206 100 21 42 0 1374345504 12420128 5385472
21:50:51 36234 8192000000 10250643760 10706903040 1344935040 14.102 99 18 37 0 1358752464 9853904 3963520
21:52:27 69861 8192000000 10222631136 10675900416 1316957008 13.849 98 14 28 0 1332053376 11578624 3517744
21:54:41 95809 8192000000 10252726240 10709090304 1352069840 14.166 100 16 32 0 1364321872 8549040 3702992
21:57:25 66953 8192000000 10257633712 10730115072 1364371504 14.277 100 13 26 0 1378269104 10328288 3569312
21:59:32 85657 8192000000 10255709936 10699272192 1282575824 13.537 94 15 30 0 1295065728 8673152 3816752
22:01:59 50191 8192000000 10274647376 10723094528 1352446144 14.169 99 25 50 0 1372174032 14072720 5655168
22:03:50 101926 8192000000 10272502496 10714669056 1330644400 13.973 97 20 39 0 1349083200 14338640 4100160
22:06:32 40185 8192000000 10296776336 10738155520 1365376096 14.286 99 36 72 2 1388758784 14689696 8692992
22:08:25 56997 8192000000 10292617152 10736476160 1352633296 14.171 98 21 42 0 1376246096 19457408 4155392
22:10:32 65173 8192000000 10284136592 10732687360 1342492096 14.080 97 19 39 1 1357258768 10910240 3856432
22:12:37 98549 8192000000 10297389760 10748112896 1390430240 14.510 100 34 68 1 1410104416 12494288 7179888
22:15:16 61650 8192000000 10256065184 10720538624 1328705728 13.955 96 4 8 0 1336526960 7250368 570864
22:17:18 44573 8192000000 10258158480 10720874496 1361255216 14.249 99 11 22 0 1370661008 7057728 2348064
22:19:27 55544 8192000000 10271733536 10727858176 1341372320 14.070 97 7 14 0 1350370560 7663408 1334832
22:21:28 41007 8192000000 10266145120 10719133696 1345655264 14.108 97 20 38 1 1361040016 10484592 4900160
22:23:14 36957 8192000000 10285699280 10736451584 1377303856 14.392 99 33 66 2 1399301120 14673696 7323568
22:24:51 75216 8192000000 10292929440 10759188480 1381442672 14.429 99 16 32 1 1397049424 11895408 3711344
22:27:11 65987 8192000000 10301855632 10759598080 1346458048 14.116 96 23 45 1 1365086512 13016752 5611712
22:29:26 67792 8192000000 10314326576 10762485760 1397552576 14.573 100 26 52 1 1413312592 9680128 6079888
22:31:37 50451 8192000000 10287129520 10745163776 1340207840 14.059 96 14 28 1 1360971904 16979872 3784192
22:33:35 71175 8192000000 10264294128 10719277056 1360037840 14.238 99 15 30 0 1375218512 11609952 3570720
22:36:27 24350 8192000000 10283116848 10744221696 1381728032 14.432 100 16 32 0 1404367152 19564848 3074272
22:37:52 16716 8192000000 9370107904 9675653120 531742240 6.095 39 2 4 0 534647168 2583536 321392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-12-2021 22:40:20 to 10-12-2021 22:40:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 144 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-12-2021 22:40:36 to 10-12-2021 22:40:38.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239079
MON$OLDEST_ACTIVE               239080
MON$OLDEST_SNAPSHOT             239080
MON$NEXT_TRANSACTION            239080
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-03 16:41:55.3470
MON$PAGES                       2032352
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:212: 
:213: mscs-test-161.budzdorov.ru    Fri Dec 10 22:38:14 2021
:214:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:215: 
:216: 
:217: mscs-test-161.budzdorov.ru    Fri Dec 10 22:38:17 2021
:218:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:219: 
--- end of diff output ---

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

10.12.21 22:40:39. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-10 22:40:41 No severe PSQL-related problems occured
10.12.21 22:40:42. End of report.