Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2022 01:38
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 15263672 kB
MemAvailable 30425448 kB
Buffers 0 kB
Cached 16430152 kB
SwapCached 2084 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-26 01:38:29
DTS_END                         2022-10-26 01:38:29.4420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44070
TRN_ID                          239559
ATT_ID                          697
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: 9124.79 20836 1094975
customer order: creation 172.95 394 20754
customer order: refuse 15.27 352 1832
order to supplier: creation 45.91 1303 5509
order to supplier: removal 3.33 113247 400
invoice (draft): creation 45.47 3238 5456
invoice (draft): removal 52.37 887 6284
invoice accept: apply 41.40 19340 4968
invoice accept: cancel 46.83 833 5620
customer reserve: creation 3825.76 193 459091
customer reserve: removal 4573.56 65 548827
realization accept: apply 84.22 183 10106
realization accept: cancel 88.88 120 10666
payment from customer: creation 58.70 104 7044
payment from customer: removal 5.68 116 682
payment to supplier: creation 49.48 123 5937
payment to supplier: removal 3.46 69 415
service: total inventory turnovers 4.55 1137 546
service: total monetary turnovers 6.13 45 735
service: aggregate perf. data 0.83 24546 99
service: refresh index statistics 0.03 250433 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-10-2022 01:39:12 to 26-10-2022 01:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5475
TEST_TIME 2 4480
TEST_TIME 3 4914
TEST_TIME 4 4700
TEST_TIME 5 4433
TEST_TIME 6 4445
TEST_TIME 7 5333
TEST_TIME 8 5415
TEST_TIME 9 6026
TEST_TIME 10 6698
TEST_TIME 11 6735
TEST_TIME 12 6728
TEST_TIME 13 7029
TEST_TIME 14 7202
TEST_TIME 15 7182
TEST_TIME 16 7451
TEST_TIME 17 7787
TEST_TIME 18 7788
TEST_TIME 19 7915
TEST_TIME 20 8078
TEST_TIME 21 8573
TEST_TIME 22 8645
TEST_TIME 23 8729
TEST_TIME 24 8705
TEST_TIME 25 8973
TEST_TIME 26 9126
TEST_TIME 27 9450
TEST_TIME 28 9752
TEST_TIME 29 10234
TEST_TIME 30 11271
TEST_TIME 31 11432
TEST_TIME 32 11174
TEST_TIME 33 11192
TEST_TIME 34 11126
TEST_TIME 35 10839
TEST_TIME 36 10621
TEST_TIME 37 10595
TEST_TIME 38 10359
TEST_TIME 39 10199
TEST_TIME 40 10153
TEST_TIME 41 10090
TEST_TIME 42 10060
TEST_TIME 43 9930
TEST_TIME 44 9962
TEST_TIME 45 9949
TEST_TIME 46 9858
TEST_TIME 47 9781
TEST_TIME 48 9660
TEST_TIME 49 9583
TEST_TIME 50 9396
TEST_TIME 51 9482
TEST_TIME 52 9405
TEST_TIME 53 9405
TEST_TIME 54 9345
TEST_TIME 55 9259
TEST_TIME 56 9204
TEST_TIME 57 9222
TEST_TIME 58 9246
TEST_TIME 59 9198
TEST_TIME 60 9138
TEST_TIME 61 9047
TEST_TIME 62 9209
TEST_TIME 63 9213
TEST_TIME 64 9235
TEST_TIME 65 9169
TEST_TIME 66 9333
TEST_TIME 67 9361
TEST_TIME 68 9336
TEST_TIME 69 9369
TEST_TIME 70 9364
TEST_TIME 71 9346
TEST_TIME 72 9422
TEST_TIME 73 9397
TEST_TIME 74 9347
TEST_TIME 75 9421
TEST_TIME 76 9324
TEST_TIME 77 9299
TEST_TIME 78 9217
TEST_TIME 79 9179
TEST_TIME 80 9246
TEST_TIME 81 9196
TEST_TIME 82 9148
TEST_TIME 83 9178
TEST_TIME 84 9138
TEST_TIME 85 9119
TEST_TIME 86 9050
TEST_TIME 87 9048
TEST_TIME 88 9031
TEST_TIME 89 9015
TEST_TIME 90 9078
TEST_TIME 91 9041
TEST_TIME 92 9077
TEST_TIME 93 9111
TEST_TIME 94 9120
TEST_TIME 95 9104
TEST_TIME 96 9174
TEST_TIME 97 9212
TEST_TIME 98 9205
TEST_TIME 99 9217
TEST_TIME 100 9235
TEST_TIME 101 9275
TEST_TIME 102 9329
TEST_TIME 103 9330
TEST_TIME 104 9247
TEST_TIME 105 9256
TEST_TIME 106 9212
TEST_TIME 107 9191
TEST_TIME 108 9160
TEST_TIME 109 9109
TEST_TIME 110 9082
TEST_TIME 111 9076
TEST_TIME 112 9158
TEST_TIME 113 9154
TEST_TIME 114 9134
TEST_TIME 115 9149
TEST_TIME 116 9138
TEST_TIME 117 9148
TEST_TIME 118 9123
TEST_TIME 119 9161
TEST_TIME 120 9135

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-10-2022 01:39:16 to 26-10-2022 01:39:20.

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 1519436 1519436 0 0.00 0 28069 7 0 0
doc_list_biud 1519434 1519434 0 0.00 0 73106 30 0 0
sp_add_invoice_to_stock 5086 4968 118 2.32 29 172413 19340 0 118
sp_cancel_adding_invoice 5620 5620 0 0.00 5 74648 833 0 0
sp_cancel_client_order 1832 1832 0 0.00 4 9560 352 0 0
sp_cancel_customer_reserve 548827 548827 0 0.00 0 73107 65 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 7177 116 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 2832 70 0 0
sp_cancel_supplier_invoice 6294 6284 10 0.15 1 36704 888 0 10
sp_cancel_supplier_order 400 400 0 0.00 6 1261391 113248 0 0
sp_cancel_write_off 10666 10666 0 0.00 1 16889 121 0 0
sp_client_order 20754 20754 0 0.00 3 25016 394 0 0
sp_customer_reserve 459091 459091 0 0.00 0 32469 194 0 0
sp_fill_shopping_cart 31715 31715 0 0.00 1 13146 41 0 0
sp_fill_shopping_cart_clo_res 459073 459073 0 0.00 0 12832 14 0 0
sp_get_clo_for_invoice 4950 4950 0 0.00 0 18514 308 0 0
sp_kill_cost_storno 17383 17383 0 0.00 0 10139 57 0 0
sp_kill_qstorno_ret_qs2qd 1115004 1115004 0 0.00 0 73104 35 0 0
sp_kill_qty_storno 588683 588683 0 0.00 0 73106 76 0 0
sp_lock_selected_doc 20891 20891 0 0.00 0 10736 5 0 0
sp_make_cost_storno 28037 28037 0 0.00 0 28004 48 0 0
sp_multiply_rows_for_pdistr 28037 28037 0 0.00 0 14624 27 0 0
sp_multiply_rows_for_qdistr 20754 20754 0 0.00 0 11818 161 0 0
sp_pay_from_customer 7044 7044 0 0.00 4 14699 105 0 0
sp_pay_to_supplier 5937 5937 0 0.00 4 28088 123 0 0
sp_payment_common 12981 12981 0 0.00 2 6787 19 0 0
sp_qd_handle_on_cancel_clo 1832 1832 0 0.00 0 9554 327 0 0
sp_qd_handle_on_invoice_upd_sts 10568 10568 0 0.00 0 22359 320 0 0
sp_qd_handle_on_reserve_upd_sts 10106 10106 0 0.00 0 20687 76 0 0
sp_reserve_write_off 10106 10106 0 0.00 6 20761 184 0 0
sp_supplier_invoice 5456 5456 0 0.00 5 90940 3239 0 0
sp_supplier_order 5509 5509 0 0.00 5 31726 1304 0 0
srv_aggregate_perf_data 99 99 0 0.00 2832 376049 24546 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1209 134665 39486 0 0
srv_find_qd_qs_mism 1047027 1047027 0 0.00 0 16753 7 0 0
srv_make_invnt_saldo 546 546 0 0.00 47 43959 1137 0 0
srv_make_money_saldo 735 735 0 0.00 0 7808 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 87634 400525 250434 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4971 4971 0 0.00 0 14727 196 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 8583 127 0 0
t$perf-norm:sp_cancel_client_order 1832 1832 0 0.00 0 11370 28 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 350 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 4566 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 3104 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 862 15 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 20792 1646 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 5376 20 0 0
t$perf-norm:sp_client_order 20754 20754 0 0.00 0 11716 23 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 5912 17 0 0
t$perf-norm:sp_pay_from_customer 7044 7044 0 0.00 0 11403 17 0 0
t$perf-norm:sp_pay_to_supplier 5937 5937 0 0.00 0 11385 15 0 0
t$perf-norm:sp_reserve_write_off 10106 10106 0 0.00 0 8793 14 0 0
t$perf-norm:sp_supplier_invoice 5456 5456 0 0.00 0 7187 18 0 0
t$perf-norm:sp_supplier_order 5509 5509 0 0.00 0 9763 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 581 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 107 2 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 2069 26 0 0
t$perf-norm:srv_make_money_saldo 735 735 0 0.00 0 4089 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 23 9 0 0
v_add_invoice_to_stock 10130 9894 236 2.32 0 7934 18 0 236
v_all_customers 37790 37790 0 0.00 0 23 1 0 0
v_all_suppliers 30288 30288 0 0.00 0 28 1 0 0
v_all_wares 41508 41508 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 3349 18 0 0
v_cancel_client_order 3664 3664 0 0.00 0 2194 6 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 7158 13 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 2248 8 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 2788 8 0 0
v_cancel_supplier_invoice 816 796 20 2.45 0 1409 16 0 20
v_cancel_supplier_order 800 800 0 0.00 0 1614 6 0 0
v_cancel_write_off 1956 1956 0 0.00 0 2069 16 0 0
v_max_id_clo_ord 5508 5508 0 0.00 0 1379 2 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 1520 12 0 0
v_max_id_ord_sup 5453 5453 0 0.00 0 6318 5 0 0
v_max_non_paid_invoice 1754 1754 0 0.00 0 476 2 0 0
v_max_non_paid_realizn 3744 3744 0 0.00 0 6782 4 0 0
v_min_id_clo_ord 5508 5508 0 0.00 0 3295 7 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 4529 11 0 0
v_min_id_ord_sup 5453 5453 0 0.00 0 7350 8 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 4126 14 0 0
v_min_non_paid_realizn 7044 7044 0 0.00 0 5223 12 0 0
v_our_firm 10318 10318 0 0.00 0 15 1 0 0
v_reserve_write_off 20212 20212 0 0.00 0 3445 6 0 0
x_lock_depdocs_on_canc_invoice 11506 11506 0 0.00 0 17621 119 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 8893 424 0 0
x_make_qstorno_1000_1200 5509 5509 0 0.00 1 31716 1223 0 0
x_make_qstorno_1200_2000 5456 5456 0 0.00 2 83387 3091 0 0
x_make_qstorno_2100_3300 459091 459091 0 0.00 0 32468 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2022 01:39:21 to 26-10-2022 01:39:25.

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
23:08:33 18 8192000000 8827068800 8848130048 27895664 0.339 11 5 4 0 28105520 97376 112480
23:09:34 17471 8192000000 9895116704 10202333184 975537648 10.641 100 87 174 0 1004163120 6104064 22521408
23:10:59 21194 8192000000 9953528992 10275610624 1034193232 11.209 100 88 176 0 1065221488 8721856 22306400
23:12:25 19628 8192000000 9991473520 10324889600 1072623088 11.577 100 79 158 0 1101894752 10002240 19269424
23:13:47 9212 8192000000 10038714944 10383970304 1120719952 12.034 100 78 156 0 1150335088 11123952 18491184
23:15:08 5488 8192000000 10083546096 10442592256 1171054096 12.507 100 86 172 0 1204365328 13946928 19364304
23:16:17 3813 8192000000 10120154720 10485477376 1208207616 12.852 100 76 152 0 1237369840 11960528 17201696
23:17:23 2961 8192000000 10148343648 10527350784 1248933360 13.228 100 68 129 1 1274449344 11332448 14183536
23:18:29 2423 8192000000 10184494944 10563551232 1267277008 13.397 100 81 159 0 1297117792 12587024 17253760
23:19:33 2268 8192000000 10221415376 10604503040 1296099744 13.660 100 85 168 0 1327149024 13257616 17791664
23:20:37 1321 8192000000 10218524384 10606116864 1304388800 13.735 100 80 158 1 1333659120 10234096 19036224
23:21:39 8218 8192000000 10199368272 10608148480 1310621872 13.792 100 27 54 0 1323563312 7336656 5604784
23:22:50 34893 8192000000 10206709344 10624311296 1318786448 13.866 100 33 64 3 1330652608 5295520 6570640
23:24:26 6605 8192000000 10213897248 10635632640 1330243184 13.969 100 19 38 0 1340754752 6117680 4393888
23:25:37 11163 8192000000 10220113616 10641350656 1334620368 14.009 100 18 32 1 1343767328 5203280 3943680
23:26:51 64866 8192000000 10213223392 10635436032 1321419872 13.890 99 34 64 0 1333817632 4786768 7610992
23:28:57 6577 8192000000 10203328528 10620403712 1294475232 13.645 98 15 30 0 1303836896 5214992 4146672
23:30:07 4521 8192000000 10172681184 10567446528 1259060192 13.321 98 32 63 0 1272260304 5135120 8064992
23:31:13 70431 8192000000 10142053568 10552532992 1215660624 12.922 96 30 58 0 1227785728 5163344 6961760
23:33:30 6288 8192000000 10114220304 10511011840 1198904480 12.766 96 21 42 0 1209627424 5170480 5552464
23:34:36 9154 8192000000 10177972144 10580758528 1281672224 13.528 100 24 47 0 1292352816 4877792 5802800
23:35:47 10853 8192000000 10186124688 10597203968 1297284384 13.671 100 19 38 0 1307115072 5419216 4411472
23:36:58 16802 8192000000 10198292480 10605404160 1300993888 13.704 100 45 91 0 1318713376 5557360 12162128
23:38:15 7685 8192000000 10212093520 10631204864 1323897088 13.912 100 26 53 0 1335097696 5221232 5979376
23:39:31 3198 8192000000 10260444960 10662645760 1344044720 14.094 100 68 131 0 1368997904 10372320 14580864
23:40:48 22565 8192000000 10264475712 10677792768 1361181136 14.248 100 53 106 1 1385371600 11926128 12264336
23:42:22 23752 8192000000 10282202368 10703249408 1371131616 14.337 100 45 90 3 1398125840 16412944 10581280
23:43:57 14841 8192000000 10287540896 10709688320 1380814576 14.424 100 50 96 0 1405372816 13300208 11258032
23:45:20 6033 8192000000 10299115344 10721865728 1387583808 14.484 100 54 108 0 1416048352 15199824 13264720
23:46:36 16776 8192000000 10294761632 10711724032 1375415904 14.376 99 52 102 0 1402826944 14905504 12505536
23:48:02 5345 8192000000 10297576592 10717822976 1380234416 14.419 100 65 128 0 1415129536 19012800 15882320
23:49:12 13877 8192000000 10323203312 10750144512 1404331760 14.634 100 47 93 1 1433195296 17657552 11205984
23:50:29 1346 8192000000 10342695120 10757042176 1408397920 14.670 100 84 165 2 1450740000 21709776 20632304
23:51:35 3316 8192000000 10319647568 10734174208 1385434944 14.465 100 72 144 0 1429252960 26638016 17180000
23:52:49 4940 8192000000 10344623840 10764787712 1409393696 14.679 100 70 140 2 1457030032 31508320 16128016
23:53:59 2547 8192000000 10317439424 10723254272 1374487264 14.367 100 73 141 4 1420717360 28954016 17276080
23:55:07 1209 8192000000 10328536048 10727391232 1376409776 14.384 99 89 177 1 1433336112 35446224 21480112
23:56:14 11480 8192000000 10331055008 10740740096 1385005040 14.461 100 54 103 0 1432049680 34099456 12945184
23:57:33 1260 8192000000 10360329296 10763804672 1395534896 14.555 100 94 189 0 1463631248 43315936 24780416
23:58:39 6390 8192000000 10357676880 10774777856 1405060096 14.640 100 55 110 1 1466085888 45946016 15079776
23:59:49 12952 8192000000 10341769344 10761375744 1391942112 14.523 100 44 88 1 1445411360 41067936 12401312
00:01:06 22544 8192000000 10336777712 10760396800 1390024208 14.506 100 39 78 0 1445248848 44626352 10598288
00:02:33 13871 8192000000 10350193680 10777178112 1391981104 14.524 100 35 70 0 1453234560 51246224 10007232
00:03:51 15271 8192000000 10338943840 10765021184 1377406704 14.393 99 33 63 0 1439064704 52208080 9449920
00:05:14 26760 8192000000 10346395616 10786357248 1361249936 14.249 98 30 58 1 1432962064 62720656 8991472
00:07:47 19503 8192000000 10331085744 10774806528 1369209920 14.320 100 17 34 0 1433488432 59017600 5260912
00:09:08 37315 8192000000 10294625840 10743373824 1373080928 14.355 100 20 40 0 1408244096 30201104 4962064
00:10:46 25042 8192000000 10316170928 10747756544 1384333184 14.455 100 54 106 0 1415726240 18505776 12887280
00:12:12 106508 8192000000 10279679632 10735431680 1363197680 14.266 99 13 26 1 1379016432 12807072 3011680
00:15:09 56860 8192000000 10281993728 10729426944 1367709712 14.307 99 30 60 0 1398282256 24077616 6494928
00:17:37 48160 8192000000 10306554288 10746404864 1386046272 14.471 100 31 61 0 1410039504 16804384 7188848
00:19:26 67842 8192000000 10285818272 10736427008 1379799472 14.415 99 28 56 0 1403375552 16542688 7033392
00:21:34 51934 8192000000 10301003792 10762170368 1395624512 14.556 100 13 26 0 1416281008 17519392 3137104
00:24:11 53323 8192000000 10301780048 10760028160 1397889936 14.576 100 19 37 0 1411022624 9439520 3693168
00:27:09 88606 8192000000 10292363552 10752520192 1376252688 14.383 98 15 31 0 1391656864 11590272 3813904
00:30:04 74495 8192000000 10306769168 10758578176 1390655328 14.512 99 23 44 1 1409296112 13572704 5068080
00:32:21 63175 8192000000 10285966320 10747797504 1376888944 14.389 98 14 25 0 1387522288 8239968 2393376
00:34:26 26236 8192000000 10301199104 10757251072 1385988160 14.470 99 24 46 1 1400615568 9159680 5467728
00:36:03 50158 8192000000 10294247152 10758647808 1398339136 14.580 100 15 30 0 1412529920 10001280 4189504
00:38:02 67792 8192000000 10273694576 10743152640 1346698112 14.118 96 14 28 0 1355931536 6412480 2820944
00:40:21 49788 8192000000 10299374960 10768752640 1387403472 14.483 99 22 44 0 1401764272 9718928 4641872
00:42:14 62795 8192000000 10307898080 10773282816 1375844416 14.379 98 5 10 0 1389559312 13075584 639312
00:44:29 60815 8192000000 10277173568 10742996992 1333496784 13.999 96 11 23 0 1351878096 15774352 2606960
00:46:34 26667 8192000000 10306060016 10752679936 1388684304 14.494 100 34 68 0 1407561008 11552304 7324400
00:48:11 35566 8192000000 10281517472 10732081152 1333214624 13.996 96 27 54 0 1350346832 11033392 6098816
00:49:51 102275 8192000000 10270312960 10723147776 1273962112 13.458 92 20 40 0 1293237552 14852528 4422912
00:52:34 52996 8192000000 10268765536 10720432128 1323947920 13.912 97 32 62 0 1341695104 10827488 6919696
00:54:45 68870 8192000000 10248307280 10698489856 1320889360 13.885 97 22 42 0 1332571360 6571184 5110816
00:56:55 15766 8192000000 10244354992 10688057344 1342544144 14.080 100 19 38 0 1354750592 7507536 4698912
00:58:11 27903 8192000000 10258745504 10711982080 1355696544 14.199 100 12 24 1 1366538976 7967008 2875424
00:59:39 50648 8192000000 10245370944 10707070976 1318653840 13.865 97 9 17 0 1328381280 7704400 2023040
01:01:39 49776 8192000000 10236019648 10685100032 1332997104 13.994 99 14 26 1 1343979072 7863072 3118896
01:03:35 40242 8192000000 10236797920 10688716800 1331807440 13.983 98 14 28 0 1343248688 8398864 3042384
01:06:07 49085 8192000000 10253011264 10704195584 1330536704 13.972 98 19 36 1 1341967344 7368528 4062112
01:08:03 79224 8192000000 10246825632 10699161600 1272998464 13.449 93 20 40 1 1287435680 9839712 4597504
01:10:23 32612 8192000000 10277170048 10725482496 1367049200 14.301 100 23 46 0 1385080480 13014112 5017168
01:11:57 8275 8192000000 10273349552 10718015488 1356831744 14.209 99 40 79 0 1374192400 7510864 9849792
01:13:06 48363 8192000000 10307069488 10748006400 1378765200 14.406 100 33 66 0 1401039152 14555472 7718480
01:14:59 68348 8192000000 10295805360 10754899968 1373195408 14.356 99 14 28 0 1387944480 11797904 2951168
01:17:11 62037 8192000000 10282483872 10735472640 1348457056 14.134 97 17 34 0 1365010576 12668768 3884752
01:19:15 47823 8192000000 10301163968 10765262848 1394539648 14.546 100 34 68 0 1415848512 13768752 7540112
01:21:19 134665 8192000000 10285921936 10738176000 1352208096 14.167 98 24 48 0 1368398560 11073088 5117376
01:24:46 33464 8192000000 10291168608 10750369792 1384065696 14.453 100 20 41 0 1401208160 12371856 4770608
01:26:41 68222 8192000000 10294834704 10751582208 1368684544 14.315 98 9 18 0 1384647584 13800960 2162080
01:29:21 59008 8192000000 10257811472 10720280576 1324535200 13.918 97 16 32 1 1346182672 17657024 3990448
01:31:41 47166 8192000000 10238813200 10686869504 1305554976 13.746 97 18 34 3 1321062368 12042608 3464784
01:33:29 23371 8192000000 10264662432 10713849856 1353670112 14.180 100 33 64 0 1370409712 9603696 7135904
01:34:59 54205 8192000000 10265112272 10713464832 1346339680 14.115 99 23 46 0 1364525952 13029808 5156464
01:36:53 39285 8192000000 10288769696 10724528128 1354990144 14.192 99 39 78 0 1373901632 10185536 8725952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2022 01:39:27 to 26-10-2022 01:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2022 01:39:43 to 26-10-2022 01:39:45.

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          238785
MON$OLDEST_ACTIVE               239683
MON$OLDEST_SNAPSHOT             239683
MON$NEXT_TRANSACTION            239683
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                       2042272
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'

26.10.22 01:39:46. 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-26 01:39:48 No severe PSQL-related problems occured
26.10.22 01:39:50. End of report.