Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2022 19: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 15359996 kB
MemAvailable 30436652 kB
Buffers 0 kB
Cached 16337940 kB
SwapCached 2276 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-10-24 19:38:28
DTS_END                         2022-10-24 19:38:28.3930
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/52708
TRN_ID                          246077
ATT_ID                          676
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: 9752.52 22140 1170302
customer order: creation 173.56 361 20827
customer order: refuse 14.65 304 1758
order to supplier: creation 45.97 1266 5516
order to supplier: removal 3.64 104581 437
invoice (draft): creation 45.93 3154 5512
invoice (draft): removal 55.82 755 6698
invoice accept: apply 43.28 16956 5193
invoice accept: cancel 50.58 752 6069
customer reserve: creation 4016.06 166 481927
customer reserve: removal 4997.12 60 599654
realization accept: apply 84.23 181 10107
realization accept: cancel 88.47 112 10616
payment from customer: creation 60.53 129 7264
payment from customer: removal 5.74 116 689
payment to supplier: creation 51.22 135 6146
payment to supplier: removal 3.31 88 397
service: total inventory turnovers 4.83 1074 580
service: total monetary turnovers 6.73 26 807
service: aggregate perf. data 0.84 16576 101
service: refresh index statistics 0.03 296013 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-10-2022 19:39:27 to 24-10-2022 19:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5435
TEST_TIME 2 3547
TEST_TIME 3 3175
TEST_TIME 4 3415
TEST_TIME 5 4466
TEST_TIME 6 4464
TEST_TIME 7 4361
TEST_TIME 8 4445
TEST_TIME 9 4741
TEST_TIME 10 4767
TEST_TIME 11 5080
TEST_TIME 12 5113
TEST_TIME 13 5525
TEST_TIME 14 5963
TEST_TIME 15 6263
TEST_TIME 16 6443
TEST_TIME 17 6459
TEST_TIME 18 6764
TEST_TIME 19 7273
TEST_TIME 20 7516
TEST_TIME 21 8332
TEST_TIME 22 8841
TEST_TIME 23 8892
TEST_TIME 24 9087
TEST_TIME 25 10162
TEST_TIME 26 10390
TEST_TIME 27 10311
TEST_TIME 28 10403
TEST_TIME 29 10281
TEST_TIME 30 9996
TEST_TIME 31 10019
TEST_TIME 32 10090
TEST_TIME 33 9963
TEST_TIME 34 9812
TEST_TIME 35 9791
TEST_TIME 36 9730
TEST_TIME 37 9733
TEST_TIME 38 9998
TEST_TIME 39 10096
TEST_TIME 40 10006
TEST_TIME 41 9929
TEST_TIME 42 9736
TEST_TIME 43 9758
TEST_TIME 44 9723
TEST_TIME 45 9721
TEST_TIME 46 9721
TEST_TIME 47 9662
TEST_TIME 48 9699
TEST_TIME 49 9738
TEST_TIME 50 9715
TEST_TIME 51 9729
TEST_TIME 52 9814
TEST_TIME 53 9876
TEST_TIME 54 9979
TEST_TIME 55 10011
TEST_TIME 56 10075
TEST_TIME 57 10027
TEST_TIME 58 9946
TEST_TIME 59 10151
TEST_TIME 60 10039
TEST_TIME 61 9906
TEST_TIME 62 9904
TEST_TIME 63 9855
TEST_TIME 64 9820
TEST_TIME 65 9761
TEST_TIME 66 9727
TEST_TIME 67 9669
TEST_TIME 68 9646
TEST_TIME 69 9605
TEST_TIME 70 9669
TEST_TIME 71 9896
TEST_TIME 72 10048
TEST_TIME 73 10068
TEST_TIME 74 10007
TEST_TIME 75 10057
TEST_TIME 76 10231
TEST_TIME 77 10124
TEST_TIME 78 10079
TEST_TIME 79 9977
TEST_TIME 80 10002
TEST_TIME 81 9911
TEST_TIME 82 9926
TEST_TIME 83 9876
TEST_TIME 84 9863
TEST_TIME 85 9806
TEST_TIME 86 9722
TEST_TIME 87 9699
TEST_TIME 88 9720
TEST_TIME 89 9683
TEST_TIME 90 9697
TEST_TIME 91 9722
TEST_TIME 92 9763
TEST_TIME 93 9793
TEST_TIME 94 9769
TEST_TIME 95 9778
TEST_TIME 96 9874
TEST_TIME 97 9855
TEST_TIME 98 9788
TEST_TIME 99 9961
TEST_TIME 100 9924
TEST_TIME 101 9906
TEST_TIME 102 9900
TEST_TIME 103 9853
TEST_TIME 104 9842
TEST_TIME 105 9786
TEST_TIME 106 9775
TEST_TIME 107 9729
TEST_TIME 108 9710
TEST_TIME 109 9665
TEST_TIME 110 9682
TEST_TIME 111 9647
TEST_TIME 112 9652
TEST_TIME 113 9684
TEST_TIME 114 9686
TEST_TIME 115 9691
TEST_TIME 116 9729
TEST_TIME 117 9724
TEST_TIME 118 9683
TEST_TIME 119 9694
TEST_TIME 120 9754

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-10-2022 19:39:31 to 24-10-2022 19:39:34.

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 1614778 1614778 0 0.00 0 40823 6 0 0
doc_list_biud 1614777 1614777 0 0.00 0 31754 28 0 0
sp_add_invoice_to_stock 5298 5193 105 1.98 47 175865 16957 0 105
sp_cancel_adding_invoice 6069 6069 0 0.00 4 60398 752 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 10878 304 0 0
sp_cancel_customer_reserve 599654 599654 0 0.00 0 31756 61 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 14777 117 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 5405 89 0 0
sp_cancel_supplier_invoice 6707 6698 9 0.13 1 19028 756 0 9
sp_cancel_supplier_order 437 437 0 0.00 6 962298 104581 0 0
sp_cancel_write_off 10616 10616 0 0.00 1 17433 112 0 0
sp_client_order 20827 20827 0 0.00 3 21731 361 0 0
sp_customer_reserve 481927 481927 0 0.00 0 142709 167 0 0
sp_fill_shopping_cart 31845 31845 0 0.00 1 17353 42 0 0
sp_fill_shopping_cart_clo_res 481925 481925 0 0.00 0 23031 16 0 0
sp_get_clo_for_invoice 5191 5191 0 0.00 0 25534 321 0 0
sp_kill_cost_storno 17771 17771 0 0.00 0 20077 54 0 0
sp_kill_qstorno_ret_qs2qd 1217059 1217059 0 0.00 0 31687 33 0 0
sp_kill_qty_storno 640530 640530 0 0.00 0 31753 70 0 0
sp_lock_selected_doc 21095 21095 0 0.00 0 9405 9 0 0
sp_make_cost_storno 28708 28708 0 0.00 0 36448 54 0 0
sp_multiply_rows_for_pdistr 28708 28708 0 0.00 0 10992 28 0 0
sp_multiply_rows_for_qdistr 20827 20827 0 0.00 0 21650 185 0 0
sp_pay_from_customer 7264 7264 0 0.00 3 34701 129 0 0
sp_pay_to_supplier 6146 6146 0 0.00 4 36850 135 0 0
sp_payment_common 13410 13410 0 0.00 1 12991 33 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 10864 276 0 0
sp_qd_handle_on_invoice_upd_sts 11260 11260 0 0.00 0 13139 298 0 0
sp_qd_handle_on_reserve_upd_sts 10107 10107 0 0.00 0 19192 58 0 0
sp_reserve_write_off 10107 10107 0 0.00 5 37753 182 0 0
sp_supplier_invoice 5512 5512 0 0.00 6 101741 3154 0 0
sp_supplier_order 5516 5516 0 0.00 4 38609 1266 0 0
srv_aggregate_perf_data 101 101 0 0.00 2903 124362 16577 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1668 127272 43370 0 0
srv_find_qd_qs_mism 1120408 1120408 0 0.00 0 24120 5 0 0
srv_make_invnt_saldo 580 580 0 0.00 28 36160 1075 0 0
srv_make_money_saldo 807 807 0 0.00 0 898 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 102739 474996 296014 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5193 5193 0 0.00 0 20862 199 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 8452 118 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 10134 26 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 971 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 2475 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 7280 30 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1271 16 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 25267 1667 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 3123 10 0 0
t$perf-norm:sp_client_order 20827 20827 0 0.00 0 9113 22 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 3932 13 0 0
t$perf-norm:sp_pay_from_customer 7264 7264 0 0.00 0 7246 12 0 0
t$perf-norm:sp_pay_to_supplier 6146 6146 0 0.00 0 6977 17 0 0
t$perf-norm:sp_reserve_write_off 10107 10107 0 0.00 0 8215 13 0 0
t$perf-norm:sp_supplier_invoice 5512 5512 0 0.00 0 7193 21 0 0
t$perf-norm:sp_supplier_order 5516 5516 0 0.00 0 9007 26 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 421 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 59 2 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 5310 31 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 3053 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10592 10382 210 1.98 0 6200 19 0 210
v_all_customers 38134 38134 0 0.00 0 84 1 0 0
v_all_suppliers 31010 31010 0 0.00 0 23 1 0 0
v_all_wares 41654 41654 0 0.00 0 99 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 343 6 0 0
v_cancel_client_order 3516 3516 0 0.00 0 4032 5 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 3074 9 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 2482 7 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 5398 25 0 0
v_cancel_supplier_invoice 832 814 18 2.16 0 2897 18 0 18
v_cancel_supplier_order 874 874 0 0.00 0 563 5 0 0
v_cancel_write_off 2078 2078 0 0.00 0 4209 18 0 0
v_max_id_clo_ord 5508 5508 0 0.00 0 2055 2 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 2550 16 0 0
v_max_id_ord_sup 5509 5509 0 0.00 0 6861 6 0 0
v_max_non_paid_invoice 1658 1658 0 0.00 0 8191 8 0 0
v_max_non_paid_realizn 3810 3810 0 0.00 0 610 3 0 0
v_min_id_clo_ord 5508 5508 0 0.00 0 10974 18 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 10446 35 0 0
v_min_id_ord_sup 5509 5509 0 0.00 0 14451 18 0 0
v_min_non_paid_invoice 6146 6146 0 0.00 0 12163 26 0 0
v_min_non_paid_realizn 7264 7264 0 0.00 0 10720 22 0 0
v_our_firm 10428 10428 0 0.00 0 20 1 0 0
v_reserve_write_off 20214 20214 0 0.00 0 5927 6 0 0
x_lock_depdocs_on_canc_invoice 12360 12360 0 0.00 0 22861 116 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 13882 405 0 0
x_make_qstorno_1000_1200 5516 5516 0 0.00 1 38597 1179 0 0
x_make_qstorno_1200_2000 5512 5512 0 0.00 2 95019 3005 0 0
x_make_qstorno_2100_3300 481927 481927 0 0.00 0 142708 148 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-10-2022 19:39:35 to 24-10-2022 19:39:40.

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
17:08:32 3 8192000000 8801803392 8813969408 7737296 0.094 2 2 3 0 7878384 66144 74944
17:09:33 17197 8192000000 9888373056 10197356544 973249424 10.618 100 94 188 0 1003820656 5441360 25129872
17:10:54 22260 8192000000 9937117408 10257154048 1020921120 11.081 100 89 178 0 1051387888 7021824 23444944
17:12:31 19866 8192000000 9983605168 10313629696 1062357520 11.479 100 80 160 0 1090903168 8572432 19973216
17:14:02 5201 8192000000 10050383536 10400161792 1138577712 12.202 100 77 154 0 1167363024 10820944 17964368
17:15:10 5292 8192000000 10093825504 10458263552 1186064032 12.647 100 75 150 2 1215045616 12021856 16959728
17:16:18 2169 8192000000 10140100528 10512510976 1229427888 13.049 100 77 155 4 1259461120 12894624 17138608
17:17:22 6707 8192000000 10167770320 10544771072 1256493920 13.298 100 86 170 1 1290104576 15118896 18491760
17:18:30 936 8192000000 10208110016 10588168192 1289131536 13.596 100 94 187 0 1323413456 13641824 20640096
17:19:33 732 8192000000 10214293376 10599727104 1302367824 13.717 100 82 158 2 1332023488 12705424 16950240
17:20:34 4600 8192000000 10204369920 10606514176 1308073488 13.769 100 51 100 0 1329164960 8473024 12618448
17:21:42 17131 8192000000 10195370576 10612170752 1314257664 13.825 100 21 44 0 1324583616 6136176 4189776
17:23:01 9405 8192000000 10211073648 10626600960 1324789120 13.920 100 15 28 1 1334403984 6053648 3561216
17:24:12 8445 8192000000 10212359632 10634698752 1331626016 13.982 100 14 28 1 1342493184 7242128 3625040
17:25:21 21899 8192000000 10240230304 10651402240 1335088272 14.013 100 52 102 3 1354288032 6110880 13088880
17:26:45 6740 8192000000 10223028560 10646843392 1334807744 14.011 100 24 50 0 1346415424 6100864 5506816
17:27:52 15782 8192000000 10215143392 10636890112 1329109136 13.959 100 8 16 0 1335946608 4988960 1848512
17:29:10 15599 8192000000 10134018544 10531889152 1158036736 12.385 90 20 41 0 1172492352 9673024 4782592
17:30:26 19042 8192000000 10121065584 10516619264 1177733200 12.569 93 39 78 2 1191604256 4436176 9434880
17:31:45 5585 8192000000 10122752288 10521952256 1212205024 12.890 97 27 54 0 1224829968 6667312 5957632
17:32:53 6321 8192000000 10153979168 10552741888 1260645056 13.336 100 21 42 0 1270582112 5164384 4772672
17:34:01 11949 8192000000 10153250176 10561757184 1269581392 13.418 100 13 26 0 1278292896 5794304 2917200
17:35:14 21737 8192000000 10198344592 10594013184 1287516864 13.582 100 53 104 2 1309114912 9874496 11723552
17:36:36 10742 8192000000 10192010064 10601078784 1298661856 13.683 100 28 56 1 1311057824 5865104 6530864
17:37:47 13193 8192000000 10191659088 10611306496 1308612352 13.773 100 8 13 1 1314751936 5140784 998800
17:39:03 1668 8192000000 10230592336 10639876096 1332877296 13.993 100 42 81 1 1348458528 5430032 10151200
17:40:10 102515 8192000000 10250139472 10668478464 1338297296 14.042 99 35 68 2 1358214352 12285552 7631504
17:42:56 86584 8192000000 10273196432 10706059264 1358783360 14.226 100 23 46 0 1387526880 23331616 5411904
17:45:31 12032 8192000000 10304285808 10723409920 1385503696 14.466 100 49 98 0 1418437648 21420096 11513856
17:46:52 5950 8192000000 10313468576 10733428736 1389336576 14.500 100 45 90 1 1424569600 23895856 11337168
17:48:03 31963 8192000000 10327108592 10754453504 1393662080 14.539 99 35 69 2 1431246848 28902048 8682720
17:49:44 6638 8192000000 10340915328 10764947456 1411727712 14.699 100 47 92 1 1452502928 29390704 11384512
17:50:59 4126 8192000000 10364650496 10787459072 1422057392 14.791 100 56 112 0 1472563840 36195056 14311392
17:52:10 4253 8192000000 10349207632 10765643776 1399888688 14.594 99 63 124 0 1445245168 29876080 15480400
17:53:20 2481 8192000000 10347198960 10755010560 1399395376 14.590 100 75 149 0 1452495520 34082320 19017824
17:54:27 4385 8192000000 10341753712 10755469312 1394200224 14.543 99 53 106 1 1448409872 40230144 13979504
17:55:36 1801 8192000000 10367487040 10770403328 1400598816 14.600 100 90 178 1 1469122544 45521760 23001968
17:56:44 5847 8192000000 10355991584 10765021184 1384287136 14.455 99 66 132 1 1451584480 49721312 17576032
17:57:55 14773 8192000000 10339835136 10760331264 1377608720 14.395 99 49 97 0 1441301600 50252944 13439936
17:59:22 3601 8192000000 10341420976 10757640192 1372213184 14.347 100 77 151 0 1450015968 57073936 20728848
18:00:27 12856 8192000000 10318962768 10749026304 1379024800 14.408 100 34 67 0 1427535424 39194016 9316608
18:02:23 24753 8192000000 10297877472 10734821376 1333768816 14.001 98 19 38 0 1383071328 43771232 5531280
18:04:11 44017 8192000000 10250372976 10690691072 1278197696 13.497 95 24 48 0 1310357728 26260432 5899600
18:05:57 37517 8192000000 10245775280 10667016192 1312939952 13.813 98 30 59 0 1335902672 16571296 6391424
18:07:39 98677 8192000000 10239328384 10666639360 1262194400 13.350 94 38 76 0 1278660832 7928640 8537792
18:10:37 30082 8192000000 10276056128 10717925376 1345015616 14.103 99 29 56 0 1361719600 10961360 5742624
18:12:08 68006 8192000000 10275790000 10718502912 1367164576 14.302 100 18 36 1 1386449360 15024976 4259808
18:14:23 50546 8192000000 10305415392 10732056576 1374398400 14.366 100 31 61 1 1398526320 16551024 7576896
18:16:17 13158 8192000000 10300636160 10742915072 1374303472 14.366 100 44 89 0 1408467888 23871216 10293200
18:17:46 127272 8192000000 10283173216 10736549888 1362609056 14.261 99 15 30 0 1374910992 9001712 3300224
18:20:54 91151 8192000000 10290406016 10756915200 1385975840 14.470 100 22 44 0 1408705824 17781168 4948816
18:23:29 51991 8192000000 10279387952 10743578624 1383448048 14.447 100 15 30 0 1395648592 8817088 3383456
18:25:41 31616 8192000000 10306337648 10756407296 1398641520 14.583 100 34 68 0 1415750464 9284224 7824720
18:27:14 66470 8192000000 10303727312 10764201984 1386697744 14.476 99 11 20 0 1398362320 9712512 1952064
18:29:23 32044 8192000000 10312757920 10760519680 1376787824 14.388 98 19 38 0 1395909248 15331024 3790400
18:30:56 58039 8192000000 10303546576 10756489216 1396081456 14.560 100 18 36 0 1418024848 18362608 3580784
18:32:58 61738 8192000000 10297560960 10751832064 1372356016 14.348 98 16 30 0 1392264720 15666000 4242704
18:35:05 34354 8192000000 10317186512 10755813376 1369072800 14.319 98 30 60 0 1387218256 10548208 7597248
18:36:45 37888 8192000000 10283861968 10740314112 1348971040 14.138 98 29 58 0 1377267440 21630624 6665776
18:38:24 86897 8192000000 10254483856 10695372800 1330945296 13.976 97 19 38 0 1345461776 10429984 4086496
18:40:52 30364 8192000000 10264376144 10716704768 1354293520 14.186 99 6 13 0 1365476624 10283664 899440
18:42:29 19178 8192000000 10257029200 10715406336 1361767296 14.253 100 6 12 0 1369461648 6499168 1195184
18:43:48 12845 8192000000 10279186848 10721648640 1358904912 14.228 99 38 74 0 1376321008 8771136 8644960
18:45:05 31747 8192000000 10293871632 10739269632 1323518336 13.909 95 26 48 0 1347144016 17982432 5643248
18:46:45 45860 8192000000 10305538560 10753916928 1373915408 14.362 99 23 46 0 1402648416 22744208 5988800
18:48:31 66607 8192000000 10282038992 10750431232 1348107488 14.130 98 16 32 0 1377605744 25163264 4334992
18:52:40 14150 8192000000 10273218896 10697748480 1325490144 13.926 99 49 98 0 1355162256 18612496 11059616
18:53:57 77141 8192000000 10234945280 10699358208 1269392144 13.416 96 13 26 1 1303165456 30686304 3087008
18:56:17 88436 8192000000 10236746336 10667552768 1315822800 13.839 99 36 70 1 1333818368 9237712 8757856
18:58:46 49817 8192000000 10243282000 10687447040 1337826256 14.038 100 18 36 1 1349113808 7688384 3599168
19:00:37 54315 8192000000 10253301328 10701021184 1339714000 14.055 99 20 41 2 1352339840 7416176 5209664
19:02:36 53084 8192000000 10252531344 10710024192 1316443152 13.844 97 12 25 0 1330436736 11555152 2438432
19:04:29 54366 8192000000 10263334608 10717335552 1359763232 14.235 100 12 24 0 1374421792 11981520 2677040
19:07:46 59708 8192000000 10256370912 10704179200 1339257984 14.051 98 26 52 0 1358599200 13685584 5655632
19:09:53 64529 8192000000 10280867856 10739974144 1378176384 14.400 100 14 28 0 1392567664 11153760 3237520
19:12:15 53209 8192000000 10297402752 10753658880 1380330016 14.420 99 13 25 0 1393658528 10654352 2674160
19:14:11 71528 8192000000 10313071200 10783027200 1383164224 14.445 99 17 34 0 1405961472 19005616 3791632
19:16:24 39568 8192000000 10321251152 10766073856 1363587040 14.270 97 50 99 0 1393260960 18572592 11101328
19:18:05 34106 8192000000 10291146368 10734288896 1349406880 14.142 97 30 58 0 1362834496 7259504 6168112
19:19:43 21289 8192000000 10281608752 10715115520 1345917856 14.111 97 50 100 0 1368899440 11756592 11224992
19:21:08 80837 8192000000 10281979056 10732470272 1356138592 14.203 98 33 66 0 1371853056 7621584 8092880
19:25:04 64339 8192000000 10272526656 10733613056 1356866976 14.209 98 10 20 1 1366211824 7160048 2184800
19:27:09 79008 8192000000 10271649120 10732892160 1345551536 14.107 97 14 28 0 1361476048 12077744 3846768
19:29:37 69030 8192000000 10263153232 10722918400 1321744752 13.893 96 12 24 0 1340787072 16071504 2970816
19:31:47 34731 8192000000 10268582256 10711924736 1342097088 14.076 98 25 50 0 1356580256 8827904 5655264
19:33:38 31446 8192000000 10275318160 10722009088 1344423232 14.097 98 30 60 0 1362835392 11584864 6827296
19:35:11 50480 8192000000 10273785632 10711494656 1305033616 13.741 95 17 34 0 1319862320 10400000 4428704
19:37:08 32857 8192000000 10282656272 10719612928 1338386480 14.043 98 38 76 0 1367426960 20134144 8906336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-10-2022 19:39:42 to 24-10-2022 19:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2022 19:39:58 to 24-10-2022 19:40:00.

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

24.10.22 19:40:01. 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-10-24 19:40:03 No severe PSQL-related problems occured
24.10.22 19:40:05. End of report.