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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16178776 kB
MemAvailable 31466964 kB
Buffers 0 kB
Cached 15499928 kB
SwapCached 2564 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.10
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 2022-05-08 01:38:17
DTS_END                         2022-05-08 01:38:17.8050
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/40218
TRN_ID                          253740
ATT_ID                          680
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: 9867.25 20894 1184070
customer order: creation 187.44 293 22493
customer order: refuse 16.63 325 1996
order to supplier: creation 49.53 1170 5943
order to supplier: removal 3.63 97981 436
invoice (draft): creation 49.94 2888 5993
invoice (draft): removal 55.28 835 6634
invoice accept: apply 45.38 17163 5445
invoice accept: cancel 50.14 744 6017
customer reserve: creation 4163.29 171 499595
customer reserve: removal 4919.88 56 590386
realization accept: apply 91.43 170 10971
realization accept: cancel 93.15 100 11178
payment from customer: creation 64.04 108 7685
payment from customer: removal 6.29 75 755
payment to supplier: creation 53.50 128 6420
payment to supplier: removal 3.97 69 476
service: total inventory turnovers 4.97 1280 597
service: total monetary turnovers 7.81 41 937
service: aggregate perf. data 0.91 20417 109
service: refresh index statistics 0.03 273869 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-05-2022 01:39:15 to 08-05-2022 01:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6280
TEST_TIME 2 6753
TEST_TIME 3 6670
TEST_TIME 4 6992
TEST_TIME 5 6152
TEST_TIME 6 6130
TEST_TIME 7 5961
TEST_TIME 8 6498
TEST_TIME 9 6720
TEST_TIME 10 7050
TEST_TIME 11 7047
TEST_TIME 12 7184
TEST_TIME 13 7123
TEST_TIME 14 7323
TEST_TIME 15 7433
TEST_TIME 16 7844
TEST_TIME 17 7764
TEST_TIME 18 7881
TEST_TIME 19 8609
TEST_TIME 20 8835
TEST_TIME 21 8788
TEST_TIME 22 9003
TEST_TIME 23 9177
TEST_TIME 24 9770
TEST_TIME 25 10655
TEST_TIME 26 11402
TEST_TIME 27 11341
TEST_TIME 28 11463
TEST_TIME 29 11936
TEST_TIME 30 11693
TEST_TIME 31 11703
TEST_TIME 32 11504
TEST_TIME 33 11408
TEST_TIME 34 11234
TEST_TIME 35 11225
TEST_TIME 36 11140
TEST_TIME 37 10980
TEST_TIME 38 11120
TEST_TIME 39 11030
TEST_TIME 40 11086
TEST_TIME 41 10853
TEST_TIME 42 10765
TEST_TIME 43 10787
TEST_TIME 44 10798
TEST_TIME 45 10622
TEST_TIME 46 10561
TEST_TIME 47 10524
TEST_TIME 48 10423
TEST_TIME 49 10408
TEST_TIME 50 10440
TEST_TIME 51 10534
TEST_TIME 52 10444
TEST_TIME 53 10446
TEST_TIME 54 10440
TEST_TIME 55 10377
TEST_TIME 56 10346
TEST_TIME 57 10430
TEST_TIME 58 10415
TEST_TIME 59 10381
TEST_TIME 60 10444
TEST_TIME 61 10360
TEST_TIME 62 10329
TEST_TIME 63 10391
TEST_TIME 64 10477
TEST_TIME 65 10519
TEST_TIME 66 10456
TEST_TIME 67 10464
TEST_TIME 68 10435
TEST_TIME 69 10445
TEST_TIME 70 10434
TEST_TIME 71 10524
TEST_TIME 72 10499
TEST_TIME 73 10407
TEST_TIME 74 10318
TEST_TIME 75 10272
TEST_TIME 76 10262
TEST_TIME 77 10231
TEST_TIME 78 10162
TEST_TIME 79 10117
TEST_TIME 80 10110
TEST_TIME 81 10102
TEST_TIME 82 10074
TEST_TIME 83 10007
TEST_TIME 84 9984
TEST_TIME 85 10022
TEST_TIME 86 10013
TEST_TIME 87 10032
TEST_TIME 88 10014
TEST_TIME 89 10036
TEST_TIME 90 10026
TEST_TIME 91 10028
TEST_TIME 92 10055
TEST_TIME 93 10081
TEST_TIME 94 10107
TEST_TIME 95 10126
TEST_TIME 96 10177
TEST_TIME 97 10163
TEST_TIME 98 10159
TEST_TIME 99 10126
TEST_TIME 100 10071
TEST_TIME 101 10075
TEST_TIME 102 10027
TEST_TIME 103 10010
TEST_TIME 104 10002
TEST_TIME 105 9954
TEST_TIME 106 9907
TEST_TIME 107 9840
TEST_TIME 108 9817
TEST_TIME 109 9839
TEST_TIME 110 9871
TEST_TIME 111 9855
TEST_TIME 112 9850
TEST_TIME 113 9879
TEST_TIME 114 9982
TEST_TIME 115 9968
TEST_TIME 116 9926
TEST_TIME 117 9926
TEST_TIME 118 9923
TEST_TIME 119 9910
TEST_TIME 120 9872

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-05-2022 01:39:18 to 08-05-2022 01:39:22.

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 1645648 1645648 0 0.00 0 23510 6 0 0
doc_list_biud 1645646 1645646 0 0.00 0 31043 26 0 0
sp_add_invoice_to_stock 5583 5445 138 2.47 36 175569 17164 0 138
sp_cancel_adding_invoice 6017 6017 0 0.00 5 65014 744 0 0
sp_cancel_client_order 1996 1996 0 0.00 4 10508 325 0 0
sp_cancel_customer_reserve 590386 590386 0 0.00 1 18364 56 0 0
sp_cancel_pay_from_customer 755 755 0 0.00 3 7516 75 0 0
sp_cancel_pay_to_supplier 476 476 0 0.00 3 3160 70 0 0
sp_cancel_supplier_invoice 6647 6634 13 0.19 1 31050 835 0 13
sp_cancel_supplier_order 436 436 0 0.00 5 1376301 97982 0 0
sp_cancel_write_off 11178 11178 0 0.00 1 14716 100 0 0
sp_client_order 22493 22493 0 0.00 3 15432 294 0 0
sp_customer_reserve 499595 499595 0 0.00 0 32759 171 0 0
sp_fill_shopping_cart 34429 34429 0 0.00 1 21276 35 0 0
sp_fill_shopping_cart_clo_res 499588 499588 0 0.00 0 17206 11 0 0
sp_get_clo_for_invoice 5434 5434 0 0.00 0 22650 227 0 0
sp_kill_cost_storno 18426 18426 0 0.00 0 8338 50 0 0
sp_kill_qstorno_ret_qs2qd 1199020 1199020 0 0.00 0 31040 31 0 0
sp_kill_qty_storno 633050 633050 0 0.00 0 31042 66 0 0
sp_lock_selected_doc 22782 22782 0 0.00 0 9425 6 0 0
sp_make_cost_storno 30509 30509 0 0.00 0 16079 54 0 0
sp_multiply_rows_for_pdistr 30508 30508 0 0.00 0 5924 24 0 0
sp_multiply_rows_for_qdistr 22493 22493 0 0.00 0 15422 129 0 0
sp_pay_from_customer 7685 7685 0 0.00 4 10839 109 0 0
sp_pay_to_supplier 6420 6420 0 0.00 5 11668 128 0 0
sp_payment_common 14105 14105 0 0.00 1 11311 25 0 0
sp_qd_handle_on_cancel_clo 1996 1996 0 0.00 0 7629 286 0 0
sp_qd_handle_on_invoice_upd_sts 11449 11449 0 0.00 0 11133 321 0 0
sp_qd_handle_on_reserve_upd_sts 10971 10971 0 0.00 0 13773 52 0 0
sp_reserve_write_off 10971 10971 0 0.00 6 16287 170 0 0
sp_supplier_invoice 5993 5993 0 0.00 6 82998 2888 0 0
sp_supplier_order 5943 5943 0 0.00 5 16867 1171 0 0
srv_aggregate_perf_data 110 109 1 0.90 3115 176001 20418 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1295 151300 42437 0 0
srv_find_qd_qs_mism 1131684 1131684 0 0.00 0 23509 6 0 0
srv_make_invnt_saldo 597 597 0 0.00 41 114017 1281 0 0
srv_make_money_saldo 937 937 0 0.00 0 13707 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 159742 448398 273870 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 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 5445 5445 0 0.00 0 8530 165 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 10017 121 0 0
t$perf-norm:sp_cancel_client_order 1996 1996 0 0.00 0 7845 19 0 0
t$perf-norm:sp_cancel_customer_reserve 756 756 0 0.00 0 5465 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 755 755 0 0.00 0 1035 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 476 476 0 0.00 0 4628 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 3980 30 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 24846 1600 0 0
t$perf-norm:sp_cancel_write_off 1088 1088 0 0.00 0 3575 11 0 0
t$perf-norm:sp_client_order 22493 22493 0 0.00 0 8577 19 0 0
t$perf-norm:sp_customer_reserve 1134 1134 0 0.00 0 6336 13 0 0
t$perf-norm:sp_pay_from_customer 7685 7685 0 0.00 0 4952 8 0 0
t$perf-norm:sp_pay_to_supplier 6420 6420 0 0.00 0 6694 10 0 0
t$perf-norm:sp_reserve_write_off 10971 10971 0 0.00 0 8948 14 0 0
t$perf-norm:sp_supplier_invoice 5993 5993 0 0.00 0 6506 16 0 0
t$perf-norm:sp_supplier_order 5943 5943 0 0.00 0 7524 18 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 685 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 4901 30 0 0
t$perf-norm:srv_make_money_saldo 937 937 0 0.00 0 1887 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 11140 10864 276 2.47 0 4145 16 0 276
v_all_customers 40878 40878 0 0.00 0 21 1 0 0
v_all_suppliers 33206 33206 0 0.00 0 24 1 0 0
v_all_wares 44986 44986 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 6135 14 0 0
v_cancel_client_order 3992 3992 0 0.00 0 2829 4 0 0
v_cancel_customer_prepayment 1510 1510 0 0.00 0 1397 5 0 0
v_cancel_customer_reserve 1512 1512 0 0.00 0 2968 5 0 0
v_cancel_payment_to_supplier 952 952 0 0.00 0 2148 7 0 0
v_cancel_supplier_invoice 882 856 26 2.94 0 2326 15 0 26
v_cancel_supplier_order 872 872 0 0.00 0 1374 5 0 0
v_cancel_write_off 2176 2176 0 0.00 0 8394 18 0 0
v_max_id_clo_ord 5943 5943 0 0.00 0 2602 2 0 0
v_max_id_clo_res 1134 1134 0 0.00 0 3784 19 0 0
v_max_id_ord_sup 5993 5993 0 0.00 0 2104 2 0 0
v_max_non_paid_invoice 1753 1753 0 0.00 0 207 2 0 0
v_max_non_paid_realizn 4063 4063 0 0.00 0 4422 6 0 0
v_min_id_clo_ord 5943 5943 0 0.00 0 2118 4 0 0
v_min_id_clo_res 1134 1134 0 0.00 0 1693 7 0 0
v_min_id_ord_sup 5993 5993 0 0.00 0 9197 9 0 0
v_min_non_paid_invoice 6420 6420 0 0.00 0 11308 18 0 0
v_min_non_paid_realizn 7685 7685 0 0.00 0 10827 17 0 0
v_our_firm 11352 11352 0 0.00 0 15 1 0 0
v_reserve_write_off 21942 21942 0 0.00 0 3885 7 0 0
x_lock_depdocs_on_canc_invoice 12223 12223 0 0.00 0 10035 112 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 4987 300 0 0
x_make_qstorno_1000_1200 5943 5943 0 0.00 2 16850 1092 0 0
x_make_qstorno_1200_2000 5993 5993 0 0.00 1 82774 2766 0 0
x_make_qstorno_2100_3300 499595 499595 0 0.00 0 30587 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-05-2022 01:39:23 to 08-05-2022 01:39:27.

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
23:08:21 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:22 23837 8192000000 9911405696 10221744128 992478144 10.806 100 87 174 0 1022097168 6155552 23463472
23:10:53 28261 8192000000 9957920416 10278371328 1031607616 11.184 100 90 180 1 1063636064 8521120 23507328
23:12:26 15844 8192000000 9977998048 10313461760 1065795184 11.512 100 84 168 0 1097334080 10026048 21512848
23:13:46 12047 8192000000 10046785104 10397728768 1132344512 12.143 100 70 140 2 1159427152 10294832 16787808
23:15:04 7543 8192000000 10103115520 10463330304 1185635808 12.643 100 87 173 2 1218907232 14174656 19096768
23:16:14 4005 8192000000 10140173520 10511990784 1229787904 13.052 100 80 157 2 1259333872 12446320 17099648
23:17:20 3300 8192000000 10170457584 10550054912 1257047856 13.303 100 82 162 1 1289936640 15560000 17328784
23:18:25 1587 8192000000 10216780608 10597978112 1292881360 13.630 100 97 195 1 1329239200 15239168 21118672
23:19:28 5382 8192000000 10215761920 10602635264 1305447504 13.745 100 79 160 1 1334135008 11277552 17409952
23:20:34 4760 8192000000 10202439264 10608541696 1309899024 13.785 100 46 92 0 1327826288 7043264 10884000
23:21:40 11330 8192000000 10200755664 10618937344 1318332736 13.862 100 21 42 0 1328944144 6229696 4381712
23:22:54 74147 8192000000 10224665296 10644512768 1334383744 14.007 100 35 71 0 1349078704 5913408 8781552
23:25:09 6712 8192000000 10224635792 10651176960 1337447712 14.034 100 25 50 2 1349541104 6179936 5913456
23:26:19 11626 8192000000 10220776512 10638184448 1300828560 13.703 97 12 24 0 1308728208 5004032 2895616
23:27:32 61487 8192000000 10211374384 10623758336 1253378448 13.269 94 42 85 1 1269452176 5821536 10252192
23:29:37 5663 8192000000 10179691968 10588909568 1270607904 13.427 98 24 48 1 1281221040 5568736 5044400
23:30:44 13880 8192000000 10149495376 10548703232 1223212688 12.991 97 24 49 0 1239224080 10159712 5851680
23:32:01 48251 8192000000 10151766224 10543104000 1212698384 12.894 96 34 68 1 1226664960 4832912 9133664
23:34:10 5075 8192000000 10174894720 10580627456 1276390592 13.480 100 36 72 0 1295823056 11362144 8070320
23:35:16 9492 8192000000 10187626544 10598559744 1298691840 13.683 100 23 46 1 1309508624 5134448 5682336
23:36:29 15656 8192000000 10194696016 10612367360 1303298688 13.725 100 12 24 0 1315340544 9454512 2587344
23:37:46 72900 8192000000 10219892144 10634735616 1314265904 13.825 100 37 72 3 1327235056 4961328 8007824
23:40:02 30713 8192000000 10253329360 10663215104 1338058608 14.040 99 50 100 1 1362350416 13234320 11057488
23:41:35 40739 8192000000 10275670880 10697314304 1361879712 14.254 100 40 80 0 1387008592 16070848 9058032
23:43:23 29284 8192000000 10291720688 10716061696 1374781712 14.370 100 28 56 1 1398496640 16658048 7056880
23:44:56 16286 8192000000 10298655232 10726649856 1386615824 14.476 100 36 72 0 1416110240 21019728 8474688
23:46:17 4677 8192000000 10327722320 10739023872 1393096688 14.533 100 68 137 0 1434259232 25873360 15289184
23:47:27 1655 8192000000 10339954688 10757595136 1403664752 14.628 100 70 140 1 1448743856 28969936 16109168
23:48:31 2937 8192000000 10354844944 10770186240 1406682832 14.654 100 76 151 2 1460188144 35632752 17872560
23:49:36 5092 8192000000 10351767616 10767511552 1405263152 14.642 100 71 142 0 1459388704 35893040 18232512
23:50:49 6715 8192000000 10373461264 10790813696 1417584672 14.751 100 62 123 1 1474513040 41699792 15228576
23:51:58 3162 8192000000 10361604448 10764795904 1383093008 14.444 99 78 156 3 1453968512 51637904 19237600
23:53:05 1294 8192000000 10353602800 10759757824 1383692912 14.450 100 80 160 0 1454005248 50387392 19924944
23:54:11 1547 8192000000 10358801904 10763350016 1388142224 14.489 100 87 172 1 1461215952 51737264 21336464
23:55:19 1836 8192000000 10358556272 10760421376 1387125792 14.480 100 72 143 1 1454739376 48578096 19035488
23:56:28 2720 8192000000 10386941280 10793353216 1406015920 14.649 100 81 156 1 1485285072 58811760 20457392
23:57:38 13701 8192000000 10330834784 10756345856 1376789808 14.388 100 43 84 0 1441837248 53719904 11327536
23:58:56 12728 8192000000 10328130720 10751602688 1376807984 14.388 100 36 72 0 1440425536 53614224 10003328
00:00:13 3570 8192000000 10354005456 10770948096 1370753456 14.334 100 67 133 1 1455799648 67803472 17242720
00:01:27 19330 8192000000 10332759200 10764800000 1355066016 14.193 99 30 60 0 1431555840 67578112 8911712
00:02:57 17281 8192000000 10331983152 10772201472 1375627024 14.377 100 29 58 0 1445618640 61480576 8511040
00:04:19 32367 8192000000 10328035440 10776567808 1384887600 14.460 100 13 26 1 1433453344 44864880 3700864
00:06:01 76451 8192000000 10313372560 10772549632 1393011808 14.533 99 12 24 1 1429870048 34040496 2817744
00:09:07 50488 8192000000 10296297808 10756231168 1371877616 14.344 99 16 31 2 1392975968 17709168 3389184
00:10:58 42379 8192000000 10300008560 10746691584 1377842160 14.397 99 26 52 1 1399076848 15037696 6196992
00:12:41 84232 8192000000 10309762624 10756812800 1377519536 14.394 99 16 32 1 1390309456 9112384 3677536
00:15:06 61588 8192000000 10300564208 10757545984 1385492752 14.466 100 11 22 0 1405617600 17400416 2724432
00:17:14 52128 8192000000 10303795232 10759745536 1371153968 14.337 98 19 38 0 1386876272 11481216 4241088
00:19:33 97499 8192000000 10311004048 10762276864 1384037360 14.453 99 22 44 0 1403939744 14797600 5104784
00:22:11 30141 8192000000 10314323264 10755846144 1381103824 14.426 99 31 61 0 1394748672 6629616 7015232
00:23:43 43172 8192000000 10300106560 10751004672 1379325232 14.411 99 26 52 1 1396071728 10793216 5953280
00:25:28 25728 8192000000 10311609520 10764836864 1390810144 14.513 99 23 46 3 1409204368 12720160 5674064
00:26:54 32209 8192000000 10308071056 10760773632 1369330336 14.321 98 25 50 0 1389534656 14162320 6042000
00:28:31 45108 8192000000 10294342160 10768793600 1369144304 14.319 99 15 30 2 1395092784 21736592 4211888
00:30:18 65713 8192000000 10288565296 10744598528 1358459696 14.224 98 10 20 0 1378837072 17742976 2634400
00:32:31 49593 8192000000 10298038560 10753134592 1382929376 14.443 99 21 41 1 1407312016 18982320 5400320
00:35:50 39363 8192000000 10296825168 10756558848 1389793264 14.504 100 15 30 0 1404232576 10996224 3443088
00:37:33 61337 8192000000 10268915008 10723065856 1297073504 13.669 94 19 36 1 1311322640 9985568 4263568
00:39:35 21759 8192000000 10284487776 10727231488 1343586352 14.090 98 38 72 3 1361508688 9937856 7984480
00:40:57 43601 8192000000 10274998816 10718482432 1319486640 13.872 96 25 50 0 1339252272 14634768 5130864
00:42:46 87214 8192000000 10262743632 10720894976 1298543712 13.682 95 9 18 0 1318107040 17156208 2407120
00:45:18 21010 8192000000 10284782160 10725171200 1355201072 14.194 99 42 84 0 1375715616 11429872 9084672
00:46:42 29133 8192000000 10270675376 10709352448 1324514112 13.918 97 32 65 1 1341562704 10025488 7023104
00:48:13 50792 8192000000 10299220032 10740461568 1370759760 14.334 100 30 60 1 1389908576 13096400 6052416
00:50:09 92399 8192000000 10266864768 10720083968 1306498480 13.754 95 16 32 1 1321977744 11806816 3672448
00:52:50 56158 8192000000 10277984768 10718072832 1336615888 14.027 98 15 30 0 1346236736 6694816 2926032
00:54:49 37228 8192000000 10280129936 10735407104 1367766672 14.307 100 18 36 0 1389643808 17799072 4078064
00:56:28 98306 8192000000 10304949792 10759274496 1387628416 14.485 100 17 34 0 1406130496 14788944 3713136
00:59:07 49286 8192000000 10288504096 10756542464 1373023840 14.354 98 11 22 1 1387665120 11926752 2714528
01:02:33 62379 8192000000 10285653712 10760450048 1367904944 14.308 98 17 34 0 1387072144 15457424 3709776
01:04:38 47895 8192000000 10294888672 10756706304 1386271280 14.473 99 16 32 1 1397758096 7972256 3514560
01:06:27 29811 8192000000 10301536816 10752733184 1371474128 14.340 98 40 80 0 1389632848 9548848 8609872
01:08:00 61135 8192000000 10299592688 10756898816 1362203120 14.257 97 18 36 0 1373766672 8083712 3479840
01:10:28 151300 8192000000 10304051632 10765787136 1335924096 14.021 95 14 28 0 1354729184 16037936 2767152
01:14:00 81577 8192000000 10303351472 10771779584 1345531680 14.107 96 19 36 3 1363988096 14751792 3704624
01:16:21 41082 8192000000 10292719888 10724564992 1366775376 14.298 99 26 52 1 1388147232 16178144 5193712
01:18:05 75341 8192000000 10251309072 10703822848 1315816368 13.839 96 14 28 0 1325997632 6910512 3270752
01:20:22 30662 8192000000 10240643584 10701266944 1341085920 14.067 100 3 6 0 1352661600 11021360 554320
01:21:54 48181 8192000000 10266150560 10718937088 1361479696 14.251 100 14 28 0 1375142256 10452160 3210400
01:23:43 49208 8192000000 10258910560 10710224896 1320225840 13.879 96 18 35 2 1334274112 9962368 4085904
01:25:32 38215 8192000000 10260614320 10712956928 1356048832 14.202 100 15 30 1 1372542640 12733424 3760384
01:27:19 39878 8192000000 10276072192 10738741248 1365594624 14.288 100 16 32 1 1387711440 18032640 4084176
01:29:00 61635 8192000000 10275635856 10726301696 1302779360 13.721 95 28 53 3 1320213024 11596112 5837552
01:31:06 64047 8192000000 10271710784 10734112768 1315242848 13.834 96 15 28 1 1340411232 21384432 3783952
01:33:10 75236 8192000000 10271151408 10719391744 1327060128 13.941 97 28 56 1 1345718512 12917008 5741376
01:35:26 43712 8192000000 10291917072 10735554560 1344359568 14.097 98 38 76 1 1364511344 11961616 8190160
01:37:58 34923 8192000000 9398310304 9708351488 539456176 6.178 40 5 10 0 549510064 8940560 1113328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-05-2022 01:39:29 to 08-05-2022 01:39:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 138 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:01, from 08-05-2022 01:39:45 to 08-05-2022 01:39:46.

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          254101
MON$OLDEST_ACTIVE               254102
MON$OLDEST_SNAPSHOT             254102
MON$NEXT_TRANSACTION            254102
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                       2068320
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:25: 
:26: mscs-test-161.budzdorov.ru    Sun May  8 01:38:41 2022
:27:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:28: 
:29: 
:30: mscs-test-161.budzdorov.ru    Sun May  8 01:38:45 2022
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:32: 
--- end of diff output ---

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

08.05.22 01:39:47. 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
2022-05-08 01:39:50 No severe PSQL-related problems occured
08.05.22 01:39:51. End of report.