Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.06.2022 01: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 16265920 kB
MemAvailable 31294008 kB
Buffers 0 kB
Cached 15477204 kB
SwapCached 2460 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-06-16 01:38:20
DTS_END                         2022-06-16 01:38:20.1150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45590
TRN_ID                          243078
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: 9254.13 20307 1110495
customer order: creation 175.76 391 21091
customer order: refuse 15.48 339 1857
order to supplier: creation 47.64 1258 5717
order to supplier: removal 3.43 98874 411
invoice (draft): creation 47.35 3449 5682
invoice (draft): removal 53.42 754 6410
invoice accept: apply 41.94 16505 5033
invoice accept: cancel 48.26 741 5791
customer reserve: creation 3808.87 164 457064
customer reserve: removal 4713.27 56 565593
realization accept: apply 84.79 203 10175
realization accept: cancel 85.21 112 10225
payment from customer: creation 58.79 142 7055
payment from customer: removal 5.48 74 658
payment to supplier: creation 49.23 157 5908
payment to supplier: removal 3.43 88 412
service: total inventory turnovers 4.64 983 557
service: total monetary turnovers 6.41 25 769
service: aggregate perf. data 0.69 27885 83
service: refresh index statistics 0.03 253933 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-06-2022 01:40:19 to 16-06-2022 01:40:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4951
TEST_TIME 2 4030
TEST_TIME 3 3251
TEST_TIME 4 3976
TEST_TIME 5 4349
TEST_TIME 6 4545
TEST_TIME 7 5379
TEST_TIME 8 5325
TEST_TIME 9 5509
TEST_TIME 10 5932
TEST_TIME 11 5965
TEST_TIME 12 5838
TEST_TIME 13 5750
TEST_TIME 14 6218
TEST_TIME 15 6394
TEST_TIME 16 6708
TEST_TIME 17 7149
TEST_TIME 18 7728
TEST_TIME 19 8154
TEST_TIME 20 8283
TEST_TIME 21 8160
TEST_TIME 22 8361
TEST_TIME 23 8299
TEST_TIME 24 8795
TEST_TIME 25 8731
TEST_TIME 26 9925
TEST_TIME 27 10374
TEST_TIME 28 10319
TEST_TIME 29 10376
TEST_TIME 30 10400
TEST_TIME 31 10249
TEST_TIME 32 10201
TEST_TIME 33 10128
TEST_TIME 34 10091
TEST_TIME 35 9854
TEST_TIME 36 9903
TEST_TIME 37 9803
TEST_TIME 38 9693
TEST_TIME 39 9776
TEST_TIME 40 9753
TEST_TIME 41 9663
TEST_TIME 42 9775
TEST_TIME 43 9846
TEST_TIME 44 9741
TEST_TIME 45 9712
TEST_TIME 46 9827
TEST_TIME 47 9721
TEST_TIME 48 9571
TEST_TIME 49 9537
TEST_TIME 50 9570
TEST_TIME 51 9695
TEST_TIME 52 9672
TEST_TIME 53 9634
TEST_TIME 54 9729
TEST_TIME 55 9648
TEST_TIME 56 9635
TEST_TIME 57 9799
TEST_TIME 58 9785
TEST_TIME 59 9769
TEST_TIME 60 9913
TEST_TIME 61 9854
TEST_TIME 62 9998
TEST_TIME 63 9995
TEST_TIME 64 10071
TEST_TIME 65 9986
TEST_TIME 66 9882
TEST_TIME 67 9883
TEST_TIME 68 9784
TEST_TIME 69 9717
TEST_TIME 70 9734
TEST_TIME 71 9699
TEST_TIME 72 9633
TEST_TIME 73 9568
TEST_TIME 74 9581
TEST_TIME 75 9554
TEST_TIME 76 9576
TEST_TIME 77 9504
TEST_TIME 78 9479
TEST_TIME 79 9436
TEST_TIME 80 9461
TEST_TIME 81 9414
TEST_TIME 82 9536
TEST_TIME 83 9494
TEST_TIME 84 9539
TEST_TIME 85 9574
TEST_TIME 86 9526
TEST_TIME 87 9581
TEST_TIME 88 9592
TEST_TIME 89 9609
TEST_TIME 90 9656
TEST_TIME 91 9571
TEST_TIME 92 9498
TEST_TIME 93 9486
TEST_TIME 94 9419
TEST_TIME 95 9401
TEST_TIME 96 9415
TEST_TIME 97 9426
TEST_TIME 98 9409
TEST_TIME 99 9431
TEST_TIME 100 9469
TEST_TIME 101 9434
TEST_TIME 102 9482
TEST_TIME 103 9496
TEST_TIME 104 9498
TEST_TIME 105 9485
TEST_TIME 106 9513
TEST_TIME 107 9529
TEST_TIME 108 9526
TEST_TIME 109 9592
TEST_TIME 110 9513
TEST_TIME 111 9476
TEST_TIME 112 9452
TEST_TIME 113 9450
TEST_TIME 114 9466
TEST_TIME 115 9426
TEST_TIME 116 9377
TEST_TIME 117 9366
TEST_TIME 118 9362
TEST_TIME 119 9293
TEST_TIME 120 9260

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-06-2022 01:40:23 to 16-06-2022 01:40:26.

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 1535188 1535188 0 0.00 0 31420 6 0 0
doc_list_biud 1535188 1535188 0 0.00 0 58455 26 0 0
sp_add_invoice_to_stock 5140 5033 107 2.08 28 225323 16505 0 107
sp_cancel_adding_invoice 5791 5791 0 0.00 4 58171 742 0 0
sp_cancel_client_order 1857 1857 0 0.00 4 10178 339 0 0
sp_cancel_customer_reserve 565593 565593 0 0.00 1 58456 56 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 2 4012 75 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 4051 88 0 0
sp_cancel_supplier_invoice 6418 6410 8 0.12 1 46400 755 0 8
sp_cancel_supplier_order 411 411 0 0.00 10 951067 98874 0 0
sp_cancel_write_off 10225 10225 0 0.00 1 19050 113 0 0
sp_client_order 21091 21091 0 0.00 3 46006 392 0 0
sp_customer_reserve 457064 457064 0 0.00 0 61913 165 0 0
sp_fill_shopping_cart 32481 32481 0 0.00 1 13315 40 0 0
sp_fill_shopping_cart_clo_res 457058 457058 0 0.00 0 15423 13 0 0
sp_get_clo_for_invoice 5022 5022 0 0.00 0 24784 265 0 0
sp_kill_cost_storno 17086 17086 0 0.00 0 5387 52 0 0
sp_kill_qstorno_ret_qs2qd 1148232 1148232 0 0.00 0 58452 31 0 0
sp_kill_qty_storno 605484 605484 0 0.00 0 58454 66 0 0
sp_lock_selected_doc 21122 21122 0 0.00 0 7935 6 0 0
sp_make_cost_storno 28160 28160 0 0.00 0 31325 74 0 0
sp_multiply_rows_for_pdistr 28160 28160 0 0.00 0 15276 35 0 0
sp_multiply_rows_for_qdistr 21091 21091 0 0.00 0 46000 177 0 0
sp_pay_from_customer 7055 7055 0 0.00 4 17811 142 0 0
sp_pay_to_supplier 5908 5908 0 0.00 4 15284 158 0 0
sp_payment_common 12963 12963 0 0.00 1 15174 43 0 0
sp_qd_handle_on_cancel_clo 1857 1857 0 0.00 0 10167 307 0 0
sp_qd_handle_on_invoice_upd_sts 10813 10813 0 0.00 0 26541 319 0 0
sp_qd_handle_on_reserve_upd_sts 10175 10175 0 0.00 0 14817 55 0 0
sp_reserve_write_off 10175 10175 0 0.00 6 15394 204 0 0
sp_supplier_invoice 5682 5682 0 0.00 6 84103 3449 0 0
sp_supplier_order 5717 5717 0 0.00 5 25371 1259 0 0
srv_aggregate_perf_data 84 83 1 1.19 2468 549598 27885 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 2628 151059 51463 0 0
srv_find_qd_qs_mism 1063325 1063325 0 0.00 0 20395 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 37 19700 984 0 0
srv_make_money_saldo 769 769 0 0.00 1 3932 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 180962 450413 253933 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 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 5033 5033 0 0.00 0 8726 139 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 6900 118 0 0
t$perf-norm:sp_cancel_client_order 1857 1857 0 0.00 0 7166 21 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 1556 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 460 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 713 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 2268 15 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 22419 1812 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 1004 3 0 0
t$perf-norm:sp_client_order 21091 21091 0 0.00 0 8434 17 0 0
t$perf-norm:sp_customer_reserve 1050 1050 0 0.00 0 1348 4 0 0
t$perf-norm:sp_pay_from_customer 7055 7055 0 0.00 0 8204 9 0 0
t$perf-norm:sp_pay_to_supplier 5908 5908 0 0.00 0 5738 10 0 0
t$perf-norm:sp_reserve_write_off 10175 10175 0 0.00 0 7267 12 0 0
t$perf-norm:sp_supplier_invoice 5682 5682 0 0.00 0 4385 13 0 0
t$perf-norm:sp_supplier_order 5718 5718 0 0.00 0 5996 16 0 0
t$perf-norm:srv_aggregate_perf_data 83 83 0 0.00 0 292 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 918 26 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 1306 20 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 638 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10256 10042 214 2.08 0 5512 17 0 214
v_all_customers 38180 38180 0 0.00 0 112 0 0 0
v_all_suppliers 31114 31114 0 0.00 0 112 1 0 0
v_all_wares 42180 42180 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 497 6 0 0
v_cancel_client_order 3714 3714 0 0.00 0 2168 6 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 1560 5 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 1204 4 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1660 10 0 0
v_cancel_supplier_invoice 832 816 16 1.92 0 4862 24 0 16
v_cancel_supplier_order 822 822 0 0.00 0 412 3 0 0
v_cancel_write_off 2134 2134 0 0.00 0 1857 11 0 0
v_max_id_clo_ord 5716 5716 0 0.00 0 1377 2 0 0
v_max_id_clo_res 1050 1050 0 0.00 0 2155 12 0 0
v_max_id_ord_sup 5674 5674 0 0.00 0 3902 4 0 0
v_max_non_paid_invoice 1741 1741 0 0.00 0 2537 4 0 0
v_max_non_paid_realizn 3805 3805 0 0.00 0 6623 9 0 0
v_min_id_clo_ord 5716 5716 0 0.00 0 3144 7 0 0
v_min_id_clo_res 1050 1050 0 0.00 0 2455 4 0 0
v_min_id_ord_sup 5674 5674 0 0.00 0 4558 7 0 0
v_min_non_paid_invoice 5908 5908 0 0.00 0 13400 32 0 0
v_min_non_paid_realizn 7055 7055 0 0.00 0 15119 31 0 0
v_our_firm 10500 10500 0 0.00 0 12 1 0 0
v_reserve_write_off 20350 20350 0 0.00 0 5843 6 0 0
x_lock_depdocs_on_canc_invoice 11793 11793 0 0.00 0 25619 130 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 5835 403 0 0
x_make_qstorno_1000_1200 5717 5717 0 0.00 1 25362 1169 0 0
x_make_qstorno_1200_2000 5682 5682 0 0.00 3 84093 3309 0 0
x_make_qstorno_2100_3300 457064 457064 0 0.00 0 61912 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-06-2022 01:40:27 to 16-06-2022 01:40:31.

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:23 3 8192000000 8801766176 8813973504 7792032 0.095 2 2 3 0 7933136 66144 74960
23:09:25 12693 8192000000 9915375600 10226954240 1003404576 10.912 100 89 179 0 1032396224 6190576 22801072
23:10:48 22753 8192000000 9964351808 10293084160 1044678800 11.310 100 84 168 2 1074688352 8625792 21383760
23:12:14 15891 8192000000 10000276352 10336235520 1082072656 11.667 100 89 178 1 1114563648 10308272 22182720
23:13:33 14186 8192000000 10031245680 10378919936 1116152880 11.991 100 75 150 0 1145015264 11223920 17638464
23:14:49 8481 8192000000 10075499280 10431750144 1162852784 12.430 100 80 160 1 1193481632 11855600 18773248
23:16:00 7057 8192000000 10120931376 10485882880 1210130448 12.870 100 78 156 0 1239952288 12089776 17732064
23:17:10 2496 8192000000 10161447952 10532753408 1244840224 13.191 100 87 173 1 1276410688 12616288 18954176
23:18:14 2392 8192000000 10195306864 10576220160 1276672448 13.483 100 91 180 1 1309431744 13305376 19453920
23:19:18 1561 8192000000 10217348960 10603061248 1296770576 13.666 100 90 180 0 1334142688 17448896 19923216
23:20:21 14283 8192000000 10214990112 10602938368 1303244736 13.725 100 76 154 0 1327354080 6278480 17830864
23:21:36 4422 8192000000 10199998800 10612387840 1311452800 13.799 100 20 40 1 1322024016 6224016 4347200
23:22:42 5630 8192000000 10203424704 10620153856 1322126144 13.896 100 36 72 1 1336268944 5598592 8544208
23:23:49 7577 8192000000 10214138096 10633277440 1329562736 13.963 100 19 38 0 1339951328 5905952 4482640
23:25:02 28889 8192000000 10221070448 10645237760 1334931856 14.012 100 30 60 1 1347191680 4622896 7636928
23:26:34 8819 8192000000 10225974352 10651394048 1339677568 14.055 100 22 44 1 1351132992 6793648 4661776
23:27:44 9885 8192000000 10227875360 10645581824 1322039552 13.895 99 26 51 0 1333783584 6154032 5590000
23:28:57 10274 8192000000 10200223872 10615672832 1291504368 13.618 98 27 54 0 1303883088 5519600 6859120
23:30:10 13832 8192000000 10203458128 10594840576 1254252656 13.277 96 54 108 1 1273840512 6524416 13063440
23:31:24 4919 8192000000 10164636928 10567983104 1262093856 13.349 99 26 52 0 1279303824 11126800 6083168
23:32:30 7801 8192000000 10127810640 10520113152 1200050688 12.777 97 16 30 1 1209091152 5256688 3783776
23:33:40 30472 8192000000 10157159376 10562011136 1263626720 13.363 100 22 44 1 1273973072 5283584 5062768
23:35:27 7989 8192000000 10182477760 10589794304 1283176384 13.542 100 25 50 0 1294046304 4825600 6044320
23:36:38 10949 8192000000 10186241664 10601013248 1297485952 13.672 100 17 35 0 1307074864 5570656 4018256
23:37:51 11600 8192000000 10193526416 10610585600 1307752176 13.766 100 26 53 2 1319696688 5151344 6793168
23:39:08 27839 8192000000 10252800688 10663665664 1346322560 14.114 100 39 78 0 1364487216 7981408 10183248
23:40:43 77370 8192000000 10264197120 10684461056 1359170576 14.230 100 44 88 5 1381466288 12214768 10080944
23:43:05 30707 8192000000 10282166288 10702553088 1369409936 14.322 100 32 64 0 1393256080 16297328 7548816
23:44:40 6920 8192000000 10298321888 10718486528 1381266896 14.428 100 40 81 1 1409465568 18048496 10150176
23:45:57 4789 8192000000 10305490288 10731118592 1391680144 14.521 100 56 112 1 1423116736 18345408 13091184
23:47:07 3890 8192000000 10335289984 10753773568 1403577344 14.627 100 69 134 2 1445865600 25965568 16322688
23:48:15 5039 8192000000 10312789168 10732990464 1388178960 14.490 100 43 86 1 1420873104 22038048 10656096
23:49:23 9871 8192000000 10336056208 10754445312 1408868288 14.674 100 59 118 0 1446453184 23249520 14335376
23:50:39 7471 8192000000 10321120960 10741846016 1382869024 14.442 99 55 110 5 1422130976 25514496 13747456
23:51:51 9661 8192000000 10344164976 10760482816 1402390544 14.616 100 70 140 0 1456036624 35831600 17814480
23:53:07 2711 8192000000 10356903936 10766045184 1398588208 14.582 100 78 157 2 1460402752 42005632 19808912
23:54:15 2626 8192000000 10332942304 10743230464 1354194160 14.185 97 78 156 2 1417499920 43164368 20141392
23:55:23 6661 8192000000 10319023584 10735124480 1355802560 14.200 99 51 102 1 1420493968 50675712 14015696
23:56:36 3123 8192000000 10343357376 10752733184 1373594304 14.359 100 80 160 1 1444847472 51241888 20011280
23:57:43 13112 8192000000 10320772448 10754269184 1378546736 14.404 100 28 56 0 1433025088 46033808 8444544
23:59:00 19497 8192000000 10313977056 10738835456 1361170592 14.248 99 22 44 1 1416185472 48894048 6120832
00:00:31 11066 8192000000 10329640544 10755723264 1379991680 14.416 100 35 70 0 1432407344 42896896 9518768
00:01:47 18564 8192000000 10329300576 10767892480 1378865008 14.406 100 41 82 3 1439133952 49192608 11076336
00:03:19 28084 8192000000 10289694400 10722095104 1331132304 13.977 98 21 42 0 1381200528 43420752 6647472
00:04:50 25342 8192000000 10267432768 10716299264 1314209152 13.824 97 21 42 0 1354565296 34826192 5529952
00:06:23 52764 8192000000 10274176560 10719682560 1336755152 14.028 99 27 55 2 1363498016 19802128 6940736
00:08:17 59794 8192000000 10273647648 10710994944 1327119376 13.941 97 16 31 1 1337812048 7344784 3347888
00:10:25 61805 8192000000 10302552400 10721501184 1364521936 14.278 99 37 74 1 1389715840 17130000 8063904
00:12:34 107490 8192000000 10275128128 10721718272 1344264608 14.096 98 44 85 1 1361814544 7444240 10105696
00:15:34 23068 8192000000 10301489488 10735996928 1380271776 14.419 100 59 118 1 1404343424 10482912 13588736
00:16:59 55369 8192000000 10299675616 10747510784 1387575536 14.484 100 12 25 0 1400592848 10164544 2852768
00:19:20 36816 8192000000 10321580880 10762326016 1400484176 14.599 100 34 68 3 1422018720 13034000 8500544
00:21:14 28049 8192000000 10309025408 10750500864 1382728528 14.441 99 41 82 1 1404335424 11819488 9787408
00:22:52 54929 8192000000 10306263840 10766692352 1391405712 14.518 99 13 26 0 1406388064 11895568 3086784
00:24:48 44989 8192000000 10312209552 10771406848 1387191344 14.481 99 28 55 1 1406804144 12990288 6622512
00:26:37 41379 8192000000 10328959728 10782806016 1404080464 14.631 100 20 40 1 1427738160 18912624 4745072
00:28:26 84383 8192000000 10304612032 10767134720 1377993632 14.399 98 17 34 0 1398399936 16476960 3929344
00:30:53 57069 8192000000 10313135600 10759446528 1376388464 14.384 98 31 62 1 1391876880 7796592 7691824
00:32:54 34593 8192000000 10296585264 10745511936 1372279888 14.347 98 23 46 2 1388113168 10178896 5654384
00:34:38 83991 8192000000 10273874304 10738450432 1307715296 13.765 94 14 28 0 1328203088 17206208 3281584
00:37:13 52923 8192000000 10270541456 10717376512 1297447072 13.672 95 22 44 0 1317128320 14854288 4826960
00:39:07 30806 8192000000 10278901696 10702594048 1307101584 13.760 96 48 94 1 1325676704 8242512 10332608
00:40:39 93174 8192000000 10273094768 10728235008 1299966496 13.695 96 11 22 1 1323098448 20539456 2592496
00:43:17 120090 8192000000 10276540960 10721460224 1344067584 14.094 98 30 59 0 1360450832 9510032 6873216
00:46:23 45550 8192000000 10268966160 10727219200 1359420544 14.232 99 13 26 0 1371649328 9343696 2885088
00:48:13 83262 8192000000 10275552480 10737401856 1340962224 14.066 97 8 16 1 1354600192 11864912 1773056
00:50:41 60227 8192000000 10272286928 10726674432 1361304400 14.249 99 17 35 0 1374672704 9272800 4095504
00:52:44 54025 8192000000 10267048400 10734739456 1376082064 14.382 100 15 30 2 1390280128 10687824 3510240
00:54:46 71392 8192000000 10274566112 10744963072 1378526736 14.403 100 8 14 1 1392259024 11986784 1745504
00:57:28 41293 8192000000 10269351424 10722504704 1348244336 14.132 98 13 26 0 1363382352 12019664 3118352
00:59:12 47186 8192000000 10296030320 10744274944 1382522512 14.439 100 19 38 0 1400443776 13724672 4196592
01:01:01 66688 8192000000 10288584896 10741366784 1343332944 14.087 97 24 49 0 1358967952 10010960 5624048
01:03:09 82682 8192000000 10278165312 10732032000 1323048464 13.904 96 13 26 1 1337320496 11343984 2928048
01:05:47 77982 8192000000 10265627760 10717040640 1295884656 13.658 95 31 62 2 1316158880 13707440 6566784
01:08:08 124548 8192000000 10288652848 10730172416 1357222432 14.212 98 18 36 0 1372193056 10879392 4091232
01:11:18 62406 8192000000 10281021888 10749095936 1377376256 14.393 99 11 22 1 1386570784 6989056 2205472
01:13:23 61039 8192000000 10290382080 10762821632 1397809184 14.575 100 12 24 0 1412474400 11902016 2763200
01:15:30 76537 8192000000 10302578928 10767835136 1376186976 14.382 98 16 30 1 1392440192 13011360 3241856
01:17:49 70515 8192000000 10301049824 10772328448 1387227680 14.481 99 8 16 0 1399641136 10567792 1845664
01:20:03 64392 8192000000 10274073680 10735861760 1352218064 14.167 98 19 38 0 1369209808 12850064 4141680
01:22:08 95112 8192000000 10286468928 10739662848 1314170272 13.824 95 16 31 1 1328381648 11121520 3089856
01:24:44 137387 8192000000 10251635360 10710343680 1247464640 13.215 91 14 28 0 1261132752 10659776 3008336
01:28:05 151058 8192000000 10249023536 10709118976 1309044496 13.777 96 25 50 0 1328800320 13967488 5788336
01:32:29 85216 8192000000 10255276816 10720550912 1329363600 13.961 97 11 22 0 1344180704 12455104 2362000
01:34:54 92728 8192000000 10252753920 10701991936 1316273360 13.843 96 19 39 1 1332363760 11001856 5088544
01:37:29 50124 8192000000 10267359584 10715627520 1351691088 14.163 99 39 78 1 1373149152 12219120 9238944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-06-2022 01:40:33 to 16-06-2022 01:40:48.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 16-06-2022 01:40:49 to 16-06-2022 01:40:50.

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          240496
MON$OLDEST_ACTIVE               243343
MON$OLDEST_SNAPSHOT             243343
MON$NEXT_TRANSACTION            243343
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                       2035872
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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'

16.06.22 01:40:52. 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-06-16 01:40:54 No severe PSQL-related problems occured
16.06.22 01:40:55. End of report.