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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15112680 kB
MemAvailable 30412908 kB
Buffers 0 kB
Cached 16583688 kB
SwapCached 1640 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 2023-01-22 22:38:33
DTS_END                         2023-01-22 22:38:33.5530
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/58686
TRN_ID                          248934
ATT_ID                          694
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: 9637.28 18669 1156474
customer order: creation 180.23 333 21628
customer order: refuse 14.99 367 1799
order to supplier: creation 47.94 1247 5753
order to supplier: removal 3.53 100683 424
invoice (draft): creation 47.78 3110 5734
invoice (draft): removal 54.27 810 6512
invoice accept: apply 43.79 16728 5255
invoice accept: cancel 48.78 809 5853
customer reserve: creation 4100.77 163 492093
customer reserve: removal 4788.60 58 574632
realization accept: apply 87.05 216 10446
realization accept: cancel 84.68 106 10162
payment from customer: creation 62.50 124 7500
payment from customer: removal 6.35 100 762
payment to supplier: creation 50.08 167 6010
payment to supplier: removal 3.63 86 435
service: total inventory turnovers 4.85 981 582
service: total monetary turnovers 6.61 23 793
service: aggregate perf. data 0.81 21447 97
service: refresh index statistics 0.03 225831 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-01-2023 22:39:31 to 22-01-2023 22:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5942
TEST_TIME 2 5742
TEST_TIME 3 4990
TEST_TIME 4 5515
TEST_TIME 5 5714
TEST_TIME 6 5627
TEST_TIME 7 5344
TEST_TIME 8 5181
TEST_TIME 9 5097
TEST_TIME 10 5080
TEST_TIME 11 5134
TEST_TIME 12 5229
TEST_TIME 13 5796
TEST_TIME 14 5940
TEST_TIME 15 6848
TEST_TIME 16 7089
TEST_TIME 17 7079
TEST_TIME 18 7346
TEST_TIME 19 7772
TEST_TIME 20 7837
TEST_TIME 21 8115
TEST_TIME 22 8304
TEST_TIME 23 8505
TEST_TIME 24 8788
TEST_TIME 25 8901
TEST_TIME 26 8906
TEST_TIME 27 9419
TEST_TIME 28 9517
TEST_TIME 29 10192
TEST_TIME 30 10646
TEST_TIME 31 10956
TEST_TIME 32 10915
TEST_TIME 33 10810
TEST_TIME 34 10798
TEST_TIME 35 10748
TEST_TIME 36 10671
TEST_TIME 37 10541
TEST_TIME 38 10454
TEST_TIME 39 10545
TEST_TIME 40 10515
TEST_TIME 41 10346
TEST_TIME 42 10250
TEST_TIME 43 10249
TEST_TIME 44 10195
TEST_TIME 45 10053
TEST_TIME 46 10076
TEST_TIME 47 10087
TEST_TIME 48 10075
TEST_TIME 49 10038
TEST_TIME 50 10044
TEST_TIME 51 10123
TEST_TIME 52 10020
TEST_TIME 53 9973
TEST_TIME 54 9860
TEST_TIME 55 9780
TEST_TIME 56 9925
TEST_TIME 57 9948
TEST_TIME 58 9966
TEST_TIME 59 10013
TEST_TIME 60 10020
TEST_TIME 61 10008
TEST_TIME 62 10037
TEST_TIME 63 9992
TEST_TIME 64 10039
TEST_TIME 65 10047
TEST_TIME 66 10036
TEST_TIME 67 10018
TEST_TIME 68 10115
TEST_TIME 69 10071
TEST_TIME 70 10030
TEST_TIME 71 10088
TEST_TIME 72 10042
TEST_TIME 73 9968
TEST_TIME 74 9902
TEST_TIME 75 9894
TEST_TIME 76 9861
TEST_TIME 77 9859
TEST_TIME 78 9889
TEST_TIME 79 9842
TEST_TIME 80 9822
TEST_TIME 81 9754
TEST_TIME 82 9696
TEST_TIME 83 9676
TEST_TIME 84 9689
TEST_TIME 85 9711
TEST_TIME 86 9699
TEST_TIME 87 9678
TEST_TIME 88 9655
TEST_TIME 89 9685
TEST_TIME 90 9709
TEST_TIME 91 9697
TEST_TIME 92 9744
TEST_TIME 93 9744
TEST_TIME 94 9760
TEST_TIME 95 9786
TEST_TIME 96 9868
TEST_TIME 97 9847
TEST_TIME 98 9861
TEST_TIME 99 9874
TEST_TIME 100 9916
TEST_TIME 101 9875
TEST_TIME 102 9797
TEST_TIME 103 9767
TEST_TIME 104 9745
TEST_TIME 105 9736
TEST_TIME 106 9703
TEST_TIME 107 9689
TEST_TIME 108 9684
TEST_TIME 109 9653
TEST_TIME 110 9657
TEST_TIME 111 9616
TEST_TIME 112 9592
TEST_TIME 113 9582
TEST_TIME 114 9573
TEST_TIME 115 9577
TEST_TIME 116 9568
TEST_TIME 117 9568
TEST_TIME 118 9613
TEST_TIME 119 9652
TEST_TIME 120 9639

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-01-2023 22:39:34 to 22-01-2023 22:39:38.

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 1612770 1612770 0 0.00 0 25562 6 0 0
doc_list_biud 1612769 1612769 0 0.00 0 30126 27 0 0
sp_add_invoice_to_stock 5394 5255 139 2.57 24 152333 16728 0 139
sp_cancel_adding_invoice 5853 5853 0 0.00 4 73701 810 0 0
sp_cancel_client_order 1799 1799 0 0.00 4 7839 367 0 0
sp_cancel_customer_reserve 574632 574632 0 0.00 0 29573 59 0 0
sp_cancel_pay_from_customer 762 762 0 0.00 2 21844 101 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 6627 86 0 0
sp_cancel_supplier_invoice 6515 6512 3 0.04 1 30155 811 0 3
sp_cancel_supplier_order 424 424 0 0.00 5 940022 100683 0 0
sp_cancel_write_off 10162 10162 0 0.00 1 14267 106 0 0
sp_client_order 21628 21628 0 0.00 3 18918 334 0 0
sp_customer_reserve 492093 492093 0 0.00 0 91159 163 0 0
sp_fill_shopping_cart 33112 33112 0 0.00 1 15340 35 0 0
sp_fill_shopping_cart_clo_res 492090 492090 0 0.00 0 26958 14 0 0
sp_get_clo_for_invoice 5251 5251 0 0.00 0 28594 290 0 0
sp_kill_cost_storno 17212 17212 0 0.00 0 21840 56 0 0
sp_kill_qstorno_ret_qs2qd 1166362 1166362 0 0.00 0 30108 32 0 0
sp_kill_qty_storno 615078 615078 0 0.00 0 30118 69 0 0
sp_lock_selected_doc 21795 21795 0 0.00 0 8777 9 0 0
sp_make_cost_storno 29207 29207 0 0.00 0 25560 68 0 0
sp_multiply_rows_for_pdistr 29207 29207 0 0.00 0 25096 29 0 0
sp_multiply_rows_for_qdistr 21628 21628 0 0.00 0 13054 155 0 0
sp_pay_from_customer 7500 7500 0 0.00 4 25225 125 0 0
sp_pay_to_supplier 6010 6010 0 0.00 4 37249 167 0 0
sp_payment_common 13510 13510 0 0.00 1 36532 58 0 0
sp_qd_handle_on_cancel_clo 1799 1799 0 0.00 0 7260 336 0 0
sp_qd_handle_on_invoice_upd_sts 11103 11103 0 0.00 0 20508 327 0 0
sp_qd_handle_on_reserve_upd_sts 10446 10446 0 0.00 0 22113 64 0 0
sp_reserve_write_off 10446 10446 0 0.00 6 25582 216 0 0
sp_supplier_invoice 5734 5734 0 0.00 6 78663 3110 0 0
sp_supplier_order 5753 5753 0 0.00 5 21910 1248 0 0
srv_aggregate_perf_data 97 97 0 0.00 2838 308492 21448 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1166 111960 43657 0 0
srv_find_qd_qs_mism 1106574 1106574 0 0.00 0 25394 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 15 26369 982 0 0
srv_make_money_saldo 793 793 0 0.00 1 635 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 170966 360366 225831 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5255 5255 0 0.00 0 16608 172 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 3393 81 0 0
t$perf-norm:sp_cancel_client_order 1799 1799 0 0.00 0 5897 16 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 2732 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 762 762 0 0.00 0 1556 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1919 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 468 468 0 0.00 0 1212 16 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 23542 1643 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 12548 22 0 0
t$perf-norm:sp_client_order 21629 21629 0 0.00 0 10729 20 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 3360 11 0 0
t$perf-norm:sp_pay_from_customer 7500 7500 0 0.00 0 4504 11 0 0
t$perf-norm:sp_pay_to_supplier 6010 6010 0 0.00 0 5354 12 0 0
t$perf-norm:sp_reserve_write_off 10446 10446 0 0.00 0 21991 14 0 0
t$perf-norm:sp_supplier_invoice 5734 5734 0 0.00 0 9088 22 0 0
t$perf-norm:sp_supplier_order 5753 5753 0 0.00 0 12543 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 628 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 1025 17 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 4106 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 4 0 0
v_add_invoice_to_stock 10775 10497 278 2.58 0 14717 18 0 278
v_all_customers 39408 39408 0 0.00 0 223 1 0 0
v_all_suppliers 31612 31612 0 0.00 0 225 1 0 0
v_all_wares 43256 43256 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 1009 7 0 0
v_cancel_client_order 3598 3598 0 0.00 0 4355 5 0 0
v_cancel_customer_prepayment 1524 1524 0 0.00 0 1404 4 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 584 3 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 220 3 0 0
v_cancel_supplier_invoice 942 936 6 0.63 0 8221 28 0 6
v_cancel_supplier_order 848 848 0 0.00 0 402 3 0 0
v_cancel_write_off 2114 2114 0 0.00 0 7953 19 0 0
v_max_id_clo_ord 5753 5753 0 0.00 0 163 1 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 4602 21 0 0
v_max_id_ord_sup 5731 5731 0 0.00 0 3051 3 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 679 2 0 0
v_max_non_paid_realizn 3951 3951 0 0.00 0 6137 7 0 0
v_min_id_clo_ord 5753 5753 0 0.00 0 2448 6 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 1641 5 0 0
v_min_id_ord_sup 5731 5731 0 0.00 0 10542 10 0 0
v_min_non_paid_invoice 6010 6010 0 0.00 0 25106 64 0 0
v_min_non_paid_realizn 7500 7500 0 0.00 1 25056 36 0 0
v_our_firm 10946 10946 0 0.00 0 83 1 0 0
v_reserve_write_off 20892 20892 0 0.00 0 3497 4 0 0
x_lock_depdocs_on_canc_invoice 11897 11897 0 0.00 0 28414 115 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 14471 335 0 0
x_make_qstorno_1000_1200 5753 5753 0 0.00 1 21903 1177 0 0
x_make_qstorno_1200_2000 5734 5734 0 0.00 3 78623 2980 0 0
x_make_qstorno_2100_3300 492093 492093 0 0.00 0 91157 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-01-2023 22:39:39 to 22-01-2023 22:39:43.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:37 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:38 23032 8192000000 9903140048 10211745792 980172384 10.686 100 87 174 0 1009039584 6167120 22700080
20:11:07 13194 8192000000 9943011616 10262614016 1024195056 11.112 100 92 184 0 1056136560 8787984 23153520
20:12:27 7152 8192000000 9967551904 10294284288 1049201712 11.353 100 86 172 0 1081500448 10391760 21906976
20:13:45 9027 8192000000 10015763152 10356760576 1100325696 11.841 100 83 164 0 1131297072 11463824 19507552
20:14:59 7367 8192000000 10062999808 10414882816 1146931088 12.281 100 85 171 0 1179164192 12193152 20039952
20:16:11 3037 8192000000 10111220800 10471936000 1192647712 12.708 100 84 166 1 1226291280 14903008 18740560
20:17:18 2754 8192000000 10137040144 10508603392 1224777616 13.006 100 80 160 0 1256782496 13198320 18806560
20:18:23 4185 8192000000 10173538208 10555486208 1261837376 13.347 100 82 164 0 1293888640 14107760 17943504
20:19:29 1778 8192000000 10207499376 10592202752 1292515088 13.627 100 83 163 1 1323786464 13614192 17657184
20:20:32 6251 8192000000 10203704912 10597605376 1296678208 13.665 100 60 120 0 1320487952 9905648 13904096
20:21:39 12621 8192000000 10189090032 10606358528 1307414560 13.763 100 17 34 0 1317178896 5121200 4643136
20:22:54 13594 8192000000 10206211824 10622652416 1319050256 13.868 100 16 33 1 1328785536 5446768 4288512
20:24:11 9324 8192000000 10218039696 10635718656 1325001712 13.922 100 23 46 0 1340572096 10237056 5333328
20:25:28 29110 8192000000 10236790816 10644430848 1331729504 13.983 100 65 133 1 1352789136 5265488 15794144
20:26:58 5571 8192000000 10221840608 10641027072 1326640624 13.937 100 30 59 1 1344375376 11131872 6602880
20:28:06 4202 8192000000 10202130512 10614415360 1302099824 13.714 99 28 57 0 1313882560 5919344 5863392
20:29:12 62874 8192000000 10174223648 10589851648 1245038640 13.193 95 32 62 0 1257187600 5051552 7097408
20:31:17 7340 8192000000 10148893520 10551508992 1200588256 12.782 94 15 31 0 1209238128 5383680 3266192
20:32:25 10281 8192000000 10134182752 10538524672 1230178432 13.056 97 16 34 0 1239425088 5497088 3749568
20:33:35 21800 8192000000 10162946128 10568437760 1253583072 13.271 98 30 60 0 1266370768 4849936 7937760
20:35:01 17842 8192000000 10177912640 10592800768 1281855920 13.530 99 14 28 0 1289901408 4949472 3096016
20:36:22 6782 8192000000 10198198016 10616967168 1311299264 13.798 100 28 56 0 1324600592 6699392 6601936
20:37:35 8270 8192000000 10213209792 10631335936 1322160704 13.896 100 12 25 0 1330644672 5621984 2861984
20:38:45 46106 8192000000 10261603216 10676981760 1354662592 14.189 100 43 83 2 1370613456 5670400 10280464
20:40:32 27695 8192000000 10257778400 10684461056 1364199776 14.275 100 36 72 0 1380209776 7669712 8340288
20:42:09 9248 8192000000 10283883264 10704699392 1377369616 14.393 100 49 98 1 1397501328 8191232 11940480
20:43:24 7740 8192000000 10309978176 10723278848 1389157008 14.498 100 67 133 0 1419430368 14914640 15358720
20:44:35 7558 8192000000 10293547552 10718330880 1392992368 14.533 100 51 89 3 1415295504 11737216 10565920
20:45:55 35983 8192000000 10317988816 10739654656 1404166464 14.632 100 37 74 0 1428416928 14953200 9297264
20:47:43 9647 8192000000 10330897152 10758967296 1417653920 14.752 100 39 78 0 1443974096 16884896 9435280
20:49:05 1301 8192000000 10343612288 10766299136 1422264688 14.793 100 59 116 2 1457018672 20403152 14350832
20:50:13 12355 8192000000 10344440912 10771275776 1416027344 14.737 99 46 86 0 1453012016 25619104 11365568
20:51:35 5055 8192000000 10356384480 10775994368 1415856896 14.736 100 65 130 1 1462467856 29924000 16686960
20:52:45 7426 8192000000 10346878384 10763710464 1405632624 14.645 100 61 122 0 1455368928 34298784 15437520
20:53:58 1505 8192000000 10332751376 10750849024 1394147168 14.543 100 72 140 3 1445371584 33286160 17938256
20:55:05 7369 8192000000 10345446448 10758086656 1395948016 14.559 99 75 149 1 1448791328 34855728 17987584
20:56:21 1165 8192000000 10346788448 10759442432 1387716544 14.485 99 83 162 0 1447741152 39447424 20577184
20:57:29 1685 8192000000 10340359904 10750279680 1384388720 14.456 100 71 138 2 1444388976 43169776 16830480
20:58:37 3432 8192000000 10352608784 10763108352 1392333648 14.527 100 65 131 2 1447130224 38313152 16483424
20:59:44 8302 8192000000 10313033680 10734624768 1359563200 14.233 98 41 80 1 1406393376 36216048 10614128
21:01:01 3522 8192000000 10342862640 10755981312 1387608032 14.485 100 65 129 0 1444663408 40912128 16143248
21:02:09 23976 8192000000 10330836016 10768953344 1390992512 14.515 100 31 62 1 1439625888 40410736 8222640
21:03:36 12096 8192000000 10320978512 10747064320 1364315760 14.276 98 34 69 0 1409059760 35487456 9256544
21:04:55 9639 8192000000 10321496144 10750365696 1368513360 14.314 100 29 58 1 1427491392 50378928 8599104
21:06:06 15165 8192000000 10311761488 10746912768 1369342288 14.321 100 21 42 0 1418640912 42736096 6562528
21:07:52 24691 8192000000 10297290112 10748411904 1358338080 14.222 99 23 46 1 1395634144 31307904 5988160
21:09:19 45631 8192000000 10285801904 10735325184 1358551792 14.224 99 16 32 0 1389625328 26790192 4283344
21:11:23 42727 8192000000 10272294688 10702495744 1353291296 14.177 99 11 22 0 1363884544 8836896 1756352
21:13:08 94461 8192000000 10308115104 10748710912 1335408096 14.016 96 12 24 0 1349039504 10169936 3461472
21:15:47 20887 8192000000 10306432992 10739093504 1385043360 14.462 100 36 74 0 1407031216 13863040 8124816
21:17:16 74511 8192000000 10307646320 10743095296 1389427968 14.501 100 17 34 0 1409615376 16492144 3695264
21:19:32 60650 8192000000 10304713216 10744279040 1376836848 14.388 99 43 86 0 1393003824 6266560 9900416
21:21:35 23344 8192000000 10319539984 10758860800 1393936144 14.541 100 52 98 1 1417244640 11352048 11956448
21:23:04 71543 8192000000 10292618032 10748407808 1362718560 14.262 97 16 32 0 1376021232 9810800 3491872
21:25:18 34910 8192000000 10315176128 10768207872 1400471136 14.599 100 23 44 0 1417449664 11366944 5611584
21:26:54 43908 8192000000 10308372016 10758623232 1388840368 14.496 99 26 52 1 1406265632 11539728 5885536
21:28:42 56405 8192000000 10292894432 10763685888 1391152688 14.516 100 10 20 0 1407730464 14102000 2475776
21:30:42 39440 8192000000 10303331744 10763669504 1379002640 14.408 98 12 24 2 1394545024 12525840 3016544
21:34:06 61277 8192000000 10315834480 10768756736 1386128576 14.471 99 19 38 0 1414283312 23104352 5050384
21:36:08 46177 8192000000 10297842768 10756190208 1380814016 14.424 99 23 46 1 1396257808 10194896 5248896
21:37:55 102571 8192000000 10297265648 10741301248 1311511072 13.800 94 7 14 1 1326688448 13790704 1386672
21:40:40 65166 8192000000 10279471664 10717016064 1276968192 13.485 93 27 55 0 1292227168 9265040 5993936
21:42:45 69568 8192000000 10262552192 10713288704 1295848928 13.657 95 18 37 0 1316983344 17161920 3972496
21:44:57 91548 8192000000 10279309536 10737602560 1327291488 13.943 97 11 22 0 1347479584 17911312 2276784
21:47:30 47096 8192000000 10287131600 10713026560 1312227488 13.806 96 27 52 0 1326981152 8399760 6353904
21:49:19 47884 8192000000 10271444544 10722295808 1341254048 14.069 99 32 64 0 1360919168 12884224 6780896
21:51:30 73260 8192000000 10255871136 10699091968 1336667632 14.027 99 37 72 1 1353184960 8236624 8280704
21:53:49 90475 8192000000 10259387392 10724184064 1335992176 14.021 98 15 30 0 1354720448 15687232 3041040
21:56:28 44290 8192000000 10272483104 10723737600 1347901184 14.129 99 25 49 1 1362945616 9004496 6039936
21:58:15 70755 8192000000 10274604448 10733686784 1369828640 14.326 100 18 36 0 1384793120 11097296 3867184
22:00:38 71465 8192000000 10294458848 10754662400 1385104528 14.462 100 13 26 0 1402135632 13863792 3167312
22:03:00 54928 8192000000 10275511040 10742398976 1366107904 14.292 99 21 42 0 1391341696 20047040 5186752
22:05:02 61999 8192000000 10284934256 10740957184 1353486384 14.179 98 13 27 0 1366649936 10285568 2877984
22:07:34 83209 8192000000 10298899520 10756546560 1374430528 14.367 99 12 24 0 1390839984 13605824 2803632
22:09:58 19697 8192000000 10318536080 10765090816 1402819296 14.620 100 35 68 0 1420772912 10179072 7774544
22:11:18 56661 8192000000 10311422432 10771984384 1377807312 14.397 98 18 34 0 1393858288 12273520 3777456
22:13:16 111960 8192000000 10325985504 10803159040 1396556352 14.564 99 27 54 1 1420576704 18221664 5798688
22:16:08 101544 8192000000 10295269152 10749685760 1328146176 13.950 95 20 40 1 1346185376 13618816 4420384
22:19:05 63229 8192000000 10305157024 10741334016 1354450864 14.188 97 22 43 0 1366899696 8387872 4060960
22:21:14 95374 8192000000 10228915200 10665140224 1282460240 13.535 93 26 52 1 1303048464 14642848 5945376
22:23:55 25092 8192000000 10268191328 10714857472 1341336288 14.069 98 24 48 1 1353983200 6979248 5667664
22:25:24 80501 8192000000 10276512688 10741436416 1366624176 14.297 99 14 28 0 1377688240 7561072 3502992
22:28:17 41378 8192000000 10267640352 10731040768 1348740112 14.136 98 10 20 0 1367040240 16170960 2129168
22:30:01 68345 8192000000 10255281264 10717597696 1356665232 14.207 100 17 34 0 1370057488 9374528 4017728
22:32:14 33463 8192000000 10241326048 10697527296 1289091760 13.596 95 25 50 1 1301030736 6702704 5236272
22:33:48 64022 8192000000 10236674096 10686550016 1281137376 13.523 94 22 44 0 1298984928 12375552 5472000
22:35:55 74363 8192000000 10239659152 10703261696 1305290432 13.743 97 17 34 0 1328092032 18664272 4137328
22:38:12 29280 8192000000 9214658864 9483452416 370420928 4.326 28 4 8 0 377663984 6388704 854352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-01-2023 22:39:45 to 22-01-2023 22:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-01-2023 22:40:01 to 22-01-2023 22:40: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          249265
MON$OLDEST_ACTIVE               249266
MON$OLDEST_SNAPSHOT             249266
MON$NEXT_TRANSACTION            249266
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                       2052064
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.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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 ---
:348: 
:349: mscs-test-161.budzdorov.ru    Sun Jan 22 22:38:44 2023
:350:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:351: 
--- end of diff output ---

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

22.01.23 22:40:04. 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
2023-01-22 22:40:07 No severe PSQL-related problems occured
22.01.23 22:40:08. End of report.