Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.06.2022 07:37
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 15878172 kB
MemAvailable 31211624 kB
Buffers 0 kB
Cached 15807404 kB
SwapCached 2504 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-06-23 07:37:16
DTS_END                         2022-06-23 07:37:16.9690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58708
TRN_ID                          211733
ATT_ID                          656
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: 8950.52 22922 1074062
customer order: creation 164.01 492 19681
customer order: refuse 14.45 474 1734
order to supplier: creation 42.91 1501 5149
order to supplier: removal 3.38 179419 405
invoice (draft): creation 43.82 3412 5258
invoice (draft): removal 49.76 1032 5971
invoice accept: apply 40.52 26572 4862
invoice accept: cancel 44.86 1295 5383
customer reserve: creation 3817.98 248 458157
customer reserve: removal 4444.69 106 533363
realization accept: apply 79.11 299 9493
realization accept: cancel 83.27 183 9992
payment from customer: creation 55.63 177 6676
payment from customer: removal 5.43 140 651
payment to supplier: creation 45.69 210 5483
payment to supplier: removal 3.43 152 412
service: total inventory turnovers 3.70 4420 444
service: total monetary turnovers 6.93 409 832
service: aggregate perf. data 0.93 15459 111
service: refresh index statistics 0.04 222440 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-06-2022 07:38:23 to 23-06-2022 07:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7974
TEST_TIME 2 6808
TEST_TIME 3 6821
TEST_TIME 4 6417
TEST_TIME 5 6454
TEST_TIME 6 6306
TEST_TIME 7 6025
TEST_TIME 8 5977
TEST_TIME 9 6247
TEST_TIME 10 6577
TEST_TIME 11 6485
TEST_TIME 12 6559
TEST_TIME 13 6409
TEST_TIME 14 6426
TEST_TIME 15 6488
TEST_TIME 16 6680
TEST_TIME 17 6796
TEST_TIME 18 6862
TEST_TIME 19 6866
TEST_TIME 20 6874
TEST_TIME 21 7168
TEST_TIME 22 7169
TEST_TIME 23 7258
TEST_TIME 24 8023
TEST_TIME 25 8516
TEST_TIME 26 8523
TEST_TIME 27 8582
TEST_TIME 28 8772
TEST_TIME 29 8884
TEST_TIME 30 8728
TEST_TIME 31 9317
TEST_TIME 32 9597
TEST_TIME 33 9901
TEST_TIME 34 10405
TEST_TIME 35 10568
TEST_TIME 36 10414
TEST_TIME 37 10222
TEST_TIME 38 10419
TEST_TIME 39 10536
TEST_TIME 40 10400
TEST_TIME 41 10322
TEST_TIME 42 10190
TEST_TIME 43 10153
TEST_TIME 44 10106
TEST_TIME 45 9985
TEST_TIME 46 9853
TEST_TIME 47 9999
TEST_TIME 48 9892
TEST_TIME 49 9879
TEST_TIME 50 9785
TEST_TIME 51 9770
TEST_TIME 52 9836
TEST_TIME 53 9763
TEST_TIME 54 9646
TEST_TIME 55 9530
TEST_TIME 56 9548
TEST_TIME 57 9466
TEST_TIME 58 9395
TEST_TIME 59 9362
TEST_TIME 60 9345
TEST_TIME 61 9373
TEST_TIME 62 9419
TEST_TIME 63 9361
TEST_TIME 64 9281
TEST_TIME 65 9272
TEST_TIME 66 9196
TEST_TIME 67 9154
TEST_TIME 68 9150
TEST_TIME 69 9196
TEST_TIME 70 9292
TEST_TIME 71 9266
TEST_TIME 72 9234
TEST_TIME 73 9301
TEST_TIME 74 9321
TEST_TIME 75 9305
TEST_TIME 76 9305
TEST_TIME 77 9263
TEST_TIME 78 9349
TEST_TIME 79 9360
TEST_TIME 80 9392
TEST_TIME 81 9428
TEST_TIME 82 9407
TEST_TIME 83 9359
TEST_TIME 84 9299
TEST_TIME 85 9263
TEST_TIME 86 9243
TEST_TIME 87 9238
TEST_TIME 88 9175
TEST_TIME 89 9131
TEST_TIME 90 9090
TEST_TIME 91 9093
TEST_TIME 92 9059
TEST_TIME 93 9062
TEST_TIME 94 9032
TEST_TIME 95 9013
TEST_TIME 96 8987
TEST_TIME 97 9089
TEST_TIME 98 9144
TEST_TIME 99 9148
TEST_TIME 100 9134
TEST_TIME 101 9210
TEST_TIME 102 9199
TEST_TIME 103 9221
TEST_TIME 104 9266
TEST_TIME 105 9269
TEST_TIME 106 9278
TEST_TIME 107 9253
TEST_TIME 108 9201
TEST_TIME 109 9172
TEST_TIME 110 9152
TEST_TIME 111 9129
TEST_TIME 112 9080
TEST_TIME 113 9021
TEST_TIME 114 9023
TEST_TIME 115 8992
TEST_TIME 116 8954
TEST_TIME 117 8985
TEST_TIME 118 9000
TEST_TIME 119 8999
TEST_TIME 120 8970

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-06-2022 07:38:27 to 23-06-2022 07:38:30.

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 1495247 1495247 0 0.00 0 33575 14 0 0
doc_list_biud 1495230 1495230 0 0.00 0 38734 44 0 0
sp_add_invoice_to_stock 4965 4862 103 2.07 45 216952 26572 0 103
sp_cancel_adding_invoice 5383 5383 0 0.00 6 90767 1296 0 0
sp_cancel_client_order 1734 1734 0 0.00 6 14226 474 0 0
sp_cancel_customer_reserve 533363 533363 0 0.00 1 23338 106 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 4 5202 141 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 6531 152 0 0
sp_cancel_supplier_invoice 5982 5971 11 0.18 3 17927 1032 0 11
sp_cancel_supplier_order 405 405 0 0.00 14 1529511 179419 0 0
sp_cancel_write_off 9992 9992 0 0.00 2 10172 184 0 0
sp_client_order 19681 19681 0 0.00 5 32043 492 0 0
sp_customer_reserve 458157 458157 0 0.00 1 85627 248 0 0
sp_fill_shopping_cart 30075 30075 0 0.00 1 25172 87 0 0
sp_fill_shopping_cart_clo_res 458120 458120 0 0.00 0 13083 21 0 0
sp_get_clo_for_invoice 4819 4819 0 0.00 0 23663 311 0 0
sp_kill_cost_storno 16438 16438 0 0.00 0 8835 87 0 0
sp_kill_qstorno_ret_qs2qd 1083094 1083094 0 0.00 0 23334 46 0 0
sp_kill_qty_storno 571157 571157 0 0.00 0 38734 106 0 0
sp_lock_selected_doc 19820 19820 0 0.00 0 7179 7 0 0
sp_make_cost_storno 26470 26470 0 0.00 0 13762 87 0 0
sp_multiply_rows_for_pdistr 26468 26468 0 0.00 0 8588 45 0 0
sp_multiply_rows_for_qdistr 19681 19681 0 0.00 0 14420 176 0 0
sp_pay_from_customer 6676 6676 0 0.00 5 11451 178 0 0
sp_pay_to_supplier 5483 5483 0 0.00 7 14902 211 0 0
sp_payment_common 12158 12158 0 0.00 2 12461 47 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 1 14216 392 0 0
sp_qd_handle_on_invoice_upd_sts 10200 10200 0 0.00 1 38734 545 0 0
sp_qd_handle_on_reserve_upd_sts 9492 9492 0 0.00 0 16583 68 0 0
sp_reserve_write_off 9493 9493 0 0.00 9 16618 300 0 0
sp_supplier_invoice 5258 5258 0 0.00 10 95388 3412 0 0
sp_supplier_order 5149 5149 0 0.00 5 19258 1502 0 0
srv_aggregate_perf_data 111 111 0 0.00 2233 189250 15459 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 743 133241 33371 0 0
srv_find_qd_qs_mism 1026604 1026604 0 0.00 0 33573 11 0 0
srv_make_invnt_saldo 444 444 0 0.00 75 89500 4420 0 0
srv_make_money_saldo 832 832 0 0.00 1 16159 409 0 0
srv_recalc_idx_stat 5 5 0 0.00 40490 420261 222440 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4867 4867 0 0.00 1 12099 948 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 8808 528 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 3084 32 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 4112 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 2414 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 703 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 727 32 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 106379 8070 0 0
t$perf-norm:sp_cancel_write_off 920 920 0 0.00 0 1777 20 0 0
t$perf-norm:sp_client_order 19681 19681 0 0.00 0 12791 42 0 0
t$perf-norm:sp_customer_reserve 912 912 0 0.00 0 2502 20 0 0
t$perf-norm:sp_pay_from_customer 6676 6676 0 0.00 0 6255 21 0 0
t$perf-norm:sp_pay_to_supplier 5483 5483 0 0.00 0 6780 24 0 0
t$perf-norm:sp_reserve_write_off 9493 9493 0 0.00 0 10678 25 0 0
t$perf-norm:sp_supplier_invoice 5258 5258 0 0.00 0 5782 33 0 0
t$perf-norm:sp_supplier_order 5149 5149 0 0.00 0 10682 39 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 409 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 183 8 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 6647 31 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 2315 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 189 100 0 0
v_add_invoice_to_stock 9833 9627 206 2.09 0 6660 25 0 206
v_all_customers 35786 35786 0 0.00 0 180 4 0 0
v_all_suppliers 28918 28918 0 0.00 0 71 4 0 0
v_all_wares 39358 39358 0 0.00 0 69 4 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 6450 26 0 0
v_cancel_client_order 3468 3468 0 0.00 0 6196 15 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 5147 16 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 3615 13 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 6485 23 0 0
v_cancel_supplier_invoice 758 736 22 2.90 0 617 23 0 22
v_cancel_supplier_order 810 810 0 0.00 0 664 11 0 0
v_cancel_write_off 1840 1840 0 0.00 0 2665 24 0 0
v_max_id_clo_ord 5147 5147 0 0.00 0 235 5 0 0
v_max_id_clo_res 912 912 0 0.00 0 2293 16 0 0
v_max_id_ord_sup 5248 5248 0 0.00 0 7504 7 0 0
v_max_non_paid_invoice 1419 1419 0 0.00 0 79 7 0 0
v_max_non_paid_realizn 3457 3457 0 0.00 0 874 7 0 0
v_min_id_clo_ord 5147 5147 0 0.00 0 2779 8 0 0
v_min_id_clo_res 912 912 0 0.00 0 1869 11 0 0
v_min_id_ord_sup 5248 5248 0 0.00 0 4864 6 0 0
v_min_non_paid_invoice 5483 5483 0 0.00 0 12457 20 0 0
v_min_non_paid_realizn 6675 6675 0 0.00 0 6033 16 0 0
v_our_firm 10006 10006 0 0.00 0 210 4 0 0
v_reserve_write_off 18982 18982 0 0.00 0 6442 14 0 0
x_lock_depdocs_on_canc_invoice 10986 10986 0 0.00 0 14867 139 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 18241 413 0 0
x_make_qstorno_1000_1200 5149 5149 0 0.00 2 19246 1320 0 0
x_make_qstorno_1200_2000 5258 5258 0 0.00 4 95219 3168 0 0
x_make_qstorno_2100_3300 458157 458157 0 0.00 0 85622 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-06-2022 07:38:32 to 23-06-2022 07:38:36.

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
05:07:20 20 8192000000 8800912320 8812875776 5768432 0.070 3 1 2 0 5869600 65392 35776
05:08:21 34704 8192000000 9728104224 10028363776 805734368 8.954 100 90 180 0 835684384 8369104 21580912
05:10:00 11264 8192000000 9760100160 10068017152 837645952 9.276 100 89 178 0 871654192 12560592 21447648
05:11:16 25578 8192000000 9809605072 10130599936 874868976 9.649 100 86 170 0 912661808 18697968 19094864
05:12:47 12266 8192000000 9876734720 10206126080 913361840 10.031 100 86 172 0 979586288 47405376 18819072
05:14:06 5282 8192000000 9923491056 10266284032 955939520 10.449 100 89 171 0 1037361520 63666400 17755600
05:15:14 3292 8192000000 9965103888 10316029952 987871328 10.761 100 86 172 0 1075483664 69260128 18352208
05:16:22 1234 8192000000 9986492256 10347966464 1017244176 11.045 100 95 185 0 1108248384 72166096 18838112
05:17:26 1442 8192000000 10006498368 10375589888 1043048640 11.294 100 86 165 3 1126646144 66643776 16953728
05:18:29 1181 8192000000 10038771424 10409381888 1059497840 11.452 100 94 185 0 1155569472 77269248 18802384
05:19:31 1163 8192000000 10052423488 10433781760 1070751248 11.559 100 96 188 2 1170020784 80913856 18355680
05:20:35 3008 8192000000 10050612400 10430291968 1072557728 11.576 100 86 160 1 1171362976 83385568 15419680
05:21:41 2898 8192000000 10052572208 10436272128 1084010576 11.686 100 83 159 0 1174223200 75455648 14756976
05:22:45 1150 8192000000 10058830624 10442420224 1084662480 11.692 100 95 187 0 1178373552 74683584 19027488
05:23:48 8124 8192000000 10051569248 10438127616 1086296192 11.707 100 77 147 1 1168706416 67904656 14505568
05:24:57 1324 8192000000 10048396208 10435989504 1091463504 11.757 100 95 179 1 1167825536 57349104 19012928
05:26:01 1278 8192000000 10037276144 10426699776 1094517680 11.786 100 96 179 4 1161739216 48703136 18518400
05:27:03 1148 8192000000 10082198800 10475814912 1093755664 11.778 100 86 164 1 1203333344 92389888 17187792
05:28:05 1421 8192000000 10081719888 10470883328 1093190848 11.773 100 97 189 0 1201349360 89399376 18759136
05:29:08 1217 8192000000 10055262960 10439688192 1080439232 11.652 100 96 188 0 1174802288 74821536 19541520
05:30:12 1316 8192000000 10059349248 10444869632 1085842896 11.703 100 91 180 1 1179149088 74794432 18511760
05:31:14 1210 8192000000 10028473680 10412756992 1061693920 11.473 100 92 173 2 1154543424 75288576 17560928
05:32:18 1151 8192000000 10023843392 10401103872 1056757536 11.425 100 94 185 1 1144291808 68757760 18776512
05:33:20 1189 8192000000 10011140544 10383704064 1036307376 11.229 100 98 185 1 1132592912 76230128 20055408
05:34:23 859 8192000000 9978551584 10353561600 1024951328 11.120 99 90 170 0 1104251504 60311840 18988336
05:35:27 4280 8192000000 9984354896 10361692160 1031954784 11.187 100 67 129 0 1102952992 57501264 13496944
05:36:33 970 8192000000 9978242864 10345238528 1024551200 11.116 100 96 178 0 1097100032 54102688 18446144
05:37:37 1054 8192000000 10000952720 10372706304 1044360128 11.307 100 94 180 2 1123996912 62023072 17613712
05:38:49 2280 8192000000 10017376928 10399817728 1055376768 11.412 100 79 146 0 1142642272 72061744 15203760
05:39:59 4498 8192000000 10039733216 10429861888 1073423392 11.585 100 72 144 0 1156178176 68120848 14633936
05:41:10 2318 8192000000 10092095696 10472116224 1095934720 11.799 100 88 163 1 1210927808 99015136 15977952
05:42:20 2712 8192000000 10101757616 10495016960 1113072640 11.961 100 72 137 1 1218004352 91025472 13906240
05:43:27 4733 8192000000 10137529488 10531700736 1126137504 12.085 100 75 146 1 1251022848 109512272 15373072
05:44:36 991 8192000000 10187575488 10575843328 1131157312 12.132 100 91 176 1 1306011904 155979440 18875152
05:45:43 1274 8192000000 10239086544 10630795264 1136632736 12.184 100 89 168 4 1350137664 196141696 17363232
05:46:49 907 8192000000 10229976848 10638737408 1152877712 12.337 100 86 162 1 1341774752 172361504 16535536
05:47:54 1524 8192000000 10278752784 10681294848 1163669248 12.438 100 89 173 0 1388153792 204047968 20436576
05:49:00 1893 8192000000 10304437584 10706665472 1176229024 12.555 100 80 156 2 1412101584 218197328 17675232
05:50:05 725 8192000000 10345266288 10743877632 1181468160 12.604 100 90 169 1 1452638112 251974928 19195024
05:51:10 1652 8192000000 10369868640 10784776192 1190602272 12.689 100 89 176 1 1481102816 270155584 20344960
05:52:19 1478 8192000000 10412292128 10828419072 1194930544 12.729 100 83 164 0 1521887392 307477216 19479632
05:53:23 798 8192000000 10450764976 10868686848 1195625088 12.736 100 99 196 0 1561415888 341594272 24196528
05:54:27 2456 8192000000 10488670176 10910806016 1201748016 12.793 100 92 184 0 1596151424 372233040 22170368
05:55:33 828 8192000000 10513423920 10923626496 1200001712 12.776 100 100 193 1 1620000448 396717056 23281680
05:56:38 907 8192000000 10549656576 10956759040 1200379408 12.780 100 95 183 0 1656327024 432258768 23688848
05:57:43 1397 8192000000 10536501936 10966417408 1175188672 12.545 99 98 193 0 1640323888 442227024 22908192
05:58:47 1890 8192000000 10573579408 10988077056 1192660608 12.708 100 86 168 0 1680747200 466884320 21202272
05:59:52 2595 8192000000 10569572992 11049037824 1174656528 12.540 100 69 138 0 1692508464 500224336 17627600
06:01:05 931 8192000000 10577839872 11088179200 1185495104 12.641 100 93 186 0 1679814496 470833472 23485920
06:02:08 6590 8192000000 10473731728 10930753536 1167803504 12.476 100 61 118 0 1583003088 400831616 14367968
06:03:29 7760 8192000000 10487003184 10926592000 1183279696 12.621 100 54 108 1 1596480832 399723424 13477712
06:04:40 3870 8192000000 10498299504 10930425856 1171544272 12.511 99 61 123 0 1589735584 403583664 14607648
06:05:47 21812 8192000000 10449480000 10943623168 1175878656 12.552 100 39 77 2 1567484608 381982608 9623344
06:07:12 13007 8192000000 10494485776 10943401984 1163516896 12.436 100 27 54 0 1590737424 420158160 7062368
06:08:28 16441 8192000000 10368874624 10931519488 1159716688 12.401 99 29 58 1 1520592656 353529920 7346048
06:09:52 27507 8192000000 10298684656 10772004864 1157929776 12.384 100 24 48 0 1427856976 263682640 6244560
06:11:24 15753 8192000000 10183307152 10737582080 1145203312 12.264 99 32 63 0 1289832960 137868592 6761056
06:13:21 2311 8192000000 10183356016 10602446848 1157361024 12.379 100 74 137 1 1276485264 104418752 14705488
06:14:25 8068 8192000000 10204139280 10639302656 1156111472 12.367 100 60 107 3 1302922384 135358272 11452640
06:15:52 59672 8192000000 10085606928 10599403520 1148015056 12.291 100 9 18 0 1239493072 89541088 1936928
06:17:52 34683 8192000000 10085784560 10535288832 1116277360 11.992 98 55 100 2 1161289136 33997120 11014656
06:19:49 122091 8192000000 10073022992 10527674368 1105231760 11.887 97 19 35 3 1142057216 32368208 4457248
06:22:54 44721 8192000000 10122209136 10565627904 1135984768 12.178 99 24 46 0 1185557216 43872544 5699904
06:24:40 90066 8192000000 10066566816 10493423616 1115493920 11.984 98 25 43 1 1146246656 26586672 4166064
06:27:12 114059 8192000000 10104252976 10537709568 1119728768 12.024 97 18 36 0 1151168800 27791552 3648480
06:30:21 133239 8192000000 10116832800 10564964352 1145446576 12.267 99 37 73 1 1193823184 40738032 7638576
06:33:41 13610 8192000000 10074613984 10504617984 1128610048 12.108 98 59 107 1 1163486752 23315248 11561456
06:34:59 76379 8192000000 10078766896 10507378688 1096578832 11.805 96 44 82 1 1142456080 36505184 9372064
06:37:18 23414 8192000000 10094736512 10524798976 1143565168 12.249 100 25 50 0 1193903840 44927424 5411248
06:38:42 37581 8192000000 10106233456 10606227456 1139424912 12.210 99 45 90 1 1226095776 77378384 9292480
06:40:21 43728 8192000000 10092274752 10541166592 1128380464 12.106 98 15 27 1 1185050320 53625120 3044736
06:42:12 55963 8192000000 10150403472 10627637248 1156293072 12.369 100 24 48 0 1246696496 85102400 5301024
06:44:42 61980 8192000000 10144129136 10631221248 1119636784 12.024 97 25 49 1 1254792496 129736832 5418880
06:46:45 51900 8192000000 10075212624 10601897984 1125776320 12.082 99 14 28 1 1237514624 109027776 2710528
06:48:46 42309 8192000000 10122561456 10569428992 1123256240 12.058 98 28 55 2 1185633584 56546736 5830608
06:50:50 28161 8192000000 10100452448 10541404160 1133854160 12.158 99 39 78 0 1195931744 54433904 7643680
06:52:21 31738 8192000000 10074525408 10510151680 1068618352 11.539 94 43 84 0 1122042576 44741600 8682624
06:53:54 26027 8192000000 10126236640 10571988992 1137496352 12.192 100 37 74 1 1208717280 63680704 7540224
06:55:43 57599 8192000000 10119614512 10612162560 1120285680 12.030 98 14 28 0 1224042240 100542848 3213712
06:58:40 83316 8192000000 10082379904 10593787904 1109419408 11.927 97 17 33 0 1192317536 79331296 3566832
07:01:46 71321 8192000000 10074203616 10539032576 1120603680 12.033 99 31 61 0 1182188592 55890096 5694816
07:03:58 52957 8192000000 10058344784 10541350912 1111795776 11.949 98 22 44 0 1181466272 64509152 5161344
07:05:59 27565 8192000000 10108066816 10558451712 1139904256 12.215 100 44 86 1 1204681952 55045344 9732352
07:07:28 70618 8192000000 10117240688 10619424768 1127810784 12.101 98 11 22 0 1262773008 132581952 2380272
07:09:43 62816 8192000000 10140146880 10585604096 1131166528 12.132 99 23 45 2 1220765664 83940272 5658864
07:11:47 52075 8192000000 10154543888 10601132032 1128313040 12.105 98 27 51 2 1235993408 102383232 5297136
07:13:42 23418 8192000000 10174358144 10640072704 1161987440 12.422 100 24 48 0 1273934736 106428208 5519088
07:15:06 54967 8192000000 10117256480 10619756544 1130154368 12.123 99 24 46 1 1253280224 117047552 6078304
07:17:01 28932 8192000000 10111533024 10548166656 1105333664 11.888 97 31 62 0 1187871424 75655680 6882080
07:18:30 37755 8192000000 10088756032 10531094528 1111278768 11.945 99 35 70 1 1216595760 97778880 7538112
07:20:10 55790 8192000000 10110737888 10546610176 1122937088 12.055 99 38 76 1 1205122672 74577088 7608496
07:22:07 61338 8192000000 10100857584 10522357760 1126572736 12.089 99 37 72 1 1166109792 31893520 7643536
07:24:10 80333 8192000000 10093968704 10515947520 1144838784 12.261 100 73 141 4 1198531120 38734864 14957472
07:26:31 32322 8192000000 10096407600 10543972352 1151602624 12.325 100 30 60 2 1198845984 41227056 6016304
07:28:04 34145 8192000000 10077752768 10535063552 1133823536 12.157 99 14 29 1 1174654096 37866176 2964384
07:29:44 58607 8192000000 10113346848 10553335808 1125932528 12.083 98 17 34 1 1188842576 59084288 3825760
07:31:52 80115 8192000000 10107870224 10604969984 1107580160 11.910 98 14 28 0 1227786736 116846304 3360272
07:34:17 92651 8192000000 10081674800 10525384704 1115577872 11.985 99 23 46 0 1211287888 90602208 5107808
07:36:51 29516 8192000000 9150395440 9422241792 299025968 3.521 27 5 10 0 324258480 24252448 980064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 23-06-2022 07:38:37 to 23-06-2022 07:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-06-2022 07:38:55 to 23-06-2022 07:38:57.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          210589
MON$OLDEST_ACTIVE               212014
MON$OLDEST_SNAPSHOT             212014
MON$NEXT_TRANSACTION            212014
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-30 07:30:08.6030 GMT
MON$PAGES                       2072992
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             759
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2782 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2782 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:98: 
:99: mscs-test-161.budzdorov.ru    Thu Jun 23 07:37:27 2022
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:101: 
--- end of diff output ---

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

23.06.22 07:38:58. There are 3 logs in /var/tmp/logs-oltp40 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-06-23 07:39:00 No severe PSQL-related problems occured
23.06.22 07:39:01. End of report.