Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.01.2023 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 15500244 kB
MemAvailable 30374384 kB
Buffers 0 kB
Cached 16193292 kB
SwapCached 9236 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-01 19:38:32
DTS_END                         2023-01-01 19:38:32.5850
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43992
TRN_ID                          240630
ATT_ID                          675
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: 8992.38 16976 1079086
customer order: creation 173.29 366 20795
customer order: refuse 14.47 358 1736
order to supplier: creation 46.58 1277 5589
order to supplier: removal 3.34 94900 401
invoice (draft): creation 46.39 3540 5567
invoice (draft): removal 50.48 722 6057
invoice accept: apply 42.33 17740 5080
invoice accept: cancel 45.60 699 5472
customer reserve: creation 3847.49 180 461699
customer reserve: removal 4430.61 56 531673
realization accept: apply 84.57 184 10148
realization accept: cancel 77.81 112 9337
payment from customer: creation 59.98 120 7198
payment from customer: removal 5.68 87 682
payment to supplier: creation 48.93 162 5872
payment to supplier: removal 3.19 59 383
service: total inventory turnovers 4.34 1190 521
service: total monetary turnovers 6.38 41 766
service: aggregate perf. data 0.88 15027 106
service: refresh index statistics 0.03 202690 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-01-2023 19:39:27 to 01-01-2023 19:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8422
TEST_TIME 2 5359
TEST_TIME 3 5353
TEST_TIME 4 5333
TEST_TIME 5 5204
TEST_TIME 6 5125
TEST_TIME 7 5208
TEST_TIME 8 5042
TEST_TIME 9 5109
TEST_TIME 10 5195
TEST_TIME 11 5205
TEST_TIME 12 5396
TEST_TIME 13 5437
TEST_TIME 14 5696
TEST_TIME 15 6197
TEST_TIME 16 6712
TEST_TIME 17 6941
TEST_TIME 18 7165
TEST_TIME 19 7349
TEST_TIME 20 7495
TEST_TIME 21 7888
TEST_TIME 22 7943
TEST_TIME 23 8166
TEST_TIME 24 8545
TEST_TIME 25 8716
TEST_TIME 26 9066
TEST_TIME 27 9164
TEST_TIME 28 9336
TEST_TIME 29 9289
TEST_TIME 30 9565
TEST_TIME 31 9434
TEST_TIME 32 9572
TEST_TIME 33 9384
TEST_TIME 34 9287
TEST_TIME 35 9237
TEST_TIME 36 9139
TEST_TIME 37 9064
TEST_TIME 38 9087
TEST_TIME 39 8981
TEST_TIME 40 8894
TEST_TIME 41 8935
TEST_TIME 42 8805
TEST_TIME 43 8751
TEST_TIME 44 8704
TEST_TIME 45 8671
TEST_TIME 46 8701
TEST_TIME 47 8686
TEST_TIME 48 8594
TEST_TIME 49 8514
TEST_TIME 50 8559
TEST_TIME 51 8481
TEST_TIME 52 8441
TEST_TIME 53 8424
TEST_TIME 54 8454
TEST_TIME 55 8403
TEST_TIME 56 8433
TEST_TIME 57 8527
TEST_TIME 58 8538
TEST_TIME 59 8590
TEST_TIME 60 8609
TEST_TIME 61 8746
TEST_TIME 62 8752
TEST_TIME 63 8735
TEST_TIME 64 8762
TEST_TIME 65 8735
TEST_TIME 66 8849
TEST_TIME 67 8910
TEST_TIME 68 8972
TEST_TIME 69 8951
TEST_TIME 70 9066
TEST_TIME 71 9058
TEST_TIME 72 8956
TEST_TIME 73 9013
TEST_TIME 74 8938
TEST_TIME 75 8896
TEST_TIME 76 8857
TEST_TIME 77 8933
TEST_TIME 78 8928
TEST_TIME 79 8935
TEST_TIME 80 8921
TEST_TIME 81 8865
TEST_TIME 82 8856
TEST_TIME 83 8831
TEST_TIME 84 8883
TEST_TIME 85 8864
TEST_TIME 86 8794
TEST_TIME 87 8795
TEST_TIME 88 8858
TEST_TIME 89 8842
TEST_TIME 90 8866
TEST_TIME 91 8893
TEST_TIME 92 8956
TEST_TIME 93 9001
TEST_TIME 94 9009
TEST_TIME 95 9009
TEST_TIME 96 9076
TEST_TIME 97 9065
TEST_TIME 98 9074
TEST_TIME 99 9032
TEST_TIME 100 9118
TEST_TIME 101 9128
TEST_TIME 102 9179
TEST_TIME 103 9185
TEST_TIME 104 9104
TEST_TIME 105 9097
TEST_TIME 106 9109
TEST_TIME 107 9069
TEST_TIME 108 9087
TEST_TIME 109 9077
TEST_TIME 110 9034
TEST_TIME 111 9039
TEST_TIME 112 9010
TEST_TIME 113 8957
TEST_TIME 114 8962
TEST_TIME 115 8943
TEST_TIME 116 8962
TEST_TIME 117 9011
TEST_TIME 118 9017
TEST_TIME 119 9002
TEST_TIME 120 8995

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-01-2023 19:39:31 to 01-01-2023 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 1507134 1507134 0 0.00 0 20253 6 0 0
doc_list_biud 1507134 1507134 0 0.00 0 59447 25 0 0
sp_add_invoice_to_stock 5194 5080 114 2.19 38 186778 17740 0 114
sp_cancel_adding_invoice 5472 5472 0 0.00 4 60698 699 0 0
sp_cancel_client_order 1736 1736 0 0.00 4 8861 359 0 0
sp_cancel_customer_reserve 531673 531673 0 0.00 0 21888 57 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 4422 88 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 1755 59 0 0
sp_cancel_supplier_invoice 6074 6057 17 0.27 1 59491 723 0 17
sp_cancel_supplier_order 401 401 0 0.00 6 966733 94900 0 0
sp_cancel_write_off 9337 9337 0 0.00 1 15666 113 0 0
sp_client_order 20795 20795 0 0.00 3 17596 366 0 0
sp_customer_reserve 461699 461699 0 0.00 0 57270 180 0 0
sp_fill_shopping_cart 31946 31946 0 0.00 1 14978 43 0 0
sp_fill_shopping_cart_clo_res 461693 461693 0 0.00 0 10004 13 0 0
sp_get_clo_for_invoice 5073 5073 0 0.00 0 25268 294 0 0
sp_kill_cost_storno 15874 15874 0 0.00 0 20083 54 0 0
sp_kill_qstorno_ret_qs2qd 1079141 1079141 0 0.00 0 59446 31 0 0
sp_kill_qty_storno 569897 569897 0 0.00 0 59446 66 0 0
sp_lock_selected_doc 20914 20914 0 0.00 0 7927 5 0 0
sp_make_cost_storno 28291 28291 0 0.00 0 20240 53 0 0
sp_multiply_rows_for_pdistr 28291 28291 0 0.00 0 12912 29 0 0
sp_multiply_rows_for_qdistr 20795 20795 0 0.00 0 11480 161 0 0
sp_pay_from_customer 7198 7198 0 0.00 4 11057 120 0 0
sp_pay_to_supplier 5872 5872 0 0.00 4 16037 162 0 0
sp_payment_common 13070 13070 0 0.00 1 12011 32 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 6801 320 0 0
sp_qd_handle_on_invoice_upd_sts 10545 10545 0 0.00 0 13562 305 0 0
sp_qd_handle_on_reserve_upd_sts 10148 10148 0 0.00 0 19110 57 0 0
sp_reserve_write_off 10148 10148 0 0.00 5 19144 184 0 0
sp_supplier_invoice 5567 5567 0 0.00 6 83366 3541 0 0
sp_supplier_order 5589 5589 0 0.00 4 22684 1278 0 0
srv_aggregate_perf_data 106 106 0 0.00 2833 148166 15027 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1302 98838 41666 0 0
srv_find_qd_qs_mism 1031452 1031452 0 0.00 0 17676 6 0 0
srv_make_invnt_saldo 521 521 0 0.00 49 88510 1191 0 0
srv_make_money_saldo 766 766 0 0.00 0 11950 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 118531 352314 202691 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5080 5080 0 0.00 0 12872 176 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 7537 111 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 8142 22 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 1679 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 802 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 1385 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 1209 17 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 24568 1668 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 10027 19 0 0
t$perf-norm:sp_client_order 20795 20795 0 0.00 0 8373 19 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 1365 6 0 0
t$perf-norm:sp_pay_from_customer 7198 7198 0 0.00 0 7870 15 0 0
t$perf-norm:sp_pay_to_supplier 5872 5872 0 0.00 0 10008 15 0 0
t$perf-norm:sp_reserve_write_off 10148 10148 0 0.00 0 15777 13 0 0
t$perf-norm:sp_supplier_invoice 5567 5567 0 0.00 0 8825 23 0 0
t$perf-norm:sp_supplier_order 5589 5589 0 0.00 0 6167 15 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 723 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 36 1 0 0
t$perf-norm:srv_make_invnt_saldo 521 521 0 0.00 0 1057 22 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 1645 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 6 0 0
v_add_invoice_to_stock 10374 10146 228 2.19 0 6919 23 0 228
v_all_customers 37410 37410 0 0.00 0 143 1 0 0
v_all_suppliers 30614 30614 0 0.00 0 22 1 0 0
v_all_wares 41590 41590 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2651 13 0 0
v_cancel_client_order 3472 3472 0 0.00 0 8361 11 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 2288 6 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 832 5 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 697 4 0 0
v_cancel_supplier_invoice 822 788 34 4.13 0 3118 20 0 34
v_cancel_supplier_order 802 802 0 0.00 0 2854 10 0 0
v_cancel_write_off 2036 2036 0 0.00 0 13734 23 0 0
v_max_id_clo_ord 5588 5588 0 0.00 0 422 1 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 4498 20 0 0
v_max_id_ord_sup 5563 5563 0 0.00 0 3095 5 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 156 2 0 0
v_max_non_paid_realizn 4053 4053 0 0.00 0 2848 5 0 0
v_min_id_clo_ord 5588 5588 0 0.00 0 5263 9 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 5624 8 0 0
v_min_id_ord_sup 5563 5563 0 0.00 0 2984 6 0 0
v_min_non_paid_invoice 5872 5872 0 0.00 0 12008 28 0 0
v_min_non_paid_realizn 7198 7198 0 0.00 0 8669 20 0 0
v_our_firm 10470 10470 0 0.00 0 35 1 0 0
v_reserve_write_off 20296 20296 0 0.00 0 15708 11 0 0
x_lock_depdocs_on_canc_invoice 11135 11135 0 0.00 0 24568 119 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 11532 349 0 0
x_make_qstorno_1000_1200 5589 5589 0 0.00 1 22677 1196 0 0
x_make_qstorno_1200_2000 5567 5567 0 0.00 2 83349 3378 0 0
x_make_qstorno_2100_3300 461699 461699 0 0.00 0 57268 165 0 0

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

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:36 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:37 24255 8192000000 9921758992 10238205952 1006678480 10.943 100 85 170 1 1035441184 6637184 22125520
17:11:08 15140 8192000000 9969054864 10295971840 1049582688 11.357 100 86 172 0 1084526352 13273120 21670544
17:12:29 13943 8192000000 10006688400 10342957056 1088595520 11.729 100 90 180 0 1121969744 10693696 22680528
17:13:52 11031 8192000000 10039188800 10389471232 1125559504 12.079 100 76 151 0 1154435760 10806240 18070016
17:15:08 5924 8192000000 10081958336 10438492160 1166947808 12.468 100 84 166 0 1198213760 11832240 19433712
17:16:18 7229 8192000000 10117083408 10482851840 1201705904 12.792 100 76 149 0 1230818832 12877040 16235888
17:17:28 1480 8192000000 10156948656 10530050048 1246430016 13.205 100 89 174 0 1277851840 12815280 18606544
17:18:32 1572 8192000000 10184045968 10561552384 1274087472 13.459 100 91 181 0 1308231088 14767312 19376304
17:19:35 964 8192000000 10206102752 10595110912 1298764656 13.684 100 88 176 0 1332503232 14468608 19269968
17:20:37 7366 8192000000 10206002624 10605920256 1305930064 13.749 100 61 122 0 1328551840 7840032 14781744
17:22:04 2768 8192000000 10205723184 10618265600 1314745488 13.829 100 33 65 0 1329931936 8395760 6790688
17:23:08 3189 8192000000 10216094576 10629922816 1328106624 13.950 100 37 72 1 1341997104 5909728 7980752
17:24:13 10938 8192000000 10216265120 10636562432 1333026144 13.994 100 14 26 0 1345594592 9750736 2817712
17:25:25 38329 8192000000 10233323424 10653429760 1338663584 14.045 100 44 83 2 1353705344 5754512 9287248
17:27:04 5749 8192000000 10230790528 10651885568 1337650224 14.036 100 13 25 0 1345388816 5238992 2499600
17:28:12 8795 8192000000 10206286576 10630553600 1304574016 13.737 98 17 34 0 1314037632 5511616 3952000
17:29:24 62552 8192000000 10174458400 10587787264 1257693840 13.309 97 26 52 0 1268231360 5056704 5480816
17:31:31 5932 8192000000 10142066496 10545758208 1252719984 13.263 100 20 40 0 1262370800 5353408 4297408
17:32:37 10981 8192000000 10140204288 10538606592 1228217728 13.038 98 17 31 2 1236647552 4814320 3615504
17:33:49 28103 8192000000 10149464096 10554114048 1262698976 13.355 100 26 51 1 1277304288 9043888 5561424
17:35:29 2515 8192000000 10177120976 10575847424 1277004880 13.486 100 53 110 2 1295988848 5337040 13646928
17:36:36 4316 8192000000 10181690768 10592256000 1292034912 13.623 100 24 48 0 1303624512 5362704 6226896
17:37:40 8883 8192000000 10186963376 10600062976 1298848976 13.685 100 20 41 1 1308997008 5491440 4656592
17:38:54 7260 8192000000 10233708720 10639474688 1328302752 13.952 100 49 97 0 1345356992 6118352 10935888
17:40:06 52851 8192000000 10240053600 10658496512 1339733552 14.055 100 52 104 1 1359903120 7429664 12739904
17:42:34 24866 8192000000 10265660800 10686255104 1363411504 14.268 100 46 91 0 1383234992 9732016 10091472
17:44:09 9394 8192000000 10278702832 10699051008 1372672912 14.351 100 51 99 1 1395800400 11007888 12119600
17:45:25 5972 8192000000 10291205568 10715119616 1384639552 14.458 100 41 81 1 1406812144 12339440 9833152
17:46:34 15290 8192000000 10310602480 10735734784 1393409984 14.536 100 44 86 1 1423255216 19850496 9994736
17:47:58 11804 8192000000 10310723312 10738556928 1399433408 14.590 100 42 84 1 1425601968 16092400 10076160
17:49:34 9330 8192000000 10322551168 10747932672 1401407104 14.608 100 57 111 5 1435487232 20858880 13221248
17:50:47 2621 8192000000 10326355712 10746048512 1397880592 14.576 99 61 120 1 1432077296 19589936 14606768
17:51:54 8155 8192000000 10308035248 10722820096 1376496688 14.385 99 55 108 0 1413834064 24151888 13185488
17:53:11 1630 8192000000 10296830736 10706755584 1350997488 14.156 97 75 148 2 1399404368 30067008 18339872
17:54:16 3431 8192000000 10329048080 10743091200 1375210096 14.374 99 71 140 0 1430370400 37102384 18057920
17:55:26 8158 8192000000 10335320272 10754174976 1397638736 14.574 100 64 127 1 1443839056 29751088 16449232
17:56:39 3598 8192000000 10319821312 10735550464 1369465456 14.322 99 74 145 1 1420985824 33432320 18088048
17:57:50 1302 8192000000 10323893984 10732965888 1371181760 14.338 99 65 128 1 1423806384 36035712 16588912
17:58:59 6340 8192000000 10316100624 10738860032 1356103392 14.202 98 45 88 0 1405064208 36777536 12183280
18:00:10 17799 8192000000 10304030576 10728017920 1370513136 14.332 100 39 77 0 1414803920 33535248 10755536
18:01:30 19860 8192000000 10282598768 10703273984 1340586960 14.063 100 27 53 0 1382783824 34666048 7530816
18:03:09 17024 8192000000 10283366912 10711810048 1336693504 14.028 99 28 56 0 1378360352 33948208 7718640
18:04:29 13713 8192000000 10286170480 10711769088 1353830272 14.182 100 19 38 0 1391389968 32269536 5290160
18:05:46 12428 8192000000 10289712896 10718089216 1360887456 14.245 100 39 75 0 1397142512 27213440 9041616
18:07:25 8602 8192000000 10275991472 10712829952 1357136528 14.212 100 27 53 0 1386977520 23038848 6802144
18:08:57 35807 8192000000 10269718384 10710179840 1356407184 14.205 99 14 28 0 1373208176 13832000 2968992
18:10:34 24105 8192000000 10264357936 10712748032 1355599152 14.198 99 43 81 0 1378812304 14166384 9046768
18:11:59 16336 8192000000 10278592384 10711257088 1368250352 14.311 100 51 102 2 1388706880 8212288 12244240
18:13:25 54737 8192000000 10270789392 10716966912 1351426032 14.160 98 16 32 1 1372288720 17024496 3838192
18:15:20 14345 8192000000 10276145200 10712154112 1367380304 14.304 100 42 84 1 1385096464 7298992 10417168
18:17:02 50696 8192000000 10287542768 10724990976 1380442544 14.421 100 39 76 0 1397222256 7995504 8784208
18:18:55 47263 8192000000 10276337392 10732781568 1379691824 14.414 100 41 82 0 1398726480 9794608 9240048
18:20:49 11951 8192000000 10286115440 10740469760 1388429968 14.492 100 29 56 1 1403362016 8240832 6691216
18:22:02 33910 8192000000 10296161984 10744918016 1385660848 14.467 99 18 36 0 1398191536 8063872 4466816
18:23:47 56817 8192000000 10319073728 10770259968 1412468880 14.706 100 35 66 0 1434837040 14190176 8177984
18:25:46 81044 8192000000 10302263632 10771394560 1403886944 14.630 100 13 26 1 1426531968 19708176 2936848
18:28:08 43446 8192000000 10311755936 10747154432 1388695600 14.494 99 51 90 2 1406193952 5428112 12070240
18:29:52 33985 8192000000 10302941392 10764963840 1407737520 14.664 100 15 31 1 1424784736 13730784 3316432
18:31:31 51524 8192000000 10303419376 10774142976 1392799040 14.531 99 12 22 0 1417495776 22213568 2483168
18:33:26 79801 8192000000 10312888480 10769715200 1385667024 14.467 98 21 42 0 1403055584 12609008 4779552
18:35:48 69765 8192000000 10312918992 10770321408 1407350880 14.660 100 20 40 0 1423730896 11600448 4779568
18:38:05 96303 8192000000 10290005744 10749030400 1354799776 14.191 97 14 28 0 1372182304 14396240 2986288
18:40:45 29157 8192000000 10291196608 10731962368 1356697616 14.208 98 29 57 0 1370662224 7739088 6225520
18:42:14 29427 8192000000 10262723952 10707701760 1343041072 14.085 99 39 76 0 1362897568 11448720 8407776
18:43:45 33092 8192000000 10289922240 10725703680 1337923104 14.039 98 37 74 0 1370060944 24142704 7995136
18:45:23 86777 8192000000 10237406224 10673741824 1203569552 12.809 89 15 30 0 1222213584 15135936 3508096
18:47:56 89934 8192000000 10241085408 10693390336 1312429280 13.808 98 18 37 1 1340739920 24309952 4000688
18:50:42 40830 8192000000 10240454800 10672496640 1315591168 13.837 99 26 52 0 1329136976 7585920 5959888
18:52:23 67423 8192000000 10250998496 10696826880 1331350592 13.979 99 17 34 0 1354171248 18470528 4350128
18:54:31 80513 8192000000 10249805120 10711879680 1332543008 13.990 99 14 29 0 1358942096 22819760 3579328
18:56:55 54683 8192000000 10239547440 10690932736 1306490256 13.754 97 19 39 0 1324966528 14014432 4461840
18:58:50 91355 8192000000 10257762544 10708664320 1331846496 13.984 98 21 42 0 1349697424 12814720 5036208
19:01:46 68048 8192000000 10255980448 10714374144 1339976080 14.057 99 23 45 1 1364861712 19650096 5235536
19:04:00 66252 8192000000 10262021888 10714849280 1363916784 14.273 100 15 30 0 1380422464 13035952 3469728
19:06:07 83015 8192000000 10260634528 10710175744 1329927952 13.967 97 17 34 0 1344682000 10636112 4117936
19:08:44 34365 8192000000 10284915072 10732036096 1367511984 14.305 99 36 72 1 1385390928 10346032 7532912
19:10:22 73996 8192000000 10295618224 10763694080 1377938640 14.398 99 15 30 0 1392261984 11048784 3274560
19:12:37 25247 8192000000 10304734448 10755694592 1392801248 14.531 100 30 60 0 1408796880 8817152 7178480
19:14:09 69271 8192000000 10302446800 10766086144 1382484176 14.439 99 30 60 0 1402858320 14035648 6338496
19:16:19 51429 8192000000 10315053248 10755211264 1360095600 14.238 97 26 52 0 1374522816 8770704 5656512
19:18:15 98838 8192000000 10321920016 10789404672 1378776832 14.406 98 25 50 0 1402558496 18448656 5333008
19:20:57 90322 8192000000 10277317648 10728116224 1336032768 14.022 96 7 14 1 1349875488 12130256 1712464
19:23:56 78411 8192000000 10277792560 10733027328 1348927712 14.138 97 21 42 0 1364889488 10799904 5161872
19:26:29 82210 8192000000 10287908848 10754580480 1383944528 14.452 100 16 33 0 1399485520 11651440 3889552
19:28:51 32506 8192000000 10276750176 10732957696 1349832128 14.146 97 20 40 1 1365881440 10623040 5426272
19:30:43 65731 8192000000 10261124352 10716999680 1329942048 13.967 97 7 14 0 1345595552 14116768 1536736
19:32:49 90644 8192000000 10254056976 10712657920 1305071616 13.741 96 10 20 0 1324287360 16990784 2224960
19:35:22 36005 8192000000 10239633344 10684968960 1266254880 13.387 93 17 33 1 1280884912 10914960 3715072
19:37:04 37102 8192000000 10260007872 10704965632 1344303648 14.096 99 28 56 0 1358791472 7587264 6900560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-01-2023 19:39:41 to 01-01-2023 19:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-01-2023 19:39:57 to 01-01-2023 19:39:59.

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          239794
MON$OLDEST_ACTIVE               240748
MON$OLDEST_SNAPSHOT             240748
MON$NEXT_TRANSACTION            240748
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                       2009152
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1525: 
:1526: mscs-test-161.budzdorov.ru    Sun Jan  1 19:38:35 2023
:1527:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1528: 
:1529: 
:1530: mscs-test-161.budzdorov.ru    Sun Jan  1 19:38:36 2023
:1531:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1532: 
:1533: 
:1534: mscs-test-161.budzdorov.ru    Sun Jan  1 19:38:36 2023
:1535:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1536: 
:1537: 
:1538: mscs-test-161.budzdorov.ru    Sun Jan  1 19:38:36 2023
:1539:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1540: 
:1541: 
:1542: mscs-test-161.budzdorov.ru    Sun Jan  1 19:38:36 2023
:1543:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1544: 
--- end of diff output ---

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

01.01.23 19:40:00. 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-01 19:40:03 No severe PSQL-related problems occured
01.01.23 19:40:04. End of report.