Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.12.2021 10: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 16718604 kB
MemAvailable 31505656 kB
Buffers 0 kB
Cached 14945552 kB
SwapCached 0 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-12-01 10:37:10
DTS_END                         2021-12-01 10:37:10.6900
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/53820
TRN_ID                          231028
ATT_ID                          608
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: 8703.49 21138 1044419
customer order: creation 167.76 391 20131
customer order: refuse 14.66 412 1759
order to supplier: creation 45.16 1397 5419
order to supplier: removal 3.42 108091 410
invoice (draft): creation 44.58 3819 5350
invoice (draft): removal 48.81 921 5857
invoice accept: apply 40.99 20162 4919
invoice accept: cancel 43.29 1003 5195
customer reserve: creation 3761.51 199 451381
customer reserve: removal 4256.27 68 510753
realization accept: apply 82.88 211 9946
realization accept: cancel 68.25 140 8190
payment from customer: creation 58.33 132 6999
payment from customer: removal 5.77 121 692
payment to supplier: creation 48.23 148 5787
payment to supplier: removal 3.12 143 374
service: total inventory turnovers 3.54 3991 425
service: total monetary turnovers 5.96 589 715
service: aggregate perf. data 0.94 13223 113
service: refresh index statistics 0.03 267600 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-12-2021 10:38:26 to 01-12-2021 10:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5016
TEST_TIME 2 3874
TEST_TIME 3 5072
TEST_TIME 4 6372
TEST_TIME 5 6331
TEST_TIME 6 6321
TEST_TIME 7 6427
TEST_TIME 8 6869
TEST_TIME 9 6978
TEST_TIME 10 6759
TEST_TIME 11 6713
TEST_TIME 12 6506
TEST_TIME 13 6469
TEST_TIME 14 6472
TEST_TIME 15 6505
TEST_TIME 16 6882
TEST_TIME 17 7380
TEST_TIME 18 7802
TEST_TIME 19 7957
TEST_TIME 20 8119
TEST_TIME 21 8688
TEST_TIME 22 9091
TEST_TIME 23 9001
TEST_TIME 24 9273
TEST_TIME 25 9098
TEST_TIME 26 9093
TEST_TIME 27 9187
TEST_TIME 28 9688
TEST_TIME 29 9674
TEST_TIME 30 9811
TEST_TIME 31 9871
TEST_TIME 32 9873
TEST_TIME 33 9741
TEST_TIME 34 9467
TEST_TIME 35 9228
TEST_TIME 36 9289
TEST_TIME 37 9301
TEST_TIME 38 9370
TEST_TIME 39 9413
TEST_TIME 40 9416
TEST_TIME 41 9350
TEST_TIME 42 9358
TEST_TIME 43 9224
TEST_TIME 44 9074
TEST_TIME 45 9159
TEST_TIME 46 9056
TEST_TIME 47 9055
TEST_TIME 48 8970
TEST_TIME 49 8912
TEST_TIME 50 8909
TEST_TIME 51 8882
TEST_TIME 52 8940
TEST_TIME 53 8896
TEST_TIME 54 8873
TEST_TIME 55 9039
TEST_TIME 56 9001
TEST_TIME 57 9035
TEST_TIME 58 8988
TEST_TIME 59 9135
TEST_TIME 60 9160
TEST_TIME 61 9154
TEST_TIME 62 9101
TEST_TIME 63 9143
TEST_TIME 64 9013
TEST_TIME 65 8949
TEST_TIME 66 8937
TEST_TIME 67 8891
TEST_TIME 68 8835
TEST_TIME 69 8772
TEST_TIME 70 8730
TEST_TIME 71 8653
TEST_TIME 72 8676
TEST_TIME 73 8665
TEST_TIME 74 8666
TEST_TIME 75 8759
TEST_TIME 76 8731
TEST_TIME 77 8752
TEST_TIME 78 8772
TEST_TIME 79 8748
TEST_TIME 80 8808
TEST_TIME 81 8886
TEST_TIME 82 8899
TEST_TIME 83 8917
TEST_TIME 84 8934
TEST_TIME 85 8866
TEST_TIME 86 8831
TEST_TIME 87 8797
TEST_TIME 88 8810
TEST_TIME 89 8766
TEST_TIME 90 8726
TEST_TIME 91 8711
TEST_TIME 92 8705
TEST_TIME 93 8698
TEST_TIME 94 8708
TEST_TIME 95 8689
TEST_TIME 96 8678
TEST_TIME 97 8705
TEST_TIME 98 8665
TEST_TIME 99 8741
TEST_TIME 100 8749
TEST_TIME 101 8788
TEST_TIME 102 8873
TEST_TIME 103 8992
TEST_TIME 104 8963
TEST_TIME 105 8989
TEST_TIME 106 8917
TEST_TIME 107 8894
TEST_TIME 108 8858
TEST_TIME 109 8852
TEST_TIME 110 8804
TEST_TIME 111 8772
TEST_TIME 112 8753
TEST_TIME 113 8721
TEST_TIME 114 8709
TEST_TIME 115 8721
TEST_TIME 116 8716
TEST_TIME 117 8717
TEST_TIME 118 8709
TEST_TIME 119 8778
TEST_TIME 120 8707

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-12-2021 10:38:30 to 01-12-2021 10:38:33.

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 1460924 1460924 0 0.00 0 29646 7 0 0
doc_list_biud 1460921 1460921 0 0.00 0 66126 31 0 0
sp_add_invoice_to_stock 5022 4919 103 2.05 29 209891 20163 0 103
sp_cancel_adding_invoice 5195 5195 0 0.00 6 72073 1003 0 0
sp_cancel_client_order 1759 1759 0 0.00 4 10584 412 0 0
sp_cancel_customer_reserve 510753 510753 0 0.00 1 66133 68 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 17010 122 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 3 7497 144 0 0
sp_cancel_supplier_invoice 5867 5857 10 0.17 3 14207 922 0 10
sp_cancel_supplier_order 410 410 0 0.00 7 1110157 108092 0 0
sp_cancel_write_off 8190 8190 0 0.00 1 12797 141 0 0
sp_client_order 20131 20131 0 0.00 4 56563 392 0 0
sp_customer_reserve 451381 451381 0 0.00 0 67227 200 0 0
sp_fill_shopping_cart 30896 30896 0 0.00 1 14213 47 0 0
sp_fill_shopping_cart_clo_res 451373 451373 0 0.00 0 16103 17 0 0
sp_get_clo_for_invoice 4911 4911 0 0.00 0 45686 367 0 0
sp_kill_cost_storno 14451 14451 0 0.00 0 16997 70 0 0
sp_kill_qstorno_ret_qs2qd 1035963 1035963 0 0.00 0 55535 37 0 0
sp_kill_qty_storno 547021 547021 0 0.00 0 66125 81 0 0
sp_lock_selected_doc 20590 20590 0 0.00 0 11185 6 0 0
sp_make_cost_storno 27643 27643 0 0.00 0 27170 66 0 0
sp_multiply_rows_for_pdistr 27643 27643 0 0.00 0 19052 40 0 0
sp_multiply_rows_for_qdistr 20131 20131 0 0.00 0 32260 198 0 0
sp_pay_from_customer 6999 6999 0 0.00 4 18958 132 0 0
sp_pay_to_supplier 5787 5787 0 0.00 5 21299 148 0 0
sp_payment_common 12786 12786 0 0.00 1 19018 31 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 10575 384 0 0
sp_qd_handle_on_invoice_upd_sts 10106 10106 0 0.00 0 49671 424 0 0
sp_qd_handle_on_reserve_upd_sts 9946 9946 0 0.00 0 14417 54 0 0
sp_reserve_write_off 9946 9946 0 0.00 7 19137 212 0 0
sp_supplier_invoice 5350 5350 0 0.00 6 93648 3819 0 0
sp_supplier_order 5419 5419 0 0.00 6 25945 1397 0 0
srv_aggregate_perf_data 113 113 0 0.00 2488 112110 13224 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 3676 154725 42811 0 0
srv_find_qd_qs_mism 997050 997050 0 0.00 0 24078 6 0 0
srv_make_invnt_saldo 425 425 0 0.00 66 121722 3991 0 0
srv_make_money_saldo 715 715 0 0.00 1 14882 590 0 0
srv_recalc_idx_stat 4 4 0 0.00 111994 409078 267600 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 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4919 4919 0 0.00 0 13003 183 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 16490 209 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 12612 26 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 8716 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 3747 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 247 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 16310 58 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 17961 1689 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 668 5 0 0
t$perf-norm:sp_client_order 20131 20131 0 0.00 0 21143 22 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 17802 28 0 0
t$perf-norm:sp_pay_from_customer 6999 6999 0 0.00 0 4714 9 0 0
t$perf-norm:sp_pay_to_supplier 5787 5787 0 0.00 0 6143 18 0 0
t$perf-norm:sp_reserve_write_off 9946 9946 0 0.00 0 21144 15 0 0
t$perf-norm:sp_supplier_invoice 5351 5351 0 0.00 0 10460 22 0 0
t$perf-norm:sp_supplier_order 5419 5419 0 0.00 0 11100 25 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 333 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 32 1 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 2674 13 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 1126 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10028 9822 206 2.05 0 6457 18 0 206
v_all_customers 36326 36326 0 0.00 0 76 1 0 0
v_all_suppliers 29808 29808 0 0.00 0 25 1 0 0
v_all_wares 40262 40262 0 0.00 0 141 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 1234 7 0 0
v_cancel_client_order 3518 3518 0 0.00 0 5921 7 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 2762 10 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 5956 8 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 7486 26 0 0
v_cancel_supplier_invoice 806 786 20 2.48 0 5586 21 0 20
v_cancel_supplier_order 820 820 0 0.00 0 7122 13 0 0
v_cancel_write_off 2028 2028 0 0.00 0 6222 18 0 0
v_max_id_clo_ord 5418 5418 0 0.00 0 2445 2 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 7864 19 0 0
v_max_id_ord_sup 5346 5346 0 0.00 0 5529 8 0 0
v_max_non_paid_invoice 1647 1647 0 0.00 0 132 2 0 0
v_max_non_paid_realizn 3915 3915 0 0.00 0 985 3 0 0
v_min_id_clo_ord 5418 5418 0 0.00 0 3709 10 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 7536 21 0 0
v_min_id_ord_sup 5346 5346 0 0.00 0 5538 13 0 0
v_min_non_paid_invoice 5787 5787 0 0.00 0 19012 18 0 0
v_min_non_paid_realizn 6999 6999 0 0.00 0 18905 29 0 0
v_our_firm 10104 10104 0 0.00 0 28 1 0 0
v_reserve_write_off 19892 19892 0 0.00 0 8155 8 0 0
x_lock_depdocs_on_canc_invoice 10659 10659 0 0.00 0 21651 120 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 15123 427 0 0
x_make_qstorno_1000_1200 5419 5419 0 0.00 2 25656 1310 0 0
x_make_qstorno_1200_2000 5350 5350 0 0.00 3 89339 3642 0 0
x_make_qstorno_2100_3300 451381 451381 0 0.00 0 67226 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-12-2021 10:38:34 to 01-12-2021 10:38:38.

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
08:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 37952 8192000000 9734606960 10033152000 805469776 8.952 100 88 176 1 834693856 7995136 21228944
08:09:56 33085 8192000000 9781172064 10089447424 844856736 9.349 100 89 179 1 880032128 14040848 21134544
08:11:34 20162 8192000000 9828329760 10152296448 885142192 9.751 100 78 156 0 931636720 29128320 17366208
08:13:03 8972 8192000000 9886219440 10221522944 928329888 10.178 100 84 167 1 997960784 51229488 18401408
08:14:15 8681 8192000000 9926353904 10269163520 955921216 10.449 100 84 168 0 1036611632 62982112 17708304
08:15:27 3708 8192000000 9954041088 10309787648 985940080 10.742 100 82 161 4 1067771456 64235296 17596080
08:16:33 3846 8192000000 9971084640 10340356096 1008730288 10.963 100 69 138 1 1089726032 67224336 13771408
08:17:39 2501 8192000000 10005037808 10368774144 1043505552 11.298 100 83 166 1 1112928656 51973616 17449488
08:18:46 2471 8192000000 10049304864 10421383168 1056395808 11.422 100 92 183 0 1160666624 85788352 18482464
08:19:52 5340 8192000000 10017344624 10390134784 1065719152 11.511 100 72 142 2 1139215136 58920640 14575344
08:20:59 5925 8192000000 9985344992 10384265216 1069264304 11.545 100 32 64 0 1124593184 48297696 7031184
08:22:06 6001 8192000000 9994897888 10380435456 1077821488 11.627 100 41 79 0 1105435984 18872032 8742464
08:23:14 7096 8192000000 10006194080 10403979264 1081852912 11.665 100 33 66 1 1125311088 36138320 7319856
08:24:23 29444 8192000000 10006393632 10408337408 1090352240 11.746 100 49 96 2 1115905312 15960320 9592752
08:25:59 5093 8192000000 10012631440 10403778560 1091408848 11.756 100 48 95 1 1112123472 9638000 11076624
08:27:09 6886 8192000000 10017422768 10411212800 1095317712 11.793 100 38 75 1 1127328240 23631792 8378736
08:28:17 66195 8192000000 9968831216 10363912192 982506416 10.709 90 40 79 0 1010366688 18534688 9325584
08:30:23 4998 8192000000 9943255120 10325037056 1003744208 10.915 96 48 93 2 1021109424 6691792 10673424
08:31:32 5803 8192000000 9973547136 10356461568 1016777536 11.041 99 58 117 0 1069891184 40245088 12868560
08:32:40 7485 8192000000 9938817232 10319687680 1015362000 11.027 99 34 68 1 1037548768 14995888 7190880
08:33:49 11470 8192000000 9971174096 10372173824 1016532224 11.039 99 39 79 0 1072072464 47865344 7674896
08:35:04 4349 8192000000 9961295232 10346319872 1038259552 11.248 100 53 106 2 1075032640 25605008 11168080
08:36:13 8620 8192000000 9992283184 10393182208 1062549936 11.481 100 46 90 0 1098276416 25654688 10071792
08:37:24 25500 8192000000 10005244512 10395824128 1086406544 11.708 100 39 75 3 1117419680 23486256 7526880
08:38:57 65469 8192000000 10045202608 10439163904 1097293328 11.812 100 36 72 0 1156165248 51373648 7498272
08:41:07 5488 8192000000 10088219152 10488283136 1117218016 12.001 100 44 88 3 1188783120 62383488 9181616
08:42:20 4943 8192000000 10104639632 10500919296 1130902592 12.130 100 61 121 1 1202266640 58365568 12998480
08:43:28 10169 8192000000 10136699056 10542354432 1139453712 12.210 100 65 122 2 1241813664 89358032 13001920
08:44:41 7286 8192000000 10155580064 10564980736 1153817664 12.345 100 58 116 1 1267509248 101374336 12317248
08:45:50 4048 8192000000 10178493152 10577584128 1156035808 12.366 100 78 153 1 1286819488 114598000 16185680
08:46:57 13762 8192000000 10185942768 10601132032 1168364560 12.482 100 53 106 2 1296954400 116818544 11771296
08:48:13 6235 8192000000 10200578416 10615902208 1170830800 12.505 100 49 98 0 1302473184 120411168 11231216
08:49:24 3819 8192000000 10241379728 10652143616 1167081744 12.470 99 58 114 2 1336736624 156306864 13348016
08:50:33 10197 8192000000 10298152016 10711646208 1183815808 12.626 100 61 122 0 1405103936 207592592 13695536
08:51:51 6422 8192000000 10337049760 10750459904 1188576768 12.670 100 69 136 1 1438958720 234535920 15846032
08:53:02 4561 8192000000 10358157408 10766790656 1180558880 12.595 100 78 156 0 1456446896 257618080 18269936
08:54:15 3766 8192000000 10381985408 10791432192 1163141648 12.433 99 74 146 0 1476588016 295862944 17583424
08:55:26 4327 8192000000 10280810288 10685050880 1121972768 12.046 99 77 154 1 1373708176 233761040 17974368
08:56:36 3676 8192000000 10288035968 10697383936 1122079056 12.047 98 75 149 1 1381192384 241744912 17368416
08:57:43 5337 8192000000 10277929392 10676695040 1131835328 12.139 100 76 152 0 1364378768 215589552 16953888
08:58:52 6211 8192000000 10240646880 10666856448 1138040864 12.197 100 63 126 1 1353532448 200599136 14892448
09:00:01 8721 8192000000 10187619568 10605383680 1116976816 11.998 100 31 62 1 1285270064 160028656 8264592
09:01:14 9262 8192000000 10192823328 10611023872 1122825616 12.054 100 40 79 1 1292772928 160863312 9084000
09:02:38 21270 8192000000 10193718560 10615508992 1119850112 12.026 100 21 42 0 1289064224 164619264 4594848
09:04:04 32976 8192000000 10199130528 10627899392 1097406144 11.813 98 20 40 0 1280615840 178654704 4554992
09:05:38 43595 8192000000 10105572432 10547507200 1107263776 11.907 99 30 60 0 1217192928 103104128 6825024
09:07:25 29150 8192000000 10116909808 10548170752 1125416288 12.078 100 21 42 1 1219918160 89742928 4758944
09:08:59 17366 8192000000 10094451360 10560319488 1127845104 12.101 100 16 32 1 1194200288 62946336 3408848
09:10:32 154722 8192000000 10156294864 10596466688 1144795328 12.261 100 40 78 2 1248284816 94043360 9446128
09:14:20 73314 8192000000 10174874032 10655637504 1144855232 12.261 99 26 52 0 1264553328 114128064 5570032
09:16:34 77108 8192000000 10048532800 10489749504 1088052592 11.724 95 11 22 0 1132191200 41625632 2512976
09:19:25 44281 8192000000 10050254544 10491645952 1121157600 12.038 99 13 26 0 1150060032 26543408 2359024
09:21:10 97193 8192000000 10069775616 10534772736 1127922176 12.102 99 29 58 1 1210465024 76361744 6181104
09:23:56 48070 8192000000 10086146976 10572910592 1142737264 12.241 100 14 28 1 1196283584 50706144 2840176
09:26:06 31914 8192000000 10104342672 10550161408 1145496192 12.267 99 33 66 0 1210240928 57504512 7240224
09:27:40 59015 8192000000 10113160288 10598203392 1163337872 12.435 100 16 32 0 1237106288 70139568 3628848
09:29:39 53890 8192000000 10085217760 10539585536 1147936256 12.290 99 16 32 0 1178594304 26866224 3791824
09:31:33 50961 8192000000 10151653968 10623328256 1155350032 12.360 99 21 42 0 1231285328 71529200 4406096
09:33:29 19841 8192000000 10121189680 10568794112 1166892704 12.468 100 32 64 1 1199981648 26615280 6473664
09:34:50 48854 8192000000 10126462800 10603327488 1156301680 12.369 99 13 26 0 1232966064 73795088 2869296
09:36:39 96683 8192000000 10094990432 10534391808 1120463824 12.031 96 16 31 0 1168250544 44796848 2989872
09:39:23 14174 8192000000 10119567552 10548948992 1150995600 12.319 100 57 114 1 1213590880 50803328 11791952
09:40:44 79213 8192000000 10082144032 10517225472 1111593280 11.947 98 34 66 1 1179056320 60241440 7221600
09:43:18 10089 8192000000 10065718192 10523725824 1124601056 12.070 100 30 60 1 1170558528 39616432 6341040
09:44:29 32834 8192000000 10017067024 10456498176 1096327984 11.803 98 5 10 0 1118981216 21495616 1157616
09:46:03 50474 8192000000 10041820144 10476806144 1072836144 11.579 96 10 20 0 1105943952 30851632 2256176
09:48:06 26660 8192000000 10067860960 10519105536 1100336768 11.841 99 11 22 0 1175321136 72416128 2568240
09:49:33 54145 8192000000 10062380944 10528673792 1093297504 11.774 98 13 26 0 1150315168 54204736 2812928
09:51:29 44987 8192000000 10100609488 10529001472 1083240800 11.678 97 27 54 1 1152934400 63929664 5763936
09:53:18 43458 8192000000 10069300704 10491604992 1092786800 11.769 98 16 32 0 1129561248 33555376 3219072
09:55:01 58612 8192000000 10069083568 10503172096 1098438064 11.823 99 11 22 1 1143714400 42955840 2320496
09:57:00 47464 8192000000 10126546448 10586587136 1116905968 11.998 99 22 44 1 1222679216 100825888 4947360
09:58:54 53301 8192000000 10094128448 10553454592 1116181968 11.991 99 20 40 1 1150594768 30554128 3858672
10:00:48 74648 8192000000 10063318608 10538561536 1091872016 11.760 97 10 20 0 1139375568 45550768 1952784
10:03:05 88743 8192000000 10060824944 10492223488 1093701584 11.778 97 28 55 0 1130855648 31953072 5200992
10:05:35 74169 8192000000 10049022768 10536120320 1121290704 12.039 100 4 8 0 1179838096 57759712 787680
10:07:50 68473 8192000000 10039899056 10509697024 1108861968 11.922 98 12 24 0 1143813728 32601552 2350208
10:10:05 77257 8192000000 10082284464 10542133248 1119233168 12.020 98 12 22 1 1185378576 64091888 2053520
10:12:28 56780 8192000000 10123883984 10561355776 1139903200 12.215 99 29 58 0 1192451040 46067808 6480032
10:14:32 46396 8192000000 10162456304 10605924352 1155146160 12.358 100 24 48 1 1258067904 97328448 5593296
10:16:21 47210 8192000000 10204659712 10692362240 1161538848 12.418 100 34 68 1 1338204768 168770688 7895232
10:18:15 60902 8192000000 10207266528 10656874496 1155470640 12.361 99 32 64 0 1278426704 115511712 7444352
10:20:17 67909 8192000000 10105063344 10528317440 1137383472 12.191 98 23 46 3 1184293744 41469728 5440544
10:22:54 122581 8192000000 10082959584 10541060096 1122127616 12.047 97 30 60 0 1178661664 50903920 5630128
10:26:21 44595 8192000000 10072740784 10534416384 1146660448 12.278 100 15 30 1 1190638816 40881744 3096624
10:28:06 45790 8192000000 10071689952 10541654016 1145339776 12.266 100 5 10 0 1178096928 31884688 872464
10:29:54 85703 8192000000 10096500096 10593603584 1155228176 12.359 100 15 30 0 1205256208 46441888 3586144
10:32:24 65987 8192000000 10051644048 10508677120 1093322272 11.774 95 18 36 1 1137504704 40353328 3829104
10:34:31 73186 8192000000 10126292976 10588053504 1115962464 11.989 98 30 60 1 1192273040 70063504 6247072
10:36:44 43113 8192000000 9045388208 9326518272 196395440 2.341 18 3 6 0 212772704 16052832 324432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-12-2021 10:38:40 to 01-12-2021 10:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 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 01-12-2021 10:38:56 to 01-12-2021 10:38:58.

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          231646
MON$OLDEST_ACTIVE               231647
MON$OLDEST_SNAPSHOT             231647
MON$NEXT_TRANSACTION            231647
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                       2037984
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2672 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2672 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2672 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2672 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 ---
--- end of diff output ---

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

01.12.21 10:38:59. 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-12-01 10:39:01 No severe PSQL-related problems occured
01.12.21 10:39:02. End of report.