Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.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 17420268 kB
MemAvailable 31548516 kB
Buffers 0 kB
Cached 14225272 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-16 01:26:58
DTS_END                         2020-10-16 01:26:58.9950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/40228
TRN_ID                          251892
ATT_ID                          663
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: 9837.78 21604 1180534
customer order: creation 178.91 341 21469
customer order: refuse 14.90 266 1788
order to supplier: creation 47.69 1070 5723
order to supplier: removal 3.48 92647 418
invoice (draft): creation 48.20 2769 5784
invoice (draft): removal 56.72 654 6806
invoice accept: apply 44.17 16118 5300
invoice accept: cancel 51.21 667 6145
customer reserve: creation 4060.62 160 487274
customer reserve: removal 5026.80 51 603216
realization accept: apply 87.53 144 10503
realization accept: cancel 82.23 102 9868
payment from customer: creation 62.31 95 7477
payment from customer: removal 5.85 67 702
payment to supplier: creation 51.22 116 6146
payment to supplier: removal 3.33 103 400
service: total inventory turnovers 5.27 835 632
service: total monetary turnovers 6.33 30 760
service: aggregate perf. data 0.99 16361 119
service: refresh index statistics 0.03 299481 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-10-2020 01:28:04 to 16-10-2020 01:28:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4566
TEST_TIME 2 4070
TEST_TIME 3 4464
TEST_TIME 4 6036
TEST_TIME 5 5994
TEST_TIME 6 5783
TEST_TIME 7 5823
TEST_TIME 8 5712
TEST_TIME 9 6032
TEST_TIME 10 6029
TEST_TIME 11 6210
TEST_TIME 12 6392
TEST_TIME 13 6645
TEST_TIME 14 7054
TEST_TIME 15 7693
TEST_TIME 16 7803
TEST_TIME 17 8516
TEST_TIME 18 9161
TEST_TIME 19 9156
TEST_TIME 20 9532
TEST_TIME 21 10296
TEST_TIME 22 10240
TEST_TIME 23 10962
TEST_TIME 24 12095
TEST_TIME 25 12297
TEST_TIME 26 12139
TEST_TIME 27 12098
TEST_TIME 28 11883
TEST_TIME 29 11778
TEST_TIME 30 11673
TEST_TIME 31 11327
TEST_TIME 32 11541
TEST_TIME 33 11292
TEST_TIME 34 11186
TEST_TIME 35 11142
TEST_TIME 36 11016
TEST_TIME 37 11110
TEST_TIME 38 11112
TEST_TIME 39 10980
TEST_TIME 40 10919
TEST_TIME 41 10854
TEST_TIME 42 10725
TEST_TIME 43 10597
TEST_TIME 44 10538
TEST_TIME 45 10506
TEST_TIME 46 10462
TEST_TIME 47 10485
TEST_TIME 48 10527
TEST_TIME 49 10454
TEST_TIME 50 10435
TEST_TIME 51 10520
TEST_TIME 52 10540
TEST_TIME 53 10539
TEST_TIME 54 10641
TEST_TIME 55 10677
TEST_TIME 56 10693
TEST_TIME 57 10575
TEST_TIME 58 10704
TEST_TIME 59 10671
TEST_TIME 60 10605
TEST_TIME 61 10494
TEST_TIME 62 10454
TEST_TIME 63 10364
TEST_TIME 64 10406
TEST_TIME 65 10305
TEST_TIME 66 10286
TEST_TIME 67 10223
TEST_TIME 68 10194
TEST_TIME 69 10189
TEST_TIME 70 10135
TEST_TIME 71 10127
TEST_TIME 72 10089
TEST_TIME 73 10099
TEST_TIME 74 10116
TEST_TIME 75 10075
TEST_TIME 76 10105
TEST_TIME 77 10286
TEST_TIME 78 10236
TEST_TIME 79 10246
TEST_TIME 80 10253
TEST_TIME 81 10281
TEST_TIME 82 10371
TEST_TIME 83 10400
TEST_TIME 84 10297
TEST_TIME 85 10250
TEST_TIME 86 10210
TEST_TIME 87 10130
TEST_TIME 88 10046
TEST_TIME 89 10026
TEST_TIME 90 10018
TEST_TIME 91 9988
TEST_TIME 92 9987
TEST_TIME 93 9945
TEST_TIME 94 9987
TEST_TIME 95 10065
TEST_TIME 96 10075
TEST_TIME 97 10025
TEST_TIME 98 10086
TEST_TIME 99 10099
TEST_TIME 100 10085
TEST_TIME 101 10116
TEST_TIME 102 10116
TEST_TIME 103 10084
TEST_TIME 104 10108
TEST_TIME 105 10099
TEST_TIME 106 10039
TEST_TIME 107 10055
TEST_TIME 108 10012
TEST_TIME 109 9945
TEST_TIME 110 9909
TEST_TIME 111 9882
TEST_TIME 112 9886
TEST_TIME 113 9862
TEST_TIME 114 9856
TEST_TIME 115 9852
TEST_TIME 116 9833
TEST_TIME 117 9855
TEST_TIME 118 9847
TEST_TIME 119 9825
TEST_TIME 120 9842

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-10-2020 01:28:08 to 16-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 1633591 1633591 0 0.00 0 38226 5 0 0
doc_list_biud 1633582 1633582 0 0.00 0 28915 24 0 0
sp_add_invoice_to_stock 5412 5300 112 2.06 23 186597 16118 0 112
sp_cancel_adding_invoice 6145 6145 0 0.00 4 51638 667 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 14751 267 0 0
sp_cancel_customer_reserve 603216 603216 0 0.00 1 28919 52 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 3319 67 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 2 11177 104 0 0
sp_cancel_supplier_invoice 6815 6806 9 0.13 2 21008 655 0 9
sp_cancel_supplier_order 418 418 0 0.00 5 1069069 92648 0 0
sp_cancel_write_off 9868 9868 0 0.00 1 14041 103 0 0
sp_client_order 21469 21469 0 0.00 4 21363 342 0 0
sp_customer_reserve 487274 487274 0 0.00 0 127934 161 0 0
sp_fill_shopping_cart 32961 32961 0 0.00 1 13627 34 0 0
sp_fill_shopping_cart_clo_res 487264 487264 0 0.00 0 13354 13 0 0
sp_get_clo_for_invoice 5285 5285 0 0.00 0 14404 291 0 0
sp_kill_cost_storno 17115 17115 0 0.00 0 23509 49 0 0
sp_kill_qstorno_ret_qs2qd 1223524 1223524 0 0.00 0 28885 28 0 0
sp_kill_qty_storno 644026 644026 0 0.00 0 28915 59 0 0
sp_lock_selected_doc 21714 21714 0 0.00 0 7472 4 0 0
sp_make_cost_storno 29409 29409 0 0.00 0 38201 45 0 0
sp_multiply_rows_for_pdistr 29408 29408 0 0.00 0 8047 23 0 0
sp_multiply_rows_for_qdistr 21469 21469 0 0.00 0 21356 170 0 0
sp_pay_from_customer 7477 7477 0 0.00 4 16762 96 0 0
sp_pay_to_supplier 6146 6146 0 0.00 5 24553 116 0 0
sp_payment_common 13621 13621 0 0.00 2 16734 24 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 11902 239 0 0
sp_qd_handle_on_invoice_upd_sts 11428 11428 0 0.00 0 16947 261 0 0
sp_qd_handle_on_reserve_upd_sts 10502 10502 0 0.00 0 8713 45 0 0
sp_reserve_write_off 10503 10503 0 0.00 6 15065 145 0 0
sp_supplier_invoice 5784 5784 0 0.00 6 87393 2770 0 0
sp_supplier_order 5723 5723 0 0.00 5 47852 1071 0 0
srv_aggregate_perf_data 119 119 0 0.00 2445 251931 16362 0 0
srv_fill_mon_cache_memory 58 58 0 0.00 2313 184071 57147 0 0
srv_find_qd_qs_mism 1131455 1131455 0 0.00 0 10225 5 0 0
srv_make_invnt_saldo 632 632 0 0.00 30 47462 835 0 0
srv_make_money_saldo 760 760 0 0.00 1 6217 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 187472 425323 299481 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5300 5300 0 0.00 0 41849 167 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 4381 71 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 3245 11 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 4444 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 1119 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 617 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 668 12 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 34691 1359 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 5130 17 0 0
t$perf-norm:sp_client_order 21469 21469 0 0.00 0 8359 15 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 2085 5 0 0
t$perf-norm:sp_pay_from_customer 7477 7477 0 0.00 0 9411 10 0 0
t$perf-norm:sp_pay_to_supplier 6146 6146 0 0.00 0 5010 12 0 0
t$perf-norm:sp_reserve_write_off 10503 10503 0 0.00 0 8104 11 0 0
t$perf-norm:sp_supplier_invoice 5784 5784 0 0.00 0 9724 23 0 0
t$perf-norm:sp_supplier_order 5723 5723 0 0.00 0 6011 14 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 392 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 58 58 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 632 632 0 0.00 0 1756 20 0 0
t$perf-norm:srv_make_money_saldo 760 760 0 0.00 0 3456 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10790 10566 224 2.07 0 13396 20 0 224
v_all_customers 39342 39342 0 0.00 0 21 1 0 0
v_all_suppliers 32268 32268 0 0.00 0 33 1 0 0
v_all_wares 42924 42924 0 0.00 0 71 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 1289 8 0 0
v_cancel_client_order 3576 3576 0 0.00 0 14591 7 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 1921 4 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 2805 5 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 8992 21 0 0
v_cancel_supplier_invoice 810 792 18 2.22 0 660 13 0 18
v_cancel_supplier_order 836 836 0 0.00 0 14325 24 0 0
v_cancel_write_off 2118 2118 0 0.00 0 14018 20 0 0
v_max_id_clo_ord 5723 5723 0 0.00 0 596 1 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 2516 18 0 0
v_max_id_ord_sup 5776 5776 0 0.00 0 5421 6 0 0
v_max_non_paid_invoice 1509 1509 0 0.00 0 5594 7 0 0
v_max_non_paid_realizn 3927 3927 0 0.00 0 4092 4 0 0
v_min_id_clo_ord 5723 5723 0 0.00 0 1976 4 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 8985 20 0 0
v_min_id_ord_sup 5776 5776 0 0.00 0 13619 7 0 0
v_min_non_paid_invoice 6144 6144 0 0.00 0 4431 12 0 0
v_min_non_paid_realizn 7477 7477 0 0.00 0 12515 17 0 0
v_our_firm 10682 10682 0 0.00 0 12 1 0 0
v_reserve_write_off 21004 21004 0 0.00 0 14584 6 0 0
x_lock_depdocs_on_canc_invoice 12555 12555 0 0.00 0 29423 88 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 6982 337 0 0
x_make_qstorno_1000_1200 5723 5723 0 0.00 2 47846 1002 0 0
x_make_qstorno_1200_2000 5784 5784 0 0.00 3 86848 2652 0 0
x_make_qstorno_2100_3300 487274 487274 0 0.00 0 127933 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2020 01:28:12 to 16-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:02 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:03 13570 8192000000 10357547120 10677223424 1415683344 14.734 100 88 176 0 1451509104 5977200 29848560
22:59:23 22192 8192000000 10442427744 10773241856 1489559824 15.385 100 84 168 0 1524513488 7889120 27064544
23:00:48 15660 8192000000 10489420752 10832052224 1547851520 15.891 100 89 178 0 1585890672 10159088 27880064
23:02:08 11899 8192000000 10573683072 10932424704 1630443328 16.599 100 81 162 2 1667050208 12253184 24353696
23:03:24 6058 8192000000 10629262368 11000586240 1694644624 17.140 100 77 154 2 1730204480 13218544 22341312
23:04:32 4195 8192000000 10699368432 11080364032 1763620528 17.714 100 77 154 0 1798491936 13486240 21385168
23:05:38 3798 8192000000 10755275840 11140968448 1816116240 18.146 100 85 169 1 1853001904 13997952 22887712
23:06:44 1226 8192000000 10817488224 11205996544 1875717344 18.631 100 97 193 1 1916607104 13793072 27096688
23:07:45 26113 8192000000 10783737952 11207028736 1876012928 18.633 100 47 94 4 1895385488 5152288 14220272
23:09:12 5588 8192000000 10803079296 11228930048 1885745152 18.711 100 18 36 1 1898162176 7716240 4700784
23:10:21 13645 8192000000 10810768496 11246706688 1898268912 18.812 100 16 32 0 1912486256 9886672 4330672
23:11:34 79217 8192000000 10823813744 11261050880 1913095248 18.931 100 35 70 1 1928907712 5500256 10312208
23:13:54 7579 8192000000 10824989328 11268153344 1917748736 18.969 100 22 44 0 1929671824 5560544 6362544
23:15:05 4376 8192000000 10807258704 11236048896 1876036352 18.633 99 29 58 0 1891000608 6856608 8107648
23:16:13 68663 8192000000 10746713888 11176017920 1756470512 17.655 93 23 46 1 1768672672 5265952 6936208
23:18:22 6662 8192000000 10705482304 11117826048 1770883088 17.774 98 25 50 1 1784451472 6868896 6699488
23:19:29 3879 8192000000 10720385552 11135639552 1790532640 17.936 99 26 52 2 1803273632 5723968 7017024
23:20:34 5573 8192000000 10739132608 11154743296 1818362544 18.164 100 29 58 1 1831953440 5156144 8434752
23:21:42 48299 8192000000 10740468176 11165929472 1798929616 18.005 98 20 40 1 1809666160 4994176 5742368
23:23:30 6168 8192000000 10764499904 11189215232 1850605536 18.427 100 29 58 2 1865248272 5898720 8744016
23:24:41 7980 8192000000 10777876800 11205038080 1859454832 18.499 100 22 44 0 1872116288 5249904 7411552
23:25:49 69521 8192000000 10794813808 11216437248 1873460368 18.612 100 28 54 1 1887144160 5339824 8343968
23:28:03 9836 8192000000 10852735280 11270283264 1916803216 18.961 100 46 92 1 1937481808 8377888 12300704
23:29:19 25921 8192000000 10866398352 11291807744 1917430880 18.966 99 49 98 1 1942350688 11493296 13426512
23:30:51 16031 8192000000 10886879920 11321610240 1953345680 19.253 100 43 86 0 1982800112 16323696 13130736
23:32:09 5715 8192000000 10882650464 11312513024 1942491472 19.167 99 39 76 2 1970157200 16669520 10996208
23:33:25 12549 8192000000 10899460400 11330068480 1941396544 19.158 99 43 85 3 1975805568 21032864 13376160
23:34:41 9633 8192000000 10946002048 11375259648 1991826624 19.558 100 71 140 1 2039177472 27155152 20195696
23:35:59 11600 8192000000 10935472080 11374747648 1984426752 19.500 100 43 85 0 2028033264 29662496 13944016
23:37:15 3781 8192000000 10947554848 11374481408 1986671568 19.517 100 49 98 0 2035138592 32983632 15483392
23:38:26 2311 8192000000 10961621424 11372171264 1966475856 19.357 99 87 172 1 2032703872 39747920 26480096
23:39:33 2378 8192000000 10979525328 11392409600 1990752080 19.550 100 86 169 0 2062894480 47030896 25111504
23:40:41 3182 8192000000 10972701312 11388944384 1985249008 19.506 100 86 173 1 2065469152 52853568 27366576
23:41:48 7606 8192000000 10948459600 11377528832 1959770288 19.304 100 52 103 0 2033192736 56905344 16517104
23:43:02 3736 8192000000 10929538992 11346812928 1918954528 18.978 99 76 151 1 2007526928 63802256 24770144
23:44:10 15391 8192000000 10901226992 11332849664 1918952880 18.978 100 38 76 1 1992593040 60558000 13082160
23:45:35 14403 8192000000 10917200736 11349716992 1919458960 18.983 99 34 68 0 1984797824 53611584 11727280
23:46:51 24285 8192000000 10924226000 11362619392 1938212864 19.132 100 36 72 1 2009375552 58375488 12787200
23:48:26 29842 8192000000 10914821328 11369558016 1943120528 19.172 100 24 48 3 2005052800 53330816 8601456
23:49:57 55637 8192000000 10887917120 11361964032 1920472048 18.991 99 10 20 0 1967879120 44019616 3387456
23:52:29 89412 8192000000 10853321680 11305037824 1803551328 18.043 93 25 50 2 1823714640 13332240 6831072
23:54:59 78920 8192000000 10880780656 11319136256 1925105808 19.028 100 17 32 2 1938335936 8965808 4264320
23:57:21 130008 8192000000 10878990400 11322241024 1886320624 18.716 97 26 52 1 1912021344 18306864 7393856
00:00:51 69800 8192000000 10913255968 11356352512 1970151984 19.387 100 38 76 0 1993237504 11585200 11500320
00:03:02 114936 8192000000 10893099136 11372384256 1979327152 19.459 100 20 40 1 2000909344 15570944 6011248
00:06:02 38901 8192000000 10902461616 11377668096 1986184992 19.514 100 12 23 0 1999052656 9714944 3152720
00:07:51 79890 8192000000 10905209824 11376312320 1969149456 19.379 99 20 40 2 1987132704 11679440 6303808
00:10:23 21473 8192000000 10898159408 11370356736 1966452544 19.357 100 16 31 1 1986221168 15248288 4520336
00:11:46 81780 8192000000 10898273536 11377541120 1953956320 19.258 98 14 28 1 1970843232 12392880 4494032
00:14:38 58720 8192000000 10903228464 11365871616 1913830176 18.937 96 29 58 3 1934309024 12674224 7804624
00:16:41 73798 8192000000 10873392720 11351367680 1913133936 18.932 97 16 32 0 1932798624 15061920 4602768
00:19:30 118874 8192000000 10906500944 11385090048 1939166704 19.140 98 15 29 2 1958007184 14961072 3879408
00:22:38 79082 8192000000 10882274752 11318104064 1829751872 18.257 93 42 84 1 1851332688 9395216 12185600
00:25:07 49677 8192000000 10861132224 11318284288 1878390144 18.652 97 29 58 1 1896632368 10288288 7953936
00:27:01 108986 8192000000 10852317840 11295772672 1843841568 18.372 95 37 73 2 1862302160 8429280 10031312
00:29:51 34994 8192000000 10843508720 11314475008 1914097536 18.940 99 51 102 0 1941044624 12653952 14293136
00:31:32 53166 8192000000 10807264432 11273056256 1828279312 18.245 95 10 20 1 1837219728 6223216 2717200
00:33:38 105306 8192000000 10838182944 11315830784 1916852704 18.962 100 15 30 0 1932460448 10940912 4666832
00:38:04 81924 8192000000 10852534112 11318480896 1861646928 18.517 96 19 38 0 1879053312 12088928 5317456
00:40:33 51778 8192000000 10881402944 11351097344 1933697056 19.096 99 24 48 2 1955500704 14958656 6844992
00:42:25 89120 8192000000 10850853312 11345027072 1885437808 18.709 97 12 24 2 1914189056 24997200 3754048
00:44:57 62774 8192000000 10872777760 11340292096 1898540048 18.815 97 12 24 0 1914677792 12505536 3632208
00:47:03 19864 8192000000 10924100576 11373764608 1970903232 19.393 100 53 105 3 1997800560 12424496 14472832
00:48:24 184071 8192000000 10899293504 11384655872 1947955680 19.210 98 22 44 1 1972781248 18725360 6100208
00:52:29 72873 8192000000 10883406976 11367690240 1949318080 19.221 98 10 20 1 1959877200 7945216 2613904
00:54:42 63254 8192000000 10883798960 11366608896 1951639104 19.240 99 6 12 0 1964642608 11326352 1677152
00:57:08 59637 8192000000 10891352912 11368112128 1920696672 18.992 97 15 30 0 1933863280 9316432 3850176
00:59:08 96979 8192000000 10923279552 11407749120 1975382864 19.428 99 16 32 0 1992738384 13329872 4025648
01:02:03 71977 8192000000 10924468064 11384074240 1935096352 19.108 97 16 31 2 1950550432 10958752 4495328
01:04:15 129370 8192000000 10932468944 11401203712 1907898560 18.890 95 16 32 0 1925040912 12501648 4640704
01:07:31 142413 8192000000 10868358576 11324190720 1694252256 17.137 85 9 18 0 1705489904 8935232 2302416
01:11:01 117046 8192000000 10847025488 11321450496 1917207024 18.964 99 15 30 1 1939338352 17863872 4267456
01:14:12 53539 8192000000 10841658064 11322052608 1928168832 19.052 100 9 15 2 1937118224 6904928 2044464
01:16:05 60115 8192000000 10846980032 11327303680 1932530992 19.087 100 11 22 0 1945061184 9174976 3355216
01:18:46 42734 8192000000 10842481024 11304177664 1841551904 18.353 95 9 18 0 1850596832 6718448 2326480
01:20:32 62389 8192000000 10855896864 11318222848 1904552608 18.863 98 28 56 1 1922398752 10126480 7719664
01:22:35 32993 8192000000 10884372752 11347054592 1946512208 19.199 100 25 50 0 1965574160 12111120 6950832
01:24:13 44356 8192000000 10885903088 11325788160 1837575552 18.321 94 19 38 0 1853413584 10190528 5647504
01:25:57 64141 8192000000 10360100032 10775879680 1445344032 14.997 74 13 26 0 1464210000 14755776 4110192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 112 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

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

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          249206
MON$OLDEST_ACTIVE               252102
MON$OLDEST_SNAPSHOT             252102
MON$NEXT_TRANSACTION            252102
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                       2036448
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 ---
:213: 
:214: mscs-test-161.budzdorov.ru    Fri Oct 16 01:27:09 2020
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Fri Oct 16 01:27:19 2020
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
:221: 
:222: mscs-test-161.budzdorov.ru    Fri Oct 16 01:27:21 2020
:223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:224: 
--- end of diff output ---

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

16.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-16 01:28:39 No severe PSQL-related problems occured
16.10.20 01:28:40. End of report.