Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.01.2021 04:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16478744 kB
MemAvailable 31221756 kB
Buffers 20 kB
Cached 15013532 kB
SwapCached 4864 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.0
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-01-10 04:26:54
DTS_END                         2021-01-10 04:26:54.8920
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/45954
TRN_ID                          227246
ATT_ID                          665
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: 8988.41 22844 1078609
customer order: creation 167.15 440 20058
customer order: refuse 14.76 394 1771
order to supplier: creation 44.58 1539 5350
order to supplier: removal 3.34 123390 401
invoice (draft): creation 45.17 3563 5420
invoice (draft): removal 51.11 978 6133
invoice accept: apply 40.99 19712 4919
invoice accept: cancel 46.04 907 5525
customer reserve: creation 3755.15 195 450618
customer reserve: removal 4531.67 72 543800
realization accept: apply 80.28 195 9634
realization accept: cancel 82.51 128 9901
payment from customer: creation 57.78 130 6934
payment from customer: removal 6.06 91 727
payment to supplier: creation 47.47 128 5696
payment to supplier: removal 3.28 87 394
service: total inventory turnovers 3.80 3897 456
service: total monetary turnovers 6.26 611 751
service: aggregate perf. data 0.98 14509 117
service: refresh index statistics 0.03 285914 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-01-2021 04:27:40 to 10-01-2021 04:27:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4171
TEST_TIME 2 3866
TEST_TIME 3 4527
TEST_TIME 4 4710
TEST_TIME 5 5112
TEST_TIME 6 5130
TEST_TIME 7 4863
TEST_TIME 8 5032
TEST_TIME 9 4910
TEST_TIME 10 5151
TEST_TIME 11 5599
TEST_TIME 12 5635
TEST_TIME 13 5870
TEST_TIME 14 5842
TEST_TIME 15 5839
TEST_TIME 16 5949
TEST_TIME 17 6403
TEST_TIME 18 6549
TEST_TIME 19 7001
TEST_TIME 20 7603
TEST_TIME 21 7697
TEST_TIME 22 7931
TEST_TIME 23 8025
TEST_TIME 24 8371
TEST_TIME 25 8932
TEST_TIME 26 8720
TEST_TIME 27 8982
TEST_TIME 28 9504
TEST_TIME 29 9808
TEST_TIME 30 10275
TEST_TIME 31 10150
TEST_TIME 32 10323
TEST_TIME 33 10633
TEST_TIME 34 10586
TEST_TIME 35 10698
TEST_TIME 36 10625
TEST_TIME 37 10540
TEST_TIME 38 10361
TEST_TIME 39 10274
TEST_TIME 40 10107
TEST_TIME 41 10040
TEST_TIME 42 9881
TEST_TIME 43 9865
TEST_TIME 44 9768
TEST_TIME 45 9673
TEST_TIME 46 9599
TEST_TIME 47 9588
TEST_TIME 48 9463
TEST_TIME 49 9398
TEST_TIME 50 9408
TEST_TIME 51 9397
TEST_TIME 52 9312
TEST_TIME 53 9281
TEST_TIME 54 9315
TEST_TIME 55 9357
TEST_TIME 56 9285
TEST_TIME 57 9252
TEST_TIME 58 9396
TEST_TIME 59 9357
TEST_TIME 60 9419
TEST_TIME 61 9386
TEST_TIME 62 9549
TEST_TIME 63 9570
TEST_TIME 64 9536
TEST_TIME 65 9590
TEST_TIME 66 9556
TEST_TIME 67 9571
TEST_TIME 68 9479
TEST_TIME 69 9377
TEST_TIME 70 9385
TEST_TIME 71 9290
TEST_TIME 72 9225
TEST_TIME 73 9135
TEST_TIME 74 9057
TEST_TIME 75 9111
TEST_TIME 76 9093
TEST_TIME 77 9144
TEST_TIME 78 9117
TEST_TIME 79 9108
TEST_TIME 80 9138
TEST_TIME 81 9068
TEST_TIME 82 9210
TEST_TIME 83 9222
TEST_TIME 84 9228
TEST_TIME 85 9250
TEST_TIME 86 9279
TEST_TIME 87 9260
TEST_TIME 88 9162
TEST_TIME 89 9114
TEST_TIME 90 9067
TEST_TIME 91 9008
TEST_TIME 92 8978
TEST_TIME 93 9046
TEST_TIME 94 9013
TEST_TIME 95 8990
TEST_TIME 96 8965
TEST_TIME 97 8922
TEST_TIME 98 8941
TEST_TIME 99 9053
TEST_TIME 100 9037
TEST_TIME 101 9091
TEST_TIME 102 9157
TEST_TIME 103 9144
TEST_TIME 104 9205
TEST_TIME 105 9201
TEST_TIME 106 9264
TEST_TIME 107 9237
TEST_TIME 108 9158
TEST_TIME 109 9098
TEST_TIME 110 9082
TEST_TIME 111 9046
TEST_TIME 112 9006
TEST_TIME 113 8981
TEST_TIME 114 8987
TEST_TIME 115 9044
TEST_TIME 116 9042
TEST_TIME 117 9031
TEST_TIME 118 9044
TEST_TIME 119 9025
TEST_TIME 120 8993

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-01-2021 04:27:44 to 10-01-2021 04:27:47.

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 1495814 1495814 0 0.00 0 21967 8 0 0
doc_list_biud 1495812 1495812 0 0.00 0 32229 33 0 0
sp_add_invoice_to_stock 5042 4919 123 2.43 26 234243 19713 0 123
sp_cancel_adding_invoice 5525 5525 0 0.00 3 80816 908 0 0
sp_cancel_client_order 1771 1771 0 0.00 4 12961 395 0 0
sp_cancel_customer_reserve 543800 543800 0 0.00 1 28838 72 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 2 3514 92 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 5064 88 0 0
sp_cancel_supplier_invoice 6146 6133 13 0.21 1 32239 979 0 13
sp_cancel_supplier_order 401 401 0 0.00 15 1223247 123391 0 0
sp_cancel_write_off 9901 9901 0 0.00 1 21958 129 0 0
sp_client_order 20058 20058 0 0.00 4 16258 441 0 0
sp_customer_reserve 450618 450618 0 0.00 0 163346 195 0 0
sp_fill_shopping_cart 30821 30821 0 0.00 1 20780 42 0 0
sp_fill_shopping_cart_clo_res 450609 450609 0 0.00 0 24971 17 0 0
sp_get_clo_for_invoice 4909 4909 0 0.00 0 34970 319 0 0
sp_kill_cost_storno 16547 16547 0 0.00 0 10375 67 0 0
sp_kill_qstorno_ret_qs2qd 1104035 1104035 0 0.00 0 32227 39 0 0
sp_kill_qty_storno 582074 582074 0 0.00 0 32228 84 0 0
sp_lock_selected_doc 20256 20256 0 0.00 0 8883 6 0 0
sp_make_cost_storno 27173 27173 0 0.00 0 20384 62 0 0
sp_multiply_rows_for_pdistr 27173 27173 0 0.00 0 9002 34 0 0
sp_multiply_rows_for_qdistr 20058 20058 0 0.00 0 16225 230 0 0
sp_pay_from_customer 6934 6934 0 0.00 4 17477 130 0 0
sp_pay_to_supplier 5696 5696 0 0.00 5 9919 129 0 0
sp_payment_common 12630 12630 0 0.00 1 11035 22 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 0 12952 360 0 0
sp_qd_handle_on_invoice_upd_sts 10434 10434 0 0.00 0 17207 405 0 0
sp_qd_handle_on_reserve_upd_sts 9634 9634 0 0.00 0 17323 59 0 0
sp_reserve_write_off 9634 9634 0 0.00 7 17502 196 0 0
sp_supplier_invoice 5420 5420 0 0.00 6 111637 3564 0 0
sp_supplier_order 5350 5350 0 0.00 5 41971 1539 0 0
srv_aggregate_perf_data 117 117 0 0.00 2536 117168 14509 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2025 169661 45569 0 0
srv_find_qd_qs_mism 1032072 1032072 0 0.00 0 21966 6 0 0
srv_make_invnt_saldo 456 456 0 0.00 53 124592 3898 0 0
srv_make_money_saldo 751 751 0 0.00 1 14816 612 0 0
srv_recalc_idx_stat 4 4 0 0.00 115031 413118 285914 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4919 4919 0 0.00 0 18756 197 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 3820 96 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 2820 13 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 2468 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 1632 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 511 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1124 16 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 32380 1617 0 0
t$perf-norm:sp_cancel_write_off 925 925 0 0.00 0 954 4 0 0
t$perf-norm:sp_client_order 20058 20058 0 0.00 0 17547 21 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 4678 10 0 0
t$perf-norm:sp_pay_from_customer 6934 6934 0 0.00 0 8608 10 0 0
t$perf-norm:sp_pay_to_supplier 5696 5696 0 0.00 0 10446 13 0 0
t$perf-norm:sp_reserve_write_off 9634 9634 0 0.00 0 10529 10 0 0
t$perf-norm:sp_supplier_invoice 5420 5420 0 0.00 0 4807 23 0 0
t$perf-norm:sp_supplier_order 5350 5350 0 0.00 0 3273 16 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 438 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 175 5 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 1231 14 0 0
t$perf-norm:srv_make_money_saldo 751 751 0 0.00 0 4075 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10064 9818 246 2.44 0 8330 19 0 246
v_all_customers 36794 36794 0 0.00 0 14 1 0 0
v_all_suppliers 29662 29662 0 0.00 0 155 1 0 0
v_all_wares 40116 40116 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 827 8 0 0
v_cancel_client_order 3542 3542 0 0.00 0 8316 8 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 3405 8 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 1883 6 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 535 5 0 0
v_cancel_supplier_invoice 806 780 26 3.22 0 370 14 0 26
v_cancel_supplier_order 802 802 0 0.00 0 3967 15 0 0
v_cancel_write_off 1850 1850 0 0.00 0 3983 17 0 0
v_max_id_clo_ord 5349 5349 0 0.00 0 1053 2 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 6854 14 0 0
v_max_id_ord_sup 5414 5414 0 0.00 0 6035 5 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 109 2 0 0
v_max_non_paid_realizn 3634 3634 0 0.00 0 8060 5 0 0
v_min_id_clo_ord 5349 5349 0 0.00 0 10279 10 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 221 2 0 0
v_min_id_ord_sup 5414 5414 0 0.00 0 6284 9 0 0
v_min_non_paid_invoice 5696 5696 0 0.00 0 5515 12 0 0
v_min_non_paid_realizn 6934 6934 0 0.00 0 11026 15 0 0
v_our_firm 9922 9922 0 0.00 0 15 1 0 0
v_reserve_write_off 19268 19268 0 0.00 0 8800 8 0 0
x_lock_depdocs_on_canc_invoice 11268 11268 0 0.00 0 24722 109 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 4068 332 0 0
x_make_qstorno_1000_1200 5350 5350 0 0.00 2 41963 1449 0 0
x_make_qstorno_1200_2000 5420 5420 0 0.00 3 111617 3414 0 0
x_make_qstorno_2100_3300 450618 450618 0 0.00 0 163345 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-01-2021 04:27:48 to 10-01-2021 04:27:52.

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
01:56:58 8 8192000000 8797543696 8808353792 3168016 0.038 1 1 2 0 3265632 61840 35776
01:57:59 27101 8192000000 9707122816 9999163392 781745120 8.711 100 80 160 0 808658864 7210384 19703360
01:59:34 22013 8192000000 9763258752 10065141760 835356096 9.253 100 94 188 0 871021712 13430208 22235408
02:01:06 16652 8192000000 9798903856 10117120000 860508896 9.505 100 74 148 0 901664720 24453024 16702800
02:02:27 19585 8192000000 9866328512 10198110208 914620464 10.043 100 83 167 1 976825008 43841616 18362928
02:03:49 10430 8192000000 9913943584 10263195648 952245568 10.413 100 79 156 1 1030532336 62087072 16199696
02:05:07 5145 8192000000 9957783136 10310885376 995435488 10.834 100 79 158 0 1069206896 57523264 16248144
02:06:15 2668 8192000000 10008710448 10369679360 1022561632 11.097 100 88 177 1 1120647376 80304944 17780800
02:07:19 6313 8192000000 9994099088 10365071360 1038032624 11.246 100 77 152 1 1112151424 58822992 15295808
02:08:27 2022 8192000000 10031788608 10405236736 1062918928 11.484 100 81 161 2 1145743712 65804528 17020256
02:09:32 2252 8192000000 10044892240 10423422976 1075540272 11.605 100 86 166 1 1156957360 64229632 17187456
02:10:36 4090 8192000000 10042390992 10435153920 1079849856 11.646 100 58 116 1 1151235120 60166784 11218480
02:11:41 31593 8192000000 10021668048 10409586688 1086220832 11.707 100 75 153 2 1137556320 34735136 16600352
02:13:13 5709 8192000000 10022070176 10422124544 1086414640 11.709 100 42 83 2 1127551376 32841488 8295248
02:14:21 7690 8192000000 10004991520 10398720000 1093060816 11.772 100 39 78 0 1125485360 23406928 9017616
02:15:30 4615 8192000000 10006620848 10412097536 1094941856 11.790 100 40 83 0 1126183120 22944896 8296368
02:16:35 61835 8192000000 10025990912 10414669824 1087628704 11.720 99 47 90 2 1115701232 18078608 9993920
02:18:38 7161 8192000000 10007210928 10400858112 1075055936 11.600 98 30 59 2 1115530224 34435216 6039072
02:19:47 9816 8192000000 9982208752 10380328960 1043078624 11.294 98 38 76 0 1066932704 15175296 8678784
02:20:58 72021 8192000000 9915737968 10296360960 934307952 10.237 90 23 44 1 959991040 21004160 4678928
02:23:12 6820 8192000000 9959496832 10348498944 1031802416 11.186 100 42 84 2 1062164320 20974240 9387664
02:24:19 7025 8192000000 9971179056 10358829056 1046389824 11.326 100 51 102 2 1083068704 25316096 11362784
02:25:27 7801 8192000000 9959905776 10355396608 1061222272 11.468 100 35 68 2 1080772576 12033504 7516800
02:26:39 29445 8192000000 9990057312 10389299200 1079499760 11.643 100 24 48 1 1105967424 21798560 4669104
02:28:13 18473 8192000000 10050042656 10448166912 1099136272 11.829 100 60 120 1 1155599584 44071872 12391440
02:30:00 17605 8192000000 10079790576 10482487296 1113681360 11.967 100 55 108 0 1178643296 54104592 10857344
02:31:20 3842 8192000000 10090336160 10491113472 1133288416 12.152 100 63 126 4 1197872688 50642480 13941792
02:32:29 31207 8192000000 10150280464 10567430144 1142807472 12.242 100 36 72 1 1252212464 101200016 8204976
02:34:06 11113 8192000000 10182888992 10592784384 1150048224 12.310 100 49 94 3 1288721408 128028336 10644848
02:35:22 18783 8192000000 10207433952 10619617280 1163511424 12.436 100 52 104 0 1310334240 135634608 11188208
02:36:46 6854 8192000000 10257406080 10667581440 1174879392 12.542 100 59 118 1 1360595648 172571600 13144656
02:37:57 6279 8192000000 10253059168 10671321088 1186315888 12.649 100 57 113 0 1358245824 158873808 13056128
02:39:08 7238 8192000000 10308360080 10722201600 1194661872 12.727 100 68 134 0 1408181280 198248592 15270816
02:40:21 3851 8192000000 10340399728 10747912192 1191721184 12.699 100 81 162 0 1435016416 225212496 18082736
02:41:32 4435 8192000000 10371376432 10783444992 1195579056 12.735 100 84 168 2 1479056608 264368928 19108624
02:42:42 6850 8192000000 10421265840 10839306240 1196730624 12.746 100 71 142 1 1520748976 307237312 16781040
02:43:58 3749 8192000000 10407627024 10814775296 1187377872 12.659 100 82 162 2 1504406016 299224608 17803536
02:45:07 4039 8192000000 10424742784 10853261312 1164180400 12.442 99 88 174 2 1511224144 327064880 19978864
02:46:20 2024 8192000000 10420266896 10826977280 1153497744 12.342 99 79 158 0 1509028672 337092672 18438256
02:47:29 4185 8192000000 10343732384 10754572288 1141773984 12.232 100 65 126 0 1436861520 280293280 14794256
02:48:46 8729 8192000000 10362445024 10777243648 1136775936 12.185 100 47 92 2 1468817280 320625456 11415888
02:49:59 5942 8192000000 10374164672 10827759616 1147402832 12.285 100 42 85 0 1475623264 316714000 11506432
02:51:18 30496 8192000000 10330408096 10821480448 1136378288 12.181 99 25 49 1 1402858128 260042784 6437056
02:52:57 23266 8192000000 10306745072 10794905600 1105164896 11.887 98 26 52 1 1420197808 307868528 7164384
02:54:27 22378 8192000000 10295073216 10782851072 1112534080 11.956 100 23 46 1 1433152464 314087248 6531136
02:55:52 14478 8192000000 10215753632 10673143808 1109637024 11.929 99 21 42 1 1293462848 179023920 4801904
02:57:18 36636 8192000000 10152940960 10602278912 1124628400 12.071 100 16 32 0 1241847584 113435456 3783728
02:58:58 48251 8192000000 10119219152 10598547456 1112895200 11.960 98 16 32 0 1199750768 83282176 3573392
03:01:26 134065 8192000000 10088189168 10533031936 1076434960 11.613 94 15 30 2 1113578096 34049984 3093152
03:05:08 125260 8192000000 10102892672 10567856128 1137625040 12.193 100 44 88 2 1230321440 83646896 9049504
03:08:14 30519 8192000000 10081789200 10510467072 1131676272 12.137 99 38 76 0 1160970480 21785648 7508560
03:09:46 55831 8192000000 10105127632 10542481408 1142143344 12.236 99 27 54 3 1195477696 47714480 5619872
03:11:46 34671 8192000000 10096769072 10550325248 1135747344 12.176 99 35 69 1 1197696256 53947744 8001168
03:13:25 55642 8192000000 10074402944 10517327872 1116026224 11.989 97 12 24 0 1171442544 52539024 2877296
03:15:21 39261 8192000000 10081423152 10557599744 1126535664 12.089 99 25 48 1 1185218880 53408368 5274848
03:17:09 169661 8192000000 10118976032 10605580288 1118353952 12.011 97 24 48 1 1218735760 95063760 5318048
03:20:59 71579 8192000000 10079748368 10532982784 1122937024 12.055 98 8 16 0 1159540336 35000016 1603296
03:23:27 33817 8192000000 10150644384 10603356160 1146400496 12.276 100 29 58 1 1245942304 93219104 6322704
03:25:02 35338 8192000000 10127640032 10582175744 1147007040 12.281 99 36 71 1 1202193360 46867104 8319216
03:26:38 52361 8192000000 10154378592 10596466688 1142439008 12.238 99 30 59 2 1227175744 78849344 5887392
03:28:31 93913 8192000000 10077886560 10522308608 1096076000 11.800 95 12 24 0 1141951632 43902352 1973280
03:31:05 28390 8192000000 10079301200 10510798848 1108666800 11.920 98 26 52 1 1165790320 51566448 5557072
03:33:02 37425 8192000000 10105199312 10523000832 1097416176 11.813 97 41 83 2 1169217072 62511472 9289424
03:34:43 124460 8192000000 10036554608 10518929408 1062813408 11.483 95 20 40 0 1123105744 56067792 4224544
03:37:52 44631 8192000000 10048374064 10486616064 1075708832 11.607 97 13 26 0 1135180576 56709008 2762736
03:39:43 101224 8192000000 10091899696 10554888192 1092981968 11.771 98 15 30 2 1171045968 74350992 3713008
03:42:37 67912 8192000000 10038624688 10516815872 1081274384 11.660 97 14 28 1 1131588848 47135568 3178896
03:44:45 62686 8192000000 10115690320 10572185600 1129656192 12.118 100 32 64 3 1214191008 77664528 6870288
03:46:54 37146 8192000000 10112990240 10536603648 1120290704 12.030 99 25 50 2 1176044208 49830416 5923088
03:48:31 55117 8192000000 10066033584 10588790784 1106314768 11.898 99 14 29 0 1196505888 87266896 2924224
03:50:39 99482 8192000000 10084376992 10548006912 1089197856 11.735 97 19 37 0 1136650368 43824016 3628496
03:53:23 126596 8192000000 10061448736 10493210624 1131052928 12.131 100 27 51 1 1165146160 28785152 5308080
03:56:32 54178 8192000000 10071287952 10528821248 1132389328 12.144 100 10 19 1 1188662176 54302000 1970848
03:58:28 62069 8192000000 10099735936 10589310976 1123341024 12.059 98 10 20 0 1193755280 68572096 1842160
04:00:34 50865 8192000000 10091580368 10533183488 1129236864 12.114 98 28 56 2 1163838384 28870880 5730640
04:02:27 29123 8192000000 10153100000 10597306368 1158927360 12.393 99 31 62 1 1230484544 64962416 6594768
04:03:56 32376 8192000000 10211722784 10660544512 1156756512 12.373 99 39 79 2 1296564400 130403456 9404432
04:05:31 58975 8192000000 10170906832 10659602432 1163153872 12.433 100 15 30 1 1268835728 102083104 3598752
04:07:44 73968 8192000000 10109862352 10597806080 1138485632 12.201 98 17 34 1 1211000816 68685280 3829904
04:09:58 19336 8192000000 10130474768 10559057920 1132156672 12.142 99 53 105 1 1205798496 63043520 10598304
04:11:18 67591 8192000000 10089144256 10557886464 1133133536 12.151 99 7 14 0 1185813776 51426352 1253888
04:13:35 112718 8192000000 10084507392 10560712704 1126628496 12.090 99 17 34 0 1187655088 57276688 3749904
04:16:44 52906 8192000000 10111835088 10625150976 1123125936 12.057 98 7 12 0 1189086000 64774288 1185776
04:18:37 60540 8192000000 10161034224 10617393152 1126055840 12.084 98 28 55 1 1215596624 83805856 5734928
04:21:08 86141 8192000000 10100860880 10540240896 1109069600 11.924 98 15 30 0 1190165344 77761008 3334736
04:23:36 41283 8192000000 10094245040 10527727616 1116163152 11.991 98 40 78 2 1159700112 35817632 7719328
04:25:28 27086 8192000000 10069426896 10512748544 1116984080 11.998 99 39 78 1 1160595808 35932544 7679184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-01-2021 04:27:54 to 10-01-2021 04:28:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-01-2021 04:28:10 to 10-01-2021 04:28:12.

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          224054
MON$OLDEST_ACTIVE               228431
MON$OLDEST_SNAPSHOT             228431
MON$NEXT_TRANSACTION            228431
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                       2103424
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             788
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Sun Jan 10 04:26:55 2021
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Sun Jan 10 04:27:01 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
--- end of diff output ---

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

10.01.21 04:28:13. 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-01-10 04:28:15 No severe PSQL-related problems occured
10.01.21 04:28:17. End of report.