Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.07.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 16035244 kB
MemAvailable 31130352 kB
Buffers 0 kB
Cached 15705220 kB
SwapCached 2524 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.11
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-07-14 16:38:24
DTS_END                         2022-07-14 16:38:24.9630
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/55988
TRN_ID                          236894
ATT_ID                          627
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9527.84 19624 1143341
customer order: creation 168.11 366 20173
customer order: refuse 14.48 406 1737
order to supplier: creation 44.83 1216 5380
order to supplier: removal 3.43 105168 411
invoice (draft): creation 45.64 3459 5477
invoice (draft): removal 55.45 745 6654
invoice accept: apply 41.04 17075 4925
invoice accept: cancel 50.05 712 6006
customer reserve: creation 3878.21 164 465385
customer reserve: removal 4934.60 58 592152
realization accept: apply 81.16 200 9739
realization accept: cancel 85.25 108 10230
payment from customer: creation 57.86 132 6943
payment from customer: removal 5.60 70 672
payment to supplier: creation 47.56 150 5707
payment to supplier: removal 3.45 70 414
service: total inventory turnovers 4.46 1251 535
service: total monetary turnovers 5.70 34 684
service: aggregate perf. data 0.94 14931 113
service: refresh index statistics 0.03 246171 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-07-2022 16:40:38 to 14-07-2022 16:40:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6652
TEST_TIME 2 7186
TEST_TIME 3 5646
TEST_TIME 4 5433
TEST_TIME 5 5773
TEST_TIME 6 5789
TEST_TIME 7 5955
TEST_TIME 8 5807
TEST_TIME 9 5724
TEST_TIME 10 5951
TEST_TIME 11 5774
TEST_TIME 12 5639
TEST_TIME 13 5982
TEST_TIME 14 5939
TEST_TIME 15 6007
TEST_TIME 16 6436
TEST_TIME 17 7134
TEST_TIME 18 7049
TEST_TIME 19 7831
TEST_TIME 20 7867
TEST_TIME 21 8213
TEST_TIME 22 9002
TEST_TIME 23 8892
TEST_TIME 24 9132
TEST_TIME 25 9354
TEST_TIME 26 9577
TEST_TIME 27 9830
TEST_TIME 28 10181
TEST_TIME 29 10848
TEST_TIME 30 10962
TEST_TIME 31 11041
TEST_TIME 32 10852
TEST_TIME 33 10874
TEST_TIME 34 10991
TEST_TIME 35 10731
TEST_TIME 36 10516
TEST_TIME 37 10437
TEST_TIME 38 10387
TEST_TIME 39 10242
TEST_TIME 40 10068
TEST_TIME 41 9983
TEST_TIME 42 10208
TEST_TIME 43 10177
TEST_TIME 44 10162
TEST_TIME 45 10093
TEST_TIME 46 10081
TEST_TIME 47 9975
TEST_TIME 48 9796
TEST_TIME 49 9716
TEST_TIME 50 9669
TEST_TIME 51 9654
TEST_TIME 52 9616
TEST_TIME 53 9607
TEST_TIME 54 9587
TEST_TIME 55 9648
TEST_TIME 56 9609
TEST_TIME 57 9657
TEST_TIME 58 9603
TEST_TIME 59 9780
TEST_TIME 60 9808
TEST_TIME 61 9871
TEST_TIME 62 9819
TEST_TIME 63 9885
TEST_TIME 64 9905
TEST_TIME 65 9904
TEST_TIME 66 9910
TEST_TIME 67 9805
TEST_TIME 68 9831
TEST_TIME 69 9771
TEST_TIME 70 9685
TEST_TIME 71 9690
TEST_TIME 72 9635
TEST_TIME 73 9752
TEST_TIME 74 9796
TEST_TIME 75 9751
TEST_TIME 76 9770
TEST_TIME 77 9708
TEST_TIME 78 9749
TEST_TIME 79 9813
TEST_TIME 80 9799
TEST_TIME 81 9844
TEST_TIME 82 9806
TEST_TIME 83 9836
TEST_TIME 84 9821
TEST_TIME 85 9887
TEST_TIME 86 9898
TEST_TIME 87 9894
TEST_TIME 88 9953
TEST_TIME 89 9875
TEST_TIME 90 9822
TEST_TIME 91 9907
TEST_TIME 92 9843
TEST_TIME 93 9839
TEST_TIME 94 9784
TEST_TIME 95 9752
TEST_TIME 96 9736
TEST_TIME 97 9688
TEST_TIME 98 9647
TEST_TIME 99 9684
TEST_TIME 100 9686
TEST_TIME 101 9673
TEST_TIME 102 9690
TEST_TIME 103 9822
TEST_TIME 104 9833
TEST_TIME 105 9828
TEST_TIME 106 9845
TEST_TIME 107 9811
TEST_TIME 108 9873
TEST_TIME 109 9878
TEST_TIME 110 9906
TEST_TIME 111 9858
TEST_TIME 112 9796
TEST_TIME 113 9755
TEST_TIME 114 9746
TEST_TIME 115 9705
TEST_TIME 116 9678
TEST_TIME 117 9652
TEST_TIME 118 9617
TEST_TIME 119 9607
TEST_TIME 120 9531

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-07-2022 16:40:42 to 14-07-2022 16:40:45.

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 1572466 1572466 0 0.00 0 28965 6 0 0
doc_list_biud 1572463 1572463 0 0.00 0 35890 27 0 0
sp_add_invoice_to_stock 5025 4925 100 1.99 35 140578 17076 0 100
sp_cancel_adding_invoice 6006 6006 0 0.00 5 94658 713 0 0
sp_cancel_client_order 1737 1737 0 0.00 4 10647 407 0 0
sp_cancel_customer_reserve 592152 592152 0 0.00 0 35892 58 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 2 4809 71 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 2 6497 71 0 0
sp_cancel_supplier_invoice 6660 6654 6 0.09 1 13070 746 0 6
sp_cancel_supplier_order 411 411 0 0.00 8 1145148 105168 0 0
sp_cancel_write_off 10230 10230 0 0.00 1 12396 108 0 0
sp_client_order 20173 20173 0 0.00 3 20070 367 0 0
sp_customer_reserve 465385 465385 0 0.00 0 51783 164 0 0
sp_fill_shopping_cart 31027 31027 0 0.00 1 12798 37 0 0
sp_fill_shopping_cart_clo_res 465380 465380 0 0.00 0 13230 14 0 0
sp_get_clo_for_invoice 4919 4919 0 0.00 0 23236 305 0 0
sp_kill_cost_storno 17322 17322 0 0.00 0 28957 52 0 0
sp_kill_qstorno_ret_qs2qd 1201599 1201599 0 0.00 0 35887 31 0 0
sp_kill_qty_storno 631847 631847 0 0.00 0 35890 68 0 0
sp_lock_selected_doc 20255 20255 0 0.00 0 8380 6 0 0
sp_make_cost_storno 27308 27308 0 0.00 0 16113 61 0 0
sp_multiply_rows_for_pdistr 27308 27308 0 0.00 0 28913 33 0 0
sp_multiply_rows_for_qdistr 20172 20172 0 0.00 0 19085 161 0 0
sp_pay_from_customer 6943 6943 0 0.00 4 15742 132 0 0
sp_pay_to_supplier 5707 5707 0 0.00 5 28931 151 0 0
sp_payment_common 12650 12650 0 0.00 1 13083 34 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 10640 370 0 0
sp_qd_handle_on_invoice_upd_sts 10925 10925 0 0.00 0 24289 310 0 0
sp_qd_handle_on_reserve_upd_sts 9738 9738 0 0.00 0 18863 71 0 0
sp_reserve_write_off 9739 9739 0 0.00 6 18876 201 0 0
sp_supplier_invoice 5477 5477 0 0.00 6 97459 3459 0 0
sp_supplier_order 5380 5380 0 0.00 5 45276 1217 0 0
srv_aggregate_perf_data 114 113 1 0.87 2689 120765 14931 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 881 158972 45098 0 0
srv_find_qd_qs_mism 1095290 1095290 0 0.00 0 9197 5 0 0
srv_make_invnt_saldo 535 535 0 0.00 42 87828 1251 0 0
srv_make_money_saldo 684 684 0 0.00 0 6312 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 139434 451437 246172 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4925 4925 0 0.00 0 11366 161 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 0 2397 89 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 7504 24 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 3348 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 1743 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 308 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 2633 23 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 29579 1661 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 3672 17 0 0
t$perf-norm:sp_client_order 20173 20173 0 0.00 0 9948 16 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 4100 12 0 0
t$perf-norm:sp_pay_from_customer 6943 6943 0 0.00 0 5845 11 0 0
t$perf-norm:sp_pay_to_supplier 5707 5707 0 0.00 0 6845 13 0 0
t$perf-norm:sp_reserve_write_off 9739 9739 0 0.00 0 4571 10 0 0
t$perf-norm:sp_supplier_invoice 5477 5477 0 0.00 0 8940 18 0 0
t$perf-norm:sp_supplier_order 5380 5380 0 0.00 0 8903 14 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 247 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 13 0 0 0
t$perf-norm:srv_make_invnt_saldo 535 535 0 0.00 0 578 18 0 0
t$perf-norm:srv_make_money_saldo 684 684 0 0.00 0 5893 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10038 9838 200 1.99 0 13211 24 0 200
v_all_customers 37394 37394 0 0.00 0 18 1 0 0
v_all_suppliers 30058 30058 0 0.00 0 93 1 0 0
v_all_wares 40344 40344 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 1517 9 0 0
v_cancel_client_order 3474 3474 0 0.00 0 5620 7 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 1196 5 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 5158 13 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 6278 12 0 0
v_cancel_supplier_invoice 816 804 12 1.47 0 1042 16 0 12
v_cancel_supplier_order 822 822 0 0.00 0 4832 12 0 0
v_cancel_write_off 1880 1880 0 0.00 0 5800 17 0 0
v_max_id_clo_ord 5379 5379 0 0.00 0 2112 2 0 0
v_max_id_clo_res 977 977 0 0.00 0 3207 23 0 0
v_max_id_ord_sup 5474 5474 0 0.00 0 5171 5 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 546 3 0 0
v_max_non_paid_realizn 3483 3483 0 0.00 0 8340 8 0 0
v_min_id_clo_ord 5379 5379 0 0.00 0 2113 5 0 0
v_min_id_clo_res 977 977 0 0.00 0 2943 5 0 0
v_min_id_ord_sup 5474 5474 0 0.00 0 7998 8 0 0
v_min_non_paid_invoice 5707 5707 0 0.00 0 10577 18 0 0
v_min_non_paid_realizn 6943 6943 0 0.00 0 13079 32 0 0
v_our_firm 9870 9870 0 0.00 0 166 1 0 0
v_reserve_write_off 19476 19476 0 0.00 0 6670 7 0 0
x_lock_depdocs_on_canc_invoice 12258 12258 0 0.00 0 10535 100 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 9251 349 0 0
x_make_qstorno_1000_1200 5380 5380 0 0.00 1 45269 1148 0 0
x_make_qstorno_1200_2000 5477 5477 0 0.00 3 96206 3325 0 0
x_make_qstorno_2100_3300 465385 465385 0 0.00 0 51782 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-07-2022 16:40:46 to 14-07-2022 16:40:50.

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:28 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:30 18385 8192000000 9906183344 10219540480 995410640 10.834 100 86 172 0 1024834112 6242496 23180976
14:10:53 17491 8192000000 9947348016 10271375360 1028664496 11.156 100 84 168 0 1058687728 8318448 21704784
14:12:18 12842 8192000000 9987897104 10322534400 1072727120 11.578 100 86 172 0 1104238352 10164112 21347120
14:13:37 7583 8192000000 10024409680 10368503808 1110661120 11.939 100 81 162 0 1141635904 11470144 19504640
14:14:50 5157 8192000000 10068446064 10424676352 1154932384 12.356 100 80 160 0 1186108064 12406928 18768752
14:15:57 3349 8192000000 10121138288 10486226944 1208963504 12.859 100 84 168 0 1240127552 12667328 18496720
14:17:02 2765 8192000000 10152683552 10526363648 1238350272 13.131 100 80 158 1 1269257440 13101408 17805760
14:18:07 1660 8192000000 10179070352 10558283776 1268380032 13.407 100 82 161 0 1299369552 13580592 17408928
14:19:10 1464 8192000000 10208000400 10590515200 1295819760 13.657 100 92 184 0 1329470096 13511616 20138720
14:20:14 1148 8192000000 10219724336 10609733632 1307819648 13.766 100 79 158 0 1336629680 10859376 17950656
14:21:16 14050 8192000000 10198428128 10607210496 1307817280 13.766 100 19 39 0 1318250928 5884944 4548704
14:22:32 8327 8192000000 10216465376 10627039232 1320251744 13.879 100 22 44 0 1335373088 10508240 4613104
14:23:44 16015 8192000000 10209682736 10633330688 1330395360 13.971 100 15 30 0 1338730608 5088720 3246528
14:25:00 10323 8192000000 10221366880 10638618624 1335291056 14.015 100 27 53 1 1346589552 5211568 6086928
14:26:11 26470 8192000000 10222037472 10646409216 1322632384 13.901 99 32 64 0 1335784192 5456000 7695808
14:27:38 7473 8192000000 10229853072 10651643904 1328253392 13.951 99 36 71 1 1343167088 6813376 8100320
14:28:46 13349 8192000000 10204886416 10619043840 1292994960 13.632 98 22 44 0 1304195984 6290384 4910640
14:30:03 62723 8192000000 10130383872 10526277632 1132384784 12.144 88 27 53 0 1144645552 4902688 7358080
14:32:16 6404 8192000000 10153717824 10560745472 1264022752 13.367 100 25 51 1 1275727664 6161792 5543120
14:33:25 5451 8192000000 10159914224 10564788224 1257325648 13.305 99 25 48 0 1268663840 6093840 5244352
14:34:31 9956 8192000000 10181670144 10592182272 1287782560 13.584 100 14 29 0 1296688832 5690960 3215312
14:35:43 41593 8192000000 10182820912 10597613568 1293200960 13.633 100 41 82 1 1309886112 6308736 10376416
14:37:27 7662 8192000000 10190289424 10609340416 1303664576 13.729 100 24 47 0 1314808560 4930176 6213808
14:38:39 3966 8192000000 10238509872 10648096768 1333356160 13.997 100 42 82 0 1349583520 6592000 9635360
14:39:48 30530 8192000000 10251365056 10670276608 1349504832 14.143 100 41 80 0 1366885200 8958560 8421808
14:41:25 28698 8192000000 10284548016 10702135296 1365549984 14.287 100 47 95 0 1390148400 13617504 10980912
14:43:01 7810 8192000000 10289488560 10711654400 1375979424 14.381 100 48 93 1 1403251920 16551568 10720928
14:44:12 5072 8192000000 10300917568 10723061760 1385422368 14.465 100 57 114 1 1416222896 18002656 12797872
14:45:23 19427 8192000000 10305422944 10735181824 1397990496 14.577 100 41 81 1 1422963680 15311552 9661632
14:47:09 3216 8192000000 10335728528 10754248704 1407615424 14.663 100 63 124 1 1448064752 25736464 14712864
14:48:18 1303 8192000000 10339499728 10754289664 1406432608 14.652 100 63 122 0 1449184592 28290608 14461376
14:49:26 18066 8192000000 10355008416 10780016640 1419085440 14.765 100 48 96 0 1464706928 33096688 12524800
14:50:48 12057 8192000000 10376263040 10799013888 1429156480 14.854 100 65 128 0 1488202944 43193504 15852960
14:52:07 3870 8192000000 10379139440 10795577344 1424799136 14.815 100 76 151 0 1485870496 42591680 18479680
14:53:17 1044 8192000000 10381792416 10785689600 1411286272 14.695 100 93 187 0 1484097632 50017712 22793648
14:54:24 876 8192000000 10361687952 10763976704 1388113488 14.489 99 86 171 0 1466480416 55696064 22670864
14:55:27 1966 8192000000 10336499872 10744463360 1354167280 14.185 98 68 135 1 1421258688 49771904 17319504
14:56:35 7028 8192000000 10326258544 10738405376 1363472272 14.269 100 57 114 1 1430203888 51463664 15267952
14:57:48 5298 8192000000 10326040432 10737373184 1364505216 14.278 100 63 124 0 1433783376 52905680 16372480
14:58:57 4136 8192000000 10321511856 10735669248 1359850176 14.236 100 51 100 2 1423754320 50266336 13637808
15:00:04 22670 8192000000 10323603808 10751217664 1358400000 14.223 99 32 64 0 1428818496 61068832 9349664
15:01:51 19529 8192000000 10323407184 10759938048 1371277664 14.338 100 30 60 0 1430540448 50526496 8736288
15:03:14 8698 8192000000 10334031504 10762829824 1383210352 14.445 100 38 76 1 1435752816 42353296 10189168
15:04:50 24217 8192000000 10316478176 10767134720 1369517472 14.323 99 25 50 0 1424238496 47472416 7248608
15:06:51 28050 8192000000 10292550736 10740375552 1352582592 14.171 99 19 38 1 1396534080 38636784 5314704
15:09:02 72389 8192000000 10282834400 10726547456 1359902368 14.236 99 16 31 0 1374404960 10805408 3697184
15:11:16 15113 8192000000 10301705504 10747375616 1387958160 14.488 100 21 42 0 1405017152 12350192 4708800
15:12:32 69802 8192000000 10320764688 10754416640 1399701344 14.592 100 30 58 0 1424219296 19172736 5345216
15:14:43 90169 8192000000 10315884192 10762829824 1403845968 14.629 100 37 74 0 1420042144 8017936 8178240
15:17:40 24005 8192000000 10316688544 10771001344 1410067232 14.685 100 14 28 0 1423097856 9263952 3766672
15:19:06 57138 8192000000 10319272032 10774261760 1413058256 14.711 100 21 42 0 1431571248 13314784 5198208
15:21:20 32432 8192000000 10299934432 10777677824 1395983968 14.559 100 6 12 0 1410691408 13449184 1258256
15:22:53 48353 8192000000 10299558400 10766741504 1380011968 14.417 98 9 18 0 1397444032 15489536 1942528
15:24:48 77309 8192000000 10294951168 10760134656 1392937760 14.532 99 8 16 0 1403499840 8687728 1874352
15:27:09 67976 8192000000 10292878256 10756542464 1390662944 14.512 99 15 31 0 1401099552 7146192 3290416
15:29:19 47989 8192000000 10302384176 10777165824 1409562864 14.680 100 19 38 0 1423943968 9981968 4399136
15:31:10 81626 8192000000 10300750592 10770919424 1373584560 14.359 98 10 20 0 1387459328 11594624 2280144
15:34:22 34871 8192000000 10298808944 10753830912 1368460000 14.313 98 41 82 1 1391943440 13672208 9811232
15:36:02 52961 8192000000 10299605312 10760531968 1364467776 14.277 98 10 20 1 1386862528 19716560 2678192
15:37:57 40062 8192000000 10329887792 10779598848 1413160624 14.712 100 34 65 0 1430101392 9649056 7291712
15:39:39 60592 8192000000 10289408016 10727436288 1278161696 13.496 91 19 34 1 1288259312 6086320 4011296
15:41:43 60054 8192000000 10282254080 10724810752 1308241248 13.770 95 12 24 0 1321775760 11144464 2390048
15:43:45 23679 8192000000 10267788288 10715217920 1346099536 14.112 99 24 47 0 1361791920 10499056 5193328
15:45:13 124083 8192000000 10255095568 10704691200 1306206288 13.752 96 31 63 0 1323161920 9773872 7181760
15:48:21 20692 8192000000 10274640816 10715881472 1354184272 14.185 99 41 80 0 1373686736 9750624 9751840
15:49:43 76200 8192000000 10258829312 10716966912 1321667072 13.892 97 21 42 0 1347356496 20450096 5239328
15:52:03 65198 8192000000 10229445920 10688409600 1311424448 13.799 97 14 28 0 1332966976 18249520 3293008
15:55:20 158972 8192000000 10270762640 10742571008 1297708224 13.674 95 9 16 0 1322556832 22934656 1913952
15:59:07 46250 8192000000 10256511440 10717147136 1360499520 14.242 100 13 26 0 1371991824 8642960 2849344
16:01:04 23469 8192000000 10294633344 10740117504 1374309520 14.366 100 44 86 1 1395220768 11499264 9411984
16:02:29 38283 8192000000 10285660048 10726617088 1351030544 14.157 98 39 78 0 1367644464 8100480 8513440
16:04:09 50286 8192000000 10284644672 10743193600 1362927072 14.264 99 9 18 0 1375390496 10700896 1762528
16:06:03 79652 8192000000 10285796720 10732990464 1351804752 14.164 98 19 38 0 1369749712 12855248 5089712
16:08:31 91337 8192000000 10292937152 10751471616 1383988304 14.452 100 18 36 0 1411222064 23194720 4039040
16:11:07 60360 8192000000 10288185328 10754129920 1386645232 14.476 100 17 35 0 1396945760 6350896 3949632
16:13:15 76906 8192000000 10284804000 10747506688 1385927344 14.470 99 15 30 0 1397868576 8694560 3246672
16:15:59 49854 8192000000 10287319200 10745393152 1348308640 14.132 96 14 26 0 1365455280 14095744 3050896
16:17:51 112020 8192000000 10303475216 10768289792 1339762176 14.055 96 8 16 0 1356311456 14953312 1595968
16:20:47 70708 8192000000 10298902560 10776743936 1368874960 14.317 98 14 28 1 1396516480 23888640 3752880
16:23:21 116430 8192000000 10314184640 10777620480 1352526992 14.170 97 11 22 0 1366736176 12524304 1684880
16:26:25 40210 8192000000 10287195152 10739994624 1356638336 14.207 98 26 52 0 1374313456 11507408 6167712
16:28:06 66077 8192000000 10276746320 10740895744 1328228336 13.951 96 9 18 0 1348121616 17828304 2064976
16:30:12 101897 8192000000 10274201088 10722254848 1310787184 13.793 95 26 52 0 1326538560 9843120 5908256
16:32:54 45145 8192000000 10265026528 10718060544 1336659296 14.027 97 13 26 0 1348274096 8658480 2956320
16:34:49 66295 8192000000 10260652704 10717429760 1294839680 13.648 94 10 19 0 1310740560 13687728 2213152
16:36:56 46706 8192000000 10274920768 10732814336 1342959552 14.084 98 18 36 0 1366990208 20210336 3820320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-07-2022 16:40:52 to 14-07-2022 16:41:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 100 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-07-2022 16:41:08 to 14-07-2022 16:41:10.

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          232720
MON$OLDEST_ACTIVE               238101
MON$OLDEST_SNAPSHOT             238101
MON$NEXT_TRANSACTION            238101
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                       2040352
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:1066: 
:1067: mscs-test-161.budzdorov.ru    Thu Jul 14 16:38:28 2022
:1068:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1069: 
:1070: 
:1071: mscs-test-161.budzdorov.ru    Thu Jul 14 16:38:30 2022
:1072:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1073: 
--- end of diff output ---

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

14.07.22 16:41:11. 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-07-14 16:41:13 No severe PSQL-related problems occured
14.07.22 16:41:14. End of report.