Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.2020 19: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 17609072 kB
MemAvailable 31534048 kB
Buffers 0 kB
Cached 14044388 kB
SwapCached 5440 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-19 19:26:56
DTS_END                         2020-10-19 19:26:56.3830
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(56:17)
IP                              ::1/44256
TRN_ID                          252023
ATT_ID                          685
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: 9595.09 19629 1151411
customer order: creation 182.78 369 21933
customer order: refuse 14.71 252 1765
order to supplier: creation 48.95 1245 5874
order to supplier: removal 3.51 89984 421
invoice (draft): creation 48.34 3030 5801
invoice (draft): removal 54.63 749 6556
invoice accept: apply 43.38 15701 5205
invoice accept: cancel 49.52 626 5942
customer reserve: creation 3961.78 158 475413
customer reserve: removal 4875.60 51 585072
realization accept: apply 87.67 160 10520
realization accept: cancel 87.50 101 10500
payment from customer: creation 61.88 120 7425
payment from customer: removal 5.79 73 695
payment to supplier: creation 51.80 110 6216
payment to supplier: removal 3.89 61 467
service: total inventory turnovers 5.63 787 676
service: total monetary turnovers 6.76 21 811
service: aggregate perf. data 0.96 15847 115
service: refresh index statistics 0.03 263137 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-10-2020 19:27:49 to 19-10-2020 19:27:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7284
TEST_TIME 2 5368
TEST_TIME 3 5276
TEST_TIME 4 5095
TEST_TIME 5 5707
TEST_TIME 6 5709
TEST_TIME 7 5924
TEST_TIME 8 6151
TEST_TIME 9 6053
TEST_TIME 10 6218
TEST_TIME 11 6232
TEST_TIME 12 6210
TEST_TIME 13 6560
TEST_TIME 14 7019
TEST_TIME 15 7466
TEST_TIME 16 7865
TEST_TIME 17 8404
TEST_TIME 18 8621
TEST_TIME 19 8643
TEST_TIME 20 8558
TEST_TIME 21 9066
TEST_TIME 22 9766
TEST_TIME 23 10958
TEST_TIME 24 11484
TEST_TIME 25 11139
TEST_TIME 26 11099
TEST_TIME 27 11126
TEST_TIME 28 11151
TEST_TIME 29 10941
TEST_TIME 30 10683
TEST_TIME 31 10567
TEST_TIME 32 10484
TEST_TIME 33 10426
TEST_TIME 34 10264
TEST_TIME 35 10227
TEST_TIME 36 10141
TEST_TIME 37 10026
TEST_TIME 38 10039
TEST_TIME 39 9917
TEST_TIME 40 9771
TEST_TIME 41 9578
TEST_TIME 42 9539
TEST_TIME 43 9617
TEST_TIME 44 9518
TEST_TIME 45 9557
TEST_TIME 46 9605
TEST_TIME 47 9599
TEST_TIME 48 9545
TEST_TIME 49 9587
TEST_TIME 50 9619
TEST_TIME 51 9663
TEST_TIME 52 9705
TEST_TIME 53 9696
TEST_TIME 54 9811
TEST_TIME 55 9918
TEST_TIME 56 9861
TEST_TIME 57 9990
TEST_TIME 58 10042
TEST_TIME 59 9947
TEST_TIME 60 9820
TEST_TIME 61 9801
TEST_TIME 62 9737
TEST_TIME 63 9751
TEST_TIME 64 9723
TEST_TIME 65 9640
TEST_TIME 66 9565
TEST_TIME 67 9620
TEST_TIME 68 9616
TEST_TIME 69 9618
TEST_TIME 70 9617
TEST_TIME 71 9595
TEST_TIME 72 9584
TEST_TIME 73 9723
TEST_TIME 74 9741
TEST_TIME 75 9742
TEST_TIME 76 9744
TEST_TIME 77 9786
TEST_TIME 78 9786
TEST_TIME 79 9827
TEST_TIME 80 9854
TEST_TIME 81 9769
TEST_TIME 82 9766
TEST_TIME 83 9735
TEST_TIME 84 9703
TEST_TIME 85 9649
TEST_TIME 86 9611
TEST_TIME 87 9606
TEST_TIME 88 9544
TEST_TIME 89 9550
TEST_TIME 90 9527
TEST_TIME 91 9511
TEST_TIME 92 9528
TEST_TIME 93 9585
TEST_TIME 94 9633
TEST_TIME 95 9650
TEST_TIME 96 9654
TEST_TIME 97 9658
TEST_TIME 98 9713
TEST_TIME 99 9742
TEST_TIME 100 9762
TEST_TIME 101 9747
TEST_TIME 102 9735
TEST_TIME 103 9756
TEST_TIME 104 9767
TEST_TIME 105 9682
TEST_TIME 106 9658
TEST_TIME 107 9634
TEST_TIME 108 9576
TEST_TIME 109 9561
TEST_TIME 110 9558
TEST_TIME 111 9554
TEST_TIME 112 9522
TEST_TIME 113 9538
TEST_TIME 114 9526
TEST_TIME 115 9565
TEST_TIME 116 9557
TEST_TIME 117 9605
TEST_TIME 118 9587
TEST_TIME 119 9612
TEST_TIME 120 9600

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-10-2020 19:27:53 to 19-10-2020 19:27:56.

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 1592859 1592859 0 0.00 0 17269 6 0 0
doc_list_biud 1592857 1592857 0 0.00 0 26146 24 0 0
sp_add_invoice_to_stock 5338 5205 133 2.49 41 137950 15701 0 133
sp_cancel_adding_invoice 5942 5942 0 0.00 5 65613 627 0 0
sp_cancel_client_order 1765 1765 0 0.00 4 8854 253 0 0
sp_cancel_customer_reserve 585072 585072 0 0.00 1 27528 51 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 11493 73 0 0
sp_cancel_pay_to_supplier 467 467 0 0.00 3 2843 61 0 0
sp_cancel_supplier_invoice 6564 6556 8 0.12 2 18761 749 0 8
sp_cancel_supplier_order 421 421 0 0.00 9 864936 89984 0 0
sp_cancel_write_off 10500 10500 0 0.00 1 10675 102 0 0
sp_client_order 21933 21933 0 0.00 4 20405 369 0 0
sp_customer_reserve 475413 475413 0 0.00 0 59559 158 0 0
sp_fill_shopping_cart 33591 33591 0 0.00 1 18792 38 0 0
sp_fill_shopping_cart_clo_res 475404 475404 0 0.00 0 18334 15 0 0
sp_get_clo_for_invoice 5196 5196 0 0.00 0 18200 306 0 0
sp_kill_cost_storno 17604 17604 0 0.00 0 7221 45 0 0
sp_kill_qstorno_ret_qs2qd 1187621 1187621 0 0.00 0 20592 28 0 0
sp_kill_qty_storno 625971 625971 0 0.00 0 26145 60 0 0
sp_lock_selected_doc 21655 21655 0 0.00 0 7435 7 0 0
sp_make_cost_storno 29357 29357 0 0.00 0 13122 46 0 0
sp_multiply_rows_for_pdistr 29356 29356 0 0.00 0 17261 27 0 0
sp_multiply_rows_for_qdistr 21932 21932 0 0.00 0 18788 176 0 0
sp_pay_from_customer 7425 7425 0 0.00 4 21779 121 0 0
sp_pay_to_supplier 6216 6216 0 0.00 5 8530 110 0 0
sp_payment_common 13641 13641 0 0.00 1 21275 30 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 5761 223 0 0
sp_qd_handle_on_invoice_upd_sts 11138 11138 0 0.00 0 26143 257 0 0
sp_qd_handle_on_reserve_upd_sts 10519 10519 0 0.00 0 11133 57 0 0
sp_reserve_write_off 10520 10520 0 0.00 6 14565 161 0 0
sp_supplier_invoice 5801 5801 0 0.00 5 83955 3031 0 0
sp_supplier_order 5874 5874 0 0.00 5 71331 1245 0 0
srv_aggregate_perf_data 115 115 0 0.00 3070 267563 15847 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 3624 107224 46405 0 0
srv_find_qd_qs_mism 1102022 1102022 0 0.00 0 14796 5 0 0
srv_make_invnt_saldo 676 676 0 0.00 44 23441 787 0 0
srv_make_money_saldo 811 811 0 0.00 0 2095 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 132055 397636 263137 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5205 5205 0 0.00 0 21976 137 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 16031 186 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 1974 8 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 687 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 5018 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 467 467 0 0.00 0 2295 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 1173 13 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 26501 1419 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 2303 8 0 0
t$perf-norm:sp_client_order 21933 21933 0 0.00 0 6867 16 0 0
t$perf-norm:sp_customer_reserve 1087 1087 0 0.00 0 11704 25 0 0
t$perf-norm:sp_pay_from_customer 7425 7425 0 0.00 0 11932 11 0 0
t$perf-norm:sp_pay_to_supplier 6216 6216 0 0.00 0 14248 11 0 0
t$perf-norm:sp_reserve_write_off 10520 10520 0 0.00 0 5303 11 0 0
t$perf-norm:sp_supplier_invoice 5801 5801 0 0.00 0 8666 19 0 0
t$perf-norm:sp_supplier_order 5874 5874 0 0.00 0 6904 12 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 251 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 347 6 0 0
t$perf-norm:srv_make_invnt_saldo 676 676 0 0.00 0 3573 11 0 0
t$perf-norm:srv_make_money_saldo 811 811 0 0.00 0 9200 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10654 10388 266 2.49 0 12931 22 0 266
v_all_customers 39646 39646 0 0.00 0 13 0 0 0
v_all_suppliers 32254 32254 0 0.00 0 63 0 0 0
v_all_wares 43860 43860 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 756 6 0 0
v_cancel_client_order 3530 3530 0 0.00 0 8629 7 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 5348 6 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3454 6 0 0
v_cancel_payment_to_supplier 934 934 0 0.00 0 1455 6 0 0
v_cancel_supplier_invoice 894 878 16 1.78 0 1431 16 0 16
v_cancel_supplier_order 842 842 0 0.00 0 804 3 0 0
v_cancel_write_off 2122 2122 0 0.00 0 4655 15 0 0
v_max_id_clo_ord 5874 5874 0 0.00 0 4545 2 0 0
v_max_id_clo_res 1087 1087 0 0.00 0 8239 21 0 0
v_max_id_ord_sup 5787 5787 0 0.00 0 4326 8 0 0
v_max_non_paid_invoice 1750 1750 0 0.00 0 1166 3 0 0
v_max_non_paid_realizn 4013 4013 0 0.00 0 2859 4 0 0
v_min_id_clo_ord 5874 5874 0 0.00 0 15457 15 0 0
v_min_id_clo_res 1087 1087 0 0.00 0 1207 4 0 0
v_min_id_ord_sup 5787 5787 0 0.00 0 5022 5 0 0
v_min_non_paid_invoice 6216 6216 0 0.00 0 5732 17 0 0
v_min_non_paid_realizn 7425 7425 0 0.00 0 5686 20 0 0
v_our_firm 11038 11038 0 0.00 0 15 1 0 0
v_reserve_write_off 21038 21038 0 0.00 0 12261 6 0 0
x_lock_depdocs_on_canc_invoice 12059 12059 0 0.00 0 23141 97 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 9251 315 0 0
x_make_qstorno_1000_1200 5874 5874 0 0.00 1 71276 1168 0 0
x_make_qstorno_1200_2000 5801 5801 0 0.00 2 83932 2898 0 0
x_make_qstorno_2100_3300 475413 475413 0 0.00 0 59161 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2020 19:27:57 to 19-10-2020 19:28:01.

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
16:57:00 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:00 18522 8192000000 10331469424 10646585344 1388633008 14.494 100 86 172 1 1423758544 6183584 28941952
16:59:24 19515 8192000000 10396898848 10728718336 1451215056 15.049 100 86 172 0 1487057456 7346512 28495888
17:00:47 16612 8192000000 10505791248 10846969856 1547409152 15.888 100 80 160 0 1581109648 9225952 24474544
17:02:05 5952 8192000000 10573557200 10931015680 1634122448 16.630 100 75 150 0 1668454640 11821376 22510816
17:03:19 6920 8192000000 10631774256 11000676352 1692990512 17.126 100 75 150 2 1727494272 12737584 21766176
17:04:28 6011 8192000000 10703783616 11083415552 1761067744 17.693 100 69 134 2 1791925328 12004512 18853072
17:05:36 5233 8192000000 10756410912 11149467648 1820857504 18.185 100 81 159 1 1856405712 13556400 21991808
17:06:43 1608 8192000000 10818688224 11205001216 1873476112 18.612 100 93 186 1 1912628736 12896896 26255728
17:07:45 3216 8192000000 10803511248 11212255232 1875173952 18.626 100 62 122 0 1901073632 8996672 16903008
17:08:50 5553 8192000000 10796246016 11221454848 1885287680 18.708 100 25 50 3 1899210496 7214528 6708288
17:10:00 69263 8192000000 10818565888 11249819648 1903508144 18.855 100 36 73 3 1919740768 6180336 10052288
17:12:13 7025 8192000000 10821340496 11259056128 1913453728 18.934 100 19 38 1 1925089536 6156928 5478880
17:13:20 7153 8192000000 10833990192 11268943872 1922528048 19.007 100 19 38 0 1934148672 5682608 5938016
17:14:30 62641 8192000000 10820520368 11257487360 1887294560 18.724 98 17 34 1 1896918592 5112976 4511056
17:16:34 6918 8192000000 10794918560 11226001408 1852164544 18.440 98 28 54 2 1870624880 10483744 7976592
17:17:42 9076 8192000000 10703274096 11127599104 1730040240 17.436 95 20 40 0 1741761024 6012768 5708016
17:18:51 76191 8192000000 10683918336 11098324992 1612974208 16.450 90 28 54 1 1625288224 4726208 7587808
17:21:10 7900 8192000000 10734468656 11149717504 1803738992 18.045 99 33 66 0 1820410848 6779904 9891952
17:22:18 13014 8192000000 10766508064 11187400704 1843343088 18.368 100 26 52 3 1857181616 6246192 7592336
17:23:31 58830 8192000000 10820105712 11213455360 1866426416 18.555 100 62 125 4 1890796912 5921408 18449088
17:25:30 4647 8192000000 10796926944 11217608704 1872071488 18.601 100 30 60 0 1887341808 6938320 8332000
17:26:39 5692 8192000000 10791953648 11224268800 1878527008 18.653 100 23 46 1 1890591472 5088688 6975776
17:27:48 17921 8192000000 10875881600 11289980928 1936773232 19.121 100 56 110 0 1960220000 7413760 16033008
17:29:17 4079 8192000000 10892306288 11310424064 1951393856 19.238 100 66 130 0 1980360320 9621376 19345088
17:30:25 10959 8192000000 10901539648 11330502656 1966384464 19.357 100 44 88 1 1993876400 14477088 13014848
17:31:46 12076 8192000000 10904894784 11337576448 1969849504 19.384 100 52 104 2 1998969760 13797040 15323216
17:33:03 11720 8192000000 10896162336 11320225792 1940158144 19.148 98 55 110 3 1974174016 17188640 16827232
17:34:19 12046 8192000000 10932189744 11368583168 1989312800 19.538 100 44 88 1 2025334000 22863616 13157584
17:35:36 8473 8192000000 10935690688 11367436288 1981627968 19.478 100 51 102 1 2025539904 27924656 15987280
17:36:49 7178 8192000000 10908824256 11335114752 1954609440 19.263 100 46 89 3 2000360496 31149088 14601968
17:38:01 7029 8192000000 10921858224 11345260544 1948090864 19.211 98 51 100 2 1998021776 34106576 15824336
17:39:13 3623 8192000000 10941531520 11361951744 1962956352 19.330 100 67 134 1 2028192992 44382336 20854304
17:40:24 6156 8192000000 10945171408 11358478336 1952444528 19.246 100 70 139 1 2024969920 51548000 20977392
17:41:34 4004 8192000000 10928389024 11339001856 1940896928 19.154 100 74 148 1 2017020336 53631760 22491648
17:42:45 5734 8192000000 10949834928 11375714304 1954375152 19.261 100 55 108 0 2029761984 57376176 18010656
17:43:59 17509 8192000000 10936153312 11378982912 1950606560 19.231 100 34 68 1 2020074640 57763760 11704320
17:45:22 29226 8192000000 10930072688 11376656384 1952422112 19.246 100 34 68 1 2019828624 55576096 11830416
17:46:56 13662 8192000000 10916704096 11361247232 1913214416 18.932 99 35 70 2 1992421856 66549280 12658160
17:48:16 28149 8192000000 10904495840 11354210304 1909909712 18.906 100 20 40 1 1979162752 61995280 7257760
17:50:33 100015 8192000000 10847945792 11297730560 1873718144 18.614 97 43 84 0 1899645312 12863040 13064128
17:53:16 72216 8192000000 10828776608 11310051328 1866705920 18.558 97 8 16 2 1885415200 16189808 2519472
17:55:28 99873 8192000000 10839748240 11299659776 1887739552 18.728 98 15 30 0 1909874064 18113968 4020544
17:58:23 63065 8192000000 10868767728 11328475136 1933014144 19.091 99 18 34 1 1951042704 11668480 6360080
18:00:36 103028 8192000000 10892421904 11347795968 1948250544 19.213 99 14 29 1 1960547392 8711936 3584912
18:04:34 55077 8192000000 10911345776 11387244544 1990870224 19.551 100 12 24 1 2009064400 14489488 3704688
18:07:15 107224 8192000000 10932904320 11399634944 2001595456 19.635 100 28 56 1 2026121056 16421440 8104160
18:10:19 63631 8192000000 10916018992 11390758912 1984196864 19.498 99 13 26 1 2005041840 17051552 3793424
18:12:25 66781 8192000000 10914636176 11390066688 1958162640 19.291 98 12 24 0 1974438496 13123392 3152464
18:14:34 64168 8192000000 10914905728 11376603136 1942314400 19.165 97 17 34 1 1958433696 11314864 4804432
18:16:38 36476 8192000000 10899439056 11358646272 1964677792 19.343 98 32 64 2 1985289360 11664784 8946784
18:18:21 39936 8192000000 10932275744 11399450624 2003197248 19.648 100 29 58 1 2019915552 9186800 7531504
18:20:02 49417 8192000000 10904077376 11381792768 1915225872 18.949 96 12 24 2 1933703280 14858000 3619408
18:21:52 66014 8192000000 10896242944 11341664256 1863050768 18.528 93 28 56 0 1884116224 12666880 8398576
18:23:59 102044 8192000000 10886664144 11348803584 1856923760 18.478 94 11 22 0 1879039344 19310688 2804896
18:26:41 83040 8192000000 10853875648 11310383104 1847204912 18.399 94 28 55 3 1861491216 6528608 7757696
18:29:05 34118 8192000000 10833648480 11281596416 1866850880 18.559 96 21 38 3 1879096976 6815376 5430720
18:30:40 33497 8192000000 10828620960 11295879168 1893890848 18.777 98 15 30 0 1903891296 6538096 3462352
18:32:13 55641 8192000000 10816407040 11280269312 1848226496 18.408 96 8 16 0 1860533056 9962352 2344208
18:35:01 15930 8192000000 10874853648 11325853696 1945655616 19.192 100 53 104 3 1969588048 9021856 14910576
18:36:22 27913 8192000000 10875576608 11333816320 1947452256 19.206 100 27 55 3 1970258128 15164320 7641552
18:37:50 32671 8192000000 10855894320 11310460928 1922198960 19.004 99 25 50 4 1945706656 15900880 7606816
18:39:25 58147 8192000000 10866780000 11350114304 1920574384 18.991 99 8 16 0 1941372160 18655728 2142048
18:41:28 41018 8192000000 10895642592 11343245312 1934198272 19.100 99 33 66 2 1952250944 8604272 9448400
18:43:09 64744 8192000000 10900230864 11347513344 1916135088 18.956 98 28 57 1 1938327824 14852304 7340432
18:45:21 65478 8192000000 10882352672 11362385920 1938863616 19.138 99 20 40 1 1961017808 16134944 6019248
18:47:27 106938 8192000000 10887555664 11354435584 1937561248 19.127 99 23 45 1 1958133216 13186576 7385392
18:50:20 30416 8192000000 10871390144 11343872000 1923765328 19.017 98 8 16 0 1936514592 10484480 2264784
18:51:51 54972 8192000000 10883547968 11355103232 1947057552 19.203 99 8 15 1 1956728736 7688448 1982736
18:53:50 67642 8192000000 10883486016 11353403392 1965330512 19.348 100 14 28 0 1981075360 10966144 4778704
18:56:33 80760 8192000000 10923743280 11392909312 1979951232 19.464 99 18 36 1 1997862080 13023344 4887504
18:58:54 93657 8192000000 10927154880 11385245696 1924330912 19.022 96 18 37 1 1947659552 17681712 5646928
19:01:29 43981 8192000000 10945686208 11391623168 1983503056 19.492 99 37 74 2 2006257136 12292768 10461312
19:03:16 44746 8192000000 10939516304 11381706752 1945440336 19.190 97 34 66 1 1963608704 8534176 9634192
19:05:01 75995 8192000000 10907161232 11366428672 1914673760 18.944 97 10 20 2 1934635616 17115296 2846560
19:07:17 26645 8192000000 10851028192 11296301056 1851472512 18.434 95 32 62 2 1869137072 8264752 9399808
19:08:45 39557 8192000000 10884117296 11331239936 1917389920 18.966 99 33 66 0 1939458528 13023104 9045504
19:10:26 103449 8192000000 10862576512 11339943936 1924712208 19.025 99 16 32 0 1948128160 18931376 4484576
19:13:09 30254 8192000000 10857860160 11323318272 1907494112 18.887 98 14 28 4 1918735680 7440736 3800832
19:14:47 43125 8192000000 10824996544 11296178176 1893182528 18.771 99 8 17 0 1903812000 8533456 2096016
19:16:31 55040 8192000000 10844631872 11322146816 1925546672 19.031 100 9 18 0 1940373504 11741616 3085216
19:18:44 32857 8192000000 10836289088 11306094592 1889256944 18.740 98 25 49 1 1908391232 12818480 6315808
19:20:18 54595 8192000000 10873573824 11338047488 1946319088 19.197 100 23 45 3 1964466640 11853184 6294368
19:22:19 67989 8192000000 10854317840 11320057856 1881152064 18.674 97 18 36 0 1900377568 13815568 5409936
19:24:27 29251 8192000000 10863932704 11320864768 1900209824 18.828 98 22 44 0 1913884208 7638064 6036320
19:25:59 40909 8192000000 10875598448 11326324736 1902250304 18.844 98 36 72 1 1923551872 11254400 10047168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-10-2020 19:28:03 to 19-10-2020 19:28:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 133 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-10-2020 19:28:19 to 19-10-2020 19:28:21.

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          251569
MON$OLDEST_ACTIVE               253181
MON$OLDEST_SNAPSHOT             253181
MON$NEXT_TRANSACTION            253181
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                       2015488
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.33375 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33375 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33375 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33375 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 ---
:143: 
:144: mscs-test-161.budzdorov.ru    Mon Oct 19 19:26:57 2020
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:146: 
:147: 
:148: mscs-test-161.budzdorov.ru    Mon Oct 19 19:26:58 2020
:149:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:150: 
:151: 
:152: mscs-test-161.budzdorov.ru    Mon Oct 19 19:27:04 2020
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:154: 
:155: 
:156: mscs-test-161.budzdorov.ru    Mon Oct 19 19:27:06 2020
:157:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:158: 
:159: 
:160: mscs-test-161.budzdorov.ru    Mon Oct 19 19:27:07 2020
:161:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:162: 
--- end of diff output ---

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

19.10.20 19:28:22. 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-19 19:28:24 No severe PSQL-related problems occured
19.10.20 19:28:25. End of report.