Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.06.2022 16:40
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 15992656 kB
MemAvailable 31259076 kB
Buffers 0 kB
Cached 15744220 kB
SwapCached 2492 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-23 16:38:32
DTS_END                         2022-06-23 16:38:32.0630
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/49564
TRN_ID                          253713
ATT_ID                          735
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: 9412.31 24709 1129477
customer order: creation 185.19 351 22223
customer order: refuse 15.33 362 1839
order to supplier: creation 48.35 1347 5802
order to supplier: removal 3.34 99944 401
invoice (draft): creation 49.25 3463 5910
invoice (draft): removal 51.11 818 6133
invoice accept: apply 45.26 17893 5431
invoice accept: cancel 45.84 925 5501
customer reserve: creation 4141.33 178 496959
customer reserve: removal 4521.93 58 542632
realization accept: apply 89.48 213 10737
realization accept: cancel 78.16 112 9379
payment from customer: creation 62.75 132 7530
payment from customer: removal 5.93 109 712
payment to supplier: creation 52.41 156 6289
payment to supplier: removal 3.86 90 463
service: total inventory turnovers 4.82 1315 578
service: total monetary turnovers 7.14 56 857
service: aggregate perf. data 0.81 21211 97
service: refresh index statistics 0.03 345444 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-06-2022 16:40:32 to 23-06-2022 16:40:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4782
TEST_TIME 2 3203
TEST_TIME 3 3864
TEST_TIME 4 4684
TEST_TIME 5 5177
TEST_TIME 6 5031
TEST_TIME 7 5004
TEST_TIME 8 5070
TEST_TIME 9 5212
TEST_TIME 10 5448
TEST_TIME 11 5847
TEST_TIME 12 6058
TEST_TIME 13 6116
TEST_TIME 14 6767
TEST_TIME 15 6987
TEST_TIME 16 7281
TEST_TIME 17 7305
TEST_TIME 18 7911
TEST_TIME 19 8004
TEST_TIME 20 8469
TEST_TIME 21 8317
TEST_TIME 22 8341
TEST_TIME 23 9054
TEST_TIME 24 9306
TEST_TIME 25 9546
TEST_TIME 26 9628
TEST_TIME 27 10009
TEST_TIME 28 10173
TEST_TIME 29 10053
TEST_TIME 30 10438
TEST_TIME 31 10690
TEST_TIME 32 10883
TEST_TIME 33 10706
TEST_TIME 34 10789
TEST_TIME 35 10815
TEST_TIME 36 10683
TEST_TIME 37 10645
TEST_TIME 38 10546
TEST_TIME 39 10444
TEST_TIME 40 10354
TEST_TIME 41 10495
TEST_TIME 42 10340
TEST_TIME 43 10201
TEST_TIME 44 10079
TEST_TIME 45 10126
TEST_TIME 46 9933
TEST_TIME 47 9861
TEST_TIME 48 9858
TEST_TIME 49 9778
TEST_TIME 50 9859
TEST_TIME 51 9992
TEST_TIME 52 9855
TEST_TIME 53 9810
TEST_TIME 54 9743
TEST_TIME 55 9656
TEST_TIME 56 9741
TEST_TIME 57 9710
TEST_TIME 58 9684
TEST_TIME 59 9760
TEST_TIME 60 9853
TEST_TIME 61 9855
TEST_TIME 62 9884
TEST_TIME 63 9948
TEST_TIME 64 9918
TEST_TIME 65 9865
TEST_TIME 66 9872
TEST_TIME 67 9911
TEST_TIME 68 9949
TEST_TIME 69 10039
TEST_TIME 70 9953
TEST_TIME 71 9866
TEST_TIME 72 9835
TEST_TIME 73 9760
TEST_TIME 74 9750
TEST_TIME 75 9715
TEST_TIME 76 9672
TEST_TIME 77 9678
TEST_TIME 78 9612
TEST_TIME 79 9537
TEST_TIME 80 9543
TEST_TIME 81 9640
TEST_TIME 82 9644
TEST_TIME 83 9609
TEST_TIME 84 9629
TEST_TIME 85 9652
TEST_TIME 86 9636
TEST_TIME 87 9600
TEST_TIME 88 9653
TEST_TIME 89 9646
TEST_TIME 90 9688
TEST_TIME 91 9705
TEST_TIME 92 9676
TEST_TIME 93 9712
TEST_TIME 94 9708
TEST_TIME 95 9686
TEST_TIME 96 9606
TEST_TIME 97 9583
TEST_TIME 98 9574
TEST_TIME 99 9532
TEST_TIME 100 9522
TEST_TIME 101 9557
TEST_TIME 102 9519
TEST_TIME 103 9496
TEST_TIME 104 9507
TEST_TIME 105 9506
TEST_TIME 106 9511
TEST_TIME 107 9490
TEST_TIME 108 9495
TEST_TIME 109 9494
TEST_TIME 110 9542
TEST_TIME 111 9512
TEST_TIME 112 9570
TEST_TIME 113 9610
TEST_TIME 114 9577
TEST_TIME 115 9548
TEST_TIME 116 9511
TEST_TIME 117 9480
TEST_TIME 118 9458
TEST_TIME 119 9480
TEST_TIME 120 9418

Performance per minute, chart


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

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 1588208 1588208 0 0.00 0 24926 7 0 0
doc_list_biud 1588203 1588203 0 0.00 0 54669 26 0 0
sp_add_invoice_to_stock 5622 5431 191 3.39 25 194665 17894 0 191
sp_cancel_adding_invoice 5501 5501 0 0.00 5 73880 925 0 0
sp_cancel_client_order 1839 1839 0 0.00 4 7083 363 0 0
sp_cancel_customer_reserve 542632 542632 0 0.00 1 54671 58 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 2 20145 110 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 3 5260 90 0 0
sp_cancel_supplier_invoice 6147 6133 14 0.22 1 18067 819 0 14
sp_cancel_supplier_order 401 401 0 0.00 7 1091470 99944 0 0
sp_cancel_write_off 9379 9379 0 0.00 1 18681 112 0 0
sp_client_order 22223 22223 0 0.00 3 20859 351 0 0
sp_customer_reserve 496959 496959 0 0.00 0 97488 179 0 0
sp_fill_shopping_cart 33926 33926 0 0.00 1 12936 38 0 0
sp_fill_shopping_cart_clo_res 496954 496954 0 0.00 0 27049 12 0 0
sp_get_clo_for_invoice 5424 5424 0 0.00 0 25741 280 0 0
sp_kill_cost_storno 16055 16055 0 0.00 0 20098 59 0 0
sp_kill_qstorno_ret_qs2qd 1101177 1101177 0 0.00 0 54636 32 0 0
sp_kill_qty_storno 582043 582043 0 0.00 0 54667 70 0 0
sp_lock_selected_doc 22362 22362 0 0.00 0 8052 6 0 0
sp_make_cost_storno 29980 29980 0 0.00 0 24089 71 0 0
sp_multiply_rows_for_pdistr 29976 29976 0 0.00 0 22063 32 0 0
sp_multiply_rows_for_qdistr 22222 22222 0 0.00 0 11340 137 0 0
sp_pay_from_customer 7530 7530 0 0.00 4 23570 133 0 0
sp_pay_to_supplier 6289 6289 0 0.00 4 23303 156 0 0
sp_payment_common 13818 13818 0 0.00 2 22041 40 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 6284 332 0 0
sp_qd_handle_on_invoice_upd_sts 10925 10925 0 0.00 0 27827 353 0 0
sp_qd_handle_on_reserve_upd_sts 10734 10734 0 0.00 0 13207 64 0 0
sp_reserve_write_off 10737 10737 0 0.00 6 22096 213 0 0
sp_supplier_invoice 5910 5910 0 0.00 6 91718 3464 0 0
sp_supplier_order 5802 5802 0 0.00 5 27874 1348 0 0
srv_aggregate_perf_data 97 97 0 0.00 2688 325536 21212 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1262 138789 44414 0 0
srv_find_qd_qs_mism 1077633 1077633 0 0.00 0 17236 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 40 100013 1316 0 0
srv_make_money_saldo 857 857 0 0.00 0 9637 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 91732 589550 345444 0 0
t$perf-abend:sp_add_invoice_to_stock 191 191 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5431 5431 0 0.00 0 21124 190 0 0
t$perf-norm:sp_cancel_adding_invoice 468 468 0 0.00 1 4013 96 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 10464 18 0 0
t$perf-norm:sp_cancel_customer_reserve 789 789 0 0.00 0 1699 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 3231 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 751 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 463 463 0 0.00 0 7176 34 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 22645 1568 0 0
t$perf-norm:sp_cancel_write_off 1069 1069 0 0.00 0 2715 8 0 0
t$perf-norm:sp_client_order 22223 22223 0 0.00 0 8909 19 0 0
t$perf-norm:sp_customer_reserve 1100 1100 0 0.00 0 1217 6 0 0
t$perf-norm:sp_pay_from_customer 7530 7530 0 0.00 0 15920 14 0 0
t$perf-norm:sp_pay_to_supplier 6289 6289 0 0.00 0 5286 12 0 0
t$perf-norm:sp_reserve_write_off 10737 10737 0 0.00 0 7875 13 0 0
t$perf-norm:sp_supplier_invoice 5910 5910 0 0.00 0 6801 20 0 0
t$perf-norm:sp_supplier_order 5802 5802 0 0.00 0 9222 21 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 175 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 202 4 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 1357 25 0 0
t$perf-norm:srv_make_money_saldo 857 857 0 0.00 0 1826 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 11230 10848 382 3.40 0 6453 22 0 382
v_all_customers 40002 40002 0 0.00 0 16 1 0 0
v_all_suppliers 32484 32484 0 0.00 0 20 1 0 0
v_all_wares 44442 44442 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 936 936 0 0.00 0 3496 14 0 0
v_cancel_client_order 3678 3678 0 0.00 0 4317 7 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 1906 4 0 0
v_cancel_customer_reserve 1578 1578 0 0.00 0 5625 12 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 5244 11 0 0
v_cancel_supplier_invoice 954 926 28 2.93 0 5157 23 0 28
v_cancel_supplier_order 802 802 0 0.00 0 914 5 0 0
v_cancel_write_off 2138 2138 0 0.00 0 5251 17 0 0
v_max_id_clo_ord 5801 5801 0 0.00 0 825 2 0 0
v_max_id_clo_res 1100 1100 0 0.00 0 10093 23 0 0
v_max_id_ord_sup 5903 5903 0 0.00 0 4454 4 0 0
v_max_non_paid_invoice 1750 1750 0 0.00 0 1618 3 0 0
v_max_non_paid_realizn 4208 4208 0 0.00 0 3438 4 0 0
v_min_id_clo_ord 5801 5801 0 0.00 0 2327 6 0 0
v_min_id_clo_res 1100 1100 0 0.00 0 2040 5 0 0
v_min_id_ord_sup 5903 5903 0 0.00 0 5214 8 0 0
v_min_non_paid_invoice 6288 6288 0 0.00 0 20424 27 0 0
v_min_non_paid_realizn 7530 7530 0 0.00 0 20700 36 0 0
v_our_firm 11082 11082 0 0.00 0 18 1 0 0
v_reserve_write_off 21468 21468 0 0.00 0 6834 8 0 0
x_lock_depdocs_on_canc_invoice 11171 11171 0 0.00 0 30557 119 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 25073 387 0 0
x_make_qstorno_1000_1200 5802 5802 0 0.00 1 26604 1262 0 0
x_make_qstorno_1200_2000 5910 5910 0 0.00 2 91694 3334 0 0
x_make_qstorno_2100_3300 496959 496959 0 0.00 0 97487 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-06-2022 16:40:40 to 23-06-2022 16:40:44.

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:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:36 37486 8192000000 9915513856 10227060736 994671856 10.827 100 93 186 0 1025071488 6604432 23795200
14:11:21 26142 8192000000 9957702144 10283491328 1037692544 11.242 100 95 190 0 1071740112 9634608 24412960
14:12:56 18533 8192000000 10003001024 10338959360 1081495088 11.662 100 85 170 0 1113434656 10869744 21069824
14:14:19 13102 8192000000 10052172656 10398195712 1129205136 12.114 100 81 162 1 1160386048 12501776 18679136
14:15:35 6036 8192000000 10091417136 10450640896 1175597168 12.549 100 85 170 2 1208792224 13914480 19280576
14:16:43 4254 8192000000 10131347920 10501844992 1218803168 12.951 100 80 160 0 1249125248 12363728 17958352
14:17:48 5241 8192000000 10164158560 10537951232 1253839216 13.273 100 84 167 3 1284686352 12774176 18072960
14:18:56 1187 8192000000 10208649008 10592350208 1293538560 13.636 100 91 183 1 1326752704 13072784 20141360
14:19:58 42778 8192000000 10211084896 10612457472 1308964896 13.777 100 52 104 0 1330281984 9236016 12081072
14:21:42 11643 8192000000 10197749792 10613944320 1314578864 13.828 100 18 36 1 1324130928 5593376 3958688
14:22:57 5934 8192000000 10205981520 10623942656 1319023936 13.868 100 22 44 0 1330155680 6198832 4932912
14:24:07 64732 8192000000 10206045376 10632142848 1322602224 13.900 100 31 60 1 1335018144 5887008 6528912
14:26:14 5894 8192000000 10217321136 10642657280 1330138128 13.968 100 26 52 2 1342220560 6200048 5882384
14:27:21 7131 8192000000 10216897584 10643038208 1327746928 13.947 100 15 30 1 1337203456 6191568 3264960
14:28:30 17484 8192000000 10180302464 10598621184 1253679616 13.272 96 15 30 0 1262344080 5016128 3648336
14:29:52 16860 8192000000 10164073872 10575679488 1234289584 13.094 96 14 28 0 1242724528 5283184 3151760
14:31:11 10104 8192000000 10161972576 10559287296 1244375472 13.187 98 35 70 1 1263230864 10762144 8093248
14:32:22 6694 8192000000 10128725856 10533687296 1205637184 12.829 96 23 46 0 1216619568 5796480 5185904
14:33:31 13434 8192000000 10172259088 10572660736 1256639856 13.299 99 25 49 2 1267083680 5368976 5074848
14:34:44 22304 8192000000 10175988800 10589872128 1287211104 13.579 100 17 35 0 1296196352 4890656 4094592
14:36:07 8756 8192000000 10195847456 10613092352 1304033392 13.732 100 20 40 2 1314810784 5695136 5082256
14:37:16 12579 8192000000 10213038528 10628911104 1312668608 13.810 100 18 34 1 1321473264 4997344 3807312
14:38:31 42543 8192000000 10232829952 10648031232 1335816752 14.020 100 34 67 2 1348733520 5459824 7456944
14:40:15 56656 8192000000 10256521360 10679468032 1353685248 14.181 100 33 66 1 1371279024 10064608 7529168
14:42:30 4972 8192000000 10282238480 10700914688 1369581888 14.323 100 44 87 0 1393612816 14292240 9738688
14:43:38 8127 8192000000 10294479152 10718076928 1380321920 14.419 100 50 100 2 1407808288 15876992 11609376
14:44:51 12362 8192000000 10310893680 10735742976 1397907968 14.576 100 48 96 1 1427195472 18397088 10890416
14:46:09 3264 8192000000 10331362688 10750582784 1398768880 14.584 100 66 131 3 1441249120 27108960 15371280
14:47:16 4129 8192000000 10344392704 10761887744 1412146272 14.703 100 71 143 2 1455390096 25625392 17618432
14:48:29 8079 8192000000 10345617888 10766004224 1411455552 14.697 100 65 129 0 1456362880 29518496 15388832
14:49:48 11379 8192000000 10326103072 10745028608 1386867840 14.478 99 56 111 0 1438753888 37638336 14247712
14:51:02 7559 8192000000 10316725984 10732204032 1364751360 14.280 99 60 118 0 1415731280 36241856 14738064
14:52:15 4953 8192000000 10323097264 10732322816 1373319232 14.357 99 79 159 0 1430957984 37868576 19770176
14:53:26 2611 8192000000 10345435984 10761949184 1398326496 14.580 100 75 149 1 1448677568 31010416 19340656
14:54:35 6161 8192000000 10318774784 10736300032 1368500448 14.314 99 62 124 1 1418962064 35347136 15114480
14:55:51 1599 8192000000 10322706672 10730405888 1366603824 14.297 100 83 166 1 1426116368 39658176 19854368
14:57:01 7777 8192000000 10299931872 10712584192 1344417440 14.097 99 61 121 1 1399176976 40139776 14619760
14:58:13 3463 8192000000 10296362032 10702307328 1338611680 14.045 99 80 160 0 1392420656 34238064 19570912
14:59:21 3375 8192000000 10300410640 10699493376 1340956816 14.066 99 84 165 1 1395489104 33652896 20879392
15:00:32 1261 8192000000 10344550096 10745757696 1373033072 14.354 100 93 186 0 1443311216 47037648 23240496
15:01:37 8788 8192000000 10313136992 10744012800 1361959168 14.255 99 35 70 2 1416482352 44474592 10048592
15:02:54 23584 8192000000 10310678512 10747752448 1363856464 14.272 99 36 72 0 1413874944 40447744 9570736
15:04:39 15545 8192000000 10295472400 10722254848 1343314720 14.087 99 32 64 2 1394386784 42061888 9010176
15:05:56 16947 8192000000 10288279280 10732597248 1353714656 14.181 100 17 34 0 1399559280 41076848 4767776
15:07:15 19983 8192000000 10302429728 10733301760 1355379952 14.196 100 52 101 4 1403544624 34903840 13260832
15:08:40 20002 8192000000 10307334880 10756247552 1378242480 14.401 100 20 40 1 1413676576 30362752 5071344
15:10:05 34693 8192000000 10288249408 10750529536 1376716736 14.387 100 19 38 1 1399312912 18499696 4096480
15:11:58 61259 8192000000 10307083776 10769928192 1391245600 14.517 100 6 12 0 1409200960 16683536 1271824
15:15:07 95644 8192000000 10299147568 10737414144 1358552080 14.224 98 14 28 1 1377333920 15332880 3448960
15:17:43 54166 8192000000 10336085536 10770829312 1399390608 14.590 100 31 61 3 1424264864 17945024 6929232
15:19:42 97527 8192000000 10302733216 10747916288 1362901824 14.263 97 24 48 0 1379411904 10987328 5522752
15:22:20 38473 8192000000 10315985792 10752184320 1385561040 14.466 99 37 70 3 1402061008 7965232 8534736
15:24:08 86519 8192000000 10339796096 10779123712 1396732816 14.566 99 58 115 5 1420206560 9530000 13943744
15:26:36 62295 8192000000 10322844928 10775953408 1389227680 14.499 98 18 36 0 1409888064 16145104 4515280
15:28:39 37224 8192000000 10300909968 10771378176 1372609088 14.350 98 22 44 0 1395588816 17399824 5579904
15:30:17 37773 8192000000 10310436944 10766295040 1399646144 14.592 100 16 32 0 1417020720 13460288 3914288
15:32:09 45923 8192000000 10278742416 10735538176 1354527296 14.188 98 11 22 0 1369292096 12005440 2759360
15:33:59 44875 8192000000 10281610992 10731180032 1363198128 14.266 99 11 23 1 1375297328 10023856 2075344
15:35:51 54916 8192000000 10283812528 10730696704 1329141136 13.959 96 25 50 0 1350242048 15157152 5943760
15:37:46 59196 8192000000 10265603024 10729431040 1348746096 14.136 98 12 24 0 1364051136 12644992 2660048
15:39:54 38660 8192000000 10294868384 10744684544 1368084160 14.310 99 22 44 0 1386360448 13552384 4723904
15:41:32 95967 8192000000 10297090112 10740797440 1301841840 13.712 94 14 28 0 1317902688 13583504 2477344
15:44:08 75770 8192000000 10304244864 10736226304 1336373904 14.025 96 32 64 0 1359625472 16069696 7181872
15:46:26 111920 8192000000 10266920800 10732560384 1291119696 13.614 94 8 16 0 1316879776 23762352 1997728
15:49:20 52208 8192000000 10241368656 10686464000 1311509584 13.800 97 28 57 0 1325607520 7047328 7050608
15:51:17 31329 8192000000 10271366512 10714902528 1364698240 14.280 100 40 80 0 1381866064 8594656 8573168
15:52:51 42202 8192000000 10279027408 10738315264 1359276144 14.231 99 25 50 0 1382692848 17982176 5434528
15:54:37 39630 8192000000 10248614656 10701910016 1273410000 13.453 93 22 44 0 1302190192 23733120 5047072
15:56:20 66740 8192000000 10281430784 10732830720 1375227600 14.374 100 16 32 0 1392961280 13823616 3910064
15:58:31 64405 8192000000 10258156928 10724409344 1348642096 14.135 99 13 26 1 1374864096 22686496 3535504
16:00:35 59664 8192000000 10290161072 10736693248 1348029824 14.130 98 38 75 0 1365654928 9148976 8476128
16:02:35 38922 8192000000 10264235888 10716344320 1358093568 14.220 99 26 52 1 1373133344 8904768 6135008
16:04:17 57659 8192000000 10290265136 10748280832 1382840224 14.442 100 14 28 0 1395019728 9003424 3176080
16:06:29 58128 8192000000 10309071696 10756120576 1391289856 14.517 100 27 54 0 1407888784 10621184 5977744
16:08:28 138789 8192000000 10299992512 10758119424 1347418000 14.124 96 13 26 0 1362441840 12634112 2389728
16:12:09 37698 8192000000 10298141216 10754789376 1359105808 14.229 97 18 36 2 1369849136 6871088 3872240
16:15:00 7872 8192000000 10314554096 10764070912 1402681120 14.619 100 50 97 3 1419415296 5166688 11567488
16:16:08 77162 8192000000 10279116816 10746572800 1331881808 13.984 95 10 20 1 1347432048 13190704 2359536
16:18:25 104233 8192000000 10292637104 10760556544 1338257040 14.042 96 12 24 0 1357596160 16545248 2793872
16:21:10 40069 8192000000 10287413360 10726846464 1337411424 14.034 97 31 62 0 1357018848 12278976 7328448
16:22:52 69084 8192000000 10273329632 10721878016 1328791840 13.956 97 14 28 0 1342105248 10100544 3212864
16:25:05 53062 8192000000 10268305392 10717491200 1322425344 13.899 96 30 61 0 1338246720 8669520 7151856
16:27:05 40533 8192000000 10280105488 10726490112 1356129808 14.203 99 27 54 1 1375609472 13673984 5805680
16:28:46 116888 8192000000 10291761280 10756399104 1352642688 14.171 98 8 16 1 1366414816 12089136 1682992
16:31:45 113607 8192000000 10246033472 10704437248 1283331120 13.543 94 9 18 1 1303284624 17794432 2159072
16:34:48 60109 8192000000 10252268720 10701086720 1327841200 13.948 98 26 52 1 1349078288 15571776 5665312
16:36:50 88930 8192000000 10252251696 10721312768 1330131920 13.968 98 15 30 1 1345158960 11734720 3292320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 191 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 23-06-2022 16:41:02 to 23-06-2022 16:41:03.

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

23.06.22 16:41:05. 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-23 16:41:07 No severe PSQL-related problems occured
23.06.22 16:41:08. End of report.