Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.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 17345244 kB
MemAvailable 31591552 kB
Buffers 0 kB
Cached 14305772 kB
SwapCached 5836 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-10 19:26:56
DTS_END                         2020-10-10 19:26:56.9070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/39728
TRN_ID                          260553
ATT_ID                          665
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: 9617.03 19382 1154043
customer order: creation 189.09 309 22691
customer order: refuse 16.07 263 1928
order to supplier: creation 50.98 1053 6118
order to supplier: removal 3.48 80910 418
invoice (draft): creation 50.92 3035 6110
invoice (draft): removal 52.67 563 6320
invoice accept: apply 45.60 15636 5472
invoice accept: cancel 47.73 663 5727
customer reserve: creation 4134.77 158 496173
customer reserve: removal 4709.56 47 565147
realization accept: apply 90.71 144 10885
realization accept: cancel 82.94 88 9953
payment from customer: creation 65.47 95 7856
payment from customer: removal 5.93 66 712
payment to supplier: creation 54.10 98 6492
payment to supplier: removal 3.83 54 459
service: total inventory turnovers 5.32 858 638
service: total monetary turnovers 6.85 35 822
service: aggregate perf. data 0.98 15278 118
service: refresh index statistics 0.03 268289 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2020 19:28:09 to 10-10-2020 19:28:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1458
TEST_TIME 2 4579
TEST_TIME 3 4873
TEST_TIME 4 5324
TEST_TIME 5 5454
TEST_TIME 6 5871
TEST_TIME 7 6357
TEST_TIME 8 7020
TEST_TIME 9 7879
TEST_TIME 10 8067
TEST_TIME 11 8202
TEST_TIME 12 8231
TEST_TIME 13 8969
TEST_TIME 14 9304
TEST_TIME 15 9632
TEST_TIME 16 9810
TEST_TIME 17 10310
TEST_TIME 18 10161
TEST_TIME 19 10861
TEST_TIME 20 11097
TEST_TIME 21 10742
TEST_TIME 22 10831
TEST_TIME 23 11541
TEST_TIME 24 11898
TEST_TIME 25 11801
TEST_TIME 26 11529
TEST_TIME 27 11371
TEST_TIME 28 11375
TEST_TIME 29 11215
TEST_TIME 30 11050
TEST_TIME 31 11198
TEST_TIME 32 11288
TEST_TIME 33 11171
TEST_TIME 34 11148
TEST_TIME 35 11127
TEST_TIME 36 11187
TEST_TIME 37 11062
TEST_TIME 38 10868
TEST_TIME 39 10674
TEST_TIME 40 10534
TEST_TIME 41 10658
TEST_TIME 42 10528
TEST_TIME 43 10552
TEST_TIME 44 10525
TEST_TIME 45 10448
TEST_TIME 46 10310
TEST_TIME 47 10260
TEST_TIME 48 10279
TEST_TIME 49 10349
TEST_TIME 50 10258
TEST_TIME 51 10288
TEST_TIME 52 10193
TEST_TIME 53 10134
TEST_TIME 54 10200
TEST_TIME 55 10424
TEST_TIME 56 10408
TEST_TIME 57 10414
TEST_TIME 58 10386
TEST_TIME 59 10363
TEST_TIME 60 10547
TEST_TIME 61 10508
TEST_TIME 62 10535
TEST_TIME 63 10453
TEST_TIME 64 10401
TEST_TIME 65 10376
TEST_TIME 66 10326
TEST_TIME 67 10247
TEST_TIME 68 10170
TEST_TIME 69 10113
TEST_TIME 70 10071
TEST_TIME 71 10066
TEST_TIME 72 10064
TEST_TIME 73 10050
TEST_TIME 74 10060
TEST_TIME 75 10092
TEST_TIME 76 10144
TEST_TIME 77 10101
TEST_TIME 78 10108
TEST_TIME 79 10187
TEST_TIME 80 10137
TEST_TIME 81 10165
TEST_TIME 82 10205
TEST_TIME 83 10217
TEST_TIME 84 10148
TEST_TIME 85 10064
TEST_TIME 86 10005
TEST_TIME 87 10003
TEST_TIME 88 9952
TEST_TIME 89 9916
TEST_TIME 90 9913
TEST_TIME 91 9894
TEST_TIME 92 9835
TEST_TIME 93 9763
TEST_TIME 94 9924
TEST_TIME 95 9898
TEST_TIME 96 9891
TEST_TIME 97 9899
TEST_TIME 98 9932
TEST_TIME 99 9923
TEST_TIME 100 9919
TEST_TIME 101 9938
TEST_TIME 102 9918
TEST_TIME 103 9927
TEST_TIME 104 9909
TEST_TIME 105 9920
TEST_TIME 106 9927
TEST_TIME 107 9949
TEST_TIME 108 9929
TEST_TIME 109 9863
TEST_TIME 110 9886
TEST_TIME 111 9846
TEST_TIME 112 9841
TEST_TIME 113 9767
TEST_TIME 114 9731
TEST_TIME 115 9716
TEST_TIME 116 9667
TEST_TIME 117 9629
TEST_TIME 118 9595
TEST_TIME 119 9564
TEST_TIME 120 9617

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-10-2020 19:28:13 to 10-10-2020 19:28:16.

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 1614816 1614816 0 0.00 0 21003 5 0 0
doc_list_biud 1614816 1614816 0 0.00 0 38343 21 0 0
sp_add_invoice_to_stock 5566 5472 94 1.68 43 218069 15637 0 94
sp_cancel_adding_invoice 5727 5727 0 0.00 5 34710 664 0 0
sp_cancel_client_order 1928 1928 0 0.00 4 12299 264 0 0
sp_cancel_customer_reserve 565147 565147 0 0.00 1 38344 48 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 2 6543 67 0 0
sp_cancel_pay_to_supplier 459 459 0 0.00 3 4015 54 0 0
sp_cancel_supplier_invoice 6333 6320 13 0.20 2 21386 563 0 13
sp_cancel_supplier_order 418 418 0 0.00 7 700343 80910 0 0
sp_cancel_write_off 9953 9953 0 0.00 1 15667 89 0 0
sp_client_order 22691 22691 0 0.00 4 27523 310 0 0
sp_customer_reserve 496173 496173 0 0.00 0 99481 158 0 0
sp_fill_shopping_cart 34905 34905 0 0.00 1 11739 33 0 0
sp_fill_shopping_cart_clo_res 496167 496167 0 0.00 0 14689 12 0 0
sp_get_clo_for_invoice 5466 5466 0 0.00 0 41295 283 0 0
sp_kill_cost_storno 16851 16851 0 0.00 0 5962 42 0 0
sp_kill_qstorno_ret_qs2qd 1146985 1146985 0 0.00 0 38334 25 0 0
sp_kill_qty_storno 605844 605844 0 0.00 0 38341 56 0 0
sp_lock_selected_doc 22570 22570 0 0.00 0 7996 4 0 0
sp_make_cost_storno 30699 30699 0 0.00 0 15526 42 0 0
sp_multiply_rows_for_pdistr 30699 30699 0 0.00 0 6026 22 0 0
sp_multiply_rows_for_qdistr 22691 22691 0 0.00 0 27511 140 0 0
sp_pay_from_customer 7856 7856 0 0.00 4 15197 96 0 0
sp_pay_to_supplier 6492 6492 0 0.00 4 11735 98 0 0
sp_payment_common 14348 14348 0 0.00 1 11705 23 0 0
sp_qd_handle_on_cancel_clo 1928 1928 0 0.00 0 9990 233 0 0
sp_qd_handle_on_invoice_upd_sts 11193 11193 0 0.00 0 33582 284 0 0
sp_qd_handle_on_reserve_upd_sts 10885 10885 0 0.00 0 18078 49 0 0
sp_reserve_write_off 10885 10885 0 0.00 6 18121 145 0 0
sp_supplier_invoice 6110 6110 0 0.00 7 88047 3036 0 0
sp_supplier_order 6118 6118 0 0.00 5 33611 1054 0 0
srv_aggregate_perf_data 118 118 0 0.00 2345 148066 15279 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1821 145592 54147 0 0
srv_find_qd_qs_mism 1102579 1102579 0 0.00 0 21003 5 0 0
srv_make_invnt_saldo 638 638 0 0.00 42 52034 858 0 0
srv_make_money_saldo 822 822 0 0.00 0 9754 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 138764 376779 268289 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5472 5472 0 0.00 0 12263 137 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 4568 79 0 0
t$perf-norm:sp_cancel_client_order 1928 1928 0 0.00 0 9836 22 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 9757 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 2263 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 459 459 0 0.00 0 3113 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 452 452 0 0.00 0 988 10 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 28924 1396 0 0
t$perf-norm:sp_cancel_write_off 1097 1097 0 0.00 0 8360 13 0 0
t$perf-norm:sp_client_order 22691 22691 0 0.00 0 12279 16 0 0
t$perf-norm:sp_customer_reserve 1114 1114 0 0.00 0 3905 7 0 0
t$perf-norm:sp_pay_from_customer 7856 7856 0 0.00 0 7718 9 0 0
t$perf-norm:sp_pay_to_supplier 6492 6492 0 0.00 0 4770 6 0 0
t$perf-norm:sp_reserve_write_off 10886 10886 0 0.00 0 16295 12 0 0
t$perf-norm:sp_supplier_invoice 6110 6110 0 0.00 0 13330 21 0 0
t$perf-norm:sp_supplier_order 6118 6118 0 0.00 0 6309 13 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 115 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 514 9 0 0
t$perf-norm:srv_make_invnt_saldo 638 638 0 0.00 0 899 19 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 1141 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 4 4 0 0
v_add_invoice_to_stock 11120 10932 188 1.69 0 8076 18 0 188
v_all_customers 41522 41522 0 0.00 0 143 1 0 0
v_all_suppliers 33554 33554 0 0.00 0 80 1 0 0
v_all_wares 45382 45382 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 3680 15 0 0
v_cancel_client_order 3856 3856 0 0.00 0 12268 10 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 2706 8 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 197 3 0 0
v_cancel_payment_to_supplier 918 918 0 0.00 0 3992 7 0 0
v_cancel_supplier_invoice 930 904 26 2.79 0 6307 23 0 26
v_cancel_supplier_order 836 836 0 0.00 0 383 3 0 0
v_cancel_write_off 2194 2194 0 0.00 0 1972 13 0 0
v_max_id_clo_ord 6113 6113 0 0.00 0 335 1 0 0
v_max_id_clo_res 1114 1114 0 0.00 0 1944 11 0 0
v_max_id_ord_sup 6101 6101 0 0.00 0 7324 6 0 0
v_max_non_paid_invoice 1929 1929 0 0.00 0 864 3 0 0
v_max_non_paid_realizn 4140 4140 0 0.00 0 1110 3 0 0
v_min_id_clo_ord 6113 6113 0 0.00 0 2407 4 0 0
v_min_id_clo_res 1114 1114 0 0.00 0 1799 3 0 0
v_min_id_ord_sup 6101 6101 0 0.00 0 4649 4 0 0
v_min_non_paid_invoice 6492 6492 0 0.00 0 5479 12 0 0
v_min_non_paid_realizn 7856 7856 0 0.00 0 9771 16 0 0
v_our_firm 11292 11292 0 0.00 0 14 1 0 0
v_reserve_write_off 21770 21770 0 0.00 0 10474 7 0 0
x_lock_depdocs_on_canc_invoice 11595 11595 0 0.00 0 14255 101 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 14226 354 0 0
x_make_qstorno_1000_1200 6118 6118 0 0.00 2 33601 987 0 0
x_make_qstorno_1200_2000 6110 6110 0 0.00 3 82460 2920 0 0
x_make_qstorno_2100_3300 496173 496173 0 0.00 0 99480 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2020 19:28:17 to 10-10-2020 19:28:21.

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 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:01 30430 8192000000 10349699488 10670755840 1409860768 14.683 100 87 173 0 1444225392 6344528 28020096
16:59:34 12591 8192000000 10429130496 10756694016 1479648496 15.298 100 85 170 0 1514505216 7990128 26866592
17:00:55 12050 8192000000 10477985344 10823106560 1539485248 15.819 100 80 160 0 1574325584 10294944 24545392
17:02:08 12130 8192000000 10574440880 10934792192 1632678160 16.618 100 82 164 0 1668902320 11587696 24636464
17:03:23 6708 8192000000 10640939456 11009679360 1700836896 17.192 100 73 145 2 1732769408 11270128 20662384
17:04:32 3486 8192000000 10707603200 11084533760 1767080768 17.743 100 83 165 1 1803041888 13127088 22834032
17:05:37 6302 8192000000 10760921904 11144556544 1817781936 18.160 100 80 156 1 1853781840 14646720 21353184
17:06:44 1586 8192000000 10794147696 11183869952 1850986080 18.430 100 87 174 1 1889283152 13807040 24490032
17:07:47 34871 8192000000 10780503440 11203043328 1866124000 18.553 100 48 96 4 1886957520 6500720 14332800
17:09:25 8683 8192000000 10777962400 11211857920 1874430752 18.620 100 22 44 0 1886624224 6322544 5870928
17:10:35 14532 8192000000 10784794480 11221745664 1879653296 18.662 100 16 32 2 1890188544 5363728 5171520
17:11:52 69363 8192000000 10817345920 11247300608 1898891152 18.817 100 40 79 2 1916665056 6086480 11687424
17:14:02 9639 8192000000 10835014720 11266949120 1910831168 18.913 100 23 45 0 1923418736 5346224 7241344
17:15:12 6418 8192000000 10820559136 11253547008 1890517104 18.750 99 30 59 0 1904720160 5836560 8366496
17:16:25 68488 8192000000 10748314672 11158990848 1698387120 17.172 91 26 52 2 1710810272 4767696 7655456
17:18:34 6778 8192000000 10711445568 11132596224 1783693936 17.880 99 26 52 0 1797143968 5793568 7656464
17:19:48 5925 8192000000 10704872768 11117924352 1774927120 17.808 99 24 48 1 1787607616 6126848 6553648
17:20:57 10426 8192000000 10743655216 11175239680 1839770016 18.339 100 20 40 1 1851348416 6084048 5494352
17:22:08 31612 8192000000 10778086128 11189026816 1835330208 18.303 99 37 74 1 1851845328 5535104 10980016
17:23:45 9858 8192000000 10774523008 11203223552 1851063968 18.431 99 29 58 0 1865430096 6031584 8334544
17:24:55 8347 8192000000 10787455632 11220721664 1877207264 18.643 100 10 20 0 1884980272 4971168 2801840
17:26:07 7965 8192000000 10797739856 11232583680 1886525184 18.718 100 14 28 0 1895895216 4956976 4413056
17:27:17 38177 8192000000 10845862896 11270402048 1913677776 18.936 100 32 64 1 1927952976 5876576 8398624
17:28:58 8922 8192000000 10869243712 11293847552 1924288960 19.021 99 58 115 1 1956256864 15998656 15969248
17:30:26 9321 8192000000 10881705472 11312627712 1946623568 19.200 99 46 91 1 1973446864 12471248 14352048
17:31:40 10331 8192000000 10904519600 11328319488 1951660128 19.240 99 57 114 0 1983473776 15091744 16721904
17:32:53 5042 8192000000 10909632112 11338653696 1970148768 19.387 100 45 90 3 1997816432 14403328 13264336
17:34:02 2990 8192000000 10917566208 11335004160 1965066080 19.346 100 77 154 1 2006925616 18803440 23056096
17:35:06 6570 8192000000 10893963920 11313360896 1930743760 19.073 99 65 131 3 1971178720 21518496 18916464
17:36:18 11027 8192000000 10892549760 11304325120 1934213776 19.101 100 70 138 3 1977041632 22415168 20412688
17:37:32 1821 8192000000 10858220496 11269951488 1880809904 18.672 98 67 133 1 1926041504 25207808 20023792
17:38:39 3572 8192000000 10882767200 11292463104 1912607472 18.928 100 70 140 0 1966808544 31957472 22243600
17:39:46 4131 8192000000 10848520384 11258273792 1881083808 18.674 98 58 113 1 1929606400 29528816 18993776
17:40:57 8980 8192000000 10862702528 11290689536 1895143936 18.787 99 42 85 1 1936376048 27469184 13762928
17:42:16 6755 8192000000 10889291808 11307896832 1918720848 18.977 100 59 117 3 1969836720 33007920 18107952
17:43:25 6963 8192000000 10901402432 11334000640 1927521792 19.047 99 40 78 0 1969587520 28964960 13100768
17:44:33 14064 8192000000 10891185072 11324444672 1928059856 19.051 100 33 63 3 1972242320 33779952 10402512
17:45:59 23672 8192000000 10897246064 11346849792 1931305280 19.077 99 26 53 0 1976065696 35602784 9157632
17:47:26 96124 8192000000 10890552272 11351572480 1931521920 19.079 99 37 74 1 1973150976 29761552 11867504
17:50:24 58476 8192000000 10863550528 11336110080 1934731008 19.105 100 7 15 0 1949631232 12678192 2222032
17:52:23 109899 8192000000 10878998576 11336232960 1915006656 18.947 98 23 46 0 1933771152 12357856 6406640
17:55:12 63729 8192000000 10910592656 11345186816 1935539648 19.111 99 13 26 0 1949256112 9868112 3848352
17:57:21 145592 8192000000 10921993584 11378495488 1952836992 19.249 99 5 10 0 1970006528 16249280 920256
18:00:48 86865 8192000000 10935453584 11403878400 1994061488 19.576 100 10 20 1 2011730976 15600720 2068768
18:03:20 138017 8192000000 10948337584 11404144640 1991131456 19.553 99 26 52 0 2011819008 12398416 8289136
18:06:40 42072 8192000000 10966259808 11411984384 2009439792 19.697 100 57 114 1 2043889824 17382656 17067376
18:08:24 29978 8192000000 10919312048 11383484416 1980688928 19.470 99 34 65 0 1998199168 7921856 9588384
18:10:00 115902 8192000000 10912000592 11385344000 1924300912 19.021 96 16 32 1 1939918352 10680784 4936656
18:12:57 60299 8192000000 10896217280 11369361408 1952057568 19.243 99 16 31 0 1972023936 15398016 4568352
18:14:57 52612 8192000000 10888701440 11372236800 1945166896 19.188 99 11 22 1 1965725456 16779488 3779072
18:16:50 65172 8192000000 10866039936 11348504576 1887563888 18.726 96 7 14 0 1900981120 11722144 1695088
18:18:56 28970 8192000000 10916811216 11381080064 1974725376 19.423 100 32 64 1 1998925376 15164112 9035888
18:20:36 69659 8192000000 10880016112 11370147840 1917900352 18.970 97 12 24 0 1938522832 17038624 3583856
18:22:48 46443 8192000000 10815132288 11278364672 1816377440 18.148 95 11 22 0 1832761488 13681008 2703040
18:24:34 27200 8192000000 10838237776 11273641984 1848843680 18.413 98 47 94 1 1881922112 19266992 13811440
18:26:07 64900 8192000000 10827819520 11290492928 1865567744 18.548 98 17 34 1 1889100272 19019440 4513088
18:28:18 61124 8192000000 10893259360 11330936832 1913131536 18.932 99 25 48 1 1929984832 10462576 6390720
18:30:26 99830 8192000000 10845677952 11308916736 1904357648 18.861 98 18 34 2 1918841904 8907504 5576752
18:33:06 82942 8192000000 10836519024 11314528256 1886254560 18.716 98 7 14 0 1895391872 7395632 1741680
18:35:29 55224 8192000000 10874562992 11342860288 1949265664 19.221 100 25 50 1 1965438688 8527760 7645264
18:37:26 60693 8192000000 10881080528 11342815232 1878696912 18.655 96 12 24 0 1892638800 10135552 3806336
18:39:27 80004 8192000000 10873973024 11339259904 1890892672 18.753 97 22 45 1 1910815840 13599040 6324128
18:41:48 66646 8192000000 10896849376 11372548096 1960413008 19.309 100 16 32 1 1976498096 11667856 4417232
18:44:05 120335 8192000000 10910500880 11395235840 1955068112 19.267 99 8 16 0 1977262416 20254688 1939616
18:47:06 80222 8192000000 10933663936 11402829824 1983267216 19.491 100 21 42 1 2002112144 13008480 5836448
18:50:18 100023 8192000000 10901637328 11368235008 1972645376 19.406 99 21 42 0 1985500192 7348912 5505904
18:53:00 38578 8192000000 10882195984 11354861568 1931099440 19.076 97 15 30 1 1941797856 6845856 3852560
18:54:38 90871 8192000000 10912356784 11380756480 1926193184 19.036 97 9 18 0 1941210112 12429184 2587744
18:57:10 95499 8192000000 10931370208 11404791808 1976298144 19.435 99 16 33 0 1995377456 14874160 4205152
18:59:46 47259 8192000000 10925815296 11413553152 1981327328 19.475 99 21 40 1 2010707072 22724416 6655328
19:01:35 43946 8192000000 10893501200 11374247936 1946917968 19.202 98 17 33 1 1960535232 8889344 4727920
19:03:26 65830 8192000000 10882083328 11354902528 1911570288 18.919 97 13 26 0 1939118464 23871536 3676640
19:05:33 28902 8192000000 10861275040 11324035072 1866155824 18.553 96 21 42 1 1883069904 11019552 5894528
19:07:02 55440 8192000000 10847479216 11318693888 1823660128 18.208 94 16 32 3 1843326368 15072784 4593456
19:08:58 26683 8192000000 10875390160 11314364416 1876035696 18.633 97 41 82 3 1896469808 9391504 11042608
19:10:26 46735 8192000000 10881301872 11328937984 1934309568 19.101 100 27 54 1 1952865072 10843760 7711744
19:12:13 97719 8192000000 10854290160 11321036800 1810666752 18.101 93 16 32 0 1825460288 10090512 4703024
19:14:55 107527 8192000000 10862741648 11329933312 1900021584 18.826 99 23 46 1 1926557600 20142032 6393984
19:17:45 71524 8192000000 10854107456 11343294464 1908705440 18.896 99 17 34 0 1934632304 20890208 5036656
19:19:58 62992 8192000000 10851093168 11317948416 1892187008 18.763 98 15 30 1 1903218432 6384064 4647360
19:22:11 72076 8192000000 10821863856 11302416384 1858130480 18.488 96 14 28 1 1868523968 6574848 3818640
19:24:25 70129 8192000000 10827217328 11297984512 1832719296 18.282 95 13 26 2 1846437424 9775584 3942544
19:26:52 9851 8192000000 9348932448 9637806080 503261600 5.787 26 1 2 0 506790880 3467152 62128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 10-10-2020 19:28:23 to 10-10-2020 19:28:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 94 v_add_invoice_to_stock 335544517
user_exc 13 v_cancel_supplier_invoice 335544517

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

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          260832
MON$OLDEST_ACTIVE               260833
MON$OLDEST_SNAPSHOT             260833
MON$NEXT_TRANSACTION            260833
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                       2047328
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.33372 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33372 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33372 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33372 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Sat Oct 10 19:27:05 2020
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Sat Oct 10 19:27:05 2020
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Sat Oct 10 19:27:07 2020
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:42: 
--- end of diff output ---

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

10.10.20 19:28:41. 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-10 19:28:43 No severe PSQL-related problems occured
10.10.20 19:28:45. End of report.