Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2021 16:38
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 15831264 kB
MemAvailable 30721948 kB
Buffers 0 kB
Cached 15825600 kB
SwapCached 2964 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-28 16:37:52
DTS_END                         2021-09-28 16:37:52.1510
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(57:17)
IP                              ::1/45076
TRN_ID                          237747
ATT_ID                          696
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: 8857.90 27786 1062948
customer order: creation 162.46 374 19495
customer order: refuse 14.23 431 1707
order to supplier: creation 44.32 1464 5318
order to supplier: removal 3.45 100723 414
invoice (draft): creation 44.07 3756 5288
invoice (draft): removal 50.33 893 6039
invoice accept: apply 39.88 19985 4786
invoice accept: cancel 45.58 836 5470
customer reserve: creation 3680.30 197 441636
customer reserve: removal 4485.69 59 538283
realization accept: apply 80.23 196 9628
realization accept: cancel 82.83 114 9939
payment from customer: creation 56.99 124 6839
payment from customer: removal 5.22 88 626
payment to supplier: creation 47.14 154 5657
payment to supplier: removal 3.29 93 395
service: total inventory turnovers 4.38 1430 526
service: total monetary turnovers 6.65 28 798
service: aggregate perf. data 0.83 21544 100
service: refresh index statistics 0.03 403235 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-09-2021 16:39:04 to 28-09-2021 16:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1970
TEST_TIME 2 3212
TEST_TIME 3 3325
TEST_TIME 4 3769
TEST_TIME 5 4149
TEST_TIME 6 4401
TEST_TIME 7 4476
TEST_TIME 8 5003
TEST_TIME 9 5093
TEST_TIME 10 5403
TEST_TIME 11 5422
TEST_TIME 12 5483
TEST_TIME 13 5696
TEST_TIME 14 6384
TEST_TIME 15 6459
TEST_TIME 16 6695
TEST_TIME 17 7044
TEST_TIME 18 7622
TEST_TIME 19 8215
TEST_TIME 20 8368
TEST_TIME 21 8521
TEST_TIME 22 8618
TEST_TIME 23 9143
TEST_TIME 24 9168
TEST_TIME 25 9019
TEST_TIME 26 8959
TEST_TIME 27 9117
TEST_TIME 28 9212
TEST_TIME 29 9287
TEST_TIME 30 9271
TEST_TIME 31 9822
TEST_TIME 32 10070
TEST_TIME 33 10522
TEST_TIME 34 10436
TEST_TIME 35 10443
TEST_TIME 36 10608
TEST_TIME 37 10405
TEST_TIME 38 10213
TEST_TIME 39 10262
TEST_TIME 40 10104
TEST_TIME 41 9925
TEST_TIME 42 9815
TEST_TIME 43 9865
TEST_TIME 44 9884
TEST_TIME 45 9792
TEST_TIME 46 9719
TEST_TIME 47 9648
TEST_TIME 48 9574
TEST_TIME 49 9545
TEST_TIME 50 9513
TEST_TIME 51 9386
TEST_TIME 52 9434
TEST_TIME 53 9377
TEST_TIME 54 9310
TEST_TIME 55 9194
TEST_TIME 56 9157
TEST_TIME 57 9145
TEST_TIME 58 9192
TEST_TIME 59 9121
TEST_TIME 60 9127
TEST_TIME 61 9113
TEST_TIME 62 9003
TEST_TIME 63 8956
TEST_TIME 64 8937
TEST_TIME 65 8964
TEST_TIME 66 8950
TEST_TIME 67 9046
TEST_TIME 68 9001
TEST_TIME 69 9025
TEST_TIME 70 9041
TEST_TIME 71 9029
TEST_TIME 72 9067
TEST_TIME 73 9068
TEST_TIME 74 9094
TEST_TIME 75 9178
TEST_TIME 76 9137
TEST_TIME 77 9206
TEST_TIME 78 9182
TEST_TIME 79 9228
TEST_TIME 80 9149
TEST_TIME 81 9095
TEST_TIME 82 9056
TEST_TIME 83 9113
TEST_TIME 84 9078
TEST_TIME 85 9036
TEST_TIME 86 9022
TEST_TIME 87 9037
TEST_TIME 88 9019
TEST_TIME 89 8994
TEST_TIME 90 8932
TEST_TIME 91 8944
TEST_TIME 92 8912
TEST_TIME 93 8868
TEST_TIME 94 8894
TEST_TIME 95 8885
TEST_TIME 96 8877
TEST_TIME 97 8909
TEST_TIME 98 8902
TEST_TIME 99 8907
TEST_TIME 100 8905
TEST_TIME 101 9059
TEST_TIME 102 9053
TEST_TIME 103 9063
TEST_TIME 104 9092
TEST_TIME 105 9106
TEST_TIME 106 9105
TEST_TIME 107 9120
TEST_TIME 108 9046
TEST_TIME 109 8984
TEST_TIME 110 9003
TEST_TIME 111 8989
TEST_TIME 112 8941
TEST_TIME 113 8910
TEST_TIME 114 8873
TEST_TIME 115 8893
TEST_TIME 116 8867
TEST_TIME 117 8879
TEST_TIME 118 8878
TEST_TIME 119 8884
TEST_TIME 120 8860

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-09-2021 16:39:08 to 28-09-2021 16:39:11.

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 1473461 1473461 0 0.00 0 32729 7 0 0
doc_list_biud 1473458 1473458 0 0.00 0 33872 28 0 0
sp_add_invoice_to_stock 4965 4786 179 3.60 53 267799 19985 0 179
sp_cancel_adding_invoice 5470 5470 0 0.00 4 83790 836 0 0
sp_cancel_client_order 1707 1707 0 0.00 5 20582 431 0 0
sp_cancel_customer_reserve 538283 538283 0 0.00 1 33878 60 0 0
sp_cancel_pay_from_customer 626 626 0 0.00 3 4610 89 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 4023 93 0 0
sp_cancel_supplier_invoice 6055 6039 16 0.26 2 21175 894 0 16
sp_cancel_supplier_order 414 414 0 0.00 8 860522 100724 0 0
sp_cancel_write_off 9939 9939 0 0.00 1 9951 115 0 0
sp_client_order 19495 19495 0 0.00 3 27049 375 0 0
sp_customer_reserve 441636 441636 0 0.00 0 48951 197 0 0
sp_fill_shopping_cart 30097 30097 0 0.00 1 42099 47 0 0
sp_fill_shopping_cart_clo_res 441632 441632 0 0.00 0 18478 15 0 0
sp_get_clo_for_invoice 4781 4781 0 0.00 0 33359 316 0 0
sp_kill_cost_storno 16430 16430 0 0.00 0 5993 61 0 0
sp_kill_qstorno_ret_qs2qd 1092958 1092958 0 0.00 0 33838 33 0 0
sp_kill_qty_storno 576260 576260 0 0.00 0 33872 72 0 0
sp_lock_selected_doc 19964 19964 0 0.00 0 5420 5 0 0
sp_make_cost_storno 26905 26905 0 0.00 0 16060 66 0 0
sp_multiply_rows_for_pdistr 26902 26902 0 0.00 0 5460 30 0 0
sp_multiply_rows_for_qdistr 19495 19495 0 0.00 0 18411 154 0 0
sp_pay_from_customer 6839 6839 0 0.00 4 17884 125 0 0
sp_pay_to_supplier 5657 5657 0 0.00 5 15969 154 0 0
sp_payment_common 12494 12494 0 0.00 1 17876 35 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 20573 393 0 0
sp_qd_handle_on_invoice_upd_sts 10250 10250 0 0.00 0 19743 369 0 0
sp_qd_handle_on_reserve_upd_sts 9628 9628 0 0.00 0 20071 69 0 0
sp_reserve_write_off 9628 9628 0 0.00 6 20184 196 0 0
sp_supplier_invoice 5288 5288 0 0.00 6 87645 3756 0 0
sp_supplier_order 5318 5318 0 0.00 5 38089 1465 0 0
srv_aggregate_perf_data 100 100 0 0.00 2974 198111 21545 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2412 151320 45236 0 0
srv_find_qd_qs_mism 1018104 1018104 0 0.00 0 32729 6 0 0
srv_make_invnt_saldo 526 526 0 0.00 32 115239 1431 0 0
srv_make_money_saldo 798 798 0 0.00 1 816 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 123004 602856 403236 0 0
t$perf-abend:sp_add_invoice_to_stock 179 179 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4786 4786 0 0.00 0 12550 196 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 6267 140 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 5896 13 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 6284 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 626 626 0 0.00 0 1547 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 521 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 1162 14 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 17443 1756 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 8705 20 0 0
t$perf-norm:sp_client_order 19495 19495 0 0.00 0 8220 19 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 6621 21 0 0
t$perf-norm:sp_pay_from_customer 6839 6839 0 0.00 0 6342 11 0 0
t$perf-norm:sp_pay_to_supplier 5657 5657 0 0.00 0 7788 11 0 0
t$perf-norm:sp_reserve_write_off 9628 9628 0 0.00 0 7000 15 0 0
t$perf-norm:sp_supplier_invoice 5288 5288 0 0.00 0 8415 20 0 0
t$perf-norm:sp_supplier_order 5318 5318 0 0.00 0 10559 22 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 736 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 318 5 0 0
t$perf-norm:srv_make_invnt_saldo 526 526 0 0.00 0 5017 41 0 0
t$perf-norm:srv_make_money_saldo 798 798 0 0.00 0 1690 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 6 0 0
v_add_invoice_to_stock 9918 9560 358 3.60 0 5836 21 0 358
v_all_customers 35962 35962 0 0.00 0 17 1 0 0
v_all_suppliers 29302 29302 0 0.00 0 96 1 0 0
v_all_wares 38990 38990 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 1543 11 0 0
v_cancel_client_order 3414 3414 0 0.00 0 8728 9 0 0
v_cancel_customer_prepayment 1252 1252 0 0.00 0 4585 12 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 509 3 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 4017 11 0 0
v_cancel_supplier_invoice 762 730 32 4.19 0 3516 18 0 32
v_cancel_supplier_order 828 828 0 0.00 0 4330 14 0 0
v_cancel_write_off 1952 1952 0 0.00 0 4495 17 0 0
v_max_id_clo_ord 5316 5316 0 0.00 0 3199 4 0 0
v_max_id_clo_res 947 947 0 0.00 0 1695 19 0 0
v_max_id_ord_sup 5286 5286 0 0.00 0 3135 4 0 0
v_max_non_paid_invoice 1606 1606 0 0.00 0 1980 6 0 0
v_max_non_paid_realizn 3491 3491 0 0.00 0 3782 5 0 0
v_min_id_clo_ord 5316 5316 0 0.00 0 5080 10 0 0
v_min_id_clo_res 947 947 0 0.00 0 1154 4 0 0
v_min_id_ord_sup 5286 5286 0 0.00 0 4027 6 0 0
v_min_non_paid_invoice 5655 5655 0 0.00 0 7279 24 0 0
v_min_non_paid_realizn 6839 6839 0 0.00 0 10237 23 0 0
v_our_firm 9724 9724 0 0.00 0 74 1 0 0
v_reserve_write_off 19256 19256 0 0.00 0 14141 7 0 0
x_lock_depdocs_on_canc_invoice 11144 11144 0 0.00 0 13802 134 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 16433 415 0 0
x_make_qstorno_1000_1200 5318 5318 0 0.00 1 38081 1364 0 0
x_make_qstorno_1200_2000 5288 5288 0 0.00 3 87625 3591 0 0
x_make_qstorno_2100_3300 441636 441636 0 0.00 0 48876 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-09-2021 16:39:12 to 28-09-2021 16:39:16.

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
14:07:55 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:57 32868 8192000000 10390870400 10713239552 1444283952 14.987 100 88 176 0 1479138304 6310816 28543536
14:10:39 20818 8192000000 10458713808 10790166528 1503585648 15.507 100 89 178 0 1540305152 8358256 28361248
14:12:06 17283 8192000000 10502346688 10845597696 1558865728 15.986 100 87 174 0 1595815520 10122272 26827520
14:13:27 8652 8192000000 10567107040 10928730112 1632501184 16.616 100 87 174 0 1669848304 11251616 26095504
14:14:41 7360 8192000000 10642594976 11013550080 1703053280 17.211 100 79 157 2 1738135920 12741120 22341520
14:15:51 5537 8192000000 10696007232 11073613824 1760653296 17.690 100 77 153 0 1794705392 12584352 21467744
14:16:59 2584 8192000000 10758403920 11142471680 1822925168 18.202 100 86 170 0 1858879232 12478560 23475504
14:18:03 1862 8192000000 10809029200 11197956096 1870335712 18.587 100 89 176 0 1909993296 14034048 25623536
14:19:07 4196 8192000000 10821243264 11222032384 1889225248 18.740 100 77 152 0 1922754384 11165824 22363312
14:20:13 3013 8192000000 10830297968 11228991488 1893988752 18.778 100 64 129 1 1920144176 8391600 17763824
14:21:17 2456 8192000000 10831252160 11238690816 1903824160 18.857 100 89 182 1 1937826208 7596368 26405680
14:22:22 4993 8192000000 10813125184 11237052416 1897734960 18.808 100 30 60 2 1913309424 7650224 7924240
14:23:28 14076 8192000000 10812088624 11249221632 1904874192 18.865 100 16 31 2 1923147728 13898544 4374992
14:24:46 55745 8192000000 10832404384 11269574656 1913319200 18.933 100 35 68 1 1933229984 9029104 10881680
14:26:42 6692 8192000000 10850324560 11278802944 1920792592 18.993 100 43 86 1 1941531904 9002592 11736720
14:27:55 6604 8192000000 10804104528 11243429888 1880236144 18.667 99 22 44 1 1893052256 5992880 6823232
14:29:07 14741 8192000000 10750140464 11175870464 1781476720 17.862 96 17 33 1 1790998144 4886464 4634960
14:30:25 13552 8192000000 10726435584 11155554304 1780045120 17.850 97 11 22 0 1789009424 5440720 3523584
14:31:42 8806 8192000000 10681282560 11088191488 1760450512 17.688 98 31 62 0 1774895568 5277696 9167360
14:32:52 9473 8192000000 10748407488 11165597696 1831015328 18.268 100 40 80 0 1849350176 6468448 11866400
14:34:02 65970 8192000000 10750330064 11183915008 1831388560 18.271 99 28 56 2 1845155216 5623584 8143072
14:36:08 10143 8192000000 10768994688 11203899392 1863788912 18.534 100 30 60 0 1878846752 6509936 8547904
14:37:19 10449 8192000000 10810808464 11240931328 1897571968 18.807 100 24 48 1 1909472208 5150656 6749584
14:38:38 10504 8192000000 10854625904 11269611520 1922036704 19.003 100 62 122 1 1947660960 8096624 17527632
14:39:56 71356 8192000000 10858756080 11290963968 1922655776 19.008 99 37 74 1 1942836384 9401024 10779584
14:42:11 63954 8192000000 10885982368 11330363392 1950989088 19.234 100 24 48 1 1969843552 11493552 7360912
14:44:21 4627 8192000000 10941535648 11360296960 1988174128 19.529 100 74 146 2 2024751872 15319888 21257856
14:45:33 3606 8192000000 10961251920 11384655872 2005687792 19.668 100 63 125 1 2045393520 22271488 17434240
14:46:39 10148 8192000000 10950403360 11381063680 2003652768 19.652 100 65 129 1 2042288544 19287280 19348496
14:47:56 8706 8192000000 10963820176 11402551296 2005583136 19.667 100 54 106 0 2052309552 30223568 16502848
14:49:12 3732 8192000000 10994334832 11413479424 2024578512 19.816 100 75 152 1 2071729760 25108880 22042368
14:50:20 3139 8192000000 10969406416 11401994240 2002787040 19.645 100 54 105 3 2049942480 29857200 17298240
14:51:29 4587 8192000000 10988435344 11412287488 2014985776 19.741 100 83 165 0 2071719440 30826176 25907488
14:52:40 4076 8192000000 10971177952 11397464064 2003000096 19.646 100 68 134 0 2053150096 30088400 20061600
14:53:48 4009 8192000000 10974552896 11395538944 1993884784 19.574 100 78 152 1 2056317360 37807536 24625040
14:55:00 2412 8192000000 10956429696 11377651712 1974440928 19.421 99 70 139 1 2034946320 38968928 21536464
14:56:07 2777 8192000000 10936737888 11344125952 1947666640 19.208 100 84 163 0 2012472400 39514352 25291408
14:57:16 12810 8192000000 10907298800 11352993792 1943550272 19.175 99 36 72 2 1990562304 35371632 11640400
14:58:32 8939 8192000000 10922247776 11357904896 1948585056 19.215 100 40 79 0 2001748960 40345120 12818784
14:59:46 4347 8192000000 10951109488 11365572608 1959825456 19.305 100 69 137 3 2023553488 41070128 22657904
15:00:57 8134 8192000000 10924868528 11357380608 1956236448 19.276 100 56 110 2 2012150144 39302784 16610912
15:02:12 15533 8192000000 10918692176 11367428096 1965052880 19.346 100 37 74 0 2010650192 34462144 11135168
15:03:36 5559 8192000000 10916220048 11349942272 1947724032 19.208 100 39 79 0 1998358832 37555776 13079024
15:04:52 19890 8192000000 10893900336 11356110848 1915845168 18.954 98 17 34 1 1956982928 34668336 6469424
15:06:21 17700 8192000000 10924990320 11361501184 1949876112 19.225 100 28 54 1 2001343568 41445552 10021904
15:07:53 21923 8192000000 10928878320 11379249152 1965880256 19.353 100 26 51 2 2017651328 42884976 8886096
15:09:20 52428 8192000000 10922281968 11389624320 1969301696 19.380 100 20 40 0 2021775152 46249312 6224144
15:11:13 47230 8192000000 10898185664 11340054528 1928206160 19.053 98 17 34 0 1944678208 11732896 4739152
15:13:03 92341 8192000000 10895828000 11360460800 1911867360 18.922 97 9 18 0 1927503888 13049600 2586928
15:15:37 12230 8192000000 10924728416 11376320512 1990343808 19.547 100 36 72 0 2009274304 8607088 10323408
15:16:54 42158 8192000000 10927001776 11385913344 1996774736 19.597 100 20 43 0 2009556256 7531664 5249856
15:18:43 32290 8192000000 10932249936 11392016384 2001733968 19.636 100 31 61 0 2020000160 9186400 9079792
15:21:04 70137 8192000000 10910485536 11392794624 1974805296 19.424 99 15 30 0 1994162752 14456672 4900784
15:23:18 33018 8192000000 10925991984 11392512000 1982845680 19.487 99 30 61 3 2002723024 11135648 8741696
15:24:56 98517 8192000000 10917538992 11385106432 1970619360 19.390 99 21 41 0 1989078352 13108416 5350576
15:27:50 80024 8192000000 10924157632 11399770112 1990026896 19.544 100 13 26 0 2006022672 12400496 3595280
15:30:11 67619 8192000000 10903966400 11375124480 1943164816 19.172 97 26 52 1 1958327904 7975232 7187856
15:32:26 25344 8192000000 10932492432 11390357504 1961047104 19.314 98 31 60 1 1990652704 21052240 8553360
15:33:53 36720 8192000000 10932199552 11401187328 1997809008 19.605 100 30 59 0 2016698896 10530672 8359216
15:35:33 35046 8192000000 10881235504 11354271744 1941835792 19.161 98 27 54 1 1963085520 13087936 8161792
15:37:09 50230 8192000000 10892798288 11378094080 1979511424 19.461 100 14 28 0 1998352560 14607616 4233520
15:39:30 65693 8192000000 10892671648 11379339264 1967771760 19.368 99 25 50 1 1983001984 8329184 6901040
15:41:37 56929 8192000000 10877311872 11363176448 1892805696 18.768 95 9 18 1 1902967648 7950128 2211824
15:43:47 64154 8192000000 10865860752 11347042304 1888303872 18.732 96 11 22 1 1901147648 9648416 3195360
15:46:05 50196 8192000000 10883562304 11358699520 1899881856 18.825 96 28 56 0 1918642368 10618064 8142448
15:47:56 37134 8192000000 10904739888 11349262336 1896320976 18.797 96 41 82 1 1918282064 10258736 11702352
15:49:34 49003 8192000000 10875137792 11346403328 1918277776 18.973 98 15 30 1 1933726448 10975808 4472864
15:51:38 87023 8192000000 10852806096 11325808640 1838438608 18.328 95 25 50 0 1855383200 10498480 6446112
15:54:06 82273 8192000000 10838697936 11301470208 1838040048 18.325 95 16 32 0 1852826800 10136656 4650096
15:56:40 102682 8192000000 10877300048 11330736128 1939396544 19.142 100 34 68 2 1959075904 9783680 9895680
15:59:27 85226 8192000000 10891856704 11365781504 1940177392 19.148 99 42 84 1 1974025344 21171504 12676448
16:02:05 72419 8192000000 10879645744 11344855040 1911199264 18.916 98 16 32 0 1931478496 15765824 4513408
16:04:19 46607 8192000000 10877478624 11353382912 1936535216 19.119 99 8 16 0 1948842448 9792208 2515024
16:06:06 70346 8192000000 10888487120 11369635840 1969075040 19.378 100 16 32 1 1982592160 8233184 5283936
16:08:21 58647 8192000000 10883848192 11360878592 1939002960 19.139 98 9 18 0 1948113760 6908176 2202624
16:10:21 68588 8192000000 10889704704 11371585536 1957549008 19.287 99 10 20 0 1970043008 9799840 2694160
16:12:43 53624 8192000000 10900215088 11370414080 1961542512 19.318 99 15 30 0 1974152720 7528720 5081488
16:14:40 86054 8192000000 10917620032 11388481536 1934262592 19.101 97 20 40 1 1964372544 24638976 5470976
16:17:37 60792 8192000000 10880131872 11368771584 1923717856 19.017 98 18 36 0 1951804592 22797904 5288832
16:19:38 151320 8192000000 10913724080 11370250240 1908107600 18.891 96 19 38 0 1927149520 14347776 4694144
16:23:12 108324 8192000000 10920660800 11387195392 1952798208 19.249 98 27 55 0 1974506224 14737376 6970640
16:26:00 97629 8192000000 10874998416 11343437824 1867610240 18.565 94 35 70 0 1888395120 11141488 9643392
16:28:52 40957 8192000000 10885125728 11361013760 1934306256 19.101 99 6 12 0 1943293568 7768960 1218352
16:30:33 59998 8192000000 10879202688 11352616960 1916733840 18.961 97 11 22 1 1928425648 8792000 2899808
16:33:13 66769 8192000000 10887341952 11360456704 1944185104 19.180 99 12 24 1 1961060416 13585024 3290288
16:35:22 43554 8192000000 10899693376 11369267200 1940578320 19.151 98 23 46 1 1957006816 9945424 6483072
16:37:06 41207 8192000000 10861239024 11320860672 1846831312 18.396 95 25 50 0 1865017488 11210416 6975760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-09-2021 16:39:18 to 28-09-2021 16:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 179 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 28-09-2021 16:39:34 to 28-09-2021 16:39:35.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          234765
MON$OLDEST_ACTIVE               237857
MON$OLDEST_SNAPSHOT             237857
MON$NEXT_TRANSACTION            237857
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-03 16:41:55.3470
MON$PAGES                       2050656
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:981: 
:982: mscs-test-161.budzdorov.ru    Tue Sep 28 16:37:55 2021
:983:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:984: 
:985: 
:986: mscs-test-161.budzdorov.ru    Tue Sep 28 16:38:00 2021
:987:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:988: 
:989: 
:990: mscs-test-161.budzdorov.ru    Tue Sep 28 16:38:01 2021
:991:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:992: 
--- end of diff output ---

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

28.09.21 16:39:36. There are 3 logs in /var/tmp/logs-oltp30 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-09-28 16:39:39 No severe PSQL-related problems occured
28.09.21 16:39:40. End of report.