Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.11.2021 10:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16404392 kB
MemAvailable 31548712 kB
Buffers 0 kB
Cached 15250968 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-11-25 10:37:09
DTS_END                         2021-11-25 10:37:09.9520
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/44674
TRN_ID                          224193
ATT_ID                          674
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: 9119.54 25821 1094345
customer order: creation 167.89 372 20147
customer order: refuse 13.74 467 1649
order to supplier: creation 45.70 1498 5484
order to supplier: removal 3.19 132725 383
invoice (draft): creation 44.05 3999 5286
invoice (draft): removal 50.53 1011 6063
invoice accept: apply 41.23 21344 4948
invoice accept: cancel 46.03 971 5523
customer reserve: creation 3848.13 206 461775
customer reserve: removal 4575.99 72 549119
realization accept: apply 80.81 223 9697
realization accept: cancel 77.89 134 9347
payment from customer: creation 57.68 124 6921
payment from customer: removal 5.56 104 667
payment to supplier: creation 47.21 137 5665
payment to supplier: removal 3.39 127 407
service: total inventory turnovers 3.44 4360 413
service: total monetary turnovers 6.23 659 748
service: aggregate perf. data 0.83 20306 99
service: refresh index statistics 0.03 327589 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-11-2021 10:37:50 to 25-11-2021 10:37:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2201
TEST_TIME 2 3754
TEST_TIME 3 4760
TEST_TIME 4 4882
TEST_TIME 5 5218
TEST_TIME 6 5997
TEST_TIME 7 5680
TEST_TIME 8 5654
TEST_TIME 9 6132
TEST_TIME 10 6599
TEST_TIME 11 7256
TEST_TIME 12 7232
TEST_TIME 13 7644
TEST_TIME 14 8154
TEST_TIME 15 8273
TEST_TIME 16 8509
TEST_TIME 17 8521
TEST_TIME 18 8652
TEST_TIME 19 8919
TEST_TIME 20 8807
TEST_TIME 21 8698
TEST_TIME 22 8632
TEST_TIME 23 9094
TEST_TIME 24 9214
TEST_TIME 25 9546
TEST_TIME 26 9512
TEST_TIME 27 9373
TEST_TIME 28 9510
TEST_TIME 29 10080
TEST_TIME 30 10209
TEST_TIME 31 10512
TEST_TIME 32 10659
TEST_TIME 33 11474
TEST_TIME 34 11329
TEST_TIME 35 11083
TEST_TIME 36 10870
TEST_TIME 37 10814
TEST_TIME 38 10722
TEST_TIME 39 10727
TEST_TIME 40 10573
TEST_TIME 41 10460
TEST_TIME 42 10338
TEST_TIME 43 10289
TEST_TIME 44 10284
TEST_TIME 45 10166
TEST_TIME 46 10051
TEST_TIME 47 9955
TEST_TIME 48 9797
TEST_TIME 49 9892
TEST_TIME 50 9745
TEST_TIME 51 9712
TEST_TIME 52 9599
TEST_TIME 53 9649
TEST_TIME 54 9593
TEST_TIME 55 9526
TEST_TIME 56 9507
TEST_TIME 57 9437
TEST_TIME 58 9292
TEST_TIME 59 9213
TEST_TIME 60 9191
TEST_TIME 61 9239
TEST_TIME 62 9209
TEST_TIME 63 9150
TEST_TIME 64 9244
TEST_TIME 65 9275
TEST_TIME 66 9372
TEST_TIME 67 9306
TEST_TIME 68 9321
TEST_TIME 69 9435
TEST_TIME 70 9392
TEST_TIME 71 9392
TEST_TIME 72 9495
TEST_TIME 73 9585
TEST_TIME 74 9552
TEST_TIME 75 9486
TEST_TIME 76 9484
TEST_TIME 77 9411
TEST_TIME 78 9346
TEST_TIME 79 9351
TEST_TIME 80 9361
TEST_TIME 81 9336
TEST_TIME 82 9293
TEST_TIME 83 9263
TEST_TIME 84 9223
TEST_TIME 85 9206
TEST_TIME 86 9159
TEST_TIME 87 9205
TEST_TIME 88 9248
TEST_TIME 89 9230
TEST_TIME 90 9238
TEST_TIME 91 9279
TEST_TIME 92 9356
TEST_TIME 93 9373
TEST_TIME 94 9378
TEST_TIME 95 9370
TEST_TIME 96 9376
TEST_TIME 97 9357
TEST_TIME 98 9421
TEST_TIME 99 9425
TEST_TIME 100 9347
TEST_TIME 101 9312
TEST_TIME 102 9297
TEST_TIME 103 9267
TEST_TIME 104 9221
TEST_TIME 105 9167
TEST_TIME 106 9159
TEST_TIME 107 9138
TEST_TIME 108 9098
TEST_TIME 109 9052
TEST_TIME 110 9092
TEST_TIME 111 9118
TEST_TIME 112 9138
TEST_TIME 113 9115
TEST_TIME 114 9090
TEST_TIME 115 9067
TEST_TIME 116 9104
TEST_TIME 117 9181
TEST_TIME 118 9226
TEST_TIME 119 9193
TEST_TIME 120 9121

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-11-2021 10:37:53 to 25-11-2021 10:37:57.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1520228 1520228 0 0.00 0 34800 8 0 0
doc_list_biud 1520213 1520213 0 0.00 0 76154 33 0 0
sp_add_invoice_to_stock 5089 4948 141 2.77 28 184258 21345 0 141
sp_cancel_adding_invoice 5523 5523 0 0.00 6 71564 972 0 0
sp_cancel_client_order 1649 1649 0 0.00 4 30215 467 0 0
sp_cancel_customer_reserve 549119 549119 0 0.00 1 19374 73 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 3360 105 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 9156 127 0 0
sp_cancel_supplier_invoice 6069 6063 6 0.09 2 76248 1012 0 6
sp_cancel_supplier_order 383 383 0 0.00 11 1364619 132726 0 0
sp_cancel_write_off 9347 9347 0 0.00 1 11179 135 0 0
sp_client_order 20147 20147 0 0.00 4 26278 373 0 0
sp_customer_reserve 461775 461775 0 0.00 0 57280 206 0 0
sp_fill_shopping_cart 30898 30898 0 0.00 1 11269 44 0 0
sp_fill_shopping_cart_clo_res 461773 461773 0 0.00 0 33458 16 0 0
sp_get_clo_for_invoice 4946 4946 0 0.00 0 37127 385 0 0
sp_kill_cost_storno 15944 15944 0 0.00 0 25745 74 0 0
sp_kill_qstorno_ret_qs2qd 1114031 1114031 0 0.00 0 76151 39 0 0
sp_kill_qty_storno 586716 586716 0 0.00 0 76154 85 0 0
sp_lock_selected_doc 20166 20166 0 0.00 0 8449 5 0 0
sp_make_cost_storno 27229 27229 0 0.00 0 34790 72 0 0
sp_multiply_rows_for_pdistr 27214 27214 0 0.00 0 9344 35 0 0
sp_multiply_rows_for_qdistr 20147 20147 0 0.00 0 12702 169 0 0
sp_pay_from_customer 6921 6921 0 0.00 4 18744 124 0 0
sp_pay_to_supplier 5665 5665 0 0.00 5 33198 138 0 0
sp_payment_common 12582 12582 0 0.00 1 7981 18 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 0 30205 404 0 0
sp_qd_handle_on_invoice_upd_sts 10466 10466 0 0.00 0 44844 427 0 0
sp_qd_handle_on_reserve_upd_sts 9689 9689 0 0.00 0 10954 61 0 0
sp_reserve_write_off 9697 9697 0 0.00 7 33214 223 0 0
sp_supplier_invoice 5286 5286 0 0.00 8 127262 4000 0 0
sp_supplier_order 5484 5484 0 0.00 6 83257 1499 0 0
srv_aggregate_perf_data 99 99 0 0.00 2583 336204 20306 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1319 126597 42365 0 0
srv_find_qd_qs_mism 1046643 1046643 0 0.00 0 13865 6 0 0
srv_make_invnt_saldo 413 413 0 0.00 55 97497 4360 0 0
srv_make_money_saldo 748 748 0 0.00 1 10765 659 0 0
srv_recalc_idx_stat 4 4 0 0.00 186085 560115 327589 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4948 4948 0 0.00 0 11396 211 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 6149 125 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 1695 14 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 10758 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 11120 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 199 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1245 33 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 25981 1771 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 5693 19 0 0
t$perf-norm:sp_client_order 20147 20147 0 0.00 0 11173 23 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 1582 9 0 0
t$perf-norm:sp_pay_from_customer 6921 6921 0 0.00 0 10853 10 0 0
t$perf-norm:sp_pay_to_supplier 5665 5665 0 0.00 0 11166 11 0 0
t$perf-norm:sp_reserve_write_off 9697 9697 0 0.00 0 11175 17 0 0
t$perf-norm:sp_supplier_invoice 5286 5286 0 0.00 0 6352 24 0 0
t$perf-norm:sp_supplier_order 5484 5484 0 0.00 0 2436 22 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 773 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1862 34 0 0
t$perf-norm:srv_make_invnt_saldo 413 413 0 0.00 0 1431 17 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 2298 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 56 17 0 0
v_add_invoice_to_stock 10168 9886 282 2.77 0 5723 22 0 282
v_all_customers 36416 36416 0 0.00 0 23 1 0 0
v_all_suppliers 29606 29606 0 0.00 0 25 1 0 0
v_all_wares 40294 40294 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 11727 21 0 0
v_cancel_client_order 3298 3298 0 0.00 0 27588 19 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 3353 10 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 2257 7 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 2913 18 0 0
v_cancel_supplier_invoice 808 796 12 1.48 0 432 14 0 12
v_cancel_supplier_order 766 766 0 0.00 0 23755 36 0 0
v_cancel_write_off 1902 1902 0 0.00 0 3078 21 0 0
v_max_id_clo_ord 5480 5480 0 0.00 0 493 2 0 0
v_max_id_clo_res 978 978 0 0.00 0 1762 7 0 0
v_max_id_ord_sup 5271 5271 0 0.00 0 7069 7 0 0
v_max_non_paid_invoice 1611 1611 0 0.00 0 550 2 0 0
v_max_non_paid_realizn 3695 3695 0 0.00 0 2885 4 0 0
v_min_id_clo_ord 5480 5480 0 0.00 0 5352 8 0 0
v_min_id_clo_res 978 978 0 0.00 0 6120 11 0 0
v_min_id_ord_sup 5271 5271 0 0.00 0 5496 6 0 0
v_min_non_paid_invoice 5663 5663 0 0.00 0 4008 9 0 0
v_min_non_paid_realizn 6919 6919 0 0.00 0 7978 11 0 0
v_our_firm 10326 10326 0 0.00 0 64 1 0 0
v_reserve_write_off 19378 19378 0 0.00 0 16354 10 0 0
x_lock_depdocs_on_canc_invoice 11188 11188 0 0.00 0 13438 126 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 5722 368 0 0
x_make_qstorno_1000_1200 5484 5484 0 0.00 2 77691 1394 0 0
x_make_qstorno_1200_2000 5286 5286 0 0.00 4 121629 3842 0 0
x_make_qstorno_2100_3300 461775 461775 0 0.00 0 57209 187 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-11-2021 10:37:58 to 25-11-2021 10:38:02.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:13 4 8192000000 8799354688 8810844160 5350432 0.065 2 1 2 0 5449824 63616 35776
08:08:15 31249 8192000000 9733070640 10026901504 799973488 8.896 100 85 170 0 828771856 7755808 21042560
08:09:52 21485 8192000000 9771573280 10079154176 848424688 9.384 100 82 164 0 877844640 9990944 19429008
08:11:15 27272 8192000000 9804187248 10126540800 878068128 9.680 100 77 154 0 913291504 17682336 17541040
08:12:47 18868 8192000000 9882117200 10219712512 932707280 10.221 100 82 164 0 987915216 37768848 17439088
08:14:14 9754 8192000000 9938625888 10282770432 969800976 10.585 100 84 168 0 1051009440 64060016 17148448
08:15:27 4130 8192000000 9976195824 10331176960 995247840 10.832 100 82 164 0 1093297600 81231904 16817856
08:16:36 7020 8192000000 9981138592 10346442752 1025242704 11.123 100 78 157 1 1091544288 50577312 15724272
08:17:45 2924 8192000000 10014289968 10387677184 1045627072 11.319 100 84 169 0 1131828368 69609408 16591888
08:18:51 2285 8192000000 10040068048 10410942464 1055140080 11.410 100 86 170 1 1155669184 83086896 17442208
08:19:56 32407 8192000000 10007320752 10387451904 1063969424 11.494 100 67 134 0 1140863184 63228320 13665440
08:21:31 4280 8192000000 9991940000 10376704000 1074671600 11.597 100 50 101 1 1102261712 17069408 10520704
08:22:37 6098 8192000000 9997472352 10393071616 1080978992 11.657 100 37 72 1 1119982512 30898688 8104832
08:23:44 14350 8192000000 10011598816 10402807808 1089276544 11.736 100 49 99 1 1109771632 10081392 10413696
08:25:00 4314 8192000000 10020282000 10418307072 1099523168 11.833 100 53 107 1 1141667952 31715200 10429584
08:26:07 5863 8192000000 10012454048 10418327552 1101269776 11.850 100 29 55 2 1126201344 18847904 6083664
08:27:13 63113 8192000000 10031794256 10428325888 1098216640 11.821 99 43 86 2 1132409440 24585488 9607312
08:29:18 5758 8192000000 10009140992 10399014912 1082759056 11.674 99 37 72 1 1113247792 21986144 8502592
08:30:25 4859 8192000000 9954384640 10340229120 1008105072 10.957 94 44 85 4 1033814848 15994752 9715024
08:31:31 63030 8192000000 9922674960 10318151680 956093600 10.451 92 27 54 1 974834656 12399904 6341152
08:33:35 6842 8192000000 9960532672 10355515392 1041487616 11.279 100 32 63 1 1076781952 28436784 6857552
08:34:43 8447 8192000000 9970819104 10359496704 1049141088 11.352 100 27 55 0 1087875184 31969904 6764192
08:35:52 5158 8192000000 9975838496 10361274368 1057174768 11.429 100 36 73 2 1088009216 23492128 7342320
08:36:59 121569 8192000000 10035230160 10427658240 1083188800 11.678 100 40 79 2 1119441680 27235856 9017024
08:40:22 9689 8192000000 10083499648 10482814976 1109127728 11.924 100 43 86 0 1181560048 63938288 8494032
08:41:37 22058 8192000000 10115370352 10511462400 1120982688 12.036 100 59 118 0 1229727264 96812416 11932160
08:43:04 17176 8192000000 10140099552 10549936128 1135133760 12.170 100 52 102 1 1249508416 102666384 11708272
08:44:28 11263 8192000000 10158686704 10566696960 1146476080 12.276 100 54 107 2 1264559280 106787840 11295360
08:45:45 8236 8192000000 10205219248 10610028544 1150745344 12.316 100 64 123 4 1308092256 143483424 13863488
08:46:58 2861 8192000000 10228579984 10633580544 1163068320 12.432 100 75 148 0 1337114896 159264128 14782448
08:48:04 1360 8192000000 10262639488 10664407040 1160782160 12.411 100 90 178 1 1365428368 185122688 19523520
08:49:11 10523 8192000000 10245433808 10666033152 1162029936 12.422 100 57 114 0 1346770400 171779984 12960480
08:50:30 2675 8192000000 10241406368 10659577856 1162173344 12.424 100 59 117 1 1341713824 167120528 12419952
08:51:38 1739 8192000000 10263147888 10664931328 1160336832 12.406 100 87 174 0 1354703312 175396144 18970336
08:52:46 2405 8192000000 10236445984 10644762624 1154869824 12.355 99 72 145 1 1327487968 157213008 15405136
08:53:51 2472 8192000000 10275157248 10681712640 1158853488 12.393 100 89 177 1 1375962160 197622720 19485952
08:54:57 1442 8192000000 10317536896 10724597760 1143350432 12.247 99 84 167 0 1410173248 247822464 19000352
08:56:04 2824 8192000000 10309233168 10709798912 1149559920 12.305 100 92 183 1 1404715840 234232832 20923088
08:57:14 1826 8192000000 10324247312 10725322752 1158964688 12.394 100 73 148 0 1417528528 242279312 16284528
08:58:21 2343 8192000000 10362669056 10756046848 1149982656 12.309 100 89 177 0 1461090496 289441504 21666336
08:59:34 5131 8192000000 10407296192 10809786368 1147719376 12.288 100 80 160 1 1502709744 335996944 18993424
09:00:46 1393 8192000000 10369661056 10815762432 1142035264 12.235 100 96 190 3 1462341296 298250576 22055456
09:01:56 1316 8192000000 10371565264 10788593664 1139311344 12.209 99 78 156 0 1457472560 299230672 18930544
09:03:00 5362 8192000000 10377404352 10795020288 1151833792 12.327 100 44 87 1 1471306880 308509008 10964080
09:04:15 5106 8192000000 10405181648 10823835648 1159289104 12.397 100 57 114 0 1505894912 332610752 13995056
09:05:24 10737 8192000000 10447190832 10882514944 1153837536 12.346 100 45 90 2 1543271808 377614704 11819568
09:07:10 17702 8192000000 10302075136 10758545408 1147917088 12.290 100 23 46 0 1393554816 240029616 5608112
09:08:59 26234 8192000000 10304698912 10789064704 1147581728 12.287 100 14 28 1 1412621120 261381200 3658192
09:10:26 34372 8192000000 10101967728 10585440256 1127711216 12.100 99 12 24 1 1268609120 138267008 2630896
09:12:15 48876 8192000000 10092812720 10530590720 1130820992 12.129 99 48 92 1 1194043440 53505344 9717104
09:14:11 49706 8192000000 10125123344 10597724160 1110371776 11.936 98 38 72 2 1209721040 91035360 8313904
09:16:37 126596 8192000000 10087230224 10540728320 1128586208 12.108 99 22 44 0 1198119248 64777856 4755184
09:20:26 80986 8192000000 10110796896 10576154624 1138162656 12.198 99 13 26 0 1214744240 73760304 2821280
09:22:49 45831 8192000000 10126117120 10574946304 1129637040 12.118 98 23 46 2 1205214656 70663408 4914208
09:24:36 89938 8192000000 10125106288 10578702336 1146438928 12.276 100 14 27 0 1228014576 78639888 2935760
09:27:17 17714 8192000000 10081291216 10522177536 1136714208 12.185 99 27 54 2 1170565936 27908240 5943488
09:28:47 49124 8192000000 10100903392 10544525312 1126409504 12.088 98 32 64 0 1201036800 67497904 7129392
09:30:36 59357 8192000000 10073132000 10570526720 1147256688 12.284 100 12 23 1 1202642608 52251744 3134176
09:32:36 25558 8192000000 10098823568 10540064768 1157044528 12.376 100 50 101 0 1196368128 28585104 10738496
09:34:13 69638 8192000000 10069085936 10561732608 1155333264 12.360 100 10 20 0 1189900928 32853376 1714288
09:36:46 79210 8192000000 10101875792 10583863296 1130973600 12.131 98 19 38 0 1233513536 98850608 3689328
09:39:06 70201 8192000000 10082057696 10535391232 1139594656 12.212 99 13 26 0 1187812016 45630288 2587072
09:41:18 76886 8192000000 10095815824 10573193216 1143482992 12.248 99 13 26 0 1202935376 56847952 2604432
09:43:35 41154 8192000000 10111933456 10572701696 1088287888 11.726 94 18 36 0 1144466160 52438864 3739408
09:45:17 52963 8192000000 10098253600 10597838848 1141414704 12.229 100 31 62 0 1231286064 83382240 6489120
09:47:18 36308 8192000000 10073422240 10510966784 1059241520 11.449 93 46 92 1 1120994848 51406960 10346368
09:48:56 93600 8192000000 10095890432 10572521472 1061813344 11.474 94 16 32 0 1145113904 79729936 3570624
09:51:30 63683 8192000000 10094351760 10532536320 1082471856 11.671 96 31 63 1 1112563536 23921168 6170512
09:53:35 58439 8192000000 10040395712 10492653568 1044472256 11.308 93 23 46 0 1113369824 64516144 4381424
09:57:03 74857 8192000000 10043548432 10515906560 1091744016 11.759 99 6 12 0 1150908848 57809984 1354848
09:59:19 59958 8192000000 10068038032 10511642624 1098501424 11.823 99 11 22 0 1150625872 49693680 2430768
10:01:20 105411 8192000000 10049052992 10517397504 1093858304 11.779 98 13 26 0 1131028528 34514176 2656048
10:04:41 69489 8192000000 10037706384 10493403136 1118095472 12.009 100 21 42 1 1169152112 46776368 4280272
10:06:51 35116 8192000000 10117626576 10556473344 1137681744 12.194 100 22 43 2 1218194912 76017792 4495376
10:08:30 106977 8192000000 10081748848 10541453312 1108168832 11.915 97 14 28 0 1137599776 26584976 2845968
10:12:14 58965 8192000000 10099660640 10552549376 1137280496 12.190 99 27 53 3 1220506384 77861376 5364512
10:14:14 12894 8192000000 10124742816 10551058432 1158455776 12.389 100 48 97 0 1218650176 51020672 9173728
10:15:27 117915 8192000000 10083906576 10567610368 1132627776 12.146 98 24 48 0 1194204928 56850656 4726496
10:18:35 53086 8192000000 10097598336 10558984192 1149228400 12.302 100 18 36 0 1193717360 40818272 3670688
10:20:28 59054 8192000000 10088720448 10559737856 1155308592 12.359 100 8 16 0 1189776368 32852784 1614992
10:22:28 50259 8192000000 10140545376 10597257216 1163416352 12.435 100 19 38 0 1221757472 54287152 4053968
10:24:22 69234 8192000000 10109381440 10563739648 1152191040 12.330 99 16 32 1 1206956944 51374656 3391248
10:26:35 76341 8192000000 10149146208 10662637568 1169001056 12.487 100 15 30 0 1266933840 94921776 3011008
10:30:07 29063 8192000000 10123779104 10565976064 1166832560 12.467 100 26 52 0 1216647872 44272720 5542592
10:31:39 45836 8192000000 10202895440 10647097344 1145793680 12.270 98 34 68 0 1260074720 106700688 7580352
10:33:36 90956 8192000000 10112444352 10667143168 1129070352 12.113 98 13 26 0 1262692160 130848160 2773648
10:36:09 30320 8192000000 10090903408 10521587712 1122091632 12.047 98 30 60 0 1159475488 31040896 6342960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 25-11-2021 10:38:04 to 25-11-2021 10:38:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-11-2021 10:38:19 to 25-11-2021 10:38:21.

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          221146
MON$OLDEST_ACTIVE               225062
MON$OLDEST_SNAPSHOT             225062
MON$NEXT_TRANSACTION            225062
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                       2080544
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 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 ---
:335: 
:336: mscs-test-161.budzdorov.ru    Thu Nov 25 10:37:11 2021
:337:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:338: 
:339: 
:340: mscs-test-161.budzdorov.ru    Thu Nov 25 10:37:13 2021
:341:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:342: 
--- end of diff output ---

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

25.11.21 10:38:22. 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-11-25 10:38:24 No severe PSQL-related problems occured
25.11.21 10:38:26. End of report.