Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.06.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 16182076 kB
MemAvailable 31329600 kB
Buffers 0 kB
Cached 15510580 kB
SwapCached 2444 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-03 16:38:19
DTS_END                         2022-06-03 16:38:19.5510
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/52440
TRN_ID                          244695
ATT_ID                          726
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: 9527.85 22296 1143342
customer order: creation 178.87 340 21464
customer order: refuse 15.04 325 1805
order to supplier: creation 47.36 1300 5683
order to supplier: removal 3.70 97063 444
invoice (draft): creation 48.03 3300 5764
invoice (draft): removal 52.88 780 6346
invoice accept: apply 43.42 17914 5210
invoice accept: cancel 47.95 881 5754
customer reserve: creation 4011.59 177 481391
customer reserve: removal 4777.91 59 573349
realization accept: apply 84.63 187 10155
realization accept: cancel 84.12 115 10094
payment from customer: creation 60.81 149 7297
payment from customer: removal 5.76 89 691
payment to supplier: creation 49.28 138 5913
payment to supplier: removal 3.54 53 425
service: total inventory turnovers 5.08 1119 609
service: total monetary turnovers 6.97 25 836
service: aggregate perf. data 0.90 22589 108
service: refresh index statistics 0.03 299320 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-06-2022 16:39:27 to 03-06-2022 16:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3888
TEST_TIME 2 4539
TEST_TIME 3 4127
TEST_TIME 4 4598
TEST_TIME 5 5718
TEST_TIME 6 5849
TEST_TIME 7 6198
TEST_TIME 8 6286
TEST_TIME 9 6303
TEST_TIME 10 7043
TEST_TIME 11 6856
TEST_TIME 12 6694
TEST_TIME 13 6866
TEST_TIME 14 6902
TEST_TIME 15 7034
TEST_TIME 16 7267
TEST_TIME 17 7181
TEST_TIME 18 7417
TEST_TIME 19 7612
TEST_TIME 20 7914
TEST_TIME 21 8043
TEST_TIME 22 8115
TEST_TIME 23 8373
TEST_TIME 24 8749
TEST_TIME 25 8857
TEST_TIME 26 9129
TEST_TIME 27 9522
TEST_TIME 28 9551
TEST_TIME 29 9799
TEST_TIME 30 10350
TEST_TIME 31 11095
TEST_TIME 32 10928
TEST_TIME 33 10858
TEST_TIME 34 10695
TEST_TIME 35 10658
TEST_TIME 36 10474
TEST_TIME 37 10334
TEST_TIME 38 10371
TEST_TIME 39 10536
TEST_TIME 40 10557
TEST_TIME 41 10476
TEST_TIME 42 10393
TEST_TIME 43 10346
TEST_TIME 44 10558
TEST_TIME 45 10461
TEST_TIME 46 10308
TEST_TIME 47 10151
TEST_TIME 48 10124
TEST_TIME 49 10250
TEST_TIME 50 10140
TEST_TIME 51 10129
TEST_TIME 52 10064
TEST_TIME 53 10093
TEST_TIME 54 10008
TEST_TIME 55 10097
TEST_TIME 56 10019
TEST_TIME 57 10021
TEST_TIME 58 9987
TEST_TIME 59 9895
TEST_TIME 60 9882
TEST_TIME 61 9807
TEST_TIME 62 9741
TEST_TIME 63 9724
TEST_TIME 64 9740
TEST_TIME 65 9725
TEST_TIME 66 9790
TEST_TIME 67 9800
TEST_TIME 68 9800
TEST_TIME 69 9854
TEST_TIME 70 9822
TEST_TIME 71 9915
TEST_TIME 72 9890
TEST_TIME 73 9907
TEST_TIME 74 10006
TEST_TIME 75 10011
TEST_TIME 76 10011
TEST_TIME 77 9981
TEST_TIME 78 9893
TEST_TIME 79 9888
TEST_TIME 80 9813
TEST_TIME 81 9785
TEST_TIME 82 9729
TEST_TIME 83 9674
TEST_TIME 84 9685
TEST_TIME 85 9688
TEST_TIME 86 9659
TEST_TIME 87 9592
TEST_TIME 88 9582
TEST_TIME 89 9624
TEST_TIME 90 9601
TEST_TIME 91 9608
TEST_TIME 92 9591
TEST_TIME 93 9624
TEST_TIME 94 9660
TEST_TIME 95 9696
TEST_TIME 96 9713
TEST_TIME 97 9699
TEST_TIME 98 9743
TEST_TIME 99 9713
TEST_TIME 100 9706
TEST_TIME 101 9816
TEST_TIME 102 9818
TEST_TIME 103 9836
TEST_TIME 104 9767
TEST_TIME 105 9717
TEST_TIME 106 9650
TEST_TIME 107 9689
TEST_TIME 108 9664
TEST_TIME 109 9615
TEST_TIME 110 9595
TEST_TIME 111 9570
TEST_TIME 112 9510
TEST_TIME 113 9520
TEST_TIME 114 9516
TEST_TIME 115 9539
TEST_TIME 116 9545
TEST_TIME 117 9499
TEST_TIME 118 9547
TEST_TIME 119 9597
TEST_TIME 120 9533

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-06-2022 16:39:31 to 03-06-2022 16:39:34.

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 1589679 1589679 0 0.00 0 21230 6 0 0
doc_list_biud 1589676 1589676 0 0.00 0 69817 27 0 0
sp_add_invoice_to_stock 5274 5210 64 1.21 40 165270 17914 0 64
sp_cancel_adding_invoice 5754 5754 0 0.00 5 76108 881 0 0
sp_cancel_client_order 1805 1805 0 0.00 4 15190 325 0 0
sp_cancel_customer_reserve 573349 573349 0 0.00 1 69819 59 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 10807 90 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 931 53 0 0
sp_cancel_supplier_invoice 6355 6346 9 0.14 1 21198 781 0 9
sp_cancel_supplier_order 444 444 0 0.00 6 1115344 97064 0 0
sp_cancel_write_off 10094 10094 0 0.00 1 15112 116 0 0
sp_client_order 21464 21464 0 0.00 3 22049 341 0 0
sp_customer_reserve 481391 481391 0 0.00 0 46654 178 0 0
sp_fill_shopping_cart 32899 32899 0 0.00 1 22184 43 0 0
sp_fill_shopping_cart_clo_res 481379 481379 0 0.00 0 16406 12 0 0
sp_get_clo_for_invoice 5198 5198 0 0.00 0 26703 281 0 0
sp_kill_cost_storno 16964 16964 0 0.00 0 11188 58 0 0
sp_kill_qstorno_ret_qs2qd 1163582 1163582 0 0.00 0 69814 32 0 0
sp_kill_qty_storno 613143 613143 0 0.00 0 69816 69 0 0
sp_lock_selected_doc 21333 21333 0 0.00 0 7838 4 0 0
sp_make_cost_storno 28563 28563 0 0.00 0 21204 63 0 0
sp_multiply_rows_for_pdistr 28562 28562 0 0.00 0 11068 32 0 0
sp_multiply_rows_for_qdistr 21464 21464 0 0.00 0 21152 136 0 0
sp_pay_from_customer 7297 7297 0 0.00 4 23504 149 0 0
sp_pay_to_supplier 5913 5913 0 0.00 4 24102 138 0 0
sp_payment_common 13209 13209 0 0.00 1 23492 35 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 0 15183 296 0 0
sp_qd_handle_on_invoice_upd_sts 10952 10952 0 0.00 0 30761 346 0 0
sp_qd_handle_on_reserve_upd_sts 10152 10152 0 0.00 0 14993 57 0 0
sp_reserve_write_off 10155 10155 0 0.00 6 17633 188 0 0
sp_supplier_invoice 5764 5764 0 0.00 7 84415 3301 0 0
sp_supplier_order 5683 5683 0 0.00 4 26484 1300 0 0
srv_aggregate_perf_data 109 108 1 0.91 2780 282360 22589 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 1148 127177 39955 0 0
srv_find_qd_qs_mism 1094259 1094259 0 0.00 0 14423 5 0 0
srv_make_invnt_saldo 609 609 0 0.00 42 79789 1119 0 0
srv_make_money_saldo 836 836 0 0.00 1 953 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 262380 397960 299320 0 0
t$perf-abend:sp_add_invoice_to_stock 64 64 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 5210 5210 0 0.00 0 9350 175 0 0
t$perf-norm:sp_cancel_adding_invoice 472 472 0 0.00 0 4591 110 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 7803 21 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 4409 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 2836 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 2387 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 501 9 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 30246 1531 0 0
t$perf-norm:sp_cancel_write_off 1062 1062 0 0.00 0 2862 12 0 0
t$perf-norm:sp_client_order 21464 21464 0 0.00 0 8548 18 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 6057 16 0 0
t$perf-norm:sp_pay_from_customer 7297 7297 0 0.00 0 12080 14 0 0
t$perf-norm:sp_pay_to_supplier 5913 5913 0 0.00 0 12097 11 0 0
t$perf-norm:sp_reserve_write_off 10155 10155 0 0.00 0 12380 11 0 0
t$perf-norm:sp_supplier_invoice 5764 5764 0 0.00 0 22540 25 0 0
t$perf-norm:sp_supplier_order 5683 5683 0 0.00 0 7441 18 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 577 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 32 1 0 0
t$perf-norm:srv_make_invnt_saldo 609 609 0 0.00 0 2504 27 0 0
t$perf-norm:srv_make_money_saldo 836 836 0 0.00 0 10226 41 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 26 9 0 0
v_add_invoice_to_stock 10524 10396 128 1.21 0 18818 20 0 128
v_all_customers 39328 39328 0 0.00 0 97 1 0 0
v_all_suppliers 31934 31934 0 0.00 0 88 1 0 0
v_all_wares 42926 42926 0 0.00 0 278 1 0 0
v_cancel_adding_invoice 944 944 0 0.00 0 3893 18 0 0
v_cancel_client_order 3610 3610 0 0.00 0 3874 8 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 5606 14 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 4272 8 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 840 4 0 0
v_cancel_supplier_invoice 786 768 18 2.29 0 2137 18 0 18
v_cancel_supplier_order 888 888 0 0.00 0 1452 5 0 0
v_cancel_write_off 2124 2124 0 0.00 0 8237 17 0 0
v_max_id_clo_ord 5677 5677 0 0.00 0 3305 2 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 15141 37 0 0
v_max_id_ord_sup 5759 5759 0 0.00 0 6025 4 0 0
v_max_non_paid_invoice 1380 1380 0 0.00 0 3617 5 0 0
v_max_non_paid_realizn 3765 3765 0 0.00 0 4198 7 0 0
v_min_id_clo_ord 5677 5677 0 0.00 0 13257 16 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 2763 7 0 0
v_min_id_ord_sup 5759 5759 0 0.00 0 3786 5 0 0
v_min_non_paid_invoice 5912 5912 0 0.00 0 12965 23 0 0
v_min_non_paid_realizn 7297 7297 0 0.00 0 23486 28 0 0
v_our_firm 10662 10662 0 0.00 0 17 1 0 0
v_reserve_write_off 20304 20304 0 0.00 0 17530 8 0 0
x_lock_depdocs_on_canc_invoice 11716 11716 0 0.00 0 12797 125 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 17984 382 0 0
x_make_qstorno_1000_1200 5683 5683 0 0.00 1 26439 1203 0 0
x_make_qstorno_1200_2000 5764 5764 0 0.00 3 81822 3154 0 0
x_make_qstorno_2100_3300 481391 481391 0 0.00 0 46653 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-06-2022 16:39:35 to 03-06-2022 16:39:39.

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:23 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:24 24774 8192000000 9899434896 10211241984 981289872 10.697 100 79 156 1 1007204176 5615280 20299024
14:10:52 29372 8192000000 9944804288 10267766784 1025296352 11.123 100 90 180 1 1056441536 8060848 23084336
14:12:37 14180 8192000000 9982312736 10317193216 1067643184 11.530 100 83 166 1 1098564528 9753312 21168032
14:13:57 10642 8192000000 10031989648 10380365824 1122938032 12.055 100 76 152 0 1152026576 10503968 18584576
14:15:10 8480 8192000000 10080352736 10443399168 1172718400 12.522 100 77 154 2 1201031152 11038400 17274352
14:16:21 5096 8192000000 10135405296 10505269248 1221613376 12.977 100 81 163 1 1252572912 12256672 18702864
14:17:29 4251 8192000000 10164377472 10540883968 1252468528 13.261 100 81 158 3 1281974832 12957296 16549008
14:18:36 2495 8192000000 10198639376 10582978560 1284316272 13.552 100 86 172 2 1316658944 13351744 18990928
14:19:39 21110 8192000000 10208574848 10602164224 1300078768 13.696 100 68 136 0 1326609584 11298000 15232816
14:21:02 24423 8192000000 10214192560 10608123904 1307214704 13.761 100 68 136 2 1329858688 5791248 16852736
14:22:27 5469 8192000000 10209782288 10615615488 1312890000 13.812 100 43 86 2 1329851648 7388576 9573072
14:23:33 6269 8192000000 10203212016 10621374464 1319259152 13.870 100 30 60 3 1332845904 7174624 6412128
14:24:40 5494 8192000000 10222294144 10637344768 1328869440 13.957 100 26 52 1 1340583280 5370288 6343552
14:25:49 32844 8192000000 10234524544 10646659072 1333689600 14.000 100 52 104 0 1352662256 6749056 12223600
14:27:24 7527 8192000000 10222619424 10641555456 1330259232 13.969 100 36 72 0 1344776112 6215744 8301136
14:28:34 4500 8192000000 10205555072 10621849600 1305714752 13.747 99 24 48 1 1316909200 5230672 5963776
14:29:43 10451 8192000000 10136048512 10534248448 1162417408 12.426 91 18 36 0 1173180176 6836768 3926000
14:30:59 20924 8192000000 10171745680 10569113600 1230957456 13.063 96 37 72 0 1245477424 6527360 7992608
14:32:21 5379 8192000000 10148934192 10547994624 1244047296 13.183 99 37 74 0 1259408048 7003024 8357728
14:33:27 8231 8192000000 10148509760 10550042624 1247997840 13.220 99 23 45 2 1259869520 6262368 5609312
14:34:36 10238 8192000000 10176357328 10585276416 1284710336 13.556 100 20 41 0 1295279392 5807184 4761872
14:35:50 18845 8192000000 10184306720 10600173568 1293558176 13.637 100 20 40 1 1304416640 6254512 4603952
14:37:11 9823 8192000000 10191698192 10608910336 1301049344 13.705 100 19 38 3 1311265296 5829824 4386128
14:38:23 14768 8192000000 10229683920 10634104832 1321059216 13.886 100 42 83 2 1336295920 5476560 9760144
14:39:45 26943 8192000000 10240091632 10652938240 1337846624 14.038 100 47 92 2 1358469648 10640640 9982384
14:41:29 54937 8192000000 10252640704 10677768192 1353262672 14.177 100 32 63 0 1373026752 11921552 7842528
14:43:32 5881 8192000000 10281229904 10697474048 1367263056 14.303 100 63 126 2 1393170560 11733104 14174400
14:44:43 25522 8192000000 10276949072 10699620352 1362461280 14.259 100 45 90 1 1386980000 14378736 10139984
14:46:14 8512 8192000000 10299110720 10715414528 1377781056 14.397 100 73 144 2 1411995136 17363136 16850944
14:47:27 2154 8192000000 10313936368 10731700224 1384900864 14.460 100 60 119 0 1420879824 21879520 14099440
14:48:33 38121 8192000000 10308395664 10740686848 1394756976 14.548 100 43 86 0 1421327024 16369808 10200240
14:50:24 5238 8192000000 10321506800 10743275520 1385588384 14.466 99 58 116 1 1418319472 19613296 13117792
14:51:34 2024 8192000000 10339850080 10748633088 1398985168 14.586 100 77 154 1 1442149376 24824576 18339632
14:52:41 3127 8192000000 10351344384 10761080832 1404047392 14.631 100 84 168 0 1453582256 28995904 20538960
14:53:49 4505 8192000000 10320223072 10732982272 1383730448 14.450 100 71 142 2 1434450256 31734224 18985584
14:55:06 4482 8192000000 10337534304 10748907520 1386296064 14.473 100 71 141 0 1438649968 35121920 17231984
14:56:15 1144 8192000000 10353785216 10757685248 1384948160 14.461 99 88 174 3 1450320624 44207712 21164752
14:57:22 1679 8192000000 10362170144 10775244800 1402193472 14.615 100 85 172 2 1472559424 49099536 21266416
14:58:31 4210 8192000000 10368558528 10777206784 1394617024 14.547 100 78 156 0 1470096000 55749184 19729792
14:59:39 6346 8192000000 10373682848 10800889856 1407111760 14.658 100 54 108 0 1477068736 55269568 14687408
15:00:50 1324 8192000000 10362987232 10777911296 1383974704 14.452 100 77 155 5 1466066288 62547136 19544448
15:01:56 8283 8192000000 10370084272 10796208128 1395745248 14.557 100 51 101 1 1475795808 65551632 14498928
15:03:09 13055 8192000000 10342232880 10767892480 1370202288 14.329 99 31 62 1 1439922992 61010288 8710416
15:04:38 9138 8192000000 10352307200 10791223296 1376852720 14.388 100 43 86 0 1461954000 73347552 11753728
15:06:09 40752 8192000000 10330914320 10774683648 1372162768 14.346 100 33 66 1 1441440272 59840560 9436944
15:07:52 21313 8192000000 10313889872 10760998912 1361220000 14.248 100 28 56 0 1419705488 51282304 7203184
15:09:14 38516 8192000000 10272728720 10744840192 1340742896 14.064 98 11 22 2 1374434976 30912256 2779824
15:10:53 54797 8192000000 10280676848 10724093952 1361580336 14.252 100 17 34 0 1374993216 9720432 3692448
15:12:49 14204 8192000000 10275122304 10717982720 1368629392 14.315 100 31 60 1 1384586224 9503808 6453024
15:14:16 85713 8192000000 10316151728 10758205440 1387347232 14.482 100 24 46 0 1404074736 11522512 5204992
15:16:47 67349 8192000000 10302912336 10762080256 1379563312 14.413 99 18 36 1 1405871152 21888144 4419696
15:19:38 31915 8192000000 10292525008 10737725440 1372318592 14.348 99 26 52 2 1385093968 7681776 5093600
15:21:11 20877 8192000000 10304953904 10747813888 1389785200 14.504 100 35 70 0 1408000880 10335088 7880592
15:22:47 127177 8192000000 10301921984 10753888256 1363220320 14.266 98 8 16 0 1378860080 13627968 2011792
15:26:24 52517 8192000000 10301892704 10755592192 1383945264 14.452 99 30 58 2 1405739904 14753920 7040720
15:28:24 32200 8192000000 10299350224 10757005312 1389562656 14.502 100 19 38 1 1404694656 10128736 5003264
15:29:58 44410 8192000000 10286845040 10740719616 1373195344 14.356 99 21 42 0 1388422560 10237152 4990064
15:31:54 19296 8192000000 10327416112 10775252992 1403662336 14.628 100 48 96 1 1425751840 11408880 10680624
15:33:26 37744 8192000000 10292895984 10760892416 1369584448 14.323 98 5 10 0 1387569392 17042576 942368
15:35:04 26316 8192000000 10284914048 10743635968 1350248336 14.150 97 14 28 1 1364806640 11134592 3423712
15:36:30 56282 8192000000 10302400144 10768531456 1401144864 14.605 100 15 30 1 1416607056 11736416 3725776
15:38:29 63787 8192000000 10280414736 10739535872 1364992512 14.282 98 10 20 1 1377848384 10617392 2238480
15:40:37 66526 8192000000 10284158896 10748743680 1367450208 14.304 98 21 41 0 1381007824 9317712 4239904
15:42:51 62790 8192000000 10297870496 10766053376 1395466656 14.555 100 12 24 0 1407935968 9823040 2646272
15:44:56 42322 8192000000 10288253984 10754846720 1366746768 14.298 98 33 65 0 1385314880 10758832 7809280
15:46:57 28104 8192000000 10300957408 10754686976 1354272192 14.186 97 27 54 3 1372391040 12410448 5708400
15:48:26 62900 8192000000 10294856624 10749075456 1335052784 14.013 96 27 54 1 1357298048 16125328 6119936
15:50:33 22575 8192000000 10277460256 10715820032 1308025952 13.768 95 36 72 2 1327353744 11131200 8196592
15:51:59 51774 8192000000 10276376320 10733961216 1331808512 13.983 97 25 50 1 1352057728 14959152 5290064
15:53:55 75430 8192000000 10277024880 10723962880 1330153920 13.969 97 12 24 0 1346081616 13359280 2568416
15:56:12 105488 8192000000 10264048496 10713911296 1318427312 13.862 97 34 68 2 1335648096 9016432 8204352
15:59:03 40384 8192000000 10270205568 10721226752 1367254896 14.302 100 25 50 0 1382069456 8913328 5901232
16:00:48 50728 8192000000 10263007152 10717343744 1346111904 14.112 98 17 34 0 1358004976 8038560 3854512
16:02:39 35429 8192000000 10259161904 10719514624 1346359440 14.115 98 28 57 0 1362801584 9474224 6967920
16:04:25 43723 8192000000 10269030096 10738188288 1357802912 14.218 99 8 15 0 1368642576 9317360 1522304
16:06:09 60578 8192000000 10258798192 10723745792 1335316368 14.015 97 13 26 0 1351308528 13048544 2943616
16:08:11 62385 8192000000 10272734736 10720751616 1308232640 13.770 95 17 34 0 1318787360 7258736 3295984
16:10:14 73904 8192000000 10295033808 10750652416 1360273952 14.240 98 15 30 1 1371731648 7885712 3571984
16:12:29 33883 8192000000 10297582720 10737897472 1359928608 14.237 98 32 64 1 1377537760 9848544 7760608
16:14:06 85912 8192000000 10315405680 10750640128 1334940864 14.012 95 29 58 0 1350525072 9014192 6570016
16:16:34 70580 8192000000 10318177968 10776842240 1401251296 14.606 100 16 31 1 1416276976 11287904 3737776
16:18:46 71563 8192000000 10299339680 10771738624 1378830864 14.406 99 5 10 0 1394112960 14362160 919936
16:21:07 91703 8192000000 10279756912 10747625472 1300689680 13.702 93 9 17 0 1314577232 11580736 2306816
16:24:53 8840 8192000000 10283352208 10731544576 1370910000 14.335 100 41 82 2 1393608496 13302272 9396224
16:26:03 45390 8192000000 10271644192 10738081792 1363084320 14.265 99 5 10 0 1373869264 9655456 1129488
16:28:04 66974 8192000000 10256561440 10722516992 1335052576 14.013 97 9 18 1 1346227952 8874800 2300576
16:30:21 50380 8192000000 10283977008 10757718016 1378872816 14.406 100 11 22 1 1392989264 10970704 3145744
16:32:35 68670 8192000000 10291915312 10765578240 1379812768 14.415 99 11 22 1 1394695440 12352432 2530240
16:34:49 47433 8192000000 10294025504 10730561536 1317544912 13.854 96 37 74 1 1334728784 8190752 8993120
16:36:38 73878 8192000000 10280492272 10746032128 1328564016 13.954 97 19 39 0 1348279568 15493200 4222352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-06-2022 16:39:41 to 03-06-2022 16:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 64 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-06-2022 16:39:57 to 03-06-2022 16:39:59.

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          244694
MON$OLDEST_ACTIVE               245657
MON$OLDEST_SNAPSHOT             245657
MON$NEXT_TRANSACTION            245657
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                       2053088
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.33601 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33601 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33601 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33601 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 ---
:50: 
:51: mscs-test-161.budzdorov.ru    Fri Jun  3 16:38:32 2022
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Fri Jun  3 16:38:33 2022
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:57: 
--- end of diff output ---

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

03.06.22 16:40:00. 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-03 16:40:02 No severe PSQL-related problems occured
03.06.22 16:40:04. End of report.