Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.03.2022 19:39
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 16575952 kB
MemAvailable 31628000 kB
Buffers 0 kB
Cached 15136780 kB
SwapCached 2140 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.10
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 2022-03-27 19:38:10
DTS_END                         2022-03-27 19:38:10.3300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46286
TRN_ID                          239555
ATT_ID                          675
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: 9658.80 20406 1159056
customer order: creation 172.67 372 20720
customer order: refuse 14.80 374 1776
order to supplier: creation 46.23 1405 5547
order to supplier: removal 3.44 111597 413
invoice (draft): creation 45.00 3513 5400
invoice (draft): removal 56.73 785 6807
invoice accept: apply 41.58 18625 4989
invoice accept: cancel 51.59 850 6191
customer reserve: creation 3877.06 182 465247
customer reserve: removal 5041.29 60 604955
realization accept: apply 82.57 177 9908
realization accept: cancel 98.20 106 11784
payment from customer: creation 58.37 116 7004
payment from customer: removal 5.36 78 643
payment to supplier: creation 48.32 137 5798
payment to supplier: removal 3.27 72 392
service: total inventory turnovers 4.80 1088 576
service: total monetary turnovers 6.77 25 812
service: aggregate perf. data 0.75 27711 90
service: refresh index statistics 0.03 240849 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-03-2022 19:40:11 to 27-03-2022 19:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5950
TEST_TIME 2 7244
TEST_TIME 3 6898
TEST_TIME 4 6715
TEST_TIME 5 6550
TEST_TIME 6 6046
TEST_TIME 7 6334
TEST_TIME 8 6100
TEST_TIME 9 6081
TEST_TIME 10 6210
TEST_TIME 11 6077
TEST_TIME 12 6265
TEST_TIME 13 6312
TEST_TIME 14 6605
TEST_TIME 15 6608
TEST_TIME 16 6794
TEST_TIME 17 7138
TEST_TIME 18 7484
TEST_TIME 19 7615
TEST_TIME 20 7942
TEST_TIME 21 8243
TEST_TIME 22 8341
TEST_TIME 23 8552
TEST_TIME 24 8832
TEST_TIME 25 9490
TEST_TIME 26 9648
TEST_TIME 27 9967
TEST_TIME 28 9977
TEST_TIME 29 10369
TEST_TIME 30 11211
TEST_TIME 31 11056
TEST_TIME 32 10836
TEST_TIME 33 10816
TEST_TIME 34 10872
TEST_TIME 35 10633
TEST_TIME 36 10463
TEST_TIME 37 10228
TEST_TIME 38 10245
TEST_TIME 39 10501
TEST_TIME 40 10462
TEST_TIME 41 10401
TEST_TIME 42 10263
TEST_TIME 43 10223
TEST_TIME 44 10140
TEST_TIME 45 10279
TEST_TIME 46 10190
TEST_TIME 47 10073
TEST_TIME 48 9949
TEST_TIME 49 9911
TEST_TIME 50 9932
TEST_TIME 51 9871
TEST_TIME 52 9970
TEST_TIME 53 9947
TEST_TIME 54 9865
TEST_TIME 55 9747
TEST_TIME 56 9692
TEST_TIME 57 9568
TEST_TIME 58 9605
TEST_TIME 59 9722
TEST_TIME 60 9688
TEST_TIME 61 9766
TEST_TIME 62 9854
TEST_TIME 63 9823
TEST_TIME 64 9889
TEST_TIME 65 9919
TEST_TIME 66 10036
TEST_TIME 67 9985
TEST_TIME 68 10056
TEST_TIME 69 10123
TEST_TIME 70 10099
TEST_TIME 71 10099
TEST_TIME 72 10110
TEST_TIME 73 9991
TEST_TIME 74 9959
TEST_TIME 75 9917
TEST_TIME 76 9914
TEST_TIME 77 9878
TEST_TIME 78 9824
TEST_TIME 79 9778
TEST_TIME 80 9729
TEST_TIME 81 9660
TEST_TIME 82 9601
TEST_TIME 83 9635
TEST_TIME 84 9644
TEST_TIME 85 9633
TEST_TIME 86 9718
TEST_TIME 87 9713
TEST_TIME 88 9705
TEST_TIME 89 9718
TEST_TIME 90 9730
TEST_TIME 91 9720
TEST_TIME 92 9864
TEST_TIME 93 9890
TEST_TIME 94 9916
TEST_TIME 95 9918
TEST_TIME 96 9905
TEST_TIME 97 9817
TEST_TIME 98 9771
TEST_TIME 99 9798
TEST_TIME 100 9785
TEST_TIME 101 9749
TEST_TIME 102 9736
TEST_TIME 103 9717
TEST_TIME 104 9670
TEST_TIME 105 9636
TEST_TIME 106 9638
TEST_TIME 107 9595
TEST_TIME 108 9649
TEST_TIME 109 9648
TEST_TIME 110 9638
TEST_TIME 111 9640
TEST_TIME 112 9694
TEST_TIME 113 9702
TEST_TIME 114 9677
TEST_TIME 115 9737
TEST_TIME 116 9745
TEST_TIME 117 9765
TEST_TIME 118 9737
TEST_TIME 119 9726
TEST_TIME 120 9660

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-03-2022 19:40:15 to 27-03-2022 19:40:18.

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 1590639 1590639 0 0.00 0 23593 6 0 0
doc_list_biud 1590639 1590639 0 0.00 0 22492 29 0 0
sp_add_invoice_to_stock 5078 4989 89 1.75 36 240290 18626 0 89
sp_cancel_adding_invoice 6191 6191 0 0.00 4 86539 851 0 0
sp_cancel_client_order 1776 1776 0 0.00 4 14107 375 0 0
sp_cancel_customer_reserve 604955 604955 0 0.00 1 22496 60 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 6794 79 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 6472 73 0 0
sp_cancel_supplier_invoice 6814 6807 7 0.10 2 14131 786 0 7
sp_cancel_supplier_order 413 413 0 0.00 5 1122368 111597 0 0
sp_cancel_write_off 11784 11784 0 0.00 1 14823 107 0 0
sp_client_order 20720 20720 0 0.00 3 21206 373 0 0
sp_customer_reserve 465247 465247 0 0.00 0 58727 183 0 0
sp_fill_shopping_cart 31643 31643 0 0.00 1 9520 37 0 0
sp_fill_shopping_cart_clo_res 465244 465244 0 0.00 0 12347 13 0 0
sp_get_clo_for_invoice 4984 4984 0 0.00 0 18393 271 0 0
sp_kill_cost_storno 19010 19010 0 0.00 0 23584 55 0 0
sp_kill_qstorno_ret_qs2qd 1228914 1228914 0 0.00 0 22461 32 0 0
sp_kill_qty_storno 646815 646815 0 0.00 0 22492 70 0 0
sp_lock_selected_doc 20609 20609 0 0.00 0 12147 6 0 0
sp_make_cost_storno 27691 27691 0 0.00 0 21471 54 0 0
sp_multiply_rows_for_pdistr 27691 27691 0 0.00 0 13606 34 0 0
sp_multiply_rows_for_qdistr 20720 20720 0 0.00 0 13765 151 0 0
sp_pay_from_customer 7004 7004 0 0.00 4 13619 116 0 0
sp_pay_to_supplier 5798 5798 0 0.00 5 15140 138 0 0
sp_payment_common 12802 12802 0 0.00 2 8854 22 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 14097 342 0 0
sp_qd_handle_on_invoice_upd_sts 11172 11172 0 0.00 0 20501 334 0 0
sp_qd_handle_on_reserve_upd_sts 9908 9908 0 0.00 0 21712 56 0 0
sp_reserve_write_off 9908 9908 0 0.00 6 21750 178 0 0
sp_supplier_invoice 5400 5400 0 0.00 7 91020 3513 0 0
sp_supplier_order 5547 5547 0 0.00 5 47129 1405 0 0
srv_aggregate_perf_data 90 90 0 0.00 2671 633510 27711 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2144 129408 45223 0 0
srv_find_qd_qs_mism 1111822 1111822 0 0.00 0 16750 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 24 69583 1088 0 0
srv_make_money_saldo 812 812 0 0.00 1 741 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 168102 335393 240850 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4989 4989 0 0.00 0 19497 203 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 7449 130 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 7752 16 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 1871 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 5291 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 496 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 3167 24 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 26050 1797 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 4468 10 0 0
t$perf-norm:sp_client_order 20720 20720 0 0.00 0 10969 19 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 1174 4 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 5503 9 0 0
t$perf-norm:sp_pay_to_supplier 5798 5798 0 0.00 0 4255 14 0 0
t$perf-norm:sp_reserve_write_off 9908 9908 0 0.00 0 11018 15 0 0
t$perf-norm:sp_supplier_invoice 5400 5400 0 0.00 0 3001 17 0 0
t$perf-norm:sp_supplier_order 5547 5547 0 0.00 0 3240 18 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 104 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 2756 47 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 1834 21 0 0
t$perf-norm:srv_make_money_saldo 812 812 0 0.00 0 1391 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10140 9962 178 1.75 0 5564 17 0 178
v_all_customers 37716 37716 0 0.00 0 16 1 0 0
v_all_suppliers 30402 30402 0 0.00 0 19 1 0 0
v_all_wares 41440 41440 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 2751 10 0 0
v_cancel_client_order 3552 3552 0 0.00 0 5972 8 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 6719 12 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 3972 12 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 6005 12 0 0
v_cancel_supplier_invoice 832 818 14 1.68 0 952 13 0 14
v_cancel_supplier_order 826 826 0 0.00 0 1613 5 0 0
v_cancel_write_off 1990 1990 0 0.00 0 5707 14 0 0
v_max_id_clo_ord 5547 5547 0 0.00 0 1508 2 0 0
v_max_id_clo_res 973 973 0 0.00 0 1832 12 0 0
v_max_id_ord_sup 5375 5375 0 0.00 0 4357 5 0 0
v_max_non_paid_invoice 1519 1519 0 0.00 0 621 3 0 0
v_max_non_paid_realizn 3462 3462 0 0.00 0 2522 5 0 0
v_min_id_clo_ord 5547 5547 0 0.00 0 4060 8 0 0
v_min_id_clo_res 973 973 0 0.00 0 6045 13 0 0
v_min_id_ord_sup 5375 5375 0 0.00 0 5548 6 0 0
v_min_non_paid_invoice 5798 5798 0 0.00 0 8852 14 0 0
v_min_non_paid_realizn 7004 7004 0 0.00 0 5037 13 0 0
v_our_firm 10808 10808 0 0.00 0 16 1 0 0
v_reserve_write_off 19816 19816 0 0.00 0 7029 8 0 0
x_lock_depdocs_on_canc_invoice 12589 12589 0 0.00 0 21457 119 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 13451 366 0 0
x_make_qstorno_1000_1200 5547 5547 0 0.00 1 47117 1318 0 0
x_make_qstorno_1200_2000 5400 5400 0 0.00 3 90989 3372 0 0
x_make_qstorno_2100_3300 465247 465247 0 0.00 0 58724 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-03-2022 19:40:19 to 27-03-2022 19:40:23.

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
17:08:14 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:15 24449 8192000000 9897379856 10204610560 974973872 10.635 100 84 168 0 1003071856 5751424 22346560
17:10:42 20561 8192000000 9947180304 10268372992 1024311680 11.114 100 86 172 1 1055129472 8147088 22670704
17:12:07 13200 8192000000 9982223280 10314895360 1063299680 11.488 100 83 166 0 1094239760 9459328 21480752
17:13:22 16130 8192000000 10035170400 10380197888 1111509248 11.947 100 81 162 0 1141476304 10483024 19484032
17:14:42 10801 8192000000 10075247872 10431102976 1166045616 12.460 100 84 167 1 1196668400 11012368 19610416
17:15:56 6281 8192000000 10125037024 10494275584 1221346816 12.974 100 73 146 0 1248593008 11229792 16016400
17:17:06 6450 8192000000 10164649344 10544214016 1254399440 13.279 100 77 154 3 1285905952 14846048 16660464
17:18:13 1210 8192000000 10206281296 10587824128 1293384320 13.635 100 93 185 1 1326439712 12760992 20294400
17:19:16 27045 8192000000 10211483296 10601267200 1304053616 13.732 100 65 135 3 1326340128 6040608 16245904
17:20:45 8730 8192000000 10190969008 10611933184 1314317536 13.825 100 18 36 0 1324476720 6147568 4011616
17:21:55 10345 8192000000 10202356240 10624667648 1321225808 13.888 100 25 50 1 1333306288 6666256 5414224
17:23:06 14947 8192000000 10230651296 10643148800 1329853904 13.966 100 24 48 0 1341052672 5599088 5599680
17:24:22 14193 8192000000 10221769408 10642878464 1332193328 13.987 100 32 65 1 1344270224 4727120 7349776
17:25:39 9448 8192000000 10221442608 10646032384 1334108048 14.004 100 22 44 1 1345170416 6202400 4859968
17:26:53 14698 8192000000 10212404944 10632245248 1297143584 13.669 97 19 38 0 1307483696 5919680 4420432
17:28:09 41891 8192000000 10176192544 10589417472 1253232416 13.268 96 23 46 0 1264152496 5315872 5604208
17:29:52 6633 8192000000 10166747168 10574794752 1256213616 13.295 98 45 91 1 1275192384 7870688 11108080
17:30:59 6986 8192000000 10137321648 10538553344 1237223584 13.121 99 26 52 0 1249003888 5549184 6231120
17:32:08 16939 8192000000 10148723744 10542956544 1219134624 12.954 97 27 55 2 1230873728 4878688 6860416
17:33:56 7412 8192000000 10162836768 10567561216 1270328720 13.425 100 30 61 1 1283220224 6128368 6763136
17:35:04 6228 8192000000 10185234576 10589360128 1286873360 13.576 100 32 65 2 1300092112 5667792 7550960
17:36:10 7249 8192000000 10191502400 10609803264 1308442384 13.772 100 25 50 1 1320196416 5827056 5926976
17:37:19 67814 8192000000 10220273712 10632355840 1315161088 13.833 100 30 60 0 1326696208 4371264 7163856
17:39:31 17303 8192000000 10256854128 10665529344 1348099168 14.130 100 49 98 1 1368039568 9379376 10561024
17:40:55 2144 8192000000 10257286816 10674262016 1353896928 14.183 100 49 98 4 1374212784 9151152 11164704
17:42:00 7472 8192000000 10286094512 10704850944 1373086944 14.355 100 48 96 0 1396742224 13121088 10534192
17:43:16 11525 8192000000 10289703744 10717904896 1385644560 14.467 100 46 92 1 1408990224 12423200 10922464
17:44:41 9688 8192000000 10298877968 10719498240 1385221008 14.463 100 50 99 1 1412462032 15708704 11532320
17:45:59 11390 8192000000 10313684976 10740670464 1397036032 14.569 100 44 88 1 1428064864 20834016 10194816
17:47:19 5370 8192000000 10324345504 10739896320 1392058720 14.524 100 64 127 0 1436478512 29447472 14972320
17:48:32 21596 8192000000 10325223504 10745896960 1389191200 14.499 99 50 100 0 1431230080 30110976 11927904
17:50:03 6995 8192000000 10341237360 10755616768 1387420272 14.483 99 64 128 0 1440604768 37791536 15392960
17:51:25 5510 8192000000 10352396112 10762743808 1395931952 14.559 100 73 146 0 1454036816 40215408 17889456
17:52:37 3304 8192000000 10349100992 10759958528 1400129824 14.596 100 62 123 2 1453346112 38380304 14835984
17:53:47 12544 8192000000 10338606048 10747940864 1374764896 14.370 99 68 136 1 1438155616 46234576 17156144
17:55:03 3063 8192000000 10364826560 10769174528 1395328544 14.553 100 87 175 3 1467415616 50512320 21574752
17:56:11 2381 8192000000 10378919056 10782171136 1391306320 14.518 99 89 178 4 1468759248 54354048 23098880
17:57:18 2186 8192000000 10388739424 10795638784 1412709440 14.708 100 81 160 2 1487381168 53154752 21516976
17:58:25 3541 8192000000 10372995936 10789400576 1407478096 14.662 100 61 120 1 1476915984 54026832 15411056
17:59:33 16014 8192000000 10335476496 10761252864 1353170464 14.176 97 36 72 0 1415918928 52659808 10088656
18:00:59 18665 8192000000 10338061968 10762969088 1378788448 14.406 100 39 78 1 1445374384 55514016 11071920
18:02:20 20547 8192000000 10326056720 10756644864 1366341536 14.294 99 32 64 0 1436116880 60058160 9717184
18:03:46 14603 8192000000 10335541104 10772459520 1384463088 14.456 100 34 68 2 1442162032 48389584 9309360
18:05:03 10307 8192000000 10328780752 10762170368 1382522848 14.439 100 34 69 1 1430836688 39916320 8397520
18:06:19 32111 8192000000 10311023360 10753728512 1360448432 14.241 99 17 34 0 1409781040 44319968 5012640
18:07:53 59030 8192000000 10286108336 10743513088 1357458080 14.215 99 11 22 0 1397691056 37409312 2823664
18:09:52 32220 8192000000 10286969376 10718130176 1368549584 14.314 99 31 59 2 1381708352 5834176 7324592
18:11:26 59227 8192000000 10284030272 10740965376 1364770080 14.280 98 16 33 0 1382781632 13923888 4087664
18:13:29 30265 8192000000 10304472384 10739826688 1383456256 14.447 99 52 104 5 1403751344 8419680 11875408
18:15:01 79553 8192000000 10310864960 10750832640 1373789664 14.361 99 29 59 0 1398554592 17249888 7515040
18:17:27 86203 8192000000 10302295888 10752077824 1372866944 14.353 99 38 76 0 1398066736 15936432 9263360
18:19:56 31148 8192000000 10305085296 10751131648 1391271536 14.517 100 17 34 1 1404972064 9715104 3985424
18:21:29 23526 8192000000 10310767344 10753314816 1385954416 14.470 99 38 75 0 1404018208 9426256 8637536
18:22:56 24548 8192000000 10305310608 10752655360 1381092336 14.426 99 56 113 0 1406669824 12366768 13210720
18:24:23 45103 8192000000 10303103536 10767937536 1383699248 14.450 99 9 18 0 1398896752 13082896 2114608
18:26:08 54261 8192000000 10321061664 10774499328 1408260464 14.668 100 24 48 0 1429762160 15908432 5593264
18:28:20 115153 8192000000 10282287056 10748452864 1366166688 14.293 98 15 30 0 1385620384 15733584 3720112
18:31:24 53952 8192000000 10252599840 10717880320 1354341152 14.187 99 3 6 1 1362075440 7151936 582352
18:33:59 58563 8192000000 10247948896 10712236032 1310820704 13.794 95 22 44 0 1325812512 9872864 5118944
18:35:58 65569 8192000000 10275210000 10741755904 1354035984 14.184 98 12 25 1 1375492512 18585632 2870896
18:38:11 63316 8192000000 10297421328 10743975936 1381312128 14.428 100 28 56 0 1398805456 11423872 6069456
18:40:15 111293 8192000000 10297008896 10748104704 1335936336 14.021 96 10 20 1 1355999024 17817024 2245664
18:43:12 63223 8192000000 10293386816 10756087808 1372513088 14.350 99 10 20 1 1394599072 19467216 2618768
18:45:20 52877 8192000000 10306122928 10755743744 1379614368 14.413 99 23 46 1 1396510864 11564176 5332320
18:47:14 56046 8192000000 10312849648 10750201856 1374294640 14.366 99 13 26 1 1387295776 10357296 2643840
18:49:12 32817 8192000000 10295726720 10741227520 1365332064 14.285 99 26 52 1 1382483728 11651408 5500256
18:50:50 118915 8192000000 10279799152 10726117376 1347137968 14.122 98 29 58 1 1362872832 9197440 6537424
18:53:57 17902 8192000000 10283694992 10728157184 1369360480 14.321 100 38 75 1 1390034224 11767472 8906272
18:55:19 38503 8192000000 10251557168 10705580032 1327129280 13.941 97 6 12 0 1334405648 6023824 1252544
18:57:02 68259 8192000000 10240897344 10684551168 1309175872 13.779 96 13 26 1 1319360528 7259632 2925024
18:59:11 101336 8192000000 10252229792 10704039936 1327714448 13.946 98 18 36 0 1346306992 13912464 4680080
19:02:07 84684 8192000000 10281567312 10730491904 1369969456 14.327 100 19 38 0 1391031680 16179264 4882960
19:04:38 81148 8192000000 10275091280 10722603008 1322448864 13.899 96 29 58 0 1341591536 12763440 6379232
19:07:00 53086 8192000000 10294063072 10741379072 1366024368 14.291 99 27 54 0 1385734576 13480976 6229232
19:08:53 68772 8192000000 10308103632 10764177408 1389361168 14.500 100 14 28 0 1417501440 25007488 3132784
19:11:26 56610 8192000000 10307422752 10770448384 1385709152 14.468 99 17 34 2 1406191472 17244752 3237568
19:13:23 76718 8192000000 10300701520 10755301376 1359469888 14.233 97 17 34 0 1373021216 9656432 3894896
19:15:40 129408 8192000000 10281771872 10750050304 1360144784 14.239 98 22 45 0 1378432784 12306304 5981696
19:19:11 58836 8192000000 10272113680 10734551040 1333186432 13.996 96 9 18 0 1346811712 11713200 1912080
19:21:10 53945 8192000000 10284232304 10738282496 1353764288 14.181 98 19 38 2 1366437440 8662240 4010912
19:23:06 76128 8192000000 10256095360 10715713536 1327364640 13.943 96 11 22 1 1340085024 10353152 2367232
19:25:27 79119 8192000000 10278278528 10749956096 1366555616 14.296 99 16 32 0 1386601824 15670944 4375264
19:27:53 45679 8192000000 10287684880 10744909824 1372629120 14.351 99 22 44 0 1385229072 8162592 4437360
19:29:44 69286 8192000000 10273888256 10728792064 1339823952 14.056 97 20 40 0 1356114352 11495520 4794880
19:31:54 55076 8192000000 10290048080 10734559232 1354018304 14.184 98 29 58 1 1372849984 12188480 6643200
19:33:49 31927 8192000000 10270970560 10718740480 1342417616 14.079 98 16 32 1 1356956928 11402736 3136576
19:35:22 54390 8192000000 10261711952 10702782464 1301647168 13.710 96 16 32 0 1314961312 9903984 3410160
19:37:28 60989 8192000000 10060664992 10486906880 1171383696 12.510 87 27 54 1 1193360400 15818144 6158560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-03-2022 19:40:25 to 27-03-2022 19:40:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-03-2022 19:40:41 to 27-03-2022 19:40:43.

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          239554
MON$OLDEST_ACTIVE               240120
MON$OLDEST_SNAPSHOT             240120
MON$NEXT_TRANSACTION            240120
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                       2045664
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:567: 
:568: mscs-test-161.budzdorov.ru    Sun Mar 27 19:39:05 2022
:569:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:570: 
:571: 
:572: mscs-test-161.budzdorov.ru    Sun Mar 27 19:39:10 2022
:573:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:574: 
:575: 
:576: mscs-test-161.budzdorov.ru    Sun Mar 27 19:39:10 2022
:577:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:578: 
:579: 
:580: mscs-test-161.budzdorov.ru    Sun Mar 27 19:39:10 2022
:581:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:582: 
--- end of diff output ---

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

27.03.22 19:40:44. 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
2022-03-27 19:40:47 No severe PSQL-related problems occured
27.03.22 19:40:48. End of report.