Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2020 01:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 17353976 kB
MemAvailable 31554916 kB
Buffers 0 kB
Cached 14305192 kB
SwapCached 5448 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.7
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 2020-10-17 01:27:00
DTS_END                         2020-10-17 01:27:00.9270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(139:30) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/56128
TRN_ID                          257385
ATT_ID                          667
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: 9890.83 18087 1186900
customer order: creation 185.90 327 22308
customer order: refuse 16.25 276 1950
order to supplier: creation 49.63 1115 5955
order to supplier: removal 3.62 80873 434
invoice (draft): creation 48.91 2869 5869
invoice (draft): removal 55.79 604 6695
invoice accept: apply 45.13 15902 5416
invoice accept: cancel 51.42 675 6170
customer reserve: creation 4149.37 158 497924
customer reserve: removal 4960.48 47 595257
realization accept: apply 91.96 138 11035
realization accept: cancel 92.31 88 11077
payment from customer: creation 64.46 101 7735
payment from customer: removal 6.13 86 735
payment to supplier: creation 52.58 129 6310
payment to supplier: removal 3.69 47 443
service: total inventory turnovers 4.68 1089 562
service: total monetary turnovers 7.58 23 909
service: aggregate perf. data 0.93 20555 112
service: refresh index statistics 0.03 236644 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2020 01:28:04 to 17-10-2020 01:28:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10098
TEST_TIME 2 7624
TEST_TIME 3 7502
TEST_TIME 4 6925
TEST_TIME 5 7092
TEST_TIME 6 6684
TEST_TIME 7 6919
TEST_TIME 8 7151
TEST_TIME 9 8359
TEST_TIME 10 8250
TEST_TIME 11 8425
TEST_TIME 12 8731
TEST_TIME 13 8754
TEST_TIME 14 9499
TEST_TIME 15 10164
TEST_TIME 16 10063
TEST_TIME 17 10237
TEST_TIME 18 10992
TEST_TIME 19 11391
TEST_TIME 20 12396
TEST_TIME 21 12194
TEST_TIME 22 11913
TEST_TIME 23 11933
TEST_TIME 24 12194
TEST_TIME 25 12586
TEST_TIME 26 12252
TEST_TIME 27 11941
TEST_TIME 28 12259
TEST_TIME 29 12130
TEST_TIME 30 12014
TEST_TIME 31 11943
TEST_TIME 32 11763
TEST_TIME 33 11979
TEST_TIME 34 11876
TEST_TIME 35 11767
TEST_TIME 36 11723
TEST_TIME 37 11459
TEST_TIME 38 11364
TEST_TIME 39 11209
TEST_TIME 40 11159
TEST_TIME 41 11147
TEST_TIME 42 11039
TEST_TIME 43 10977
TEST_TIME 44 10900
TEST_TIME 45 10855
TEST_TIME 46 10916
TEST_TIME 47 10820
TEST_TIME 48 10740
TEST_TIME 49 10591
TEST_TIME 50 10568
TEST_TIME 51 10509
TEST_TIME 52 10502
TEST_TIME 53 10503
TEST_TIME 54 10599
TEST_TIME 55 10598
TEST_TIME 56 10585
TEST_TIME 57 10637
TEST_TIME 58 10633
TEST_TIME 59 10633
TEST_TIME 60 10576
TEST_TIME 61 10726
TEST_TIME 62 10682
TEST_TIME 63 10633
TEST_TIME 64 10623
TEST_TIME 65 10534
TEST_TIME 66 10489
TEST_TIME 67 10396
TEST_TIME 68 10409
TEST_TIME 69 10342
TEST_TIME 70 10289
TEST_TIME 71 10218
TEST_TIME 72 10200
TEST_TIME 73 10243
TEST_TIME 74 10200
TEST_TIME 75 10246
TEST_TIME 76 10280
TEST_TIME 77 10211
TEST_TIME 78 10264
TEST_TIME 79 10259
TEST_TIME 80 10267
TEST_TIME 81 10347
TEST_TIME 82 10376
TEST_TIME 83 10348
TEST_TIME 84 10444
TEST_TIME 85 10336
TEST_TIME 86 10230
TEST_TIME 87 10252
TEST_TIME 88 10204
TEST_TIME 89 10173
TEST_TIME 90 10150
TEST_TIME 91 10145
TEST_TIME 92 10173
TEST_TIME 93 10120
TEST_TIME 94 10174
TEST_TIME 95 10204
TEST_TIME 96 10179
TEST_TIME 97 10250
TEST_TIME 98 10215
TEST_TIME 99 10195
TEST_TIME 100 10266
TEST_TIME 101 10338
TEST_TIME 102 10349
TEST_TIME 103 10341
TEST_TIME 104 10326
TEST_TIME 105 10269
TEST_TIME 106 10230
TEST_TIME 107 10217
TEST_TIME 108 10160
TEST_TIME 109 10151
TEST_TIME 110 10167
TEST_TIME 111 10136
TEST_TIME 112 10119
TEST_TIME 113 10063
TEST_TIME 114 10033
TEST_TIME 115 10009
TEST_TIME 116 9991
TEST_TIME 117 9999
TEST_TIME 118 9986
TEST_TIME 119 9979
TEST_TIME 120 9895

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-10-2020 01:28:07 to 17-10-2020 01:28: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 1648363 1648363 0 0.00 0 15694 5 0 0
doc_list_biud 1648363 1648363 0 0.00 0 24956 22 0 0
sp_add_invoice_to_stock 5535 5416 119 2.14 34 205097 15902 0 119
sp_cancel_adding_invoice 6170 6170 0 0.00 5 51675 676 0 0
sp_cancel_client_order 1950 1950 0 0.00 3 19579 277 0 0
sp_cancel_customer_reserve 595257 595257 0 0.00 1 23698 47 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 2 5077 87 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 1084 48 0 0
sp_cancel_supplier_invoice 6706 6695 11 0.16 1 24962 605 0 11
sp_cancel_supplier_order 434 434 0 0.00 6 851043 80874 0 0
sp_cancel_write_off 11077 11077 0 0.00 1 7027 89 0 0
sp_client_order 22308 22308 0 0.00 3 24134 327 0 0
sp_customer_reserve 497924 497924 0 0.00 0 121668 158 0 0
sp_fill_shopping_cart 34132 34132 0 0.00 1 11746 33 0 0
sp_fill_shopping_cart_clo_res 497922 497922 0 0.00 0 16173 13 0 0
sp_get_clo_for_invoice 5405 5405 0 0.00 0 22544 290 0 0
sp_kill_cost_storno 18425 18425 0 0.00 0 7016 43 0 0
sp_kill_qstorno_ret_qs2qd 1208720 1208720 0 0.00 0 24952 25 0 0
sp_kill_qty_storno 638014 638014 0 0.00 0 24954 55 0 0
sp_lock_selected_doc 22778 22778 0 0.00 0 5655 5 0 0
sp_make_cost_storno 30476 30476 0 0.00 0 14293 42 0 0
sp_multiply_rows_for_pdistr 30476 30476 0 0.00 0 7804 26 0 0
sp_multiply_rows_for_qdistr 22308 22308 0 0.00 0 13408 142 0 0
sp_pay_from_customer 7735 7735 0 0.00 4 23108 102 0 0
sp_pay_to_supplier 6310 6310 0 0.00 5 21693 129 0 0
sp_payment_common 14045 14045 0 0.00 1 14960 24 0 0
sp_qd_handle_on_cancel_clo 1950 1950 0 0.00 0 19569 254 0 0
sp_qd_handle_on_invoice_upd_sts 11566 11566 0 0.00 0 19543 279 0 0
sp_qd_handle_on_reserve_upd_sts 11035 11035 0 0.00 0 13751 47 0 0
sp_reserve_write_off 11035 11035 0 0.00 6 13767 138 0 0
sp_supplier_invoice 5869 5869 0 0.00 6 83580 2870 0 0
sp_supplier_order 5955 5955 0 0.00 5 27277 1116 0 0
srv_aggregate_perf_data 112 112 0 0.00 2791 627996 20556 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2375 174037 55247 0 0
srv_find_qd_qs_mism 1135578 1135578 0 0.00 0 15694 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 41 72105 1090 0 0
srv_make_money_saldo 909 909 0 0.00 1 3066 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 123902 362116 236644 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5416 5416 0 0.00 0 14589 139 0 0
t$perf-norm:sp_cancel_adding_invoice 475 475 0 0.00 0 3224 78 0 0
t$perf-norm:sp_cancel_client_order 1950 1950 0 0.00 0 2563 13 0 0
t$perf-norm:sp_cancel_customer_reserve 758 758 0 0.00 0 7899 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 3443 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 391 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 519 13 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 24611 1419 0 0
t$perf-norm:sp_cancel_write_off 1143 1143 0 0.00 0 7159 12 0 0
t$perf-norm:sp_client_order 22308 22308 0 0.00 0 12131 15 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 2030 6 0 0
t$perf-norm:sp_pay_from_customer 7735 7735 0 0.00 0 12637 10 0 0
t$perf-norm:sp_pay_to_supplier 6310 6310 0 0.00 0 10020 12 0 0
t$perf-norm:sp_reserve_write_off 11035 11035 0 0.00 0 7315 10 0 0
t$perf-norm:sp_supplier_invoice 5869 5869 0 0.00 0 7480 13 0 0
t$perf-norm:sp_supplier_order 5955 5955 0 0.00 0 3668 14 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 49 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 37 1 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 1683 15 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 6281 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 5 0 0
v_add_invoice_to_stock 11028 10790 238 2.15 0 9365 18 0 238
v_all_customers 40726 40726 0 0.00 0 49 1 0 0
v_all_suppliers 32616 32616 0 0.00 0 222 1 0 0
v_all_wares 44615 44615 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 950 950 0 0.00 0 1275 9 0 0
v_cancel_client_order 3900 3900 0 0.00 0 894 3 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 4860 11 0 0
v_cancel_customer_reserve 1516 1516 0 0.00 0 6608 8 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 198 2 0 0
v_cancel_supplier_invoice 840 818 22 2.61 0 1105 13 0 22
v_cancel_supplier_order 868 868 0 0.00 0 4676 10 0 0
v_cancel_write_off 2286 2286 0 0.00 0 1727 15 0 0
v_max_id_clo_ord 5955 5955 0 0.00 0 554 1 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 6998 16 0 0
v_max_id_ord_sup 5868 5868 0 0.00 0 4564 5 0 0
v_max_non_paid_invoice 1825 1825 0 0.00 0 3141 4 0 0
v_max_non_paid_realizn 4140 4140 0 0.00 0 2596 3 0 0
v_min_id_clo_ord 5955 5955 0 0.00 0 4569 8 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 2017 6 0 0
v_min_id_ord_sup 5868 5868 0 0.00 0 1882 3 0 0
v_min_non_paid_invoice 6310 6310 0 0.00 0 14956 16 0 0
v_min_non_paid_realizn 7735 7735 0 0.00 0 5799 14 0 0
v_our_firm 11078 11078 0 0.00 0 13 1 0 0
v_reserve_write_off 22069 22069 0 0.00 0 5592 5 0 0
x_lock_depdocs_on_canc_invoice 12456 12456 0 0.00 0 21819 94 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 8338 283 0 0
x_make_qstorno_1000_1200 5955 5955 0 0.00 1 27268 1048 0 0
x_make_qstorno_1200_2000 5869 5869 0 0.00 3 82414 2753 0 0
x_make_qstorno_2100_3300 497924 497924 0 0.00 0 121667 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2020 01:28:12 to 17-10-2020 01:28: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
22:57:04 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:06 24928 8192000000 10384904864 10704588800 1424240880 14.810 100 91 182 0 1459852976 6112544 29499552
22:59:34 24624 8192000000 10441148784 10775363584 1496132576 15.442 100 93 186 2 1535697680 9067856 30497248
23:01:06 10905 8192000000 10503236864 10849579008 1561626528 16.010 100 79 158 1 1596226736 10008336 24591872
23:02:27 8947 8192000000 10582472560 10944458752 1640444416 16.683 100 76 152 1 1674128992 10877408 22807168
23:03:38 6737 8192000000 10647755920 11021836288 1709724992 17.266 100 76 151 2 1744024448 12348064 21951392
23:04:47 4679 8192000000 10722125728 11105529856 1784721504 17.888 100 83 166 3 1822121152 14392544 23007104
23:05:54 4838 8192000000 10758678304 11140898816 1817253856 18.155 100 86 166 3 1854295456 13948192 23093408
23:07:01 1846 8192000000 10803521424 11190661120 1858588832 18.492 100 87 174 1 1896384832 12990736 24805264
23:08:03 7871 8192000000 10795504576 11200786432 1862178048 18.521 100 45 89 1 1881063632 5798480 13087104
23:09:11 9673 8192000000 10789597024 11220508672 1880857840 18.672 100 19 36 2 1891480928 5429632 5193456
23:10:22 10151 8192000000 10808362624 11241246720 1896505424 18.798 100 20 40 3 1912425904 10852176 5068304
23:11:34 48143 8192000000 10815854784 11253112832 1904620272 18.863 100 24 48 0 1917093680 5212080 7261328
23:13:22 10013 8192000000 10835289072 11269689344 1914822288 18.945 100 24 48 0 1927834640 6469472 6542880
23:14:40 9090 8192000000 10817847680 11253387264 1876450624 18.636 98 18 36 3 1887780496 5689472 5640400
23:15:50 73871 8192000000 10773727680 11202441216 1732986256 17.460 91 30 59 0 1746668528 4722240 8960032
23:18:06 4062 8192000000 10718943968 11131715584 1767184448 17.744 97 27 54 2 1782038000 6329056 8524496
23:19:12 5226 8192000000 10678928272 11085512704 1755703824 17.649 99 21 42 1 1766998320 4967040 6327456
23:20:21 8285 8192000000 10736312672 11155013632 1814978048 18.137 100 22 44 2 1826886576 5940672 5967856
23:21:30 40528 8192000000 10743455040 11169939456 1832974352 18.284 100 15 30 2 1842261904 4833616 4453936
23:23:12 11312 8192000000 10769656320 11200688128 1857954480 18.487 100 18 35 1 1868147952 4902176 5291296
23:24:23 8102 8192000000 10786227904 11220836352 1873659504 18.614 100 26 51 1 1891587680 9889328 8038848
23:25:32 13480 8192000000 10810318208 11239448576 1887482800 18.725 100 24 49 0 1903965088 9336160 7146128
23:26:47 48485 8192000000 10834215472 11255435264 1902713680 18.848 100 35 70 1 1918350896 5714608 9922608
23:28:39 10025 8192000000 10880503328 11301588992 1938388320 19.134 100 49 97 0 1962716928 10828544 13500064
23:29:53 3868 8192000000 10915202208 11331452928 1971328592 19.396 100 60 121 1 2003210496 14680512 17201392
23:31:01 2749 8192000000 10927531904 11346771968 1978294560 19.451 100 70 138 2 2016794192 18067488 20432144
23:32:12 15986 8192000000 10906276736 11337199616 1941055664 19.155 98 48 96 0 1973432224 18355872 14020688
23:33:33 23441 8192000000 10935985024 11370541056 1989195280 19.537 100 40 79 1 2020932368 20250064 11487024
23:35:00 4162 8192000000 10928381840 11352563712 1973057680 19.410 100 65 125 2 2020278192 28316496 18904016
23:36:07 3623 8192000000 10923686624 11338874880 1940902640 19.154 98 73 146 3 2000935728 37383808 22649280
23:37:14 5117 8192000000 10918227232 11345309696 1958646736 19.295 100 54 108 2 2009465280 34202128 16616416
23:38:25 2933 8192000000 10949485696 11362942976 1966194912 19.355 100 85 168 0 2033050784 41865408 24990464
23:39:29 2679 8192000000 10928007040 11338989568 1942533968 19.167 100 78 156 0 2010655632 44132416 23989248
23:40:36 4398 8192000000 10956008528 11376525312 1969132192 19.379 100 69 136 1 2041427056 50668528 21626336
23:41:44 2374 8192000000 10959902576 11378761728 1967220016 19.363 100 77 152 2 2043236016 52121088 23894912
23:42:49 8172 8192000000 10935264672 11376979968 1967868352 19.369 100 52 105 1 2029921168 45373776 16679040
23:44:13 11458 8192000000 10951087168 11397365760 1982804624 19.487 100 43 86 0 2040782704 43992448 13985632
23:45:28 19992 8192000000 10927477344 11373752320 1937139312 19.124 98 29 57 2 1988112400 41874800 9098288
23:47:03 16716 8192000000 10946109360 11397025792 1958269056 19.292 100 41 82 0 2016691120 45250752 13171312
23:48:28 51238 8192000000 10911094208 11355049984 1936552672 19.119 98 35 70 1 1966901536 19523040 10825824
23:50:24 40733 8192000000 10912559552 11392999424 1967138432 19.363 100 15 30 0 2006623232 34114160 5370640
23:52:09 78344 8192000000 10879436752 11354648576 1911338848 18.917 98 7 14 2 1934890224 21957168 1594208
23:54:27 136468 8192000000 10896633280 11373481984 1958550224 19.295 100 10 20 1 1985865344 24279680 3035440
23:58:18 120299 8192000000 10892732560 11358900224 1893195728 18.772 96 14 28 0 1915184944 18018480 3970736
00:01:29 94592 8192000000 10883593296 11336773632 1867100816 18.561 95 4 8 0 1879326800 11639696 586288
00:04:04 81849 8192000000 10890997536 11350798336 1963779568 19.336 99 33 67 1 1981790304 9080128 8930608
00:06:29 95209 8192000000 10928301312 11379859456 1949282048 19.221 98 39 78 2 1969011168 8508384 11220736
00:09:07 113127 8192000000 10916618720 11362549760 1915559456 18.951 96 54 108 3 1939076928 7860272 15657200
00:12:02 43391 8192000000 10928862448 11392811008 2001582656 19.635 100 52 104 2 2027010640 10226352 15201632
00:13:54 174037 8192000000 10880693296 11357499392 1871119168 18.593 94 16 32 1 1889051232 12995312 4936752
00:18:13 39424 8192000000 10880651936 11355443200 1934110432 19.100 98 11 22 0 1947939856 10139680 3689744
00:19:56 51186 8192000000 10867375600 11341811712 1890099552 18.747 96 22 44 3 1909228928 12531600 6597776
00:21:50 49029 8192000000 10879070672 11347898368 1918012352 18.971 98 13 26 1 1933230416 11513920 3704144
00:23:47 96750 8192000000 10862297600 11317137408 1780874096 17.857 91 19 38 1 1798817568 12638560 5304912
00:26:24 47943 8192000000 10830613872 11283595264 1808429360 18.083 94 19 38 0 1821772784 8416080 4927344
00:28:52 62018 8192000000 10854201200 11316006912 1854820608 18.461 96 15 30 1 1872544368 13574192 4149568
00:30:56 10358 8192000000 10880758912 11311542272 1909592048 18.903 98 71 142 0 1943863808 14177872 20093888
00:32:07 105444 8192000000 10879965552 11344490496 1927555008 19.047 99 33 66 1 1949674112 12815776 9303328
00:34:59 51053 8192000000 10838550384 11322527744 1900258832 18.828 98 8 16 0 1910000960 7613440 2128688
00:37:33 61062 8192000000 10854072016 11323899904 1920270736 18.989 99 13 26 0 1931253808 7304832 3678240
00:39:36 55997 8192000000 10870278272 11353669632 1944718192 19.184 100 14 26 2 1965261152 16444112 4098848
00:41:32 24620 8192000000 10892484144 11361644544 1949363312 19.221 99 37 74 3 1975012336 14981792 10667232
00:42:59 85395 8192000000 10890878832 11362545664 1928489936 19.055 98 16 32 3 1944112944 11156608 4466400
00:45:28 55390 8192000000 10893281024 11354566656 1916529264 18.959 97 27 52 1 1935090064 10986784 7574016
00:47:29 74396 8192000000 10901453872 11364253696 1922352960 19.006 98 15 30 2 1946874128 19873472 4647696
00:49:43 77259 8192000000 10898277616 11352727552 1902021888 18.843 97 19 38 1 1922142304 15136736 4983680
00:52:05 118351 8192000000 10899822592 11368087552 1970894176 19.393 100 25 51 0 1987402000 9264544 7243280
00:55:07 59142 8192000000 10883047760 11373740032 1965101920 19.347 100 10 20 0 1982160976 14236112 2822944
00:57:10 62102 8192000000 10885027296 11353894912 1895146960 18.787 96 16 32 0 1910047056 10590896 4309200
00:59:12 79666 8192000000 10903777856 11378241536 1923192496 19.012 97 14 26 1 1941400672 14190352 4017824
01:01:33 63688 8192000000 10919581280 11387695104 1962731472 19.328 99 24 48 2 1980110800 10904368 6474960
01:03:37 31275 8192000000 10894219472 11356651520 1927943856 19.050 97 27 54 0 1946571616 11148032 7479728
01:05:19 29134 8192000000 10901081968 11359219712 1932355632 19.086 98 47 94 0 1957860080 11262352 14242096
01:06:49 89623 8192000000 10897851200 11377029120 1879407424 18.660 95 13 26 1 1904259696 20584256 4268016
01:09:43 110591 8192000000 10853361872 11319250944 1828242336 18.245 94 4 8 0 1847154528 17823888 1088304
01:12:34 116186 8192000000 10854341712 11313627136 1855494576 18.467 96 20 40 0 1868676816 7268960 5913280
01:15:30 68274 8192000000 10859266176 11330564096 1899886928 18.825 98 20 40 0 1912770304 7511104 5372272
01:19:06 76177 8192000000 10833059184 11301773312 1842639376 18.362 95 18 36 0 1854352992 6594160 5119456
01:21:28 100170 8192000000 10849035008 11336605696 1914076480 18.939 99 11 22 0 1934368304 16441376 3850448
01:24:22 62128 8192000000 10866422016 11343470592 1919680416 18.984 99 11 22 0 1932484960 9903600 2900944
01:26:35 30215 8192000000 9152205872 9390182400 317672240 3.733 17 5 8 2 322701008 3797056 1231712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-10-2020 01:28:17 to 17-10-2020 01:28:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 119 v_add_invoice_to_stock 335544517
user_exc 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 17-10-2020 01:28:33 to 17-10-2020 01:28:34.

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          257669
MON$OLDEST_ACTIVE               257670
MON$OLDEST_SNAPSHOT             257670
MON$NEXT_TRANSACTION            257670
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                       2041568
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 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 ---
:273: 
:274: mscs-test-161.budzdorov.ru    Sat Oct 17 01:27:08 2020
:275:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:276: 
:277: 
:278: mscs-test-161.budzdorov.ru    Sat Oct 17 01:27:09 2020
:279:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:280: 
--- end of diff output ---

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

17.10.20 01:28: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
2020-10-17 01:28:38 No severe PSQL-related problems occured
17.10.20 01:28:39. End of report.