Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.09.2021 07:51
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16185936 kB
MemAvailable 30855024 kB
Buffers 0 kB
Cached 15553340 kB
SwapCached 480 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.1
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 2021-09-19 07:49:28
DTS_END                         2021-09-19 07:49:28.1790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45540
TRN_ID                          226270
ATT_ID                          672
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: 8867.94 29151 1064153
customer order: creation 164.76 427 19771
customer order: refuse 15.08 368 1810
order to supplier: creation 46.05 1422 5526
order to supplier: removal 3.47 130060 416
invoice (draft): creation 45.33 3755 5439
invoice (draft): removal 50.70 1146 6084
invoice accept: apply 40.53 21608 4863
invoice accept: cancel 45.21 957 5425
customer reserve: creation 3735.93 213 448311
customer reserve: removal 4439.67 78 532760
realization accept: apply 81.28 223 9753
realization accept: cancel 73.33 145 8799
payment from customer: creation 58.01 120 6961
payment from customer: removal 5.57 95 668
payment to supplier: creation 48.73 143 5847
payment to supplier: removal 3.43 149 412
service: total inventory turnovers 3.57 4174 428
service: total monetary turnovers 6.31 595 757
service: aggregate perf. data 0.99 15337 119
service: refresh index statistics 0.03 401995 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-09-2021 07:51:50 to 19-09-2021 07:51:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4589
TEST_TIME 2 4490
TEST_TIME 3 5253
TEST_TIME 4 6021
TEST_TIME 5 5955
TEST_TIME 6 5538
TEST_TIME 7 6096
TEST_TIME 8 5932
TEST_TIME 9 6040
TEST_TIME 10 5947
TEST_TIME 11 5824
TEST_TIME 12 5888
TEST_TIME 13 6178
TEST_TIME 14 6393
TEST_TIME 15 6445
TEST_TIME 16 6656
TEST_TIME 17 6617
TEST_TIME 18 7025
TEST_TIME 19 7243
TEST_TIME 20 7311
TEST_TIME 21 7528
TEST_TIME 22 7553
TEST_TIME 23 7712
TEST_TIME 24 7987
TEST_TIME 25 7884
TEST_TIME 26 8047
TEST_TIME 27 8115
TEST_TIME 28 8434
TEST_TIME 29 8673
TEST_TIME 30 9497
TEST_TIME 31 10171
TEST_TIME 32 10092
TEST_TIME 33 10214
TEST_TIME 34 10058
TEST_TIME 35 9888
TEST_TIME 36 9686
TEST_TIME 37 9550
TEST_TIME 38 9429
TEST_TIME 39 9467
TEST_TIME 40 9482
TEST_TIME 41 9550
TEST_TIME 42 9555
TEST_TIME 43 9474
TEST_TIME 44 9371
TEST_TIME 45 9210
TEST_TIME 46 9341
TEST_TIME 47 9263
TEST_TIME 48 9294
TEST_TIME 49 9197
TEST_TIME 50 9284
TEST_TIME 51 9265
TEST_TIME 52 9283
TEST_TIME 53 9246
TEST_TIME 54 9294
TEST_TIME 55 9445
TEST_TIME 56 9496
TEST_TIME 57 9543
TEST_TIME 58 9469
TEST_TIME 59 9614
TEST_TIME 60 9584
TEST_TIME 61 9479
TEST_TIME 62 9419
TEST_TIME 63 9357
TEST_TIME 64 9355
TEST_TIME 65 9287
TEST_TIME 66 9218
TEST_TIME 67 9150
TEST_TIME 68 9093
TEST_TIME 69 9102
TEST_TIME 70 9081
TEST_TIME 71 9099
TEST_TIME 72 9071
TEST_TIME 73 9051
TEST_TIME 74 9048
TEST_TIME 75 9167
TEST_TIME 76 9196
TEST_TIME 77 9225
TEST_TIME 78 9202
TEST_TIME 79 9245
TEST_TIME 80 9301
TEST_TIME 81 9246
TEST_TIME 82 9185
TEST_TIME 83 9128
TEST_TIME 84 9122
TEST_TIME 85 9081
TEST_TIME 86 9014
TEST_TIME 87 8998
TEST_TIME 88 8972
TEST_TIME 89 8982
TEST_TIME 90 8957
TEST_TIME 91 8947
TEST_TIME 92 8917
TEST_TIME 93 9007
TEST_TIME 94 9015
TEST_TIME 95 9003
TEST_TIME 96 9050
TEST_TIME 97 9037
TEST_TIME 98 9055
TEST_TIME 99 9038
TEST_TIME 100 9101
TEST_TIME 101 9058
TEST_TIME 102 8978
TEST_TIME 103 8974
TEST_TIME 104 8921
TEST_TIME 105 8910
TEST_TIME 106 8870
TEST_TIME 107 8915
TEST_TIME 108 8920
TEST_TIME 109 8918
TEST_TIME 110 8903
TEST_TIME 111 8972
TEST_TIME 112 8962
TEST_TIME 113 8974
TEST_TIME 114 8985
TEST_TIME 115 9023
TEST_TIME 116 9022
TEST_TIME 117 8975
TEST_TIME 118 8973
TEST_TIME 119 8917
TEST_TIME 120 8875

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-09-2021 07:51:54 to 19-09-2021 07:51:57.

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 1479766 1479766 0 0.00 0 20512 8 0 0
doc_list_biud 1479766 1479766 0 0.00 0 27595 36 0 0
sp_add_invoice_to_stock 4989 4863 126 2.52 31 230144 21608 0 126
sp_cancel_adding_invoice 5425 5425 0 0.00 4 78257 957 0 0
sp_cancel_client_order 1810 1810 0 0.00 5 9586 369 0 0
sp_cancel_customer_reserve 532760 532760 0 0.00 1 23557 79 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 2 2839 95 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 16408 150 0 0
sp_cancel_supplier_invoice 6098 6084 14 0.22 2 27628 1147 0 14
sp_cancel_supplier_order 416 416 0 0.00 12 1199172 130061 0 0
sp_cancel_write_off 8799 8799 0 0.00 1 14855 146 0 0
sp_client_order 19771 19771 0 0.00 4 36446 428 0 0
sp_customer_reserve 448311 448311 0 0.00 0 42026 213 0 0
sp_fill_shopping_cart 30723 30723 0 0.00 1 20254 45 0 0
sp_fill_shopping_cart_clo_res 448303 448303 0 0.00 0 19082 17 0 0
sp_get_clo_for_invoice 4851 4851 0 0.00 0 36220 366 0 0
sp_kill_cost_storno 15304 15304 0 0.00 0 7601 76 0 0
sp_kill_qstorno_ret_qs2qd 1080819 1080819 0 0.00 0 27593 43 0 0
sp_kill_qty_storno 569897 569897 0 0.00 0 27595 91 0 0
sp_lock_selected_doc 20401 20401 0 0.00 0 9307 8 0 0
sp_make_cost_storno 27411 27411 0 0.00 0 20488 68 0 0
sp_multiply_rows_for_pdistr 27411 27411 0 0.00 0 11429 39 0 0
sp_multiply_rows_for_qdistr 19771 19771 0 0.00 0 21625 217 0 0
sp_pay_from_customer 6961 6961 0 0.00 5 11436 120 0 0
sp_pay_to_supplier 5847 5847 0 0.00 4 16683 144 0 0
sp_payment_common 12808 12808 0 0.00 1 8108 20 0 0
sp_qd_handle_on_cancel_clo 1810 1810 0 0.00 0 9579 328 0 0
sp_qd_handle_on_invoice_upd_sts 10275 10275 0 0.00 0 17842 392 0 0
sp_qd_handle_on_reserve_upd_sts 9753 9753 0 0.00 0 17509 58 0 0
sp_reserve_write_off 9753 9753 0 0.00 7 17531 224 0 0
sp_supplier_invoice 5439 5439 0 0.00 8 97092 3756 0 0
sp_supplier_order 5526 5526 0 0.00 6 30802 1422 0 0
srv_aggregate_perf_data 119 119 0 0.00 2652 157747 15338 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1715 191079 43788 0 0
srv_find_qd_qs_mism 1017897 1017897 0 0.00 0 14512 7 0 0
srv_make_invnt_saldo 428 428 0 0.00 60 108954 4174 0 0
srv_make_money_saldo 757 757 0 0.00 1 15275 596 0 0
srv_recalc_idx_stat 4 4 0 0.00 183970 733982 401995 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4863 4863 0 0.00 0 21021 207 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 5286 131 0 0
t$perf-norm:sp_cancel_client_order 1810 1810 0 0.00 0 2017 13 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 2992 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 3271 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 3640 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 889 21 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 38081 2003 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 2952 13 0 0
t$perf-norm:sp_client_order 19771 19771 0 0.00 0 19235 22 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 1542 6 0 0
t$perf-norm:sp_pay_from_customer 6961 6961 0 0.00 0 15946 15 0 0
t$perf-norm:sp_pay_to_supplier 5847 5847 0 0.00 0 4894 12 0 0
t$perf-norm:sp_reserve_write_off 9753 9753 0 0.00 0 18018 16 0 0
t$perf-norm:sp_supplier_invoice 5439 5439 0 0.00 0 4805 19 0 0
t$perf-norm:sp_supplier_order 5526 5526 0 0.00 0 4192 16 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 2878 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 298 12 0 0
t$perf-norm:srv_make_invnt_saldo 428 428 0 0.00 0 2563 17 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 3189 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 36 13 0 0
v_add_invoice_to_stock 9946 9694 252 2.53 0 4514 19 0 252
v_all_customers 36238 36238 0 0.00 0 116 1 0 0
v_all_suppliers 30728 30728 0 0.00 0 116 1 0 0
v_all_wares 39542 39542 0 0.00 0 113 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 226 7 0 0
v_cancel_client_order 3620 3620 0 0.00 0 3388 8 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 2736 9 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 3666 6 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 4936 18 0 0
v_cancel_supplier_invoice 790 762 28 3.54 0 2622 19 0 28
v_cancel_supplier_order 832 832 0 0.00 0 1620 9 0 0
v_cancel_write_off 2084 2084 0 0.00 0 5676 18 0 0
v_max_id_clo_ord 5526 5526 0 0.00 0 1513 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 4804 19 0 0
v_max_id_ord_sup 5426 5426 0 0.00 0 3818 3 0 0
v_max_non_paid_invoice 1435 1435 0 0.00 0 201 2 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 2889 4 0 0
v_min_id_clo_ord 5526 5526 0 0.00 0 2695 7 0 0
v_min_id_clo_res 989 989 0 0.00 0 4568 10 0 0
v_min_id_ord_sup 5426 5426 0 0.00 0 7446 10 0 0
v_min_non_paid_invoice 5847 5847 0 0.00 0 5404 14 0 0
v_min_non_paid_realizn 6961 6961 0 0.00 0 8105 13 0 0
v_our_firm 9934 9934 0 0.00 0 115 1 0 0
v_reserve_write_off 19506 19506 0 0.00 0 3821 7 0 0
x_lock_depdocs_on_canc_invoice 11128 11128 0 0.00 0 15918 135 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 12985 454 0 0
x_make_qstorno_1000_1200 5526 5526 0 0.00 2 30792 1335 0 0
x_make_qstorno_1200_2000 5439 5439 0 0.00 4 96887 3591 0 0
x_make_qstorno_2100_3300 448311 448311 0 0.00 0 42022 193 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-09-2021 07:51:58 to 19-09-2021 07:52:02.

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:19:31 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:20:32 27092 8192000000 9725800304 10023604224 800961808 8.906 100 89 178 0 830884864 8070896 21852160
05:22:09 21652 8192000000 9766005344 10074304512 840040848 9.300 100 89 178 1 872696128 11954208 20701072
05:23:36 15382 8192000000 9808049584 10124574720 869332864 9.593 100 83 166 0 912879632 24539840 19006928
05:25:00 13550 8192000000 9867771552 10199478272 915187696 10.049 100 83 166 0 978647328 45539120 17920512
05:26:16 8365 8192000000 9919490944 10263920640 959648880 10.486 100 80 161 0 1031227520 55231264 16347376
05:27:27 5553 8192000000 9966628336 10323075072 995738624 10.837 100 81 162 0 1080411936 68566464 16106848
05:28:37 6133 8192000000 9969559584 10332463104 1024520928 11.116 100 72 144 0 1083857504 45147488 14189088
05:29:46 2574 8192000000 10035157680 10399989760 1043179616 11.295 100 90 180 0 1149007136 87770576 18056944
05:30:51 1860 8192000000 10049967600 10418298880 1055890160 11.417 100 87 174 2 1156604832 82705712 18008960
05:31:58 2673 8192000000 10051516720 10421891072 1069417680 11.547 100 91 182 0 1161124464 72444560 19262224
05:33:02 5919 8192000000 10069081328 10447781888 1069568272 11.548 100 79 158 0 1177447248 90940896 16938080
05:34:09 5988 8192000000 10002945584 10406875136 1077113024 11.620 100 27 54 1 1120017696 37401696 5502976
05:35:17 28809 8192000000 10007633360 10397810688 1085460736 11.699 100 63 125 1 1114537968 15830096 13247136
05:36:47 5067 8192000000 10007596576 10398081024 1085727008 11.702 100 36 72 2 1123174384 29907552 7539824
05:37:53 7783 8192000000 10000941568 10405064704 1088614544 11.729 100 26 52 1 1114580992 20411952 5554496
05:39:01 9948 8192000000 10009943968 10429198336 1090495472 11.747 100 23 46 2 1127959712 33197200 4267040
05:40:16 10158 8192000000 10011823120 10419101696 1080204816 11.649 99 51 94 1 1114698144 22872464 11620864
05:41:30 8805 8192000000 9995282656 10399887360 1067236528 11.526 98 29 58 0 1115993808 42750096 6007184
05:42:40 6142 8192000000 9953990240 10328526848 1027417952 11.144 99 54 108 1 1052529680 13751136 11360592
05:43:47 15967 8192000000 9910834272 10296168448 924199152 10.137 90 30 59 2 945126160 13734000 7193008
05:45:04 16451 8192000000 9930699120 10319249408 976971488 10.655 96 21 42 0 1003061696 21864320 4225888
05:46:22 4609 8192000000 9954252192 10327662592 1032771792 11.195 100 64 129 1 1058962672 11006048 15184832
05:47:30 5125 8192000000 9949784896 10331619328 1040797904 11.272 100 39 79 0 1065992080 15919392 9274784
05:48:36 58830 8192000000 9970010272 10367635456 1048351152 11.345 100 31 62 0 1075943136 21332592 6259392
05:50:50 3551 8192000000 10036639792 10427117568 1073829296 11.589 100 56 112 0 1139577888 53857984 11890608
05:51:55 29827 8192000000 10071713616 10467209216 1082688352 11.673 100 49 98 0 1176775776 84137552 9949872
05:53:31 15187 8192000000 10063888800 10465632256 1099765584 11.835 100 49 97 1 1168053920 56823488 11464848
05:54:54 55854 8192000000 10115063456 10528493568 1112136176 11.953 100 33 66 0 1230111600 109680192 8295232
05:56:52 8310 8192000000 10100281200 10519072768 1128845648 12.110 100 42 84 1 1214021664 75876352 9299664
05:58:06 6979 8192000000 10159975856 10564333568 1140959664 12.225 100 60 119 1 1265679440 112091552 12628224
05:59:16 18219 8192000000 10170558032 10586177536 1153707488 12.344 100 52 102 2 1284853824 120195536 10950800
06:00:45 11682 8192000000 10258040080 10667249664 1168359056 12.481 100 68 136 1 1355735136 172878592 14497488
06:02:05 10016 8192000000 10281343552 10689126400 1183074208 12.619 100 69 138 0 1383408032 185109040 15224784
06:03:22 2700 8192000000 10307755536 10717126656 1191975936 12.702 100 83 166 0 1411514896 201771520 17767440
06:04:31 6566 8192000000 10336251008 10748559360 1190989184 12.693 100 80 159 2 1441431072 232616048 17825840
06:05:46 3201 8192000000 10343011648 10756460544 1188867328 12.673 100 90 176 1 1446073616 237304608 19901680
06:06:53 2270 8192000000 10389076592 10818207744 1196493088 12.744 100 70 136 1 1488080880 275875616 15712176
06:08:00 2745 8192000000 10379773600 10794971136 1185884576 12.645 100 85 168 2 1485790736 281027600 18878560
06:09:09 4185 8192000000 10383334432 10801094656 1185434384 12.641 100 87 174 0 1481092816 275975168 19683264
06:10:20 3017 8192000000 10384823584 10799501312 1195246304 12.732 100 89 179 1 1482496384 266164032 21086048
06:11:30 1713 8192000000 10422300048 10825900032 1189234224 12.676 100 92 186 1 1514165008 302400640 22530144
06:12:37 6997 8192000000 10484708336 10918002688 1189780608 12.681 100 62 122 0 1583365344 378293408 15291328
06:14:08 12823 8192000000 10473907376 10925207552 1174320864 12.537 100 41 82 0 1563886640 378880624 10685152
06:15:40 17650 8192000000 10472302912 10928783360 1144838720 12.261 98 41 82 1 1545926048 390594048 10493280
06:17:12 14531 8192000000 10459163280 10931945472 1135061776 12.169 100 32 64 0 1562056192 418169600 8824816
06:19:52 50049 8192000000 10038465136 10493411328 1071722576 11.569 96 16 32 0 1213808960 138329856 3756528
06:21:54 56807 8192000000 10071352096 10564243456 1116142112 11.991 100 11 22 0 1182883584 64572768 2168704
06:23:54 73774 8192000000 10096887552 10549747712 1128599552 12.108 99 28 56 1 1173219952 38767968 5852432
06:26:35 46074 8192000000 10125904400 10568413184 1112598864 11.957 98 34 68 1 1217370112 97475200 7296048
06:28:43 52728 8192000000 10097517392 10538397696 1116752672 11.996 100 20 40 0 1210849200 89241664 4854864
06:30:37 15672 8192000000 10079925104 10520694784 1104839456 11.884 98 43 86 0 1178089472 64381664 8868352
06:31:53 11242 8192000000 10055846032 10527408128 1121777472 12.044 99 15 30 0 1165694832 41112704 2804656
06:33:06 50608 8192000000 10114700480 10566520832 1140275280 12.218 100 18 36 1 1228600432 84168128 4157024
06:34:58 42753 8192000000 10128754624 10621927424 1143011424 12.244 100 15 30 0 1246800912 100763392 3026096
06:37:39 53063 8192000000 10083777616 10521563136 1109427696 11.927 97 24 48 0 1156383680 42177456 4778528
06:39:34 61885 8192000000 10087539456 10535202816 1102026288 11.857 96 21 42 0 1170256448 64002208 4227952
06:41:37 26008 8192000000 10116696720 10560151552 1119358416 12.021 97 28 56 2 1186780864 61503392 5919056
06:43:03 44952 8192000000 10172877600 10612629504 1146794048 12.279 100 23 46 0 1253116240 101349776 4972416
06:45:22 77442 8192000000 10096214048 10590806016 1125432832 12.078 98 20 40 0 1221262176 91726656 4102688
06:47:45 52528 8192000000 10170497136 10647633920 1164514240 12.446 100 42 84 0 1255728256 82540384 8673632
06:50:01 133374 8192000000 10097804352 10567516160 1128473312 12.107 97 33 66 0 1171384256 35816640 7094304
06:53:16 51215 8192000000 10062595696 10514243584 1104914416 11.884 96 16 32 0 1140176720 32246320 3015984
06:55:08 68253 8192000000 10077792336 10542755840 1136296848 12.181 99 12 25 0 1193058016 53919072 2842096
06:57:19 55383 8192000000 10091056672 10549882880 1127324160 12.096 98 11 22 0 1181048560 51338448 2385952
06:59:16 30940 8192000000 10071254336 10518151168 1131796304 12.138 98 24 48 0 1155975856 19343248 4836304
07:00:48 55545 8192000000 10103633632 10551197696 1073096144 11.582 93 17 34 0 1124501344 48142832 3262368
07:02:45 19294 8192000000 10152485616 10603589632 1127109872 12.094 99 23 46 1 1230392800 97970672 5312256
07:04:05 53154 8192000000 10111473920 10578542592 1101545008 11.852 97 11 22 0 1171648976 68045952 2058016
07:06:06 48999 8192000000 10069908688 10528051200 1118136224 12.009 98 16 32 0 1168489264 47491792 2861248
07:07:58 53376 8192000000 10116576960 10597470208 1093580960 11.777 96 31 61 0 1187707376 87945248 6181168
07:10:59 133229 8192000000 10074459504 10535755776 1124469424 12.069 100 33 66 2 1183134352 51563920 7101008
07:14:25 62074 8192000000 10066624560 10527326208 1088006800 11.724 97 10 19 0 1150740640 60558304 2175536
07:16:36 56388 8192000000 10056944016 10531467264 1104474176 11.880 99 8 17 0 1147164560 41118176 1572208
07:18:36 69241 8192000000 10064776816 10519433216 1107887088 11.912 98 6 11 1 1163346944 54333808 1126048
07:20:50 40186 8192000000 10118723824 10570625024 1127694096 12.100 98 20 40 1 1212542016 80756992 4090928
07:22:40 52324 8192000000 10071822880 10531241984 1143914224 12.252 100 16 32 0 1210893792 63628400 3351168
07:24:36 70781 8192000000 10099865648 10570215424 1136554176 12.183 99 34 68 3 1196087760 52877856 6655728
07:26:52 54035 8192000000 10090628768 10588987392 1117466864 12.003 98 35 70 1 1191369376 66823696 7078816
07:28:47 14750 8192000000 10109127024 10540806144 1131802016 12.138 100 54 108 1 1204617920 62158752 10657152
07:30:05 97961 8192000000 10070839792 10530471936 1126745600 12.091 99 26 51 1 1182793872 50671504 5376768
07:32:44 54145 8192000000 10106855216 10582732800 1141248960 12.227 100 12 24 0 1211471728 67748960 2473808
07:34:44 46104 8192000000 10114229424 10578542592 1127808256 12.101 98 10 20 1 1191347776 61507936 2031584
07:36:45 67953 8192000000 10084470096 10542133248 1136544848 12.183 99 21 42 2 1184668992 43950336 4173808
07:38:56 59469 8192000000 10124368032 10594459648 1134849824 12.167 99 19 38 2 1207161040 68456688 3854528
07:41:01 71432 8192000000 10117332288 10573238272 1123763088 12.063 98 19 38 0 1169665408 42100192 3802128
07:43:15 191078 8192000000 10108829344 10581082112 1055207408 11.411 92 8 16 0 1083917568 27241424 1468736
07:47:27 79179 8192000000 10058885328 10503626752 1125840032 12.082 99 22 44 0 1164206848 33701536 4665280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-09-2021 07:52:04 to 19-09-2021 07:52:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-09-2021 07:52:20 to 19-09-2021 07:52:22.

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          224692
MON$OLDEST_ACTIVE               227558
MON$OLDEST_SNAPSHOT             227558
MON$NEXT_TRANSACTION            227558
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                       2025568
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2605 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2605 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2605 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2605 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sun Sep 19 07:49:31 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sun Sep 19 07:49:42 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sun Sep 19 07:49:49 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

19.09.21 07:52:23. 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
2021-09-19 07:52:25 No severe PSQL-related problems occured
19.09.21 07:52:27. End of report.