Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.10.2021 04: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15994088 kB
MemAvailable 30802320 kB
Buffers 20 kB
Cached 15636924 kB
SwapCached 4792 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-10-29 04:37:09
DTS_END                         2021-10-29 04:37:09.1050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46856
TRN_ID                          230472
ATT_ID                          644
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: 8856.42 22172 1062770
customer order: creation 164.36 436 19723
customer order: refuse 14.03 371 1684
order to supplier: creation 43.43 1547 5211
order to supplier: removal 3.26 122981 391
invoice (draft): creation 43.49 3775 5219
invoice (draft): removal 49.58 986 5950
invoice accept: apply 39.87 20617 4784
invoice accept: cancel 44.92 874 5390
customer reserve: creation 3755.13 199 450616
customer reserve: removal 4413.67 71 529640
realization accept: apply 80.42 220 9650
realization accept: cancel 80.47 136 9656
payment from customer: creation 57.32 127 6878
payment from customer: removal 5.51 79 661
payment to supplier: creation 47.34 159 5681
payment to supplier: removal 3.28 158 393
service: total inventory turnovers 3.58 3975 430
service: total monetary turnovers 5.93 705 712
service: aggregate perf. data 0.80 19677 96
service: refresh index statistics 0.04 266349 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-10-2021 04:38:03 to 29-10-2021 04:38:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4514
TEST_TIME 2 5371
TEST_TIME 3 5688
TEST_TIME 4 6425
TEST_TIME 5 6057
TEST_TIME 6 5894
TEST_TIME 7 5873
TEST_TIME 8 6167
TEST_TIME 9 6140
TEST_TIME 10 6476
TEST_TIME 11 6606
TEST_TIME 12 6829
TEST_TIME 13 7253
TEST_TIME 14 7177
TEST_TIME 15 7518
TEST_TIME 16 7610
TEST_TIME 17 8264
TEST_TIME 18 8412
TEST_TIME 19 8457
TEST_TIME 20 8848
TEST_TIME 21 8846
TEST_TIME 22 9144
TEST_TIME 23 9228
TEST_TIME 24 9429
TEST_TIME 25 9711
TEST_TIME 26 10229
TEST_TIME 27 11044
TEST_TIME 28 11516
TEST_TIME 29 11183
TEST_TIME 30 10903
TEST_TIME 31 10766
TEST_TIME 32 10602
TEST_TIME 33 10434
TEST_TIME 34 10304
TEST_TIME 35 10098
TEST_TIME 36 10079
TEST_TIME 37 10065
TEST_TIME 38 10006
TEST_TIME 39 10012
TEST_TIME 40 9824
TEST_TIME 41 9698
TEST_TIME 42 9656
TEST_TIME 43 9572
TEST_TIME 44 9522
TEST_TIME 45 9491
TEST_TIME 46 9322
TEST_TIME 47 9225
TEST_TIME 48 9225
TEST_TIME 49 9195
TEST_TIME 50 9097
TEST_TIME 51 9163
TEST_TIME 52 9226
TEST_TIME 53 9148
TEST_TIME 54 9174
TEST_TIME 55 9175
TEST_TIME 56 9102
TEST_TIME 57 9056
TEST_TIME 58 9199
TEST_TIME 59 9139
TEST_TIME 60 9422
TEST_TIME 61 9374
TEST_TIME 62 9334
TEST_TIME 63 9208
TEST_TIME 64 9145
TEST_TIME 65 9146
TEST_TIME 66 9076
TEST_TIME 67 9063
TEST_TIME 68 9001
TEST_TIME 69 8948
TEST_TIME 70 9072
TEST_TIME 71 9083
TEST_TIME 72 9047
TEST_TIME 73 9026
TEST_TIME 74 9089
TEST_TIME 75 9152
TEST_TIME 76 9211
TEST_TIME 77 9164
TEST_TIME 78 9301
TEST_TIME 79 9272
TEST_TIME 80 9162
TEST_TIME 81 9182
TEST_TIME 82 9145
TEST_TIME 83 9115
TEST_TIME 84 9064
TEST_TIME 85 9028
TEST_TIME 86 9040
TEST_TIME 87 8982
TEST_TIME 88 8977
TEST_TIME 89 8914
TEST_TIME 90 8865
TEST_TIME 91 8940
TEST_TIME 92 8902
TEST_TIME 93 8913
TEST_TIME 94 8968
TEST_TIME 95 8968
TEST_TIME 96 9001
TEST_TIME 97 9026
TEST_TIME 98 9022
TEST_TIME 99 9033
TEST_TIME 100 9054
TEST_TIME 101 9041
TEST_TIME 102 9037
TEST_TIME 103 9069
TEST_TIME 104 9021
TEST_TIME 105 8984
TEST_TIME 106 8981
TEST_TIME 107 8939
TEST_TIME 108 8940
TEST_TIME 109 8912
TEST_TIME 110 8862
TEST_TIME 111 8843
TEST_TIME 112 8844
TEST_TIME 113 8829
TEST_TIME 114 8854
TEST_TIME 115 8848
TEST_TIME 116 8853
TEST_TIME 117 8911
TEST_TIME 118 8888
TEST_TIME 119 8878
TEST_TIME 120 8857

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-10-2021 04:38:07 to 29-10-2021 04:38:10.

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 1476446 1476446 0 0.00 0 18923 7 0 0
doc_list_biud 1476446 1476446 0 0.00 0 60068 32 0 0
sp_add_invoice_to_stock 4889 4784 105 2.14 39 212432 20618 0 105
sp_cancel_adding_invoice 5390 5390 0 0.00 5 76722 875 0 0
sp_cancel_client_order 1684 1684 0 0.00 4 36074 372 0 0
sp_cancel_customer_reserve 529640 529640 0 0.00 1 52441 72 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 4650 79 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 12937 159 0 0
sp_cancel_supplier_invoice 5961 5950 11 0.18 2 60073 986 0 11
sp_cancel_supplier_order 391 391 0 0.00 16 1097442 122981 0 0
sp_cancel_write_off 9656 9656 0 0.00 1 36890 137 0 0
sp_client_order 19723 19723 0 0.00 4 43516 437 0 0
sp_customer_reserve 450616 450616 0 0.00 0 102133 199 0 0
sp_fill_shopping_cart 30144 30144 0 0.00 1 17698 52 0 0
sp_fill_shopping_cart_clo_res 450607 450607 0 0.00 0 12074 18 0 0
sp_get_clo_for_invoice 4775 4775 0 0.00 0 19386 397 0 0
sp_kill_cost_storno 16100 16100 0 0.00 0 9163 63 0 0
sp_kill_qstorno_ret_qs2qd 1075277 1075277 0 0.00 0 60066 39 0 0
sp_kill_qty_storno 567135 567135 0 0.00 0 60067 83 0 0
sp_lock_selected_doc 19976 19976 0 0.00 0 10547 5 0 0
sp_make_cost_storno 26983 26983 0 0.00 0 18911 61 0 0
sp_multiply_rows_for_pdistr 26983 26983 0 0.00 0 9938 33 0 0
sp_multiply_rows_for_qdistr 19723 19723 0 0.00 0 12835 197 0 0
sp_pay_from_customer 6878 6878 0 0.00 4 12870 128 0 0
sp_pay_to_supplier 5681 5681 0 0.00 5 15968 160 0 0
sp_payment_common 12559 12559 0 0.00 1 10730 24 0 0
sp_qd_handle_on_cancel_clo 1684 1684 0 0.00 0 36066 317 0 0
sp_qd_handle_on_invoice_upd_sts 10164 10164 0 0.00 0 25120 365 0 0
sp_qd_handle_on_reserve_upd_sts 9650 9650 0 0.00 0 36786 72 0 0
sp_reserve_write_off 9650 9650 0 0.00 7 36809 220 0 0
sp_supplier_invoice 5219 5219 0 0.00 7 98689 3775 0 0
sp_supplier_order 5211 5211 0 0.00 5 63414 1547 0 0
srv_aggregate_perf_data 96 96 0 0.00 2842 305291 19677 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1912 136880 48228 0 0
srv_find_qd_qs_mism 1014993 1014993 0 0.00 0 13718 6 0 0
srv_make_invnt_saldo 430 430 0 0.00 75 140034 3975 0 0
srv_make_money_saldo 712 712 0 0.00 1 30027 706 0 0
srv_recalc_idx_stat 5 5 0 0.00 115499 466404 266349 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4784 4784 0 0.00 0 14707 190 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 1 4063 133 0 0
t$perf-norm:sp_cancel_client_order 1684 1684 0 0.00 0 4820 22 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 626 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 2776 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 659 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 526 11 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 17845 1639 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 2945 12 0 0
t$perf-norm:sp_client_order 19723 19723 0 0.00 0 6847 21 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 530 5 0 0
t$perf-norm:sp_pay_from_customer 6878 6878 0 0.00 0 4208 8 0 0
t$perf-norm:sp_pay_to_supplier 5681 5681 0 0.00 0 6741 11 0 0
t$perf-norm:sp_reserve_write_off 9650 9650 0 0.00 0 5720 9 0 0
t$perf-norm:sp_supplier_invoice 5219 5219 0 0.00 0 14708 22 0 0
t$perf-norm:sp_supplier_order 5211 5211 0 0.00 0 9145 19 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 391 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 713 19 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 1173 9 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 10379 21 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 12 4 0 0
v_add_invoice_to_stock 9754 9544 210 2.15 0 13079 28 0 210
v_all_customers 36084 36084 0 0.00 0 16 1 0 0
v_all_suppliers 29080 29080 0 0.00 0 19 1 0 0
v_all_wares 39444 39444 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 3613 11 0 0
v_cancel_client_order 3368 3368 0 0.00 0 11785 18 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 1301 6 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 11776 14 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 12926 46 0 0
v_cancel_supplier_invoice 782 760 22 2.81 0 2416 21 0 22
v_cancel_supplier_order 782 782 0 0.00 0 7417 13 0 0
v_cancel_write_off 2022 2022 0 0.00 0 7498 24 0 0
v_max_id_clo_ord 5211 5211 0 0.00 0 5127 3 0 0
v_max_id_clo_res 962 962 0 0.00 0 3851 20 0 0
v_max_id_ord_sup 5211 5211 0 0.00 0 3927 3 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 425 2 0 0
v_max_non_paid_realizn 3682 3682 0 0.00 0 3175 6 0 0
v_min_id_clo_ord 5211 5211 0 0.00 0 5133 13 0 0
v_min_id_clo_res 962 962 0 0.00 0 4354 18 0 0
v_min_id_ord_sup 5211 5211 0 0.00 0 8133 9 0 0
v_min_non_paid_invoice 5681 5681 0 0.00 0 10711 17 0 0
v_min_non_paid_realizn 6878 6878 0 0.00 0 5150 16 0 0
v_our_firm 9752 9752 0 0.00 0 18 1 0 0
v_reserve_write_off 19300 19300 0 0.00 0 11784 11 0 0
x_lock_depdocs_on_canc_invoice 10960 10960 0 0.00 0 21225 129 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 11567 389 0 0
x_make_qstorno_1000_1200 5211 5211 0 0.00 2 63344 1446 0 0
x_make_qstorno_1200_2000 5219 5219 0 0.00 3 88641 3583 0 0
x_make_qstorno_2100_3300 450616 450616 0 0.00 0 102131 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-10-2021 04:38:11 to 29-10-2021 04:38:15.

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
02:07:12 12 8192000000 8802622560 8815370240 7510864 0.091 3 1 2 0 7610256 63616 35776
02:08:14 28029 8192000000 9721872368 10018607104 799430352 8.891 100 90 180 0 830595904 7622192 23543360
02:09:52 16749 8192000000 9770655504 10078846976 847815072 9.378 100 86 172 0 879930656 12015264 20100320
02:11:13 17717 8192000000 9810020304 10131189760 879033056 9.690 100 81 160 1 916720416 19720448 17966912
02:12:39 14799 8192000000 9871011728 10206236672 930808576 10.203 100 83 167 0 979969328 31575344 17585408
02:13:56 7523 8192000000 9935198336 10279534592 964684592 10.535 100 81 162 0 1043282464 61646752 16951120
02:15:06 4558 8192000000 9969421536 10323447808 994712304 10.827 100 77 154 0 1078135840 67283712 16139824
02:16:13 2474 8192000000 9992230128 10355699712 1020160016 11.074 100 87 174 0 1110692608 72950768 17581824
02:17:18 2092 8192000000 10006789344 10371280896 1046746256 11.329 100 92 184 0 1119291632 52651488 19893888
02:18:21 1564 8192000000 10057101312 10429349888 1063328208 11.488 100 93 184 0 1169109920 86787120 18994592
02:19:23 9688 8192000000 10015459520 10415550464 1064763440 11.502 100 39 78 0 1132161728 59641536 7756752
02:20:40 6523 8192000000 10000099792 10398314496 1077763280 11.626 100 39 76 2 1121414720 34938288 8713152
02:21:47 5449 8192000000 10000490528 10401136640 1091533344 11.757 100 30 60 1 1110931168 12577920 6819904
02:22:53 7295 8192000000 9993852544 10402316288 1094449056 11.785 100 31 63 0 1116614352 15794224 6371072
02:24:02 1869 8192000000 10026174976 10411765760 1097585424 11.815 100 79 156 1 1125524208 10234592 17704192
02:25:05 3993 8192000000 10036123984 10438098944 1094969296 11.790 100 42 86 1 1150641408 46452576 9219536
02:26:12 3225 8192000000 10027085280 10426310656 1099329616 11.831 100 51 98 1 1136700848 27229232 10142000
02:27:17 62092 8192000000 9989080496 10381684736 1024472512 11.115 93 21 40 2 1048588608 19268160 4847936
02:29:21 6381 8192000000 9961115152 10357645312 1037717328 11.243 98 40 80 0 1075535872 29380272 8438272
02:30:30 6446 8192000000 9926246464 10308415488 989702416 10.779 97 39 79 1 1033742048 35128448 8911184
02:31:37 5602 8192000000 9958812672 10348011520 1025586880 11.126 100 34 68 1 1060590112 27618864 7384368
02:32:43 24019 8192000000 9927679072 10329157632 1001651184 10.895 97 12 24 1 1033344592 29129680 2563728
02:34:08 9620 8192000000 9979261824 10369077248 1036051680 11.227 99 53 106 2 1070712272 23803472 10857120
02:35:19 9661 8192000000 9967273200 10357469184 1058274832 11.440 100 41 82 2 1093484496 26057200 9152464
02:36:29 77581 8192000000 9985972592 10378162176 1065221408 11.506 100 37 71 2 1091608016 17891968 8494640
02:38:50 9653 8192000000 10052455456 10445176832 1095199744 11.792 100 61 120 1 1163221168 55627776 12393648
02:40:06 5070 8192000000 10053204432 10451398656 1111961920 11.951 100 57 113 0 1162416032 39207536 11246576
02:41:15 4736 8192000000 10100548896 10499014656 1123993664 12.065 100 62 124 0 1205834592 69545008 12295920
02:42:28 18694 8192000000 10134300352 10539765760 1137087360 12.188 100 52 103 1 1238721152 90808976 10824816
02:43:50 24486 8192000000 10163797680 10572578816 1143335312 12.247 100 48 96 1 1269415376 115722464 10357600
02:45:19 7092 8192000000 10213338896 10621235200 1156373840 12.369 100 64 125 1 1316818976 146810480 13634656
02:46:30 5335 8192000000 10253357024 10653966336 1161685600 12.419 100 83 165 0 1356327504 176913856 17728048
02:47:42 3712 8192000000 10252390304 10654904320 1160858576 12.411 100 75 148 2 1355948352 179154832 15934944
02:48:50 3958 8192000000 10296546784 10697834496 1167076032 12.469 100 78 150 2 1395016736 211526256 16414448
02:49:57 15178 8192000000 10326201488 10740883456 1178262480 12.574 100 61 120 1 1428899808 236843568 13793760
02:51:20 2624 8192000000 10325961648 10720489472 1163821216 12.439 100 86 170 2 1414920352 231023376 20075760
02:52:28 1912 8192000000 10361642976 10765197312 1160458000 12.408 100 90 177 0 1455477168 275051600 19967568
02:53:35 6655 8192000000 10383969296 10812776448 1169359232 12.491 100 79 155 1 1495670336 307759808 18551296
02:54:49 4443 8192000000 10342712304 10767396864 1161280960 12.415 99 84 168 0 1431262976 250576000 19406016
02:55:59 1969 8192000000 10384864080 10785562624 1175346544 12.547 100 90 178 3 1480157904 284109008 20702352
02:57:07 2737 8192000000 10417117968 10817572864 1165182176 12.452 100 81 161 0 1510706480 326505312 19018992
02:58:12 5332 8192000000 10419935584 10836873216 1172004080 12.516 100 56 112 0 1519521568 333177472 14340016
02:59:28 11051 8192000000 10387682592 10839097344 1158270080 12.387 100 38 76 0 1501467872 332981360 10216432
03:00:59 18369 8192000000 10354598816 10841468928 1142218864 12.236 99 32 65 1 1471176784 320649504 8308416
03:02:20 23515 8192000000 10329449312 10787368960 1127930816 12.102 99 25 50 0 1443729632 308746832 7051984
03:03:45 15765 8192000000 10241898528 10741280768 1124736816 12.072 100 23 45 0 1372319840 241855472 5727552
03:05:03 53446 8192000000 10068516432 10566336512 1101814944 11.855 98 7 14 0 1168328416 65146672 1366800
03:06:57 52843 8192000000 10034863408 10461433856 1068906768 11.542 95 41 81 1 1109164688 30855904 9402016
03:08:58 123871 8192000000 10094486160 10546003968 1086539888 11.710 97 22 44 0 1179845440 89122768 4182784
03:12:08 134439 8192000000 10136999984 10573434880 1086351248 11.708 96 21 42 2 1193135344 102158432 4625664
03:15:23 79374 8192000000 10053968480 10541006848 1112970288 11.961 99 14 28 0 1188308832 71990608 3347936
03:18:06 21321 8192000000 10070348256 10493169664 1132947344 12.149 100 55 111 3 1171628816 25737984 12943488
03:19:34 49927 8192000000 10071572240 10520231936 1114517664 11.975 98 12 24 1 1160212256 43076128 2618464
03:21:27 44923 8192000000 10061128032 10522931200 1130420864 12.125 100 15 29 1 1185165968 50691120 4053984
03:23:13 102835 8192000000 10076447680 10530275328 1130669248 12.128 99 25 50 0 1164781600 28770144 5342208
03:26:55 67584 8192000000 10118744256 10561060864 1111136928 11.943 97 14 28 0 1172001296 57638368 3226000
03:29:04 62981 8192000000 10100386784 10567520256 1141601840 12.231 100 16 32 0 1203755920 59067552 3086528
03:31:08 136880 8192000000 10151266864 10592350208 1127319968 12.096 97 18 36 2 1190297744 58897760 4080016
03:35:03 67933 8192000000 10170217936 10653585408 1149447536 12.304 99 20 40 2 1281569392 127503312 4618544
03:37:14 58574 8192000000 10097487536 10595663872 1133031616 12.150 98 13 26 0 1214648320 78958912 2657792
03:39:19 89420 8192000000 10100126160 10544701440 1136237536 12.180 99 41 80 0 1174933872 29236608 9459728
03:41:51 15675 8192000000 10087046288 10529595392 1137745920 12.194 99 43 86 0 1189751904 43480864 8525120
03:43:07 50255 8192000000 10067285248 10532737024 1119790816 12.025 98 11 22 0 1155877744 33473008 2613920
03:45:03 48382 8192000000 10066113920 10507653120 1084465104 11.690 95 12 24 1 1123931968 37201408 2265456
03:47:08 72183 8192000000 10093205344 10562768896 1093885936 11.780 96 21 42 0 1190217424 91398400 4933088
03:49:21 40487 8192000000 10100153648 10532691968 1125684992 12.081 99 37 74 1 1170204624 37231392 7288240
03:51:04 55128 8192000000 10075496352 10512388096 1092585664 11.767 97 22 45 0 1150846352 53677376 4583312
03:53:41 58446 8192000000 10132804816 10568040448 1118321136 12.011 98 15 29 0 1210654720 89029152 3304432
03:55:57 122811 8192000000 10077231648 10520330240 1067198704 11.525 94 13 26 0 1126778016 56557200 3022112
03:59:00 80086 8192000000 10044780784 10521812992 1095245104 11.793 97 15 30 0 1130074368 31083360 3745904
04:01:46 65683 8192000000 10097492256 10542268416 1108736192 11.920 98 13 26 0 1195864256 83870496 3257568
04:04:01 56687 8192000000 10055615024 10519511040 1110481184 11.937 100 12 24 0 1166354608 53325888 2547536
04:06:07 48789 8192000000 10065339248 10502914048 1094726176 11.788 98 21 41 0 1155307792 56027312 4554304
04:07:56 78964 8192000000 10047108144 10544185344 1109110208 11.924 99 11 22 0 1155180240 43953616 2116416
04:10:15 40930 8192000000 10115788048 10571096064 1116068544 11.990 98 41 82 3 1190473312 66256880 8147888
04:11:56 47309 8192000000 10072801136 10507747328 1098561200 11.824 97 27 54 0 1151169728 47393632 5214896
04:13:51 64586 8192000000 10064900832 10527301632 1099288336 11.831 98 18 36 0 1167408912 64790096 3330480
04:15:58 53048 8192000000 10102520480 10556563456 1133237520 12.152 100 36 72 0 1199108416 58802752 7068144
04:17:59 81173 8192000000 10090343296 10510721024 1097413744 11.813 97 32 65 0 1150672832 46519568 6739520
04:20:29 39472 8192000000 10064013072 10495782912 1125047264 12.075 99 24 48 2 1163718688 34548080 4123344
04:22:09 128273 8192000000 10098078496 10573815808 1127050720 12.094 99 35 70 0 1206963712 72760400 7152592
04:25:20 47626 8192000000 10063941488 10533912576 1116549600 11.994 99 10 20 0 1158854816 39997872 2307344
04:27:34 77633 8192000000 10066361664 10534957056 1105647040 11.891 98 15 30 0 1167970512 59263216 3060256
04:29:54 63080 8192000000 10056469312 10504486912 1097942080 11.818 98 13 26 1 1134060688 33620240 2498368
04:31:58 62609 8192000000 10095651072 10549129216 1095373184 11.794 97 28 56 0 1168250112 67133408 5743520
04:34:17 80462 8192000000 10084149184 10535305216 1075841424 11.608 95 26 52 2 1143900608 62140224 5918960
04:36:39 42296 8192000000 9322637104 9683476480 434637456 5.038 39 8 16 0 472864224 36693344 1533424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-10-2021 04:38:17 to 29-10-2021 04:38:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 29-10-2021 04:38:33 to 29-10-2021 04:38:34.

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          230706
MON$OLDEST_ACTIVE               230707
MON$OLDEST_SNAPSHOT             230707
MON$NEXT_TRANSACTION            230707
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                       2039456
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2646 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2646 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2646 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2646 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Fri Oct 29 04:37:24 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
--- end of diff output ---

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

29.10.21 04:38:35. 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-10-29 04:38:38 No severe PSQL-related problems occured
29.10.21 04:38:39. End of report.