Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.11.2021 07: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 16737284 kB
MemAvailable 31567996 kB
Buffers 0 kB
Cached 14922124 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-20 07:37:04
DTS_END                         2021-11-20 07:37:04.4420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(301:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49028
TRN_ID                          232584
ATT_ID                          664
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: 8937.84 20343 1072541
customer order: creation 165.68 442 19882
customer order: refuse 14.41 368 1729
order to supplier: creation 45.30 1548 5436
order to supplier: removal 3.53 109866 423
invoice (draft): creation 44.57 3610 5348
invoice (draft): removal 50.23 1016 6028
invoice accept: apply 40.71 19816 4885
invoice accept: cancel 45.30 848 5436
customer reserve: creation 3757.98 196 450957
customer reserve: removal 4488.98 67 538677
realization accept: apply 80.58 189 9669
realization accept: cancel 76.82 128 9218
payment from customer: creation 56.83 135 6819
payment from customer: removal 5.78 93 694
payment to supplier: creation 47.40 148 5688
payment to supplier: removal 3.34 66 401
service: total inventory turnovers 3.70 3605 444
service: total monetary turnovers 5.86 636 703
service: aggregate perf. data 0.83 16481 100
service: refresh index statistics 0.03 247601 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-11-2021 07:37:47 to 20-11-2021 07:37:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6177
TEST_TIME 2 5716
TEST_TIME 3 6381
TEST_TIME 4 6793
TEST_TIME 5 7149
TEST_TIME 6 6865
TEST_TIME 7 6540
TEST_TIME 8 6666
TEST_TIME 9 6612
TEST_TIME 10 6867
TEST_TIME 11 7045
TEST_TIME 12 7029
TEST_TIME 13 7217
TEST_TIME 14 7426
TEST_TIME 15 7852
TEST_TIME 16 8338
TEST_TIME 17 8360
TEST_TIME 18 8679
TEST_TIME 19 8930
TEST_TIME 20 8908
TEST_TIME 21 9297
TEST_TIME 22 10221
TEST_TIME 23 10327
TEST_TIME 24 10119
TEST_TIME 25 10564
TEST_TIME 26 11635
TEST_TIME 27 11294
TEST_TIME 28 11245
TEST_TIME 29 11161
TEST_TIME 30 10977
TEST_TIME 31 10944
TEST_TIME 32 10896
TEST_TIME 33 10728
TEST_TIME 34 10513
TEST_TIME 35 10426
TEST_TIME 36 10352
TEST_TIME 37 10386
TEST_TIME 38 10236
TEST_TIME 39 10090
TEST_TIME 40 10156
TEST_TIME 41 9990
TEST_TIME 42 9917
TEST_TIME 43 9817
TEST_TIME 44 9679
TEST_TIME 45 9620
TEST_TIME 46 9525
TEST_TIME 47 9403
TEST_TIME 48 9344
TEST_TIME 49 9235
TEST_TIME 50 9228
TEST_TIME 51 9205
TEST_TIME 52 9220
TEST_TIME 53 9330
TEST_TIME 54 9335
TEST_TIME 55 9282
TEST_TIME 56 9288
TEST_TIME 57 9477
TEST_TIME 58 9423
TEST_TIME 59 9465
TEST_TIME 60 9565
TEST_TIME 61 9552
TEST_TIME 62 9471
TEST_TIME 63 9442
TEST_TIME 64 9437
TEST_TIME 65 9354
TEST_TIME 66 9364
TEST_TIME 67 9298
TEST_TIME 68 9228
TEST_TIME 69 9155
TEST_TIME 70 9097
TEST_TIME 71 9206
TEST_TIME 72 9166
TEST_TIME 73 9162
TEST_TIME 74 9168
TEST_TIME 75 9160
TEST_TIME 76 9254
TEST_TIME 77 9245
TEST_TIME 78 9211
TEST_TIME 79 9202
TEST_TIME 80 9334
TEST_TIME 81 9316
TEST_TIME 82 9394
TEST_TIME 83 9309
TEST_TIME 84 9273
TEST_TIME 85 9218
TEST_TIME 86 9232
TEST_TIME 87 9198
TEST_TIME 88 9139
TEST_TIME 89 9105
TEST_TIME 90 9062
TEST_TIME 91 9019
TEST_TIME 92 9021
TEST_TIME 93 9095
TEST_TIME 94 9075
TEST_TIME 95 9095
TEST_TIME 96 9086
TEST_TIME 97 9056
TEST_TIME 98 9121
TEST_TIME 99 9210
TEST_TIME 100 9170
TEST_TIME 101 9239
TEST_TIME 102 9246
TEST_TIME 103 9263
TEST_TIME 104 9183
TEST_TIME 105 9123
TEST_TIME 106 9084
TEST_TIME 107 9043
TEST_TIME 108 8999
TEST_TIME 109 8987
TEST_TIME 110 8970
TEST_TIME 111 8935
TEST_TIME 112 8922
TEST_TIME 113 8941
TEST_TIME 114 8916
TEST_TIME 115 9014
TEST_TIME 116 8986
TEST_TIME 117 9007
TEST_TIME 118 8993
TEST_TIME 119 9015
TEST_TIME 120 8944

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-11-2021 07:37:50 to 20-11-2021 07:37:54.

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 1489925 1489925 0 0.00 0 21874 7 0 0
doc_list_biud 1489925 1489925 0 0.00 0 134030 31 0 0
sp_add_invoice_to_stock 5027 4885 142 2.82 35 193428 19817 0 142
sp_cancel_adding_invoice 5436 5436 0 0.00 7 48329 848 0 0
sp_cancel_client_order 1729 1729 0 0.00 4 16617 368 0 0
sp_cancel_customer_reserve 538677 538677 0 0.00 1 27019 68 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 13475 93 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 3247 66 0 0
sp_cancel_supplier_invoice 6042 6028 14 0.23 2 31258 1017 0 14
sp_cancel_supplier_order 423 423 0 0.00 6 995455 109867 0 0
sp_cancel_write_off 9218 9218 0 0.00 1 134054 129 0 0
sp_client_order 19882 19882 0 0.00 4 52510 442 0 0
sp_customer_reserve 450957 450957 0 0.00 0 118923 197 0 0
sp_fill_shopping_cart 30651 30651 0 0.00 1 28211 48 0 0
sp_fill_shopping_cart_clo_res 450946 450946 0 0.00 0 14252 16 0 0
sp_get_clo_for_invoice 4874 4874 0 0.00 0 30329 406 0 0
sp_kill_cost_storno 15749 15749 0 0.00 0 9439 57 0 0
sp_kill_qstorno_ret_qs2qd 1093023 1093023 0 0.00 0 134028 37 0 0
sp_kill_qty_storno 576054 576054 0 0.00 0 134028 79 0 0
sp_lock_selected_doc 20293 20293 0 0.00 0 8343 5 0 0
sp_make_cost_storno 27050 27050 0 0.00 0 18698 62 0 0
sp_multiply_rows_for_pdistr 27050 27050 0 0.00 0 11698 34 0 0
sp_multiply_rows_for_qdistr 19882 19882 0 0.00 0 51546 212 0 0
sp_pay_from_customer 6819 6819 0 0.00 4 24656 135 0 0
sp_pay_to_supplier 5688 5688 0 0.00 5 17934 148 0 0
sp_payment_common 12507 12507 0 0.00 1 15584 26 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 16610 331 0 0
sp_qd_handle_on_invoice_upd_sts 10310 10310 0 0.00 0 16267 347 0 0
sp_qd_handle_on_reserve_upd_sts 9669 9669 0 0.00 0 12537 59 0 0
sp_reserve_write_off 9669 9669 0 0.00 7 18710 190 0 0
sp_supplier_invoice 5348 5348 0 0.00 5 102190 3610 0 0
sp_supplier_order 5436 5436 0 0.00 5 54388 1549 0 0
srv_aggregate_perf_data 100 100 0 0.00 2832 376935 16482 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1758 159554 48279 0 0
srv_find_qd_qs_mism 1026197 1026197 0 0.00 0 21873 6 0 0
srv_make_invnt_saldo 444 444 0 0.00 77 133824 3605 0 0
srv_make_money_saldo 703 703 0 0.00 1 12676 637 0 0
srv_recalc_idx_stat 4 4 0 0.00 123237 479290 247602 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4885 4885 0 0.00 0 15129 172 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 13336 90 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 4915 19 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 15501 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 3049 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 866 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 3218 40 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 29427 1496 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 3080 11 0 0
t$perf-norm:sp_client_order 19882 19882 0 0.00 0 11039 20 0 0
t$perf-norm:sp_customer_reserve 914 914 0 0.00 0 3999 20 0 0
t$perf-norm:sp_pay_from_customer 6819 6819 0 0.00 0 5976 7 0 0
t$perf-norm:sp_pay_to_supplier 5688 5688 0 0.00 0 9346 11 0 0
t$perf-norm:sp_reserve_write_off 9669 9669 0 0.00 0 9032 13 0 0
t$perf-norm:sp_supplier_invoice 5348 5348 0 0.00 0 10655 19 0 0
t$perf-norm:sp_supplier_order 5436 5436 0 0.00 0 7678 17 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 1048 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 61 1 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 3433 12 0 0
t$perf-norm:srv_make_money_saldo 703 703 0 0.00 0 2329 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10032 9748 284 2.83 0 3535 17 0 284
v_all_customers 36282 36282 0 0.00 0 50 1 0 0
v_all_suppliers 29988 29988 0 0.00 0 51 1 0 0
v_all_wares 39764 39764 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 1652 7 0 0
v_cancel_client_order 3458 3458 0 0.00 0 4551 8 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 12771 19 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 969 5 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 3123 8 0 0
v_cancel_supplier_invoice 838 810 28 3.34 0 297 11 0 28
v_cancel_supplier_order 846 846 0 0.00 0 1206 5 0 0
v_cancel_write_off 1954 1954 0 0.00 0 4307 17 0 0
v_max_id_clo_ord 5434 5434 0 0.00 0 1334 2 0 0
v_max_id_clo_res 914 914 0 0.00 0 1910 13 0 0
v_max_id_ord_sup 5335 5335 0 0.00 0 6621 8 0 0
v_max_non_paid_invoice 1463 1463 0 0.00 0 404 2 0 0
v_max_non_paid_realizn 3681 3681 0 0.00 0 11690 6 0 0
v_min_id_clo_ord 5434 5434 0 0.00 0 4350 6 0 0
v_min_id_clo_res 914 914 0 0.00 0 4106 11 0 0
v_min_id_ord_sup 5335 5335 0 0.00 0 6598 9 0 0
v_min_non_paid_invoice 5688 5688 0 0.00 0 7794 16 0 0
v_min_non_paid_realizn 6819 6819 0 0.00 0 9023 21 0 0
v_our_firm 9758 9758 0 0.00 0 13 1 0 0
v_reserve_write_off 19336 19336 0 0.00 0 4553 5 0 0
x_lock_depdocs_on_canc_invoice 11059 11059 0 0.00 0 31748 139 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 23067 370 0 0
x_make_qstorno_1000_1200 5436 5436 0 0.00 2 50107 1468 0 0
x_make_qstorno_1200_2000 5348 5348 0 0.00 2 101568 3425 0 0
x_make_qstorno_2100_3300 450957 450957 0 0.00 0 118590 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-11-2021 07:37:55 to 20-11-2021 07:37:59.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:07 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:09 35869 8192000000 9724310384 10021023744 795325600 8.849 100 92 184 0 826424096 8411104 22687392
05:09:48 17469 8192000000 9761008208 10066305024 834132608 9.241 100 91 182 2 869424080 13605856 21685616
05:11:16 13265 8192000000 9803420832 10121515008 868459440 9.585 100 85 170 0 911727824 24023360 19245024
05:12:31 12307 8192000000 9847971776 10180141056 906205200 9.960 100 73 146 1 961240960 39217216 15818544
05:13:50 7001 8192000000 9939366176 10282704896 957184656 10.461 100 85 169 0 1048072048 73290592 17596800
05:14:58 5076 8192000000 9962701744 10317111296 998533776 10.864 100 76 151 0 1072876384 58566320 15776288
05:16:05 5304 8192000000 10027853520 10391830528 1026829328 11.138 100 88 176 0 1147808016 103625488 17353200
05:17:16 1375 8192000000 10029399648 10399981568 1057076928 11.428 100 95 190 2 1140421296 63795792 19548576
05:18:19 20858 8192000000 10002907760 10384662528 1066707648 11.521 100 61 122 0 1125960944 46299056 12954240
05:19:41 5669 8192000000 9999846752 10393972736 1079416304 11.642 100 47 94 0 1127569184 37894432 10258448
05:20:48 7596 8192000000 9998240288 10399502336 1084545472 11.691 100 32 65 0 1129397248 37752768 7099008
05:21:59 5849 8192000000 10010239728 10404700160 1090762704 11.750 100 38 77 0 1126554016 27150112 8641200
05:23:07 19533 8192000000 9995975888 10407694336 1091987264 11.762 100 43 86 2 1139222272 37995120 9239888
05:24:30 6217 8192000000 10007631648 10412101632 1097386208 11.813 100 25 49 1 1123577520 20609200 5582112
05:25:38 10560 8192000000 10018033440 10432684032 1098528112 11.824 100 25 49 1 1124735664 20963968 5243584
05:26:49 6683 8192000000 9996096800 10383237120 1040377408 11.268 96 47 94 1 1069146816 18343856 10425552
05:27:57 9205 8192000000 9999448432 10414612480 1083686400 11.683 100 46 90 2 1111933200 18127424 10119376
05:29:06 3715 8192000000 9980040288 10368958464 1048360912 11.345 99 54 106 2 1084138976 24738752 11039312
05:30:10 11468 8192000000 9941441296 10323656704 1011300576 10.988 97 33 65 2 1044232416 25860368 7071472
05:31:23 22207 8192000000 9908651152 10302197760 974682048 10.632 96 41 82 1 1000939888 17542208 8715632
05:33:23 8478 8192000000 9975030400 10372005888 1054533152 11.404 100 56 109 5 1085967504 20382576 11051776
05:34:32 4139 8192000000 10006585824 10391429120 1067655952 11.530 100 49 95 2 1111330864 33636800 10038112
05:35:40 7029 8192000000 9992862688 10386952192 1075982544 11.609 100 34 67 1 1096454704 12650848 7821312
05:36:49 17721 8192000000 9997981072 10398924800 1079390288 11.642 100 22 44 1 1113200464 29378832 4431344
05:38:18 9702 8192000000 10045427520 10441314304 1108296944 11.916 100 52 102 1 1158672928 38472480 11903504
05:39:33 33998 8192000000 10082919664 10491662336 1115431328 11.984 99 45 90 0 1209216080 84976048 8808704
05:41:08 15035 8192000000 10103079616 10518896640 1137280256 12.190 100 49 97 1 1244158896 96013824 10864816
05:42:29 23017 8192000000 10156935472 10565976064 1152454976 12.333 100 66 130 3 1265770336 99804128 13511232
05:44:05 18380 8192000000 10184948544 10597363712 1172267264 12.518 100 54 107 1 1284791648 101408080 11116304
05:45:26 2138 8192000000 10233525552 10641862656 1188542240 12.670 100 60 117 0 1342305440 140687824 13075376
05:46:36 6756 8192000000 10303344560 10719166464 1192235280 12.704 100 68 136 0 1408832800 201016864 15580656
05:47:48 5443 8192000000 10301618128 10717716480 1185676224 12.643 100 67 133 4 1408239184 206929968 15632992
05:48:57 2526 8192000000 10355870528 10761154560 1183497872 12.623 100 71 140 1 1463655632 264313328 15844432
05:50:03 3843 8192000000 10392242352 10794323968 1157378400 12.379 100 75 149 0 1491967760 317806656 16782704
05:51:13 4173 8192000000 10349486864 10754899968 1147325392 12.284 100 73 146 2 1456943680 291975344 17642944
05:52:19 1851 8192000000 10373314608 10773647360 1152378528 12.332 100 80 160 0 1469194608 297538240 19277840
05:53:24 8421 8192000000 10351811312 10789167104 1145964336 12.272 100 48 94 2 1464345360 306518320 11862704
05:54:37 3073 8192000000 10388531536 10797248512 1153674416 12.344 100 74 147 0 1484264496 312950400 17639680
05:55:46 1758 8192000000 10412670912 10813280256 1139770992 12.213 100 79 158 1 1506174800 347898256 18505552
05:56:59 8614 8192000000 10387285600 10810740736 1146346832 12.275 100 40 78 1 1476245296 319858400 10040064
05:58:12 2584 8192000000 10420058896 10830909440 1146492400 12.277 100 86 171 0 1512627808 345893984 20241424
05:59:16 9278 8192000000 10354200496 10860019712 1159188464 12.396 100 37 74 0 1473334896 304074336 10072096
06:00:48 27928 8192000000 10292881088 10729156608 1125345040 12.077 97 28 56 0 1353076992 220941408 6790544
06:02:24 41270 8192000000 10275711552 10750025728 1111265488 11.944 99 17 34 0 1397324544 281440688 4618368
06:04:09 41806 8192000000 10100646512 10532761600 1127765392 12.100 100 38 76 2 1201177520 64744864 8667264
06:05:52 119033 8192000000 10144715248 10588581888 1139085264 12.207 100 27 54 1 1242880016 97443088 6351664
06:09:02 60788 8192000000 10084428000 10526429184 1128632160 12.108 99 10 20 2 1176376272 45592224 2151888
06:11:04 98035 8192000000 10117091632 10562838528 1145833168 12.270 100 29 58 2 1223170784 70902336 6435280
06:13:47 55115 8192000000 10071835488 10547740672 1111181744 11.944 97 25 50 1 1169899056 53960432 4756880
06:15:54 159554 8192000000 10056266672 10510245888 1094827296 11.789 96 15 30 0 1132979488 34934912 3217280
06:19:39 68131 8192000000 10072747120 10526146560 1140518848 12.220 100 17 34 0 1182820672 39007072 3294752
06:21:52 69438 8192000000 10079388384 10558771200 1145138736 12.264 100 17 34 0 1209718384 61346016 3233632
06:24:12 51145 8192000000 10066583536 10509107200 1139025616 12.206 99 22 44 0 1172370160 29196416 4148128
06:26:03 103684 8192000000 10089013648 10582745088 1130321360 12.124 98 11 22 1 1172972480 40145920 2505200
06:28:47 65673 8192000000 10102053872 10574635008 1133834944 12.157 98 16 31 0 1210124480 72510480 3779056
06:31:08 24324 8192000000 10097796624 10528403456 1132175920 12.142 99 31 61 1 1172071552 33508352 6387280
06:32:36 31789 8192000000 10130724272 10561703936 1128688944 12.109 98 39 78 1 1213016672 76142576 8185152
06:34:12 50093 8192000000 10070708576 10558283776 1104821952 11.883 97 21 42 1 1200944400 91979984 4142464
06:36:03 80483 8192000000 10117756464 10592813056 1122597584 12.052 99 27 54 1 1210338816 82285600 5455632
06:39:00 127446 8192000000 10077917696 10560073728 1099195632 11.830 97 18 36 0 1155322032 52507040 3619360
06:42:07 45333 8192000000 10082424064 10528075776 1106296640 11.897 98 50 98 3 1167775664 51372752 10106272
06:43:53 61260 8192000000 10048179008 10490707968 1104003584 11.876 98 16 31 1 1131488336 24393280 3091472
06:45:54 75237 8192000000 10088574848 10528673792 1109290464 11.926 98 23 46 0 1161505760 46582016 5633280
06:49:03 49150 8192000000 10061153872 10497376256 1105699904 11.892 97 22 42 0 1137917056 27915200 4301952
06:50:58 55825 8192000000 10076212432 10518745088 1092277776 11.764 96 24 48 1 1156993920 59464240 5251904
06:53:01 87198 8192000000 10059861264 10537914368 1090486320 11.747 96 22 46 0 1150371184 55336752 4548112
06:55:28 36096 8192000000 10086823520 10514853888 1107835536 11.912 98 44 86 1 1154482528 38158512 8488480
06:57:05 94785 8192000000 10092977024 10543443968 1119517328 12.022 99 17 34 1 1171748320 49213424 3017568
06:59:43 111664 8192000000 10058866592 10520141824 1133393072 12.153 100 20 39 0 1197004896 58900752 4711072
07:02:44 35965 8192000000 10092575776 10547859456 1139636528 12.212 100 36 72 0 1208487344 61544544 7306272
07:04:55 64707 8192000000 10048927920 10512732160 1107624496 11.910 98 8 16 0 1143106096 33417984 2063616
07:07:05 55491 8192000000 10087762576 10531418112 1127841648 12.101 99 19 38 0 1165571568 34364592 3365328
07:09:01 55832 8192000000 10130570944 10581626880 1130604624 12.127 98 20 40 0 1207155824 72102144 4449056
07:11:01 38651 8192000000 10064285248 10492203008 1106541264 11.900 97 30 60 1 1146492560 32361120 7590176
07:12:48 18770 8192000000 10088666976 10518351872 1142129920 12.236 100 45 90 1 1179287408 27791552 9365936
07:14:12 84548 8192000000 10136814880 10641293312 1142495408 12.239 99 25 51 0 1277266608 129642736 5128464
07:16:43 82115 8192000000 10135822272 10598694912 1119091840 12.018 97 17 33 0 1165659824 43584912 2983072
07:21:01 110065 8192000000 10066043744 10502316032 1141817104 12.233 99 29 58 1 1169291024 21669264 5804656
07:23:53 40635 8192000000 10072025936 10544332800 1152433376 12.332 100 8 15 1 1190244272 36112656 1698240
07:25:40 74155 8192000000 10104311232 10556633088 1118220608 12.010 97 17 35 0 1173721072 51786496 3713968
07:28:00 63545 8192000000 10109819200 10572886016 1134552576 12.164 99 11 21 2 1194810352 57975424 2282352
07:30:09 58094 8192000000 10123757568 10565378048 1139041504 12.207 99 27 54 0 1233573024 87759008 6772512
07:32:07 57734 8192000000 10089425824 10595958784 1133629808 12.156 99 18 36 0 1225996512 88507312 3859392
07:34:07 66018 8192000000 10117532928 10568056832 1093634016 11.777 96 21 42 0 1166009808 67607440 4768352
07:36:28 16303 8192000000 10050172880 10476597248 1112560656 11.957 99 36 72 0 1136358864 16628128 7170080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-11-2021 07:38:00 to 20-11-2021 07:38:15.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-11-2021 07:38:16 to 20-11-2021 07:38:18.

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          230673
MON$OLDEST_ACTIVE               232769
MON$OLDEST_SNAPSHOT             232769
MON$NEXT_TRANSACTION            232769
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                       2040608
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             795
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 ---
:58: 
:59: mscs-test-161.budzdorov.ru    Sat Nov 20 07:37:09 2021
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
--- end of diff output ---

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

20.11.21 07:38:19. 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-20 07:38:21 No severe PSQL-related problems occured
20.11.21 07:38:22. End of report.