Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.08.2021 16:44
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 16302028 kB
MemAvailable 31040648 kB
Buffers 0 kB
Cached 15395256 kB
SwapCached 2584 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-08-10 16:42:33
DTS_END                         2021-08-10 16:42:33.1870
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/36716
TRN_ID                          235863
ATT_ID                          653
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: 8993.56 23854 1079227
customer order: creation 167.17 382 20060
customer order: refuse 14.28 373 1714
order to supplier: creation 44.17 1250 5300
order to supplier: removal 3.39 105249 407
invoice (draft): creation 45.15 3828 5418
invoice (draft): removal 52.18 803 6261
invoice accept: apply 40.41 18057 4849
invoice accept: cancel 47.82 875 5738
customer reserve: creation 3673.73 180 440847
customer reserve: removal 4625.13 61 555015
realization accept: apply 80.37 190 9644
realization accept: cancel 76.33 108 9160
payment from customer: creation 57.23 152 6867
payment from customer: removal 5.49 66 659
payment to supplier: creation 45.93 132 5512
payment to supplier: removal 3.23 68 388
service: total inventory turnovers 4.33 1234 519
service: total monetary turnovers 6.45 28 774
service: aggregate perf. data 0.76 25354 91
service: refresh index statistics 0.03 318694 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-08-2021 16:44:44 to 10-08-2021 16:44:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1542
TEST_TIME 2 2981
TEST_TIME 3 4249
TEST_TIME 4 4853
TEST_TIME 5 4815
TEST_TIME 6 4615
TEST_TIME 7 4538
TEST_TIME 8 4595
TEST_TIME 9 5398
TEST_TIME 10 5806
TEST_TIME 11 6185
TEST_TIME 12 6457
TEST_TIME 13 6380
TEST_TIME 14 6667
TEST_TIME 15 6843
TEST_TIME 16 6810
TEST_TIME 17 6826
TEST_TIME 18 7030
TEST_TIME 19 7676
TEST_TIME 20 7837
TEST_TIME 21 7940
TEST_TIME 22 8335
TEST_TIME 23 8327
TEST_TIME 24 8494
TEST_TIME 25 9067
TEST_TIME 26 9033
TEST_TIME 27 9784
TEST_TIME 28 10319
TEST_TIME 29 10294
TEST_TIME 30 10010
TEST_TIME 31 9975
TEST_TIME 32 9965
TEST_TIME 33 9883
TEST_TIME 34 9846
TEST_TIME 35 9832
TEST_TIME 36 9721
TEST_TIME 37 9627
TEST_TIME 38 9470
TEST_TIME 39 9320
TEST_TIME 40 9210
TEST_TIME 41 9077
TEST_TIME 42 9367
TEST_TIME 43 9386
TEST_TIME 44 9309
TEST_TIME 45 9218
TEST_TIME 46 9213
TEST_TIME 47 9111
TEST_TIME 48 9099
TEST_TIME 49 9058
TEST_TIME 50 9028
TEST_TIME 51 8994
TEST_TIME 52 8979
TEST_TIME 53 8873
TEST_TIME 54 8895
TEST_TIME 55 8891
TEST_TIME 56 8966
TEST_TIME 57 8987
TEST_TIME 58 9056
TEST_TIME 59 9060
TEST_TIME 60 9054
TEST_TIME 61 9052
TEST_TIME 62 9176
TEST_TIME 63 9182
TEST_TIME 64 9338
TEST_TIME 65 9278
TEST_TIME 66 9302
TEST_TIME 67 9250
TEST_TIME 68 9155
TEST_TIME 69 9128
TEST_TIME 70 9083
TEST_TIME 71 9082
TEST_TIME 72 9071
TEST_TIME 73 9045
TEST_TIME 74 9006
TEST_TIME 75 8971
TEST_TIME 76 8865
TEST_TIME 77 8899
TEST_TIME 78 8923
TEST_TIME 79 8898
TEST_TIME 80 8920
TEST_TIME 81 8941
TEST_TIME 82 8940
TEST_TIME 83 8967
TEST_TIME 84 9089
TEST_TIME 85 9054
TEST_TIME 86 9032
TEST_TIME 87 9145
TEST_TIME 88 9151
TEST_TIME 89 9138
TEST_TIME 90 9245
TEST_TIME 91 9192
TEST_TIME 92 9105
TEST_TIME 93 9083
TEST_TIME 94 9072
TEST_TIME 95 9080
TEST_TIME 96 9027
TEST_TIME 97 8999
TEST_TIME 98 9009
TEST_TIME 99 8992
TEST_TIME 100 8974
TEST_TIME 101 8955
TEST_TIME 102 9016
TEST_TIME 103 9175
TEST_TIME 104 9178
TEST_TIME 105 9181
TEST_TIME 106 9184
TEST_TIME 107 9159
TEST_TIME 108 9182
TEST_TIME 109 9218
TEST_TIME 110 9271
TEST_TIME 111 9237
TEST_TIME 112 9197
TEST_TIME 113 9167
TEST_TIME 114 9125
TEST_TIME 115 9135
TEST_TIME 116 9094
TEST_TIME 117 9061
TEST_TIME 118 9046
TEST_TIME 119 9045
TEST_TIME 120 8994

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-08-2021 16:44:48 to 10-08-2021 16:44:51.

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 1488828 1488828 0 0.00 0 24752 6 0 0
doc_list_biud 1488828 1488828 0 0.00 0 69204 29 0 0
sp_add_invoice_to_stock 4948 4849 99 2.00 24 183015 18058 0 99
sp_cancel_adding_invoice 5738 5738 0 0.00 3 73679 875 0 0
sp_cancel_client_order 1714 1714 0 0.00 4 16274 374 0 0
sp_cancel_customer_reserve 555015 555015 0 0.00 1 69205 61 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 3043 67 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 2824 68 0 0
sp_cancel_supplier_invoice 6270 6261 9 0.14 1 22117 804 0 9
sp_cancel_supplier_order 407 407 0 0.00 12 1028209 105249 0 0
sp_cancel_write_off 9160 9160 0 0.00 1 7557 109 0 0
sp_client_order 20060 20060 0 0.00 3 24544 382 0 0
sp_customer_reserve 440847 440847 0 0.00 0 93024 181 0 0
sp_fill_shopping_cart 30732 30732 0 0.00 1 16289 42 0 0
sp_fill_shopping_cart_clo_res 440846 440846 0 0.00 0 18616 14 0 0
sp_get_clo_for_invoice 4848 4848 0 0.00 0 37480 335 0 0
sp_kill_cost_storno 15945 15945 0 0.00 0 17581 62 0 0
sp_kill_qstorno_ret_qs2qd 1125858 1125858 0 0.00 0 69201 33 0 0
sp_kill_qty_storno 592787 592787 0 0.00 0 69202 72 0 0
sp_lock_selected_doc 20092 20092 0 0.00 0 8956 7 0 0
sp_make_cost_storno 26871 26871 0 0.00 0 22099 60 0 0
sp_multiply_rows_for_pdistr 26871 26871 0 0.00 0 15297 37 0 0
sp_multiply_rows_for_qdistr 20060 20060 0 0.00 0 15289 171 0 0
sp_pay_from_customer 6867 6867 0 0.00 4 22025 153 0 0
sp_pay_to_supplier 5512 5512 0 0.00 5 19335 133 0 0
sp_payment_common 12379 12379 0 0.00 1 11045 35 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 16268 345 0 0
sp_qd_handle_on_invoice_upd_sts 10586 10586 0 0.00 0 36674 346 0 0
sp_qd_handle_on_reserve_upd_sts 9644 9644 0 0.00 0 21687 59 0 0
sp_reserve_write_off 9644 9644 0 0.00 6 21704 191 0 0
sp_supplier_invoice 5418 5418 0 0.00 7 105342 3829 0 0
sp_supplier_order 5300 5300 0 0.00 5 23695 1250 0 0
srv_aggregate_perf_data 91 91 0 0.00 2383 272083 25354 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1281 163138 49956 0 0
srv_find_qd_qs_mism 1034116 1034116 0 0.00 0 24724 5 0 0
srv_make_invnt_saldo 519 519 0 0.00 33 151888 1234 0 0
srv_make_money_saldo 774 774 0 0.00 1 3275 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 189278 508701 318695 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4849 4849 0 0.00 0 7908 169 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 4787 106 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 2775 12 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 1136 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 18741 34 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 1997 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 354 354 0 0.00 0 1027 14 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 28006 1560 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 1954 7 0 0
t$perf-norm:sp_client_order 20060 20060 0 0.00 0 18758 17 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 894 4 0 0
t$perf-norm:sp_pay_from_customer 6867 6867 0 0.00 0 9412 12 0 0
t$perf-norm:sp_pay_to_supplier 5512 5512 0 0.00 0 9283 13 0 0
t$perf-norm:sp_reserve_write_off 9644 9644 0 0.00 0 5065 10 0 0
t$perf-norm:sp_supplier_invoice 5418 5418 0 0.00 0 10920 22 0 0
t$perf-norm:sp_supplier_order 5300 5300 0 0.00 0 3798 18 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 704 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 1481 55 0 0
t$perf-norm:srv_make_invnt_saldo 519 519 0 0.00 0 1176 28 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 1041 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 9894 9696 198 2.00 0 3885 15 0 198
v_all_customers 36574 36574 0 0.00 0 18 1 0 0
v_all_suppliers 29120 29120 0 0.00 0 61 1 0 0
v_all_wares 40118 40118 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 1528 10 0 0
v_cancel_client_order 3428 3428 0 0.00 0 2160 5 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 1131 4 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2224 7 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 344 3 0 0
v_cancel_supplier_invoice 726 708 18 2.47 0 1823 17 0 18
v_cancel_supplier_order 814 814 0 0.00 0 1093 7 0 0
v_cancel_write_off 1920 1920 0 0.00 0 1642 12 0 0
v_max_id_clo_ord 5300 5300 0 0.00 0 940 2 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 2914 10 0 0
v_max_id_ord_sup 5371 5371 0 0.00 0 4542 4 0 0
v_max_non_paid_invoice 1623 1623 0 0.00 0 1880 3 0 0
v_max_non_paid_realizn 3637 3637 0 0.00 0 7021 8 0 0
v_min_id_clo_ord 5300 5300 0 0.00 0 4405 8 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 4575 9 0 0
v_min_id_ord_sup 5371 5371 0 0.00 0 6118 8 0 0
v_min_non_paid_invoice 5512 5512 0 0.00 0 10972 20 0 0
v_min_non_paid_realizn 6867 6867 0 0.00 0 6951 23 0 0
v_our_firm 10004 10004 0 0.00 0 41 1 0 0
v_reserve_write_off 19288 19288 0 0.00 0 3048 6 0 0
x_lock_depdocs_on_canc_invoice 11645 11645 0 0.00 0 29626 120 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 10258 385 0 0
x_make_qstorno_1000_1200 5300 5300 0 0.00 1 23681 1164 0 0
x_make_qstorno_1200_2000 5418 5418 0 0.00 3 105287 3646 0 0
x_make_qstorno_2100_3300 440847 440847 0 0.00 0 93024 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-08-2021 16:44:52 to 10-08-2021 16:44:56.

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:12:36 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:13:37 26083 8192000000 10411276624 10738896896 1461395920 15.138 100 83 166 0 1494961552 6780960 26784672
14:15:08 25483 8192000000 10469014752 10804756480 1519937072 15.650 100 85 170 0 1556173152 9194192 27041888
14:16:42 12367 8192000000 10497208512 10839175168 1550796816 15.917 100 83 166 0 1586884688 10495152 25592720
14:17:57 10428 8192000000 10566932752 10926006272 1627132384 16.571 100 74 148 3 1660189728 11169424 21887920
14:19:13 6219 8192000000 10648798448 11019165696 1708571632 17.257 100 79 158 0 1745172032 13558272 23042128
14:20:22 3758 8192000000 10706558912 11086049280 1770607968 17.772 100 79 156 2 1805381056 12754656 22018432
14:21:30 3902 8192000000 10754083584 11140583424 1800007776 18.014 100 71 141 1 1831615216 12230960 19376480
14:22:35 1715 8192000000 10788286976 11178180608 1852875680 18.445 100 90 180 0 1894487168 15589072 26022416
14:23:39 32300 8192000000 10804135568 11199606784 1867284992 18.562 100 54 108 1 1892678336 9944848 15448496
14:25:12 5538 8192000000 10787440992 11208527872 1876025104 18.633 100 33 66 1 1892807280 7625104 9157072
14:26:19 6345 8192000000 10794804416 11227820032 1889353872 18.741 100 25 50 0 1903550400 6907456 7289072
14:27:26 8078 8192000000 10808883632 11236777984 1896529008 18.798 100 17 34 0 1906764992 5500320 4735664
14:28:39 21069 8192000000 10827370192 11254939648 1905858784 18.873 100 52 104 2 1926707392 5630832 15217776
14:30:05 2901 8192000000 10822020656 11256799232 1907941136 18.890 100 33 66 3 1924277840 7573616 8763088
14:31:09 6385 8192000000 10825320960 11255689216 1908131696 18.892 100 33 66 0 1924345632 6449616 9764320
14:32:18 65288 8192000000 10767739296 11203112960 1822940496 18.202 97 28 55 0 1836150144 5060416 8149232
14:34:24 5918 8192000000 10733341504 11153399808 1805191168 18.056 99 24 48 2 1818939920 7023888 6724864
14:35:32 7518 8192000000 10714775600 11130748928 1773841376 17.799 98 29 58 1 1787348896 5571328 7936192
14:36:40 21369 8192000000 10725343792 11121733632 1746460064 17.572 97 24 48 1 1759444336 6044304 6939968
14:38:02 16003 8192000000 10739224224 11155636224 1800422544 18.017 99 22 44 0 1812865520 6118992 6323984
14:39:20 7220 8192000000 10753914448 11186954240 1850879920 18.429 100 22 44 0 1863648704 6267488 6501296
14:40:29 11740 8192000000 10775725824 11203211264 1861391936 18.515 100 21 42 1 1873186608 5489408 6305264
14:41:41 18785 8192000000 10776220112 11211661312 1869589888 18.581 100 10 20 0 1878179792 5651072 2938832
14:43:01 81432 8192000000 10844540768 11271991296 1915162816 18.948 100 41 82 3 1933284528 6701008 11420704
14:45:28 7913 8192000000 10859528160 11285491712 1919889280 18.986 99 53 106 0 1947320608 12475904 14955424
14:46:40 13855 8192000000 10894971632 11321774080 1952742048 19.248 100 46 92 1 1980015056 13894288 13378720
14:48:08 6052 8192000000 10908415392 11340939264 1972453776 19.405 100 44 88 0 2002862960 17307920 13101264
14:49:18 21282 8192000000 10915268048 11343826944 1958612864 19.295 99 43 85 2 1990446208 19399088 12434256
14:50:43 17840 8192000000 10937827072 11368542208 1986002032 19.512 100 47 94 1 2025157856 24646032 14509792
14:52:07 7183 8192000000 10925594912 11362566144 1955384960 19.269 98 42 84 0 1989392768 20504320 13503488
14:53:20 8857 8192000000 10949899936 11377242112 1991371536 19.555 100 62 124 0 2034897072 25751904 17773632
14:54:32 1858 8192000000 10951979280 11380580352 1993835440 19.574 100 72 144 1 2043909376 28793072 21280864
14:55:39 8365 8192000000 10960395616 11389485056 1994544624 19.580 100 55 108 1 2045575712 34796896 16234192
14:57:01 2400 8192000000 10961666736 11379093504 1989070208 19.536 100 77 153 1 2047648256 33147792 25430256
14:58:09 4064 8192000000 10967210688 11379294208 1982089200 19.481 100 82 164 0 2048537840 41092304 25356336
14:59:16 1978 8192000000 10986617840 11397505024 1994897520 19.582 100 88 174 5 2068641792 46762864 26981408
15:00:25 3008 8192000000 10975434720 11391258624 1981114288 19.474 100 76 153 0 2057512864 52207680 24190896
15:01:34 1281 8192000000 10983983232 11395375104 1991943024 19.559 100 82 165 0 2063484624 45951360 25590240
15:02:42 23519 8192000000 10936652080 11381256192 1967012112 19.362 99 35 70 3 2018285120 39335056 11937952
15:04:38 14126 8192000000 10931234176 11376205824 1971284176 19.396 100 47 94 1 2030297680 43011152 16002352
15:05:56 12801 8192000000 10951199168 11383504896 1970992528 19.393 100 52 105 1 2031703680 43598176 17112976
15:07:20 18148 8192000000 10910217344 11360116736 1933078848 19.091 99 27 54 0 1983361472 41053104 9229520
15:08:50 28866 8192000000 10892938640 11342192640 1911862112 18.922 99 22 44 0 1965405200 45242624 8300464
15:10:20 26601 8192000000 10897052128 11342249984 1928090880 19.052 99 36 71 1 1973418016 34231680 11095456
15:11:49 63432 8192000000 10888923216 11334164480 1946955776 19.202 100 36 71 0 1968275360 10970000 10349584
15:14:08 37072 8192000000 10894583584 11344031744 1949477904 19.222 99 21 40 2 1965140320 9872240 5790176
15:16:00 88276 8192000000 10897721424 11368361984 1971820592 19.400 100 16 30 2 1990067920 13796672 4450656
15:18:29 107139 8192000000 10922161312 11384209408 1947631856 19.208 98 18 36 4 1966020240 13698416 4689968
15:21:17 106928 8192000000 10936587072 11406053376 2005862576 19.669 100 17 34 1 2029101600 17749856 5489168
15:24:39 63099 8192000000 10908957344 11383734272 1972233440 19.403 99 20 40 0 1995701552 17433136 6034976
15:27:06 34020 8192000000 10921222144 11377115136 1971478736 19.397 99 18 35 1 1987893824 11329600 5085488
15:28:43 48754 8192000000 10916720960 11394981888 1996487024 19.595 100 8 16 0 2010266544 11538416 2241104
15:30:32 53364 8192000000 10922648240 11391066112 1980047040 19.465 99 17 34 1 1998111568 13501648 4562880
15:32:27 70587 8192000000 10927687120 11404926976 2006833168 19.677 100 18 36 1 2025744336 14079232 4831936
15:35:58 71746 8192000000 10931118096 11425181696 2020474176 19.784 100 13 26 0 2037901344 14093744 3333424
15:38:10 45080 8192000000 10922524992 11397971968 1992676768 19.565 99 10 20 0 2005470000 9995024 2798208
15:40:00 76063 8192000000 10937745664 11405115392 1989858192 19.543 99 19 38 1 2007574800 11743440 5973168
15:42:16 25827 8192000000 10942584224 11402784768 1991004800 19.552 99 36 72 2 2011231808 9672368 10554640
15:43:42 39090 8192000000 10919575072 11392806912 1974666848 19.422 99 16 32 0 1995811184 16500208 4644128
15:45:23 79404 8192000000 10926753696 11412992000 1968701840 19.375 98 15 30 1 1991446880 18354736 4390304
15:47:50 13501 8192000000 10903226144 11355414528 1929711424 19.065 97 32 64 1 1947283568 8909440 8662704
15:49:11 90038 8192000000 10912522688 11363659776 1936969104 19.123 98 40 80 0 1958216560 9896304 11351152
15:51:48 47765 8192000000 10855632304 11313057792 1887183904 18.723 97 35 70 1 1907564960 10522336 9858720
15:53:38 19323 8192000000 10878018480 11319934976 1918579984 18.975 99 34 65 0 1935804080 7955680 9268416
15:55:00 65403 8192000000 10852496624 11321171968 1907523360 18.887 98 24 48 0 1927250208 12854384 6872464
15:58:06 83392 8192000000 10821132512 11303264256 1864022688 18.536 97 18 36 0 1883172400 14087120 5062592
16:01:39 88239 8192000000 10837397088 11310784512 1843074288 18.366 95 22 44 1 1861832880 12552208 6206384
16:04:08 57337 8192000000 10874855968 11338465280 1926778384 19.041 99 15 30 0 1947943360 17045504 4119472
16:06:08 100112 8192000000 10868781344 11332046848 1899719424 18.824 98 12 24 1 1913610272 10370512 3520336
16:08:48 77611 8192000000 10852582624 11327270912 1860262576 18.505 96 10 20 1 1877064384 13530864 3270944
16:11:08 49549 8192000000 10890457776 11347628032 1935081296 19.107 99 48 95 0 1959111312 10566224 13463792
16:13:06 79864 8192000000 10894939744 11357474816 1957316336 19.285 100 13 26 0 1975933504 15084544 3532624
16:15:41 81368 8192000000 10889746432 11374784512 1975864144 19.432 100 25 50 2 1998708064 15955296 6888624
16:18:27 65619 8192000000 10875595600 11357057024 1898402656 18.813 96 7 14 1 1909236928 9287056 1547216
16:20:43 64444 8192000000 10928310624 11400962048 1964818784 19.344 99 23 46 1 1983369632 11831424 6719424
16:22:47 85769 8192000000 10931493216 11412668416 1986244224 19.514 99 23 46 1 2011551248 18251024 7056000
16:25:18 88467 8192000000 10937146800 11414638592 1941424704 19.158 97 6 12 1 1966239664 23415808 1399152
16:28:07 163138 8192000000 10895929584 11369537536 1803076448 18.039 90 12 24 0 1821783136 15213776 3492912
16:31:58 109385 8192000000 10917077712 11374194688 1923189120 19.012 97 20 40 1 1946881536 17623728 6068688
16:34:51 75780 8192000000 10895802288 11362701312 1950756160 19.232 99 19 38 0 1962956720 7108416 5092144
16:37:20 43595 8192000000 10848232848 11320700928 1896373776 18.797 96 30 59 0 1912550304 7075168 9101360
16:39:04 63308 8192000000 10883436384 11355709440 1960179232 19.307 100 15 30 1 1971997920 7280960 4537728
16:41:09 62894 8192000000 10897003968 11374936064 1963973280 19.338 99 13 25 0 1977073200 9414880 3685040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-08-2021 16:44:58 to 10-08-2021 16:45:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-08-2021 16:45:13 to 10-08-2021 16:45:15.

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          235862
MON$OLDEST_ACTIVE               237265
MON$OLDEST_SNAPSHOT             237265
MON$NEXT_TRANSACTION            237265
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                       2034080
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Aug 10 16:42:42 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Aug 10 16:42:52 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Aug 10 16:42:53 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Tue Aug 10 16:42:54 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Tue Aug 10 16:42:54 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
--- end of diff output ---

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

10.08.21 16:45:16. 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-08-10 16:45:19 No severe PSQL-related problems occured
10.08.21 16:45:20. End of report.