Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.05.2022 16:42
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 16303780 kB
MemAvailable 31500460 kB
Buffers 0 kB
Cached 15378388 kB
SwapCached 3248 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-05-02 16:42:16
DTS_END                         2022-05-02 16:42:16.9490
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/45052
TRN_ID                          243563
ATT_ID                          703
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: 9606.50 21119 1152780
customer order: creation 180.45 335 21654
customer order: refuse 15.33 356 1840
order to supplier: creation 47.93 1320 5752
order to supplier: removal 3.73 91463 448
invoice (draft): creation 47.86 3287 5743
invoice (draft): removal 55.13 773 6616
invoice accept: apply 43.42 18341 5210
invoice accept: cancel 48.99 812 5879
customer reserve: creation 4013.70 181 481644
customer reserve: removal 4848.02 54 581763
realization accept: apply 86.41 185 10369
realization accept: cancel 79.12 111 9494
payment from customer: creation 62.99 111 7559
payment from customer: removal 6.07 60 728
payment to supplier: creation 51.28 152 6154
payment to supplier: removal 3.71 83 445
service: total inventory turnovers 4.80 1161 576
service: total monetary turnovers 6.68 42 802
service: aggregate perf. data 0.83 16605 100
service: refresh index statistics 0.03 286953 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-05-2022 16:43:22 to 02-05-2022 16:43:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5626
TEST_TIME 2 5861
TEST_TIME 3 7079
TEST_TIME 4 6543
TEST_TIME 5 6319
TEST_TIME 6 6405
TEST_TIME 7 6300
TEST_TIME 8 6740
TEST_TIME 9 7035
TEST_TIME 10 7439
TEST_TIME 11 7639
TEST_TIME 12 7735
TEST_TIME 13 7612
TEST_TIME 14 7872
TEST_TIME 15 7858
TEST_TIME 16 7857
TEST_TIME 17 8203
TEST_TIME 18 8267
TEST_TIME 19 8523
TEST_TIME 20 8490
TEST_TIME 21 8489
TEST_TIME 22 8840
TEST_TIME 23 8970
TEST_TIME 24 9753
TEST_TIME 25 9951
TEST_TIME 26 10091
TEST_TIME 27 10574
TEST_TIME 28 10392
TEST_TIME 29 10261
TEST_TIME 30 10272
TEST_TIME 31 10934
TEST_TIME 32 10872
TEST_TIME 33 10604
TEST_TIME 34 10673
TEST_TIME 35 10731
TEST_TIME 36 10616
TEST_TIME 37 10640
TEST_TIME 38 10487
TEST_TIME 39 10431
TEST_TIME 40 10433
TEST_TIME 41 10394
TEST_TIME 42 10337
TEST_TIME 43 10427
TEST_TIME 44 10377
TEST_TIME 45 10247
TEST_TIME 46 10063
TEST_TIME 47 10079
TEST_TIME 48 10107
TEST_TIME 49 10024
TEST_TIME 50 9956
TEST_TIME 51 9966
TEST_TIME 52 9873
TEST_TIME 53 9834
TEST_TIME 54 9921
TEST_TIME 55 9922
TEST_TIME 56 9846
TEST_TIME 57 9794
TEST_TIME 58 9689
TEST_TIME 59 9665
TEST_TIME 60 9627
TEST_TIME 61 9628
TEST_TIME 62 9666
TEST_TIME 63 9629
TEST_TIME 64 9679
TEST_TIME 65 9711
TEST_TIME 66 9692
TEST_TIME 67 9680
TEST_TIME 68 9747
TEST_TIME 69 9737
TEST_TIME 70 9748
TEST_TIME 71 9760
TEST_TIME 72 9839
TEST_TIME 73 9783
TEST_TIME 74 9825
TEST_TIME 75 9912
TEST_TIME 76 9875
TEST_TIME 77 9959
TEST_TIME 78 9877
TEST_TIME 79 9831
TEST_TIME 80 9834
TEST_TIME 81 9766
TEST_TIME 82 9764
TEST_TIME 83 9750
TEST_TIME 84 9711
TEST_TIME 85 9630
TEST_TIME 86 9641
TEST_TIME 87 9551
TEST_TIME 88 9676
TEST_TIME 89 9675
TEST_TIME 90 9641
TEST_TIME 91 9686
TEST_TIME 92 9731
TEST_TIME 93 9729
TEST_TIME 94 9805
TEST_TIME 95 9816
TEST_TIME 96 9794
TEST_TIME 97 9817
TEST_TIME 98 9828
TEST_TIME 99 9869
TEST_TIME 100 9900
TEST_TIME 101 9893
TEST_TIME 102 9904
TEST_TIME 103 9833
TEST_TIME 104 9782
TEST_TIME 105 9757
TEST_TIME 106 9753
TEST_TIME 107 9758
TEST_TIME 108 9692
TEST_TIME 109 9659
TEST_TIME 110 9645
TEST_TIME 111 9595
TEST_TIME 112 9651
TEST_TIME 113 9639
TEST_TIME 114 9609
TEST_TIME 115 9604
TEST_TIME 116 9644
TEST_TIME 117 9662
TEST_TIME 118 9646
TEST_TIME 119 9658
TEST_TIME 120 9608

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-05-2022 16:43:26 to 02-05-2022 16:43:29.

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 1597675 1597675 0 0.00 0 30450 6 0 0
doc_list_biud 1597669 1597669 0 0.00 0 59932 26 0 0
sp_add_invoice_to_stock 5325 5210 115 2.15 33 193682 18342 0 115
sp_cancel_adding_invoice 5879 5879 0 0.00 4 68646 812 0 0
sp_cancel_client_order 1840 1840 0 0.00 4 17766 356 0 0
sp_cancel_customer_reserve 581763 581763 0 0.00 1 54787 55 0 0
sp_cancel_pay_from_customer 728 728 0 0.00 3 3818 61 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 3 5144 83 0 0
sp_cancel_supplier_invoice 6624 6616 8 0.12 1 59938 774 0 8
sp_cancel_supplier_order 448 448 0 0.00 6 1173369 91464 0 0
sp_cancel_write_off 9494 9494 0 0.00 1 10347 112 0 0
sp_client_order 21654 21654 0 0.00 3 20537 336 0 0
sp_customer_reserve 481644 481644 0 0.00 0 95473 182 0 0
sp_fill_shopping_cart 33144 33144 0 0.00 1 15335 40 0 0
sp_fill_shopping_cart_clo_res 481637 481637 0 0.00 0 15750 12 0 0
sp_get_clo_for_invoice 5203 5203 0 0.00 0 22803 267 0 0
sp_kill_cost_storno 16546 16546 0 0.00 0 10342 58 0 0
sp_kill_qstorno_ret_qs2qd 1180084 1180084 0 0.00 0 59928 30 0 0
sp_kill_qty_storno 621608 621608 0 0.00 0 59931 65 0 0
sp_lock_selected_doc 21673 21673 0 0.00 0 7566 6 0 0
sp_make_cost_storno 29285 29285 0 0.00 0 12575 48 0 0
sp_multiply_rows_for_pdistr 29280 29280 0 0.00 0 13675 33 0 0
sp_multiply_rows_for_qdistr 21654 21654 0 0.00 0 11939 138 0 0
sp_pay_from_customer 7559 7559 0 0.00 4 18701 111 0 0
sp_pay_to_supplier 6154 6154 0 0.00 5 19029 153 0 0
sp_payment_common 13712 13712 0 0.00 2 14398 25 0 0
sp_qd_handle_on_cancel_clo 1840 1840 0 0.00 0 9554 316 0 0
sp_qd_handle_on_invoice_upd_sts 11078 11078 0 0.00 0 13623 325 0 0
sp_qd_handle_on_reserve_upd_sts 10369 10369 0 0.00 0 13643 57 0 0
sp_reserve_write_off 10369 10369 0 0.00 6 21847 186 0 0
sp_supplier_invoice 5743 5743 0 0.00 5 79388 3288 0 0
sp_supplier_order 5752 5752 0 0.00 5 23249 1320 0 0
srv_aggregate_perf_data 101 100 1 0.99 2811 121801 16605 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1345 113480 43722 0 0
srv_find_qd_qs_mism 1102200 1102200 0 0.00 0 30320 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 36 83113 1161 0 0
srv_make_money_saldo 802 802 0 0.00 1 11620 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 130037 496477 286954 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5210 5210 0 0.00 0 19795 194 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 1 3087 120 0 0
t$perf-norm:sp_cancel_client_order 1840 1840 0 0.00 0 3614 13 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 1269 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 728 728 0 0.00 0 781 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 2966 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 1646 14 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 25858 1700 0 0
t$perf-norm:sp_cancel_write_off 1051 1051 0 0.00 0 2345 7 0 0
t$perf-norm:sp_client_order 21654 21654 0 0.00 0 8030 20 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 2642 8 0 0
t$perf-norm:sp_pay_from_customer 7559 7559 0 0.00 0 9953 10 0 0
t$perf-norm:sp_pay_to_supplier 6154 6154 0 0.00 0 7546 15 0 0
t$perf-norm:sp_reserve_write_off 10369 10369 0 0.00 0 7779 18 0 0
t$perf-norm:sp_supplier_invoice 5743 5743 0 0.00 0 10538 20 0 0
t$perf-norm:sp_supplier_order 5752 5752 0 0.00 0 10291 18 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 199 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 63 2 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 2230 21 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 1035 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10628 10398 230 2.16 0 19577 22 0 230
v_all_customers 39768 39768 0 0.00 0 53 1 0 0
v_all_suppliers 31784 31784 0 0.00 0 26 1 0 0
v_all_wares 43308 43308 0 0.00 0 135 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 1061 9 0 0
v_cancel_client_order 3680 3680 0 0.00 0 17563 11 0 0
v_cancel_customer_prepayment 1456 1456 0 0.00 0 3810 6 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 237 3 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 5134 8 0 0
v_cancel_supplier_invoice 854 838 16 1.87 0 1324 15 0 16
v_cancel_supplier_order 896 896 0 0.00 0 5937 20 0 0
v_cancel_write_off 2102 2102 0 0.00 0 5261 17 0 0
v_max_id_clo_ord 5751 5751 0 0.00 0 1004 2 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 3504 14 0 0
v_max_id_ord_sup 5739 5739 0 0.00 0 3427 4 0 0
v_max_non_paid_invoice 1751 1751 0 0.00 0 254 2 0 0
v_max_non_paid_realizn 3974 3974 0 0.00 0 631 3 0 0
v_min_id_clo_ord 5751 5751 0 0.00 0 3302 6 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 1631 7 0 0
v_min_id_ord_sup 5739 5739 0 0.00 0 10199 11 0 0
v_min_non_paid_invoice 6153 6153 0 0.00 0 14392 21 0 0
v_min_non_paid_realizn 7559 7559 0 0.00 0 7553 18 0 0
v_our_firm 10710 10710 0 0.00 0 22 1 0 0
v_reserve_write_off 20738 20738 0 0.00 0 19689 13 0 0
x_lock_depdocs_on_canc_invoice 12076 12076 0 0.00 0 22036 126 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 13371 401 0 0
x_make_qstorno_1000_1200 5752 5752 0 0.00 1 23180 1232 0 0
x_make_qstorno_1200_2000 5743 5743 0 0.00 3 76362 3148 0 0
x_make_qstorno_2100_3300 481644 481644 0 0.00 0 95473 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-05-2022 16:43:30 to 02-05-2022 16:43:34.

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:12:20 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:22 14734 8192000000 9905139248 10216673280 988988928 10.772 100 86 172 0 1017927104 6520848 22417328
14:14:49 13148 8192000000 9948551568 10271551488 1030221712 11.171 100 88 176 1 1061160672 8482096 22456864
14:16:07 24792 8192000000 9988210624 10321969152 1068684080 11.540 100 86 172 0 1099960080 9308208 21967792
14:17:34 15353 8192000000 10034687296 10379231232 1116093024 11.990 100 77 153 1 1145912896 11220528 18599344
14:18:53 6544 8192000000 10072400432 10432450560 1162524528 12.427 100 76 151 0 1192060752 11909040 17627184
14:20:06 6361 8192000000 10125484368 10495217664 1213714768 12.904 100 81 162 0 1245695184 13779504 18200912
14:21:14 5449 8192000000 10147404896 10531061760 1245387920 13.196 100 63 127 2 1271138896 12155024 13595952
14:22:21 2829 8192000000 10200407376 10582061056 1285148432 13.560 100 82 161 1 1314770720 10693312 18928976
14:23:25 17540 8192000000 10214842384 10606358528 1302466240 13.718 100 88 177 2 1332599984 9998480 20135264
14:24:45 7057 8192000000 10210077664 10615570432 1312063824 13.805 100 36 72 0 1328021184 7746640 8210720
14:25:52 9225 8192000000 10208583984 10621755392 1318412288 13.862 100 17 33 1 1328093520 5758272 3922960
14:27:06 47729 8192000000 10208564576 10634825728 1323656752 13.910 100 47 94 3 1341200464 5995136 11548576
14:28:54 8533 8192000000 10229471008 10648195072 1335598752 14.018 100 22 44 0 1346359136 5513824 5246560
14:30:05 16446 8192000000 10223207472 10643746816 1324159024 13.914 99 20 40 1 1334129904 5893280 4077600
14:31:23 59214 8192000000 10225168672 10652176384 1339865408 14.056 100 31 63 0 1353688176 6692368 7130400
14:33:22 5813 8192000000 10163090752 10562146304 1243357952 13.177 97 15 29 1 1252278848 5708352 3212544
14:34:30 8782 8192000000 10156537648 10557378560 1222995072 12.989 96 21 40 0 1233190960 5603376 4592512
14:35:41 6410 8192000000 10135290032 10537893888 1238205536 13.130 99 24 49 1 1249491952 5190896 6095520
14:36:48 30871 8192000000 10146784832 10550312960 1233700320 13.088 98 39 78 1 1250055488 7752192 8602976
14:38:25 7253 8192000000 10151994256 10561003520 1263806656 13.365 100 30 60 2 1277453280 6835520 6811104
14:39:33 11177 8192000000 10192326144 10597982208 1291764448 13.620 100 26 52 1 1303542880 5609232 6169200
14:40:46 59468 8192000000 10195449808 10608177152 1299775408 13.693 100 20 40 2 1309851440 5442496 4633536
14:42:52 29077 8192000000 10241669200 10650005504 1326971216 13.940 100 55 107 2 1350518752 12120928 11426608
14:44:25 6537 8192000000 10271397184 10674171904 1347690640 14.127 100 77 151 2 1377998512 12788176 17519696
14:45:35 16682 8192000000 10264973920 10684354560 1356960352 14.210 100 45 90 2 1377671264 10086656 10624256
14:47:01 22239 8192000000 10281466928 10698432512 1367522272 14.305 100 46 93 2 1391992880 13992960 10477648
14:48:27 6780 8192000000 10291385136 10715176960 1380797536 14.424 100 42 84 2 1406205488 13937792 11470160
14:49:40 5153 8192000000 10316099344 10733113344 1392596272 14.529 100 63 126 2 1424756464 17347792 14812400
14:50:50 19201 8192000000 10328360224 10754785280 1402086704 14.614 100 60 121 2 1435975536 19965968 13922864
14:52:12 3473 8192000000 10355292672 10774097920 1418321584 14.758 100 67 134 1 1460453632 25085792 17046256
14:53:18 13819 8192000000 10339858672 10774499328 1416245728 14.739 100 54 108 2 1453941040 24517184 13178128
14:54:36 3614 8192000000 10344534288 10769850368 1405150192 14.641 99 67 133 3 1446274848 25137456 15987200
14:55:44 6140 8192000000 10352311888 10769948672 1415189840 14.730 100 76 149 2 1460442672 27530592 17722240
14:56:55 3370 8192000000 10340238272 10746920960 1384479680 14.457 99 91 179 0 1434361296 28165792 21715824
14:58:01 4592 8192000000 10350237536 10757509120 1383841680 14.451 99 82 166 2 1440176864 35481456 20853728
14:59:10 3963 8192000000 10337679264 10747224064 1366338912 14.294 98 67 134 0 1423804688 39946320 17519456
15:00:18 1787 8192000000 10316234768 10723520512 1364182512 14.275 98 74 145 2 1416564576 33983792 18398272
15:01:24 8439 8192000000 10324748016 10737905664 1366901248 14.299 99 59 118 1 1419611680 37123552 15586880
15:02:39 2392 8192000000 10338863936 10757246976 1392688640 14.530 100 65 130 2 1447973104 36016160 19268304
15:03:48 1344 8192000000 10365742608 10771070976 1392764032 14.531 100 80 160 2 1457746272 44112400 20869840
15:04:52 14373 8192000000 10339220976 10766028800 1382319664 14.437 100 37 74 1 1440501024 47281504 10899856
15:06:13 2002 8192000000 10367787648 10785124352 1384134848 14.454 100 69 141 2 1462436272 59865936 18435488
15:07:16 30623 8192000000 10313712272 10748162048 1342615728 14.081 97 23 44 1 1392438288 43275648 6546912
15:08:54 12970 8192000000 10319064896 10747760640 1364882016 14.281 100 37 74 1 1428332352 53353008 10097328
15:10:12 65797 8192000000 10289150832 10720964608 1346899344 14.120 99 34 68 1 1384055888 28908384 8248160
15:12:34 24613 8192000000 10297554384 10750676992 1358660832 14.225 100 10 20 1 1400423568 39098320 2664416
15:14:03 34097 8192000000 10275980576 10719596544 1355150400 14.194 99 15 30 1 1366869248 8395168 3323680
15:15:43 113478 8192000000 10286968112 10743164928 1352065632 14.166 98 10 20 1 1367045680 12978336 2001712
15:18:38 87648 8192000000 10282651984 10733817856 1322211632 13.897 96 7 14 0 1331979040 8641408 1126000
15:21:06 53350 8192000000 10306721888 10754768896 1393865504 14.540 100 14 28 0 1409181744 12265792 3050448
15:23:09 78813 8192000000 10312879296 10759745536 1374665184 14.369 98 30 60 1 1390903264 9640896 6597184
15:25:52 89440 8192000000 10310083216 10756771840 1397277072 14.571 100 12 25 0 1412080928 12440832 2363024
15:28:22 93456 8192000000 10290220992 10742136832 1345461776 14.107 96 23 46 1 1361329696 10331248 5536672
15:30:58 40653 8192000000 10297825568 10751840256 1390890576 14.514 100 49 98 0 1414401552 11918192 11592784
15:32:42 1574 8192000000 10315907360 10760196096 1398332736 14.580 100 58 115 0 1419958400 8503024 13122640
15:33:45 60758 8192000000 10307439152 10753925120 1375525232 14.377 98 19 38 0 1390688832 10666528 4497072
15:35:46 22167 8192000000 10305592560 10752139264 1383796512 14.450 99 61 120 3 1410781504 13639584 13345408
15:37:12 37927 8192000000 10264411632 10715340800 1334451952 14.007 97 23 46 0 1350346112 10449392 5444768
15:39:20 36318 8192000000 10275101680 10732101632 1360177568 14.239 99 16 32 1 1377027616 13069056 3780992
15:41:03 41053 8192000000 10290237296 10744582144 1378779856 14.406 100 27 54 4 1395182080 9358960 7043264
15:42:48 77952 8192000000 10278350032 10749849600 1346889328 14.119 97 18 36 0 1369209840 18070400 4250112
15:45:08 44271 8192000000 10268921664 10726281216 1330804992 13.974 96 15 30 0 1345334272 11690064 2839216
15:46:52 70368 8192000000 10254207808 10713694208 1263897072 13.366 92 9 18 0 1277074880 11277344 1900464
15:49:37 15255 8192000000 10284859664 10725187584 1368241024 14.311 100 52 105 1 1390524576 10515536 11768016
15:50:58 57209 8192000000 10273142784 10717904896 1333889664 14.002 97 26 52 1 1349334400 9683216 5761520
15:53:03 72275 8192000000 10280065632 10720231424 1315484992 13.836 96 20 40 0 1334836896 14854848 4497056
15:55:18 78874 8192000000 10283299296 10732863488 1354246464 14.186 98 16 32 1 1372169344 14199296 3723584
15:57:37 48395 8192000000 10294788896 10731327488 1368483680 14.313 100 30 60 0 1387735104 12116336 7135088
15:59:26 101404 8192000000 10276476224 10727079936 1351660224 14.162 98 16 32 0 1370171440 14680880 3830336
16:02:49 35184 8192000000 10272582384 10726658048 1350801536 14.155 98 19 38 0 1367293088 11984144 4507408
16:04:25 100345 8192000000 10268770992 10738016256 1327013856 13.940 96 17 34 1 1349197936 18349360 3834720
16:07:05 73361 8192000000 10290443808 10743238656 1378796240 14.406 100 23 46 0 1403004160 18334544 5873376
16:09:19 69099 8192000000 10280454528 10752172032 1374198912 14.365 100 16 32 1 1403635568 24768624 4668032
16:11:33 54429 8192000000 10287533536 10741592064 1360704096 14.244 98 16 32 0 1375224400 10987552 3532752
16:13:28 60631 8192000000 10292384112 10766274560 1385836480 14.469 100 7 14 2 1401506704 14243088 1427136
16:15:36 60504 8192000000 10305793552 10777608192 1400826352 14.602 100 26 52 1 1422971600 16052928 6092320
16:17:44 82028 8192000000 10317462112 10772066304 1374656816 14.369 98 15 30 1 1387067104 9071216 3339072
16:20:08 110779 8192000000 10284474176 10746802176 1313860592 13.821 94 17 32 2 1331224992 13652464 3711936
16:23:06 109437 8192000000 10285637952 10742788096 1310117776 13.787 94 19 38 0 1327109328 13122160 3869392
16:25:56 85083 8192000000 10284032016 10739781632 1371120880 14.337 99 14 28 0 1385608944 11470624 3017440
16:28:21 18583 8192000000 10274745408 10741788672 1365896480 14.290 99 19 38 1 1381680176 11715392 4068304
16:29:46 61420 8192000000 10268848992 10736353280 1367850864 14.308 100 4 8 0 1382616144 13835248 930032
16:31:52 54286 8192000000 10275616832 10740879360 1368981824 14.318 99 11 22 0 1382927408 11622336 2323248
16:33:48 66898 8192000000 10238227680 10715148288 1319340256 13.871 97 13 25 0 1339339792 16883136 3116400
16:35:55 50478 8192000000 10248302704 10707890176 1311955904 13.804 96 16 32 0 1324031472 7938800 4136768
16:38:16 55716 8192000000 10273072416 10730041344 1355661040 14.198 99 29 58 1 1373417648 11134960 6621648
16:40:12 56968 8192000000 10281503856 10731692032 1308951168 13.777 95 30 60 0 1327196224 11756624 6488432
16:42:12 14581 8192000000 9049106688 9263853568 212891328 2.532 16 2 2 1 217640480 4334656 414496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-05-2022 16:43:36 to 02-05-2022 16:43:51.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-05-2022 16:43:52 to 02-05-2022 16:43:54.

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

02.05.22 16:43:55. 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-05-02 16:43:58 No severe PSQL-related problems occured
02.05.22 16:43:59. End of report.