Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.04.2022 16: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 16442260 kB
MemAvailable 31512756 kB
Buffers 0 kB
Cached 15232912 kB
SwapCached 2752 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-04-21 16:38:23
DTS_END                         2022-04-21 16:38:23.9320
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/46470
TRN_ID                          235294
ATT_ID                          714
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: 9210.64 26035 1105277
customer order: creation 164.96 356 19795
customer order: refuse 14.37 414 1724
order to supplier: creation 45.36 1271 5443
order to supplier: removal 3.44 103729 413
invoice (draft): creation 44.38 3480 5325
invoice (draft): removal 53.73 768 6448
invoice accept: apply 41.03 19128 4923
invoice accept: cancel 47.94 778 5753
customer reserve: creation 3789.23 187 454708
customer reserve: removal 4717.97 60 566156
realization accept: apply 81.32 202 9758
realization accept: cancel 78.03 106 9363
payment from customer: creation 58.96 129 7075
payment from customer: removal 5.41 84 649
payment to supplier: creation 48.50 148 5820
payment to supplier: removal 3.23 74 388
service: total inventory turnovers 5.32 1122 638
service: total monetary turnovers 6.59 42 791
service: aggregate perf. data 0.86 22679 103
service: refresh index statistics 0.03 365952 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-04-2022 16:39:28 to 21-04-2022 16:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1706
TEST_TIME 2 2342
TEST_TIME 3 4345
TEST_TIME 4 4600
TEST_TIME 5 5310
TEST_TIME 6 5331
TEST_TIME 7 6092
TEST_TIME 8 6312
TEST_TIME 9 6726
TEST_TIME 10 6755
TEST_TIME 11 6808
TEST_TIME 12 7012
TEST_TIME 13 7058
TEST_TIME 14 6967
TEST_TIME 15 7489
TEST_TIME 16 7772
TEST_TIME 17 7900
TEST_TIME 18 8130
TEST_TIME 19 8176
TEST_TIME 20 8839
TEST_TIME 21 9117
TEST_TIME 22 9021
TEST_TIME 23 9052
TEST_TIME 24 9269
TEST_TIME 25 9423
TEST_TIME 26 9589
TEST_TIME 27 10071
TEST_TIME 28 10281
TEST_TIME 29 10602
TEST_TIME 30 10883
TEST_TIME 31 10958
TEST_TIME 32 10659
TEST_TIME 33 10645
TEST_TIME 34 10581
TEST_TIME 35 10378
TEST_TIME 36 10425
TEST_TIME 37 10283
TEST_TIME 38 10163
TEST_TIME 39 10049
TEST_TIME 40 10105
TEST_TIME 41 10038
TEST_TIME 42 9975
TEST_TIME 43 9876
TEST_TIME 44 9745
TEST_TIME 45 9646
TEST_TIME 46 9586
TEST_TIME 47 9533
TEST_TIME 48 9576
TEST_TIME 49 9444
TEST_TIME 50 9372
TEST_TIME 51 9319
TEST_TIME 52 9339
TEST_TIME 53 9311
TEST_TIME 54 9332
TEST_TIME 55 9216
TEST_TIME 56 9097
TEST_TIME 57 9168
TEST_TIME 58 9142
TEST_TIME 59 9165
TEST_TIME 60 9148
TEST_TIME 61 9203
TEST_TIME 62 9230
TEST_TIME 63 9378
TEST_TIME 64 9407
TEST_TIME 65 9379
TEST_TIME 66 9371
TEST_TIME 67 9420
TEST_TIME 68 9416
TEST_TIME 69 9408
TEST_TIME 70 9412
TEST_TIME 71 9460
TEST_TIME 72 9571
TEST_TIME 73 9532
TEST_TIME 74 9427
TEST_TIME 75 9439
TEST_TIME 76 9395
TEST_TIME 77 9319
TEST_TIME 78 9312
TEST_TIME 79 9359
TEST_TIME 80 9306
TEST_TIME 81 9249
TEST_TIME 82 9225
TEST_TIME 83 9149
TEST_TIME 84 9155
TEST_TIME 85 9343
TEST_TIME 86 9349
TEST_TIME 87 9353
TEST_TIME 88 9345
TEST_TIME 89 9454
TEST_TIME 90 9465
TEST_TIME 91 9469
TEST_TIME 92 9579
TEST_TIME 93 9579
TEST_TIME 94 9555
TEST_TIME 95 9608
TEST_TIME 96 9686
TEST_TIME 97 9712
TEST_TIME 98 9645
TEST_TIME 99 9617
TEST_TIME 100 9564
TEST_TIME 101 9550
TEST_TIME 102 9541
TEST_TIME 103 9492
TEST_TIME 104 9490
TEST_TIME 105 9438
TEST_TIME 106 9407
TEST_TIME 107 9401
TEST_TIME 108 9373
TEST_TIME 109 9361
TEST_TIME 110 9303
TEST_TIME 111 9284
TEST_TIME 112 9304
TEST_TIME 113 9230
TEST_TIME 114 9229
TEST_TIME 115 9260
TEST_TIME 116 9232
TEST_TIME 117 9219
TEST_TIME 118 9289
TEST_TIME 119 9264
TEST_TIME 120 9215

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-04-2022 16:39:32 to 21-04-2022 16:39:35.

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 1526389 1526389 0 0.00 0 23944 6 0 0
doc_list_biud 1526378 1526378 0 0.00 0 29743 28 0 0
sp_add_invoice_to_stock 5039 4923 116 2.30 75 206544 19129 0 116
sp_cancel_adding_invoice 5753 5753 0 0.00 5 85354 779 0 0
sp_cancel_client_order 1724 1724 0 0.00 4 11406 415 0 0
sp_cancel_customer_reserve 566156 566156 0 0.00 1 19946 60 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 4988 84 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 2410 74 0 0
sp_cancel_supplier_invoice 6462 6448 14 0.21 1 28025 769 0 14
sp_cancel_supplier_order 413 413 0 0.00 6 1063086 103730 0 0
sp_cancel_write_off 9363 9363 0 0.00 1 11044 107 0 0
sp_client_order 19795 19795 0 0.00 3 22248 356 0 0
sp_customer_reserve 454708 454708 0 0.00 0 46285 188 0 0
sp_fill_shopping_cart 30555 30555 0 0.00 1 31314 48 0 0
sp_fill_shopping_cart_clo_res 454706 454706 0 0.00 0 29962 14 0 0
sp_get_clo_for_invoice 4918 4918 0 0.00 0 31344 424 0 0
sp_kill_cost_storno 16153 16153 0 0.00 0 8313 55 0 0
sp_kill_qstorno_ret_qs2qd 1148536 1148536 0 0.00 0 27982 32 0 0
sp_kill_qty_storno 604528 604528 0 0.00 0 29743 70 0 0
sp_lock_selected_doc 20286 20286 0 0.00 0 8442 5 0 0
sp_make_cost_storno 27569 27569 0 0.00 0 23942 65 0 0
sp_multiply_rows_for_pdistr 27562 27562 0 0.00 0 15597 32 0 0
sp_multiply_rows_for_qdistr 19795 19795 0 0.00 0 22234 162 0 0
sp_pay_from_customer 7075 7075 0 0.00 4 19343 129 0 0
sp_pay_to_supplier 5820 5820 0 0.00 5 24011 148 0 0
sp_payment_common 12891 12891 0 0.00 1 19291 36 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 11391 384 0 0
sp_qd_handle_on_invoice_upd_sts 10667 10667 0 0.00 0 29742 343 0 0
sp_qd_handle_on_reserve_upd_sts 9757 9757 0 0.00 0 17515 66 0 0
sp_reserve_write_off 9758 9758 0 0.00 6 17654 203 0 0
sp_supplier_invoice 5325 5325 0 0.00 6 88320 3481 0 0
sp_supplier_order 5443 5443 0 0.00 6 47224 1271 0 0
srv_aggregate_perf_data 104 103 1 0.96 2196 433189 22680 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1498 159697 47501 0 0
srv_find_qd_qs_mism 1058494 1058494 0 0.00 0 14279 5 0 0
srv_make_invnt_saldo 638 638 0 0.00 28 85437 1123 0 0
srv_make_money_saldo 791 791 0 0.00 0 9655 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 237624 493290 365953 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 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 4923 4923 0 0.00 0 12392 182 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 6685 82 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 4169 17 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 2990 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 427 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 5326 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 692 11 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 29840 1733 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 982 5 0 0
t$perf-norm:sp_client_order 19795 19795 0 0.00 0 10712 19 0 0
t$perf-norm:sp_customer_reserve 1065 1065 0 0.00 0 1828 8 0 0
t$perf-norm:sp_pay_from_customer 7075 7075 0 0.00 0 10476 11 0 0
t$perf-norm:sp_pay_to_supplier 5820 5820 0 0.00 0 3918 8 0 0
t$perf-norm:sp_reserve_write_off 9758 9758 0 0.00 0 10741 14 0 0
t$perf-norm:sp_supplier_invoice 5325 5325 0 0.00 0 6408 21 0 0
t$perf-norm:sp_supplier_order 5443 5443 0 0.00 0 12729 19 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 277 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2234 38 0 0
t$perf-norm:srv_make_invnt_saldo 638 638 0 0.00 0 3207 32 0 0
t$perf-norm:srv_make_money_saldo 791 791 0 0.00 0 2389 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10060 9828 232 2.30 0 6685 21 0 232
v_all_customers 36460 36460 0 0.00 0 14 1 0 0
v_all_suppliers 30182 30182 0 0.00 0 21 1 0 0
v_all_wares 39590 39590 0 0.00 0 229 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 937 7 0 0
v_cancel_client_order 3448 3448 0 0.00 0 2333 6 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 4953 16 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 2319 6 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 1658 5 0 0
v_cancel_supplier_invoice 822 794 28 3.40 0 1701 19 0 28
v_cancel_supplier_order 826 826 0 0.00 0 2794 10 0 0
v_cancel_write_off 1932 1932 0 0.00 0 6069 26 0 0
v_max_id_clo_ord 5441 5441 0 0.00 0 2718 2 0 0
v_max_id_clo_res 1065 1065 0 0.00 0 14074 33 0 0
v_max_id_ord_sup 5319 5319 0 0.00 0 8335 6 0 0
v_max_non_paid_invoice 1487 1487 0 0.00 0 4711 6 0 0
v_max_non_paid_realizn 3747 3747 0 0.00 0 10546 13 0 0
v_min_id_clo_ord 5441 5441 0 0.00 0 1248 5 0 0
v_min_id_clo_res 1065 1065 0 0.00 0 1547 5 0 0
v_min_id_ord_sup 5319 5319 0 0.00 0 6719 9 0 0
v_min_non_paid_invoice 5818 5818 0 0.00 0 8863 24 0 0
v_min_non_paid_realizn 7073 7073 0 0.00 0 10419 26 0 0
v_our_firm 9782 9782 0 0.00 0 18 1 0 0
v_reserve_write_off 19514 19514 0 0.00 0 6180 6 0 0
x_lock_depdocs_on_canc_invoice 11804 11804 0 0.00 0 24247 119 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 9006 383 0 0
x_make_qstorno_1000_1200 5443 5443 0 0.00 1 47213 1181 0 0
x_make_qstorno_1200_2000 5325 5325 0 0.00 3 88220 3299 0 0
x_make_qstorno_2100_3300 454708 454708 0 0.00 0 46282 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-04-2022 16:39:36 to 21-04-2022 16:39:40.

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
14:08:27 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:28 21494 8192000000 9905956432 10216673280 989787280 10.779 100 90 180 0 1019207648 6287184 23133184
14:10:54 23718 8192000000 9943123824 10265636864 1030500784 11.173 100 91 182 0 1062389232 8394096 23494352
14:12:22 20493 8192000000 9966539504 10297044992 1047943088 11.341 100 81 162 0 1077962288 9789904 20229296
14:13:55 16866 8192000000 10048157152 10397003776 1128479968 12.107 100 83 166 0 1159904800 11194608 20230224
14:15:17 10310 8192000000 10086429632 10451537920 1175799488 12.551 100 79 158 0 1206462512 11871520 18791504
14:16:30 4678 8192000000 10131226992 10498576384 1215472288 12.920 100 89 178 1 1249490464 14369408 19648768
14:17:37 3143 8192000000 10164116832 10540163072 1259353568 13.324 100 87 172 2 1291023680 13384048 18286064
14:18:42 2192 8192000000 10194529584 10572140544 1274608784 13.464 100 95 189 2 1309533152 13684448 21239920
14:19:45 1999 8192000000 10207571616 10597654528 1294375136 13.644 100 70 140 1 1323160240 12855904 15929200
14:20:49 5592 8192000000 10199896016 10602803200 1306224704 13.752 100 59 118 0 1330575952 11033888 13317360
14:21:56 9821 8192000000 10186717488 10604642304 1309336208 13.780 100 17 34 0 1319007328 5868832 3802288
14:23:07 7865 8192000000 10207340960 10625753088 1319681872 13.874 100 26 52 1 1336392240 10383424 6326944
14:24:17 22084 8192000000 10219587552 10638794752 1322514256 13.899 100 17 35 1 1331570992 5407536 3649200
14:25:41 4907 8192000000 10227421328 10644443136 1332330816 13.988 100 33 66 0 1345551488 5248256 7972416
14:26:49 7978 8192000000 10223979024 10646048768 1326279120 13.934 99 19 38 1 1335886800 5075904 4531776
14:27:57 15123 8192000000 10212115520 10633080832 1324964160 13.922 100 17 34 0 1334712208 6020896 3727152
14:29:15 25704 8192000000 10179701424 10595897344 1241184160 13.157 94 35 70 0 1259251440 9812288 8254992
14:30:42 8532 8192000000 10167245040 10580897792 1243171984 13.175 96 20 40 1 1253524416 5582384 4770048
14:31:55 8290 8192000000 10134684720 10529882112 1219570736 12.958 98 35 70 2 1233447728 5492032 8384960
14:33:06 10251 8192000000 10155064160 10558156800 1241275296 13.158 98 15 30 1 1249659968 5371120 3013552
14:34:17 21155 8192000000 10159208496 10572005376 1253592736 13.271 98 27 54 0 1266202896 6683392 5926768
14:35:39 6841 8192000000 10182577984 10599112704 1295223184 13.652 100 29 58 0 1308056368 6256768 6576416
14:36:48 11808 8192000000 10199761600 10615865344 1305886656 13.749 100 27 54 0 1318035312 5798128 6350528
14:38:01 15508 8192000000 10219128704 10635857920 1326949232 13.940 100 17 33 3 1335388208 4627024 3811952
14:39:50 55660 8192000000 10258276768 10676621312 1348640144 14.135 100 41 80 2 1367495616 10243632 8611840
14:41:55 2707 8192000000 10285856384 10707337216 1375926416 14.380 100 44 90 0 1399631264 12887520 10817328
14:43:02 6991 8192000000 10304693744 10727432192 1389152880 14.498 100 55 109 1 1419450864 17407328 12890656
14:44:11 7157 8192000000 10302556016 10730917888 1391058464 14.515 100 45 90 1 1417686848 16009312 10619072
14:45:24 13782 8192000000 10307232352 10744266752 1396965360 14.568 100 48 96 0 1429279440 20976048 11338032
14:46:39 20673 8192000000 10323124864 10746949632 1401216544 14.606 100 63 125 2 1436749264 21427760 14104960
14:48:08 7347 8192000000 10347211072 10770280448 1414119152 14.721 100 70 140 0 1452995712 22647808 16228752
14:49:23 2175 8192000000 10357913040 10780479488 1422209952 14.792 100 71 142 0 1464797728 25420688 17167088
14:50:27 6152 8192000000 10353460816 10781184000 1410337408 14.687 99 59 118 0 1452288416 27568704 14382304
14:51:38 3750 8192000000 10356182096 10772795392 1399745344 14.593 99 83 165 0 1450346144 30750080 19850720
14:52:46 12311 8192000000 10343267440 10764062720 1390806960 14.513 99 60 120 0 1439199552 33587664 14804928
14:54:02 1941 8192000000 10333964912 10736992256 1376912256 14.389 100 90 179 0 1431319600 31664704 22742640
14:55:14 5023 8192000000 10314994144 10720342016 1367719376 14.307 100 79 156 2 1421496112 34074224 19702512
14:56:26 4877 8192000000 10325804832 10729934848 1370103616 14.328 100 84 168 1 1429357008 38088336 21165056
14:57:35 5314 8192000000 10348980064 10756907008 1385167312 14.463 100 83 164 0 1448299248 42311856 20820080
14:58:48 1497 8192000000 10365806992 10777698304 1408786400 14.673 100 89 176 1 1469600480 38140096 22673984
14:59:53 15649 8192000000 10341415088 10770546688 1406054912 14.649 100 34 68 1 1448341216 32651328 9634976
15:01:14 15523 8192000000 10339120144 10766110720 1379724240 14.414 98 40 80 1 1431150000 40256576 11169184
15:02:38 14416 8192000000 10341149872 10778472448 1398526704 14.582 100 40 79 2 1444188240 35042464 10619072
15:04:01 6956 8192000000 10340809120 10774777856 1378608608 14.404 99 30 60 2 1431455136 44548800 8297728
15:05:14 28511 8192000000 10294499024 10733260800 1350659952 14.153 99 20 40 1 1391536016 35159584 5716480
15:06:45 51251 8192000000 10286471824 10728775680 1335648848 14.018 99 14 28 0 1386290448 46361856 4279744
15:08:37 47820 8192000000 10268455696 10722582528 1337680928 14.036 98 40 77 3 1367748448 20025840 10041680
15:10:28 50509 8192000000 10277194848 10710310912 1335922720 14.021 98 36 72 1 1357955776 13892784 8140272
15:12:21 42918 8192000000 10276884336 10728648704 1357369584 14.214 99 11 22 0 1373619504 13904288 2345632
15:14:06 49535 8192000000 10286379296 10753818624 1383182992 14.445 100 10 20 1 1401899872 16492112 2224768
15:15:56 84207 8192000000 10286220288 10741297152 1356668560 14.207 98 11 22 0 1373112416 14006160 2437696
15:18:21 50204 8192000000 10294055360 10734886912 1378045232 14.399 100 37 74 1 1402563184 15256368 9261584
15:20:12 36062 8192000000 10307948560 10744184832 1387611344 14.485 100 43 85 1 1407459216 10040480 9807392
15:21:52 123326 8192000000 10298204144 10751561728 1379148016 14.409 99 27 54 1 1393089296 7765024 6176256
15:25:44 109685 8192000000 10300676832 10766237696 1393413776 14.536 100 30 60 2 1414554336 14138448 7002112
15:28:38 26938 8192000000 10295090720 10752139264 1392385504 14.527 99 38 74 3 1410255472 9487296 8382672
15:30:09 95035 8192000000 10303355600 10772357120 1380109040 14.418 98 10 20 0 1394411440 12066112 2236288
15:32:46 77743 8192000000 10294327824 10765492224 1391421104 14.519 99 13 26 1 1409702352 15530464 2750784
15:35:14 38491 8192000000 10296386368 10763784192 1399638944 14.592 100 19 38 0 1413404192 8815360 4949888
15:36:56 71842 8192000000 10286466528 10750246912 1358780688 14.226 97 14 28 1 1372873152 10863296 3229168
15:39:14 59220 8192000000 10287477984 10738475008 1354012384 14.184 98 17 32 1 1368381152 10190656 4178112
15:41:18 112589 8192000000 10264301728 10718650368 1285155088 13.560 93 7 14 0 1307122560 20527824 1439648
15:44:11 61380 8192000000 10273106608 10720641024 1328107200 13.950 96 23 46 0 1346892304 13193648 5591456
15:46:17 67716 8192000000 10291713904 10737967104 1351333984 14.159 98 34 68 1 1371470464 12057184 8079296
15:48:26 59725 8192000000 10273966432 10714898432 1288521456 13.591 94 29 58 1 1313605232 18821984 6261792
15:50:28 90952 8192000000 10260948288 10710491136 1327402864 13.944 98 15 30 1 1347265120 16646608 3215648
15:53:05 62472 8192000000 10249489584 10701742080 1297038832 13.668 95 20 40 1 1309380272 7779920 4561520
15:55:08 39971 8192000000 10270957488 10706219008 1333561648 13.999 98 44 88 0 1357359728 14092480 9705600
15:56:49 26886 8192000000 10228115744 10682171392 1321478592 13.890 98 27 54 1 1337254848 8417072 7359184
15:58:17 42321 8192000000 10223778176 10663006208 1283534944 13.545 95 22 44 0 1301192144 12270928 5386272
16:00:07 66394 8192000000 10267728880 10725113856 1357482912 14.215 100 19 38 0 1380639104 18298256 4857936
16:03:31 70144 8192000000 10273732912 10732052480 1352858448 14.173 99 11 22 0 1366188096 11608208 1721440
16:06:26 58995 8192000000 10290496080 10747641856 1377526864 14.394 100 18 36 2 1399567600 17891840 4148896
16:08:29 89297 8192000000 10313791344 10759446528 1367649632 14.306 98 28 56 2 1386658496 12129856 6879008
16:11:03 120491 8192000000 10299919776 10753409024 1364350128 14.276 98 11 22 0 1386308400 19940592 2017680
16:14:05 159697 8192000000 10300771520 10757120000 1352153760 14.167 97 9 18 0 1373344352 19145808 2044784
16:18:02 25853 8192000000 10304884880 10755444736 1394301920 14.544 100 33 66 3 1409618896 7444416 7872560
16:19:28 45628 8192000000 10302630912 10765578240 1400207648 14.597 100 13 26 0 1418768528 15590160 2970720
16:21:30 64662 8192000000 10300500016 10761728000 1388466912 14.492 99 7 14 1 1405735024 15812784 1455328
16:23:42 50270 8192000000 10291646528 10753196032 1381904816 14.434 98 9 17 1 1392576720 8169744 2502160
16:25:41 121673 8192000000 10281261728 10738925568 1335116544 14.013 95 17 34 0 1347055936 7939744 3999648
16:29:18 56524 8192000000 10278404160 10737930240 1345412608 14.106 96 29 58 0 1361351888 9006768 6932512
16:31:31 59031 8192000000 10267020288 10740588544 1360524320 14.242 98 7 14 0 1372786336 10765152 1496864
16:33:39 38581 8192000000 10263292784 10718109696 1316614352 13.846 96 21 42 0 1331368224 10378976 4374896
16:35:18 60859 8192000000 10258601712 10723938304 1349806928 14.146 99 33 66 0 1375480864 18199984 7473952
16:37:51 39647 8192000000 9121803168 9365721088 282983040 3.339 22 2 4 0 288230832 4980880 266912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-04-2022 16:39:42 to 21-04-2022 16:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 116 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-04-2022 16:39:58 to 21-04-2022 16:40:00.

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          237348
MON$OLDEST_ACTIVE               237349
MON$OLDEST_SNAPSHOT             237349
MON$NEXT_TRANSACTION            237349
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                       2050912
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
--- end of diff output ---

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

21.04.22 16:40:01. 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-04-21 16:40:03 No severe PSQL-related problems occured
21.04.22 16:40:05. End of report.