Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.03.2021 13:28
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16934716 kB
MemAvailable 31593380 kB
Buffers 0 kB
Cached 14757892 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.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-03-15 13:26:58
DTS_END                         2021-03-15 13:26:58.0910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57920
TRN_ID                          237081
ATT_ID                          701
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: 8953.35 21698 1074402
customer order: creation 171.43 371 20572
customer order: refuse 14.73 332 1767
order to supplier: creation 44.63 1384 5356
order to supplier: removal 3.35 102364 402
invoice (draft): creation 44.58 3626 5350
invoice (draft): removal 49.06 892 5887
invoice accept: apply 42.02 18280 5042
invoice accept: cancel 44.36 847 5323
customer reserve: creation 3873.54 180 464825
customer reserve: removal 4372.69 61 524723
realization accept: apply 84.79 175 10175
realization accept: cancel 78.70 114 9444
payment from customer: creation 60.13 117 7215
payment from customer: removal 5.60 88 672
payment to supplier: creation 48.39 138 5807
payment to supplier: removal 3.70 64 444
service: total inventory turnovers 4.26 2953 511
service: total monetary turnovers 6.53 465 784
service: aggregate perf. data 0.82 18681 98
service: refresh index statistics 0.04 282825 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-03-2021 13:29:09 to 15-03-2021 13:29:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2914
TEST_TIME 2 4427
TEST_TIME 3 3659
TEST_TIME 4 3845
TEST_TIME 5 4553
TEST_TIME 6 5099
TEST_TIME 7 5371
TEST_TIME 8 5688
TEST_TIME 9 5568
TEST_TIME 10 5580
TEST_TIME 11 6323
TEST_TIME 12 6400
TEST_TIME 13 6709
TEST_TIME 14 6933
TEST_TIME 15 6949
TEST_TIME 16 7209
TEST_TIME 17 7187
TEST_TIME 18 7502
TEST_TIME 19 8067
TEST_TIME 20 8401
TEST_TIME 21 8792
TEST_TIME 22 8866
TEST_TIME 23 9046
TEST_TIME 24 9438
TEST_TIME 25 9837
TEST_TIME 26 9681
TEST_TIME 27 9953
TEST_TIME 28 10545
TEST_TIME 29 10300
TEST_TIME 30 10319
TEST_TIME 31 10351
TEST_TIME 32 10536
TEST_TIME 33 10537
TEST_TIME 34 10391
TEST_TIME 35 10341
TEST_TIME 36 10465
TEST_TIME 37 10466
TEST_TIME 38 10434
TEST_TIME 39 10329
TEST_TIME 40 10073
TEST_TIME 41 9960
TEST_TIME 42 9820
TEST_TIME 43 9785
TEST_TIME 44 9720
TEST_TIME 45 9642
TEST_TIME 46 9615
TEST_TIME 47 9527
TEST_TIME 48 9471
TEST_TIME 49 9395
TEST_TIME 50 9378
TEST_TIME 51 9280
TEST_TIME 52 9468
TEST_TIME 53 9497
TEST_TIME 54 9448
TEST_TIME 55 9566
TEST_TIME 56 9554
TEST_TIME 57 9513
TEST_TIME 58 9535
TEST_TIME 59 9510
TEST_TIME 60 9514
TEST_TIME 61 9556
TEST_TIME 62 9542
TEST_TIME 63 9478
TEST_TIME 64 9385
TEST_TIME 65 9315
TEST_TIME 66 9283
TEST_TIME 67 9251
TEST_TIME 68 9202
TEST_TIME 69 9202
TEST_TIME 70 9181
TEST_TIME 71 9224
TEST_TIME 72 9205
TEST_TIME 73 9200
TEST_TIME 74 9232
TEST_TIME 75 9308
TEST_TIME 76 9336
TEST_TIME 77 9352
TEST_TIME 78 9336
TEST_TIME 79 9380
TEST_TIME 80 9418
TEST_TIME 81 9443
TEST_TIME 82 9480
TEST_TIME 83 9379
TEST_TIME 84 9306
TEST_TIME 85 9224
TEST_TIME 86 9175
TEST_TIME 87 9192
TEST_TIME 88 9169
TEST_TIME 89 9183
TEST_TIME 90 9147
TEST_TIME 91 9174
TEST_TIME 92 9128
TEST_TIME 93 9116
TEST_TIME 94 9155
TEST_TIME 95 9133
TEST_TIME 96 9261
TEST_TIME 97 9313
TEST_TIME 98 9324
TEST_TIME 99 9291
TEST_TIME 100 9224
TEST_TIME 101 9170
TEST_TIME 102 9149
TEST_TIME 103 9113
TEST_TIME 104 9147
TEST_TIME 105 9099
TEST_TIME 106 9108
TEST_TIME 107 9115
TEST_TIME 108 9120
TEST_TIME 109 9125
TEST_TIME 110 9093
TEST_TIME 111 9145
TEST_TIME 112 9153
TEST_TIME 113 9162
TEST_TIME 114 9146
TEST_TIME 115 9186
TEST_TIME 116 9176
TEST_TIME 117 9100
TEST_TIME 118 9056
TEST_TIME 119 9000
TEST_TIME 120 8959

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-03-2021 13:29:12 to 15-03-2021 13:29:16.

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 1504107 1504107 0 0.00 0 23112 7 0 0
doc_list_biud 1504107 1504107 0 0.00 0 47157 27 0 0
sp_add_invoice_to_stock 5143 5042 101 1.96 38 203146 18281 0 101
sp_cancel_adding_invoice 5323 5323 0 0.00 3 100882 848 0 0
sp_cancel_client_order 1767 1767 0 0.00 5 31396 332 0 0
sp_cancel_customer_reserve 524723 524723 0 0.00 1 29541 61 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 11362 89 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 3 2664 64 0 0
sp_cancel_supplier_invoice 5897 5887 10 0.16 2 16755 892 0 10
sp_cancel_supplier_order 402 402 0 0.00 10 948351 102365 0 0
sp_cancel_write_off 9444 9444 0 0.00 1 15314 114 0 0
sp_client_order 20572 20572 0 0.00 4 36128 372 0 0
sp_customer_reserve 464825 464825 0 0.00 0 37373 180 0 0
sp_fill_shopping_cart 31277 31277 0 0.00 1 23188 45 0 0
sp_fill_shopping_cart_clo_res 464817 464817 0 0.00 0 35246 15 0 0
sp_get_clo_for_invoice 5032 5032 0 0.00 0 39198 375 0 0
sp_kill_cost_storno 15883 15883 0 0.00 0 12253 59 0 0
sp_kill_qstorno_ret_qs2qd 1065179 1065179 0 0.00 0 28876 33 0 0
sp_kill_qty_storno 562753 562753 0 0.00 0 47156 72 0 0
sp_lock_selected_doc 21001 21001 0 0.00 0 9605 6 0 0
sp_make_cost_storno 28229 28229 0 0.00 0 11339 57 0 0
sp_multiply_rows_for_pdistr 28229 28229 0 0.00 0 11394 35 0 0
sp_multiply_rows_for_qdistr 20572 20572 0 0.00 0 27662 201 0 0
sp_pay_from_customer 7215 7215 0 0.00 4 13314 118 0 0
sp_pay_to_supplier 5807 5807 0 0.00 4 13270 138 0 0
sp_payment_common 13022 13022 0 0.00 1 10877 25 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 31387 307 0 0
sp_qd_handle_on_invoice_upd_sts 10355 10355 0 0.00 0 47156 366 0 0
sp_qd_handle_on_reserve_upd_sts 10175 10175 0 0.00 0 26248 46 0 0
sp_reserve_write_off 10175 10175 0 0.00 7 26320 175 0 0
sp_supplier_invoice 5350 5350 0 0.00 6 112980 3626 0 0
sp_supplier_order 5356 5356 0 0.00 6 52633 1384 0 0
srv_aggregate_perf_data 99 98 1 1.01 2965 584947 18681 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1470 135424 45186 0 0
srv_find_qd_qs_mism 1026364 1026364 0 0.00 0 23111 6 0 0
srv_make_invnt_saldo 511 511 0 0.00 72 96501 2954 0 0
srv_make_money_saldo 784 784 0 0.00 0 13990 465 0 0
srv_recalc_idx_stat 5 5 0 0.00 143802 547282 282825 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5042 5042 0 0.00 0 10039 145 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 1 2445 55 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 667 8 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 1157 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 1947 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 877 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 468 11 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 19560 1393 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 5888 16 0 0
t$perf-norm:sp_client_order 20572 20572 0 0.00 0 19904 17 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 3733 15 0 0
t$perf-norm:sp_pay_from_customer 7215 7215 0 0.00 0 7321 10 0 0
t$perf-norm:sp_pay_to_supplier 5807 5807 0 0.00 0 9472 7 0 0
t$perf-norm:sp_reserve_write_off 10175 10175 0 0.00 0 7345 7 0 0
t$perf-norm:sp_supplier_invoice 5350 5350 0 0.00 0 11184 19 0 0
t$perf-norm:sp_supplier_order 5356 5356 0 0.00 0 8007 20 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 392 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 92 3 0 0
t$perf-norm:srv_make_invnt_saldo 511 511 0 0.00 0 1448 8 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 8998 22 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 652 135 0 0
v_add_invoice_to_stock 10266 10064 202 1.96 0 8444 17 0 202
v_all_customers 37436 37436 0 0.00 0 17 1 0 0
v_all_suppliers 29760 29760 0 0.00 0 15 1 0 0
v_all_wares 41144 41144 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 2051 11 0 0
v_cancel_client_order 3534 3534 0 0.00 0 4134 5 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 1899 10 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 436 4 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 917 8 0 0
v_cancel_supplier_invoice 860 840 20 2.32 0 1938 14 0 20
v_cancel_supplier_order 804 804 0 0.00 0 2197 7 0 0
v_cancel_write_off 1956 1956 0 0.00 0 476 12 0 0
v_max_id_clo_ord 5356 5356 0 0.00 0 2049 2 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 3195 19 0 0
v_max_id_ord_sup 5349 5349 0 0.00 0 7415 4 0 0
v_max_non_paid_invoice 1632 1632 0 0.00 0 266 2 0 0
v_max_non_paid_realizn 3929 3929 0 0.00 0 661 2 0 0
v_min_id_clo_ord 5356 5356 0 0.00 0 13310 27 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 17489 25 0 0
v_min_id_ord_sup 5349 5349 0 0.00 0 5732 10 0 0
v_min_non_paid_invoice 5807 5807 0 0.00 0 10417 20 0 0
v_min_non_paid_realizn 7215 7215 0 0.00 0 10869 15 0 0
v_our_firm 10278 10278 0 0.00 0 18 1 0 0
v_reserve_write_off 20350 20350 0 0.00 0 3523 4 0 0
x_lock_depdocs_on_canc_invoice 10790 10790 0 0.00 0 20729 129 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 23408 381 0 0
x_make_qstorno_1000_1200 5356 5356 0 0.00 2 52626 1287 0 0
x_make_qstorno_1200_2000 5350 5350 0 0.00 3 107927 3464 0 0
x_make_qstorno_2100_3300 464825 464825 0 0.00 0 33263 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-03-2021 13:29:17 to 15-03-2021 13:29:21.

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
10:57:01 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:02 28346 8192000000 9714841696 10008182784 793794400 8.833 100 89 178 1 823340000 7588624 21956976
10:59:37 24612 8192000000 9763096704 10067865600 836506304 9.265 100 84 168 2 867726816 11636704 19583808
11:01:10 16660 8192000000 9811745376 10133114880 874515840 9.645 100 79 158 0 914978416 22858096 17604480
11:02:28 13219 8192000000 9884033216 10216689664 929005520 10.185 100 83 166 1 992205568 45696544 17503504
11:03:45 16992 8192000000 9939272240 10283933696 969597328 10.583 100 83 166 1 1048925568 62341264 16986976
11:05:07 4683 8192000000 9971633376 10330189824 1000240864 10.881 100 81 159 1 1089807056 72935616 16630576
11:06:13 3591 8192000000 9999016720 10362675200 1030821296 11.176 100 88 175 1 1112895712 64624032 17450384
11:07:20 4309 8192000000 10014953216 10385043456 1048357328 11.345 100 83 166 0 1129001008 64225888 16417792
11:08:25 1825 8192000000 10050838768 10421682176 1069860432 11.551 100 92 184 1 1155747136 66687760 19198944
11:09:29 17208 8192000000 9967679072 10365857792 1068835152 11.541 100 20 38 1 1102308896 28831216 4642528
11:10:47 2693 8192000000 10002180992 10399256576 1083031088 11.676 100 48 93 1 1117472400 24227504 10213808
11:11:52 6096 8192000000 9992499200 10397040640 1089605888 11.739 100 44 86 1 1116086000 16526960 9953152
11:13:03 6664 8192000000 9994994576 10404671488 1096344336 11.803 100 33 62 3 1117575600 14556064 6675200
11:14:10 31206 8192000000 10026564752 10438307840 1101349088 11.850 100 34 64 1 1134933056 26277632 7306336
11:15:43 5363 8192000000 10011232096 10417446912 1103522720 11.871 100 45 90 2 1128910624 15688880 9699024
11:16:50 4141 8192000000 10021530048 10421252096 1095399024 11.794 99 53 109 1 1127508752 19822880 12286848
11:17:58 15025 8192000000 9986270368 10379530240 1023539568 11.106 94 29 56 3 1050058336 20520864 5997904
11:19:13 9161 8192000000 9980575408 10361884672 1055624032 11.415 99 62 123 0 1077590240 8525408 13440800
11:20:22 6325 8192000000 9951228864 10327044096 1003969280 10.917 97 36 72 0 1037228048 25346752 7912016
11:21:29 7642 8192000000 9946932048 10340515840 1013461728 11.009 98 47 95 1 1050540256 26041296 11037232
11:22:37 21766 8192000000 9943778304 10345250816 1028560832 11.155 98 30 60 2 1051904336 16408448 6935056
11:24:22 9255 8192000000 9964391488 10359791616 1043933280 11.302 99 44 87 3 1073206032 18803872 10468880
11:25:32 9086 8192000000 9969907920 10377117696 1063504048 11.490 100 34 67 3 1083538752 11575936 8458768
11:26:43 9072 8192000000 9980439824 10394664960 1070176640 11.554 100 28 56 2 1096364512 19609712 6578160
11:27:56 48631 8192000000 10053202448 10457059328 1101953424 11.856 100 51 100 1 1148722944 36395264 10374256
11:29:46 31295 8192000000 10069680432 10476318720 1117749232 12.006 100 38 75 2 1186528848 61171760 7607856
11:31:21 41321 8192000000 10119792864 10533818368 1127396688 12.097 100 42 84 0 1216691520 80262096 9032736
11:33:07 11449 8192000000 10124620512 10542182400 1143760512 12.251 100 48 95 3 1240200032 86504576 9934944
11:34:21 12274 8192000000 10162951920 10580668416 1154463824 12.351 100 53 106 2 1267427760 101456496 11507440
11:35:38 6713 8192000000 10215151568 10625191936 1157170896 12.377 100 66 131 1 1308684512 137267152 14246464
11:36:49 7562 8192000000 10200430656 10650464256 1157505536 12.380 99 53 103 0 1295530560 127053504 10971520
11:38:00 1467 8192000000 10247230336 10655297536 1183130528 12.619 100 89 179 0 1349425088 146337200 19957360
11:39:07 1970 8192000000 10289134704 10692558848 1181676304 12.606 99 86 172 2 1383779776 182343584 19759888
11:40:13 2476 8192000000 10280484352 10690793472 1161870560 12.421 100 64 128 0 1383020256 206352176 14797520
11:41:20 4887 8192000000 10289617184 10696732672 1161030896 12.413 100 69 138 2 1383012784 207149584 14832304
11:42:29 2048 8192000000 10287923488 10687119360 1157421296 12.379 100 91 182 1 1387357184 208469376 21466512
11:43:35 1859 8192000000 10355042736 10755960832 1159880592 12.402 100 86 169 0 1452034368 272467520 19686256
11:44:41 1841 8192000000 10384423984 10784137216 1137524496 12.192 100 91 178 1 1485895680 327666576 20704608
11:45:46 8573 8192000000 10316287984 10744918016 1149148432 12.302 100 47 94 1 1415721072 254834624 11738016
11:46:58 6485 8192000000 10297656896 10720825344 1131788800 12.138 99 49 97 2 1389868800 246412880 11667120
11:48:14 9850 8192000000 10260759552 10691112960 1116404192 11.993 99 25 50 2 1353805296 230709392 6691712
11:49:26 6840 8192000000 10272810960 10691342336 1116826400 11.997 100 35 72 0 1370858864 244948704 9083760
11:50:37 13195 8192000000 10211034960 10626019328 1092518336 11.767 99 37 73 1 1312983872 211931744 8533792
11:51:54 34596 8192000000 10208899776 10642534400 1103218336 11.868 99 19 38 2 1288549088 180834640 4496112
11:53:39 32429 8192000000 10158909712 10625519616 1084927056 11.694 99 19 36 3 1259823040 170326912 4569072
11:55:16 41978 8192000000 10091769072 10519060480 1098456656 11.823 98 40 78 3 1167943504 60239424 9247424
11:57:20 75166 8192000000 10088376832 10615939072 1127601248 12.099 100 9 18 2 1253471072 123834048 2035776
11:59:36 19045 8192000000 10030846496 10456817664 1092444240 11.766 98 20 42 1 1114959296 18356656 4158400
12:01:01 11251 8192000000 10076431536 10490765312 1119683664 12.024 100 54 109 0 1165642496 34639088 11319744
12:02:21 20213 8192000000 10140611504 10583494656 1138237632 12.199 100 15 30 0 1238875680 97299408 3338640
12:03:43 57748 8192000000 10078469408 10507567104 1096259696 11.802 96 17 34 0 1125038944 24694928 4084320
12:05:41 111581 8192000000 10081562976 10517721088 1136725424 12.185 100 45 88 2 1188114992 41919552 9470016
12:08:36 31380 8192000000 10087655616 10518659072 1130365728 12.125 99 24 49 0 1178038160 42848496 4823936
12:10:09 61285 8192000000 10118608544 10588426240 1147497840 12.286 100 11 22 2 1190403696 40322480 2583376
12:12:10 58737 8192000000 10133041440 10598797312 1144478848 12.258 99 19 38 0 1216026576 67244672 4303056
12:14:39 60480 8192000000 10149234832 10624520192 1161689488 12.419 100 16 32 1 1273922384 108516848 3716048
12:16:41 51681 8192000000 10155486768 10618552320 1163136192 12.433 100 10 20 0 1248765584 83515856 2113536
12:18:54 46137 8192000000 10098733824 10572017664 1136215488 12.180 98 15 30 1 1184038032 44635536 3187008
12:20:45 63026 8192000000 10088904768 10595565568 1109294144 11.926 96 23 46 0 1155129680 40624624 5210912
12:22:49 43740 8192000000 10087813488 10542874624 1124320112 12.068 98 34 68 0 1168792736 37882592 6590032
12:24:37 23895 8192000000 10091940528 10526871552 1104138720 11.877 97 30 60 0 1153895104 43784320 5972064
12:26:27 36123 8192000000 10103359888 10540888064 1109135408 11.924 98 43 86 0 1168414720 50562048 8717264
12:28:11 45104 8192000000 10058581552 10483159040 1116348368 11.992 100 25 49 0 1139988272 18775536 4864368
12:30:03 135424 8192000000 10104529248 10566692864 1121536848 12.042 99 24 48 0 1195671552 68415360 5719344
12:33:19 43682 8192000000 10097095616 10624688128 1127011648 12.093 99 8 16 1 1187258864 58561648 1685568
12:36:17 82262 8192000000 10055545984 10523172864 1037670912 11.242 91 26 51 1 1070970080 27722256 5576912
12:38:43 46226 8192000000 10066789856 10501353472 1073756000 11.588 96 17 34 0 1129565968 52433472 3376496
12:40:30 86525 8192000000 10086569632 10570563584 1096379392 11.803 97 26 52 0 1196685328 94434288 5871648
12:43:00 41772 8192000000 10115830544 10581151744 1126780816 12.091 99 27 54 0 1185143984 52708288 5654880
12:44:48 64808 8192000000 10068642816 10511458304 1105328304 11.888 98 34 68 1 1155832384 43541024 6963056
12:46:53 48343 8192000000 10104687584 10557095936 1099242448 11.830 97 22 44 0 1166270448 62543232 4484768
12:48:42 104993 8192000000 10044361200 10480082944 1111163136 11.943 98 22 44 0 1136076160 20414736 4498288
12:51:57 55880 8192000000 10093437744 10568015872 1136060960 12.178 100 10 20 0 1203439648 65071984 2306704
12:53:54 31880 8192000000 10090536976 10524901376 1126521824 12.089 99 17 30 1 1173508080 44773392 2212864
12:55:27 49244 8192000000 10067056976 10515329024 1105316208 11.888 97 13 26 0 1143030080 34800064 2913808
12:57:17 55561 8192000000 10103818624 10581913600 1127879520 12.101 99 20 41 1 1179596096 47967616 3748960
12:59:13 83625 8192000000 10157834480 10658725888 1146546976 12.277 99 23 46 1 1262397168 110532192 5318000
13:01:53 80575 8192000000 10095323728 10686918656 1117602704 12.004 98 16 32 0 1250989984 129824544 3562736
13:04:28 53270 8192000000 10093436304 10530713600 1119049088 12.018 98 25 50 1 1159546032 35711488 4785456
13:06:28 106969 8192000000 10079677808 10536984576 1133225824 12.152 100 21 43 0 1185547056 47076672 5244560
13:09:16 63053 8192000000 10106396080 10567393280 1136481200 12.182 99 10 20 0 1195359760 56477296 2401264
13:11:54 86391 8192000000 10069452464 10544041984 1101572304 11.853 96 10 20 0 1147954944 43848656 2533984
13:14:28 57006 8192000000 10088151264 10527657984 1090795568 11.750 96 15 30 0 1146644048 52553216 3295264
13:16:27 35347 8192000000 10110628000 10543779840 1107968592 11.913 98 29 58 1 1170267520 56458928 5840000
13:18:20 103949 8192000000 10062029712 10529329152 1069379504 11.546 94 20 40 1 1127172240 53890784 3901952
13:21:15 122756 8192000000 10071589616 10583326720 1125431120 12.078 99 15 30 0 1163956944 35531616 2994208
13:24:18 58579 8192000000 10030376240 10483429376 1096280080 11.802 97 15 30 1 1113862400 14631248 2951072
13:26:17 47330 8192000000 9803090096 10232881152 903430976 9.932 81 5 10 0 938178000 33850448 896576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-03-2021 13:29:23 to 15-03-2021 13:29:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 101 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 15-03-2021 13:29:39 to 15-03-2021 13:29:41.

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          237080
MON$OLDEST_ACTIVE               237239
MON$OLDEST_SNAPSHOT             237239
MON$NEXT_TRANSACTION            237239
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                       2099456
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 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.2387 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 ---
:132: 
:133: mscs-test-161.budzdorov.ru    Mon Mar 15 13:27:08 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Mon Mar 15 13:27:14 2021
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:139: 
:140: 
:141: mscs-test-161.budzdorov.ru    Mon Mar 15 13:27:17 2021
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:143: 
--- end of diff output ---

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

15.03.21 13:29:42. 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-03-15 13:29:44 No severe PSQL-related problems occured
15.03.21 13:29:46. End of report.