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

Hardware and OS info

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

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-21 22:37:52
DTS_END                         2021-11-21 22:37:52.5400
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/59892
TRN_ID                          239545
ATT_ID                          706
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: 9484.92 18732 1138190
customer order: creation 174.65 360 20958
customer order: refuse 15.37 330 1844
order to supplier: creation 48.32 1246 5798
order to supplier: removal 3.76 86516 451
invoice (draft): creation 46.08 3202 5530
invoice (draft): removal 55.67 755 6680
invoice accept: apply 42.13 16442 5055
invoice accept: cancel 50.15 704 6018
customer reserve: creation 3858.20 164 462984
customer reserve: removal 4883.28 51 585994
realization accept: apply 84.66 188 10159
realization accept: cancel 93.93 96 11272
payment from customer: creation 59.13 125 7096
payment from customer: removal 5.84 95 701
payment to supplier: creation 48.53 135 5823
payment to supplier: removal 3.52 79 422
service: total inventory turnovers 4.81 1182 577
service: total monetary turnovers 6.08 26 729
service: aggregate perf. data 0.79 23960 95
service: refresh index statistics 0.03 238975 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-11-2021 22:38:52 to 21-11-2021 22:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 536
TEST_TIME 2 1695
TEST_TIME 3 4086
TEST_TIME 4 4197
TEST_TIME 5 4790
TEST_TIME 6 4643
TEST_TIME 7 4619
TEST_TIME 8 4744
TEST_TIME 9 4880
TEST_TIME 10 5223
TEST_TIME 11 5539
TEST_TIME 12 5706
TEST_TIME 13 5973
TEST_TIME 14 6180
TEST_TIME 15 6439
TEST_TIME 16 6478
TEST_TIME 17 7042
TEST_TIME 18 7265
TEST_TIME 19 7294
TEST_TIME 20 8246
TEST_TIME 21 8438
TEST_TIME 22 9227
TEST_TIME 23 9494
TEST_TIME 24 9392
TEST_TIME 25 9728
TEST_TIME 26 10017
TEST_TIME 27 10446
TEST_TIME 28 10378
TEST_TIME 29 10199
TEST_TIME 30 10350
TEST_TIME 31 10460
TEST_TIME 32 10320
TEST_TIME 33 10420
TEST_TIME 34 10495
TEST_TIME 35 10512
TEST_TIME 36 10476
TEST_TIME 37 10467
TEST_TIME 38 10220
TEST_TIME 39 10115
TEST_TIME 40 10038
TEST_TIME 41 10052
TEST_TIME 42 9945
TEST_TIME 43 9932
TEST_TIME 44 9964
TEST_TIME 45 9894
TEST_TIME 46 9826
TEST_TIME 47 9967
TEST_TIME 48 9924
TEST_TIME 49 9824
TEST_TIME 50 9756
TEST_TIME 51 9764
TEST_TIME 52 9661
TEST_TIME 53 9626
TEST_TIME 54 9594
TEST_TIME 55 9594
TEST_TIME 56 9467
TEST_TIME 57 9516
TEST_TIME 58 9488
TEST_TIME 59 9443
TEST_TIME 60 9432
TEST_TIME 61 9508
TEST_TIME 62 9518
TEST_TIME 63 9533
TEST_TIME 64 9664
TEST_TIME 65 9623
TEST_TIME 66 9635
TEST_TIME 67 9688
TEST_TIME 68 9771
TEST_TIME 69 9745
TEST_TIME 70 9736
TEST_TIME 71 9705
TEST_TIME 72 9785
TEST_TIME 73 9863
TEST_TIME 74 9910
TEST_TIME 75 9785
TEST_TIME 76 9750
TEST_TIME 77 9755
TEST_TIME 78 9694
TEST_TIME 79 9680
TEST_TIME 80 9682
TEST_TIME 81 9623
TEST_TIME 82 9612
TEST_TIME 83 9622
TEST_TIME 84 9606
TEST_TIME 85 9521
TEST_TIME 86 9483
TEST_TIME 87 9570
TEST_TIME 88 9574
TEST_TIME 89 9534
TEST_TIME 90 9470
TEST_TIME 91 9581
TEST_TIME 92 9549
TEST_TIME 93 9570
TEST_TIME 94 9618
TEST_TIME 95 9640
TEST_TIME 96 9599
TEST_TIME 97 9686
TEST_TIME 98 9653
TEST_TIME 99 9671
TEST_TIME 100 9721
TEST_TIME 101 9716
TEST_TIME 102 9685
TEST_TIME 103 9607
TEST_TIME 104 9629
TEST_TIME 105 9586
TEST_TIME 106 9552
TEST_TIME 107 9531
TEST_TIME 108 9498
TEST_TIME 109 9462
TEST_TIME 110 9440
TEST_TIME 111 9465
TEST_TIME 112 9469
TEST_TIME 113 9461
TEST_TIME 114 9470
TEST_TIME 115 9495
TEST_TIME 116 9532
TEST_TIME 117 9521
TEST_TIME 118 9542
TEST_TIME 119 9516
TEST_TIME 120 9488

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-11-2021 22:38:56 to 21-11-2021 22:38:59.

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 1567449 1567449 0 0.00 0 16881 6 0 0
doc_list_biud 1567449 1567449 0 0.00 0 75872 25 0 0
sp_add_invoice_to_stock 5143 5055 88 1.71 26 154826 16442 0 88
sp_cancel_adding_invoice 6018 6018 0 0.00 5 137310 705 0 0
sp_cancel_client_order 1844 1844 0 0.00 4 12858 331 0 0
sp_cancel_customer_reserve 585994 585994 0 0.00 1 66703 52 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 6176 95 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 2 4241 79 0 0
sp_cancel_supplier_invoice 6685 6680 5 0.07 1 75877 756 0 5
sp_cancel_supplier_order 451 451 0 0.00 7 1068548 86516 0 0
sp_cancel_write_off 11272 11272 0 0.00 1 15110 97 0 0
sp_client_order 20958 20958 0 0.00 3 44530 361 0 0
sp_customer_reserve 462984 462984 0 0.00 0 121338 164 0 0
sp_fill_shopping_cart 32284 32284 0 0.00 1 9127 34 0 0
sp_fill_shopping_cart_clo_res 462971 462971 0 0.00 0 17132 13 0 0
sp_get_clo_for_invoice 5042 5042 0 0.00 0 31843 271 0 0
sp_kill_cost_storno 18413 18413 0 0.00 0 6186 47 0 0
sp_kill_qstorno_ret_qs2qd 1190391 1190391 0 0.00 0 75869 29 0 0
sp_kill_qty_storno 627460 627460 0 0.00 0 75871 62 0 0
sp_lock_selected_doc 21049 21049 0 0.00 0 7304 8 0 0
sp_make_cost_storno 28120 28120 0 0.00 0 15094 54 0 0
sp_multiply_rows_for_pdistr 28120 28120 0 0.00 0 7545 27 0 0
sp_multiply_rows_for_qdistr 20958 20958 0 0.00 0 10951 154 0 0
sp_pay_from_customer 7096 7096 0 0.00 4 22760 126 0 0
sp_pay_to_supplier 5823 5823 0 0.00 5 18404 135 0 0
sp_payment_common 12919 12919 0 0.00 1 14751 31 0 0
sp_qd_handle_on_cancel_clo 1844 1844 0 0.00 0 12850 286 0 0
sp_qd_handle_on_invoice_upd_sts 11060 11060 0 0.00 0 22406 296 0 0
sp_qd_handle_on_reserve_upd_sts 10159 10159 0 0.00 0 16291 68 0 0
sp_reserve_write_off 10159 10159 0 0.00 6 16910 188 0 0
sp_supplier_invoice 5530 5530 0 0.00 6 93887 3202 0 0
sp_supplier_order 5798 5798 0 0.00 5 60130 1247 0 0
srv_aggregate_perf_data 96 95 1 1.04 2417 619901 23960 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 2494 119053 47298 0 0
srv_find_qd_qs_mism 1090438 1090438 0 0.00 0 13623 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 20 78205 1183 0 0
srv_make_money_saldo 729 729 0 0.00 1 5923 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 69750 404799 238975 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 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 5055 5055 0 0.00 0 15279 132 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 3470 97 0 0
t$perf-norm:sp_cancel_client_order 1844 1844 0 0.00 0 5916 13 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 1899 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 500 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 3693 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 601 11 0 0
t$perf-norm:sp_cancel_supplier_order 451 451 0 0.00 0 26215 1309 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 4385 13 0 0
t$perf-norm:sp_client_order 20958 20958 0 0.00 0 9351 16 0 0
t$perf-norm:sp_customer_reserve 1074 1074 0 0.00 0 2353 6 0 0
t$perf-norm:sp_pay_from_customer 7096 7096 0 0.00 0 6124 12 0 0
t$perf-norm:sp_pay_to_supplier 5823 5823 0 0.00 0 4476 10 0 0
t$perf-norm:sp_reserve_write_off 10159 10159 0 0.00 0 5663 12 0 0
t$perf-norm:sp_supplier_invoice 5530 5530 0 0.00 0 6094 16 0 0
t$perf-norm:sp_supplier_order 5798 5798 0 0.00 0 5352 14 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 151 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 346 9 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 1166 15 0 0
t$perf-norm:srv_make_money_saldo 729 729 0 0.00 0 713 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10242 10066 176 1.71 0 17733 25 0 176
v_all_customers 38330 38330 0 0.00 0 15 0 0 0
v_all_suppliers 30972 30972 0 0.00 0 13 0 0 0
v_all_wares 41916 41916 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 2146 13 0 0
v_cancel_client_order 3688 3688 0 0.00 0 3038 8 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 4588 7 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 2751 8 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 2248 6 0 0
v_cancel_supplier_invoice 798 788 10 1.25 0 3189 24 0 10
v_cancel_supplier_order 902 902 0 0.00 0 2668 10 0 0
v_cancel_write_off 2022 2022 0 0.00 0 9682 22 0 0
v_max_id_clo_ord 5798 5798 0 0.00 0 626 1 0 0
v_max_id_clo_res 1074 1074 0 0.00 0 2309 16 0 0
v_max_id_ord_sup 5528 5528 0 0.00 0 4075 3 0 0
v_max_non_paid_invoice 1663 1663 0 0.00 0 805 3 0 0
v_max_non_paid_realizn 3774 3774 0 0.00 0 3274 4 0 0
v_min_id_clo_ord 5798 5798 0 0.00 0 2058 4 0 0
v_min_id_clo_res 1074 1074 0 0.00 0 6794 10 0 0
v_min_id_ord_sup 5528 5528 0 0.00 0 2694 3 0 0
v_min_non_paid_invoice 5823 5823 0 0.00 0 6093 20 0 0
v_min_non_paid_realizn 7096 7096 0 0.00 0 5980 21 0 0
v_our_firm 10230 10230 0 0.00 0 16 1 0 0
v_reserve_write_off 20318 20318 0 0.00 0 12664 8 0 0
x_lock_depdocs_on_canc_invoice 12304 12304 0 0.00 0 20701 107 0 0
x_lock_depdocs_on_canc_sup_ord 451 451 0 0.00 0 13843 323 0 0
x_make_qstorno_1000_1200 5798 5798 0 0.00 1 60105 1179 0 0
x_make_qstorno_1200_2000 5530 5530 0 0.00 2 93870 3079 0 0
x_make_qstorno_2100_3300 462984 462984 0 0.00 0 121338 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-11-2021 22:39:00 to 21-11-2021 22:39:04.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:56 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:57 22425 8192000000 9904751536 10216636416 991499360 10.796 100 88 176 1 1020690416 5808384 23382672
20:10:24 24756 8192000000 9953398512 10277216256 1033491728 11.202 100 90 180 0 1065004896 8176848 23336320
20:11:56 18725 8192000000 9994153968 10331193344 1080322816 11.651 100 87 174 0 1111471440 9617728 21530896
20:13:18 14632 8192000000 10041703072 10393460736 1126955488 12.093 100 78 156 1 1156352752 11072544 18324720
20:14:37 10863 8192000000 10082058192 10444054528 1171057376 12.507 100 82 164 1 1201741392 12128704 18555312
20:15:51 5919 8192000000 10135514928 10502365184 1216750176 12.932 100 86 172 0 1249515840 13668400 19097264
20:16:59 2778 8192000000 10164757520 10542919680 1252922768 13.265 100 88 176 1 1285349392 13289520 19137104
20:18:04 1879 8192000000 10189205776 10569789440 1278628224 13.500 100 91 182 1 1313151920 14167232 20356464
20:19:07 1773 8192000000 10210044272 10593816576 1291519984 13.618 100 85 170 0 1323341648 12452848 19368816
20:20:10 3355 8192000000 10202616544 10598891520 1301015776 13.704 100 53 105 2 1321728016 8765280 11946960
20:21:13 8939 8192000000 10187221952 10604601344 1307901216 13.767 100 20 40 2 1319168144 7004080 4262848
20:22:25 9500 8192000000 10194748976 10614120448 1313228224 13.815 100 11 22 0 1321952528 5812432 2911872
20:23:41 37033 8192000000 10242975232 10642874368 1323289648 13.906 100 71 142 2 1346498768 5590640 17618480
20:25:18 5105 8192000000 10227793312 10647076864 1330322896 13.970 100 33 64 2 1345238752 7649264 7266592
20:26:25 8474 8192000000 10210233024 10633469952 1306619040 13.755 98 17 34 2 1316269712 5432208 4218464
20:27:37 10401 8192000000 10207108768 10631598080 1320237472 13.879 100 24 48 0 1332286912 6513584 5535856
20:28:48 5610 8192000000 10179767472 10590617600 1277383696 13.489 98 31 62 1 1291774400 7242656 7148048
20:29:55 6107 8192000000 10170839136 10580668416 1280269744 13.515 100 24 49 0 1291325040 5502304 5552992
20:31:02 46317 8192000000 10131301792 10529579008 1234504896 13.096 99 33 66 2 1247492448 5616032 7371520
20:32:58 6631 8192000000 10163578624 10564562944 1248288784 13.222 98 32 62 2 1261543232 5651456 7602992
20:34:06 8833 8192000000 10180293568 10591199232 1278128384 13.496 99 17 34 1 1288084448 5682016 4274048
20:35:19 10871 8192000000 10189437696 10606641152 1301626032 13.710 100 19 40 1 1310976352 4946304 4404016
20:36:30 46534 8192000000 10199311056 10614718464 1308858288 13.776 100 35 66 3 1322358352 5560096 7939968
20:38:25 37985 8192000000 10245525280 10654867456 1333635552 14.000 100 58 115 0 1354369632 8014128 12719952
20:40:12 10477 8192000000 10265000896 10677346304 1352560848 14.171 100 58 114 1 1377378864 12177648 12640368
20:41:25 14948 8192000000 10274962320 10691829760 1363778416 14.271 100 49 97 1 1386216016 11691008 10746592
20:42:49 27655 8192000000 10295281088 10715574272 1378425696 14.402 100 46 92 0 1402391904 13240160 10726048
20:44:43 16755 8192000000 10309523056 10732158976 1391916752 14.523 100 47 94 0 1420110432 16651536 11542144
20:46:05 2677 8192000000 10348636144 10760503296 1410107376 14.685 100 80 155 3 1453015664 24048128 18860160
20:47:11 19041 8192000000 10343491424 10770550784 1409021264 14.675 99 44 88 1 1444848560 24571440 11255856
20:48:35 2493 8192000000 10359433776 10779439104 1422974976 14.799 100 79 156 4 1471205104 29343424 18886704
20:49:42 8277 8192000000 10375762240 10798546944 1435141072 14.907 100 70 140 1 1484530048 31614096 17774880
20:50:58 4803 8192000000 10385868144 10809683968 1433852240 14.895 99 63 124 1 1483897728 34235952 15809536
20:52:22 4027 8192000000 10380308608 10798022656 1430111104 14.862 100 70 140 1 1482475808 34914816 17449888
20:53:29 6781 8192000000 10374769680 10804146176 1430391344 14.865 100 49 96 1 1481796352 39030928 12374080
20:54:43 6148 8192000000 10345499792 10779635712 1390765360 14.513 99 38 74 1 1447184848 46264896 10154592
20:55:54 11734 8192000000 10351449696 10777350144 1381758128 14.432 98 56 112 2 1441908512 44647504 15502880
20:57:08 22289 8192000000 10338853424 10783141888 1381200704 14.427 99 34 68 2 1446266736 54920864 10145168
20:58:37 10955 8192000000 10324189696 10758488064 1370617392 14.333 99 30 60 0 1426382272 47732256 8032624
20:59:51 49723 8192000000 10275127168 10714435584 1307064032 13.759 96 20 40 0 1352401824 39860800 5476992
21:02:00 13273 8192000000 10283158784 10718257152 1338317696 14.042 99 28 56 3 1380210288 34840864 7051728
21:03:18 63763 8192000000 10252931968 10699362304 1325101872 13.923 99 11 22 0 1349671328 21657792 2911664
21:05:22 35060 8192000000 10269262320 10694041600 1343059280 14.085 100 22 44 0 1355917936 7613024 5245632
21:07:02 48662 8192000000 10260187120 10710167552 1321677216 13.892 97 13 26 1 1340389136 15126160 3585760
21:08:55 78339 8192000000 10256515216 10685120512 1278922000 13.503 94 27 53 1 1295154288 10424032 5808256
21:11:22 116015 8192000000 10278940880 10734014464 1351252480 14.159 99 5 10 0 1365103952 12956288 895184
21:14:19 109610 8192000000 10281068304 10713235456 1346311360 14.114 99 28 56 0 1364754176 11556320 6886496
21:17:10 46109 8192000000 10288998304 10729508864 1372787872 14.352 100 29 58 0 1391458256 11930560 6739824
21:19:04 119052 8192000000 10293905920 10748284928 1356630032 14.207 98 22 44 1 1382995632 21242320 5123280
21:22:11 41885 8192000000 10309496256 10745798656 1382044016 14.435 100 31 63 1 1399750336 10708368 6997952
21:24:19 46673 8192000000 10304635264 10763419648 1393322384 14.535 100 23 46 0 1411297248 12273616 5701248
21:26:11 43359 8192000000 10309084416 10768371712 1403447408 14.626 100 14 29 0 1414504224 7973072 3083744
21:28:00 36535 8192000000 10300725280 10763141120 1394975632 14.550 99 16 31 1 1410824368 12411264 3437472
21:29:44 53663 8192000000 10289210624 10758877184 1396998368 14.568 100 8 16 0 1414630576 15447856 2184352
21:31:43 51919 8192000000 10296541296 10760884224 1373103552 14.355 98 7 14 0 1384334960 10050080 1181328
21:33:43 90951 8192000000 10294522192 10768650240 1397349488 14.571 100 12 23 1 1410783808 10678736 2755584
21:36:15 18979 8192000000 10292077760 10752192512 1392789776 14.531 100 28 56 0 1408683696 9343504 6550416
21:37:38 85815 8192000000 10313805200 10767560704 1395124896 14.552 99 23 46 2 1409967088 9822656 5019536
21:40:08 52083 8192000000 10303236736 10765721600 1356959904 14.210 97 12 24 0 1374575472 14823584 2791984
21:42:01 103821 8192000000 10268607744 10712952832 1211380224 12.882 87 16 32 0 1224936400 10230192 3325984
21:44:45 50436 8192000000 10266816192 10730565632 1328510288 13.954 97 23 46 0 1358941056 24973024 5457744
21:46:36 56225 8192000000 10263286336 10705788928 1312924448 13.813 96 22 43 0 1325557840 8389056 4244336
21:48:40 36567 8192000000 10298933104 10731806720 1359729440 14.235 99 38 76 0 1387054752 18691936 8633376
21:50:21 72114 8192000000 10269787472 10734964736 1317086048 13.850 96 16 32 1 1335391616 14999536 3306032
21:53:27 60557 8192000000 10271362960 10715414528 1343313696 14.087 99 22 46 1 1357774560 9527744 4933120
21:55:29 29173 8192000000 10271010080 10724610048 1363335312 14.267 100 15 30 1 1377866496 10772448 3758736
21:57:14 77459 8192000000 10258357904 10708574208 1335953376 14.021 98 19 38 1 1352589328 12340384 4295568
21:59:39 31123 8192000000 10253398064 10711949312 1354334224 14.186 100 15 29 0 1372414880 13955536 4125120
22:01:19 90889 8192000000 10248590432 10693685248 1311165296 13.797 97 18 36 1 1322356912 7323808 3867808
22:04:14 99887 8192000000 10245394000 10705620992 1305708032 13.747 97 8 16 0 1320063824 12322144 2033648
22:06:54 33842 8192000000 10286051280 10718154752 1348871248 14.137 99 26 52 0 1366007392 10992944 6143200
22:08:31 78653 8192000000 10280500448 10730106880 1352385104 14.169 99 13 26 0 1363912432 9016032 2511296
22:10:49 70215 8192000000 10289045936 10748973056 1378314320 14.401 100 10 20 0 1396421856 16171600 1935936
22:13:02 57304 8192000000 10317329088 10757111808 1393625968 14.538 100 36 73 2 1414560496 12397968 8536560
22:15:07 55962 8192000000 10318300320 10760925184 1391368592 14.518 100 22 43 0 1406459168 10688608 4401968
22:18:18 97036 8192000000 10309168880 10761568256 1376225136 14.383 98 34 67 1 1394881872 9814128 8842608
22:21:58 68976 8192000000 10294525568 10749485056 1379305824 14.410 99 20 40 0 1396949184 12620400 5022960
22:24:07 59490 8192000000 10307169056 10763689984 1381931536 14.434 98 13 26 1 1397437680 12079232 3426912
22:26:07 79706 8192000000 10314869104 10766258176 1385402576 14.465 98 22 45 1 1411063168 20561504 5099088
22:28:42 46451 8192000000 10315395040 10779213824 1397314736 14.571 100 14 26 1 1422064848 21244256 3505856
22:30:39 48030 8192000000 10305824464 10766086144 1364196272 14.275 97 16 32 0 1379882192 11864688 3821232
22:32:29 73151 8192000000 10268214640 10726432768 1293063568 13.632 94 11 22 1 1308508144 12646960 2797616
22:34:44 20153 8192000000 10276415328 10721619968 1352054176 14.166 99 21 41 2 1366452192 9762208 4635808
22:36:15 17710 8192000000 10276937376 10713190400 1335232992 14.014 98 47 94 1 1354259936 8714048 10312896
22:37:42 8976 8192000000 10281229072 10711257088 1345316448 14.105 99 51 102 1 1368915296 12662992 10935856

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-11-2021 22:39:06 to 21-11-2021 22:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 88 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-11-2021 22:39:21 to 21-11-2021 22:39:23.

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          234746
MON$OLDEST_ACTIVE               240273
MON$OLDEST_SNAPSHOT             240273
MON$NEXT_TRANSACTION            240273
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                       2040096
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:160: 
:161: mscs-test-161.budzdorov.ru    Sun Nov 21 22:37:57 2021
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:163: 
:164: 
:165: mscs-test-161.budzdorov.ru    Sun Nov 21 22:38:01 2021
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:167: 
:168: 
:169: mscs-test-161.budzdorov.ru    Sun Nov 21 22:38:11 2021
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:171: 
--- end of diff output ---

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

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