Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2020 16:29
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 17372588 kB
MemAvailable 31633164 kB
Buffers 0 kB
Cached 14337616 kB
SwapCached 5432 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-13 16:26:59
DTS_END                         2020-10-13 16:26:59.1900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/58014
TRN_ID                          250015
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: 9854.15 18235 1182498
customer order: creation 179.17 297 21500
customer order: refuse 14.57 280 1748
order to supplier: creation 47.00 1091 5640
order to supplier: removal 3.72 81761 446
invoice (draft): creation 47.81 2566 5737
invoice (draft): removal 55.78 624 6693
invoice accept: apply 44.13 15345 5296
invoice accept: cancel 50.14 651 6017
customer reserve: creation 4142.98 150 497157
customer reserve: removal 4955.27 49 594633
realization accept: apply 85.33 154 10239
realization accept: cancel 95.19 92 11423
payment from customer: creation 61.19 104 7343
payment from customer: removal 5.52 110 662
payment to supplier: creation 50.73 124 6087
payment to supplier: removal 3.51 67 421
service: total inventory turnovers 4.71 1266 565
service: total monetary turnovers 6.52 31 782
service: aggregate perf. data 0.88 19884 105
service: refresh index statistics 0.03 240049 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2020 16:29:26 to 13-10-2020 16:29:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6346
TEST_TIME 2 5545
TEST_TIME 3 5827
TEST_TIME 4 6160
TEST_TIME 5 6118
TEST_TIME 6 5692
TEST_TIME 7 5904
TEST_TIME 8 5970
TEST_TIME 9 6823
TEST_TIME 10 7178
TEST_TIME 11 7391
TEST_TIME 12 7642
TEST_TIME 13 8570
TEST_TIME 14 9419
TEST_TIME 15 9795
TEST_TIME 16 9558
TEST_TIME 17 9886
TEST_TIME 18 9748
TEST_TIME 19 9968
TEST_TIME 20 9678
TEST_TIME 21 10086
TEST_TIME 22 10817
TEST_TIME 23 10848
TEST_TIME 24 10968
TEST_TIME 25 11709
TEST_TIME 26 12811
TEST_TIME 27 12717
TEST_TIME 28 12492
TEST_TIME 29 12156
TEST_TIME 30 12078
TEST_TIME 31 11948
TEST_TIME 32 11880
TEST_TIME 33 11939
TEST_TIME 34 11776
TEST_TIME 35 11486
TEST_TIME 36 11276
TEST_TIME 37 11221
TEST_TIME 38 11157
TEST_TIME 39 11152
TEST_TIME 40 10969
TEST_TIME 41 10867
TEST_TIME 42 11156
TEST_TIME 43 10999
TEST_TIME 44 10919
TEST_TIME 45 10823
TEST_TIME 46 10715
TEST_TIME 47 10629
TEST_TIME 48 10674
TEST_TIME 49 10566
TEST_TIME 50 10474
TEST_TIME 51 10383
TEST_TIME 52 10298
TEST_TIME 53 10164
TEST_TIME 54 10137
TEST_TIME 55 10113
TEST_TIME 56 10140
TEST_TIME 57 10164
TEST_TIME 58 10147
TEST_TIME 59 10103
TEST_TIME 60 10254
TEST_TIME 61 10197
TEST_TIME 62 10322
TEST_TIME 63 10318
TEST_TIME 64 10362
TEST_TIME 65 10401
TEST_TIME 66 10356
TEST_TIME 67 10528
TEST_TIME 68 10500
TEST_TIME 69 10604
TEST_TIME 70 10524
TEST_TIME 71 10383
TEST_TIME 72 10342
TEST_TIME 73 10316
TEST_TIME 74 10281
TEST_TIME 75 10246
TEST_TIME 76 10176
TEST_TIME 77 10119
TEST_TIME 78 10128
TEST_TIME 79 10117
TEST_TIME 80 10058
TEST_TIME 81 10048
TEST_TIME 82 10036
TEST_TIME 83 10160
TEST_TIME 84 10152
TEST_TIME 85 10159
TEST_TIME 86 10168
TEST_TIME 87 10109
TEST_TIME 88 10128
TEST_TIME 89 10250
TEST_TIME 90 10268
TEST_TIME 91 10294
TEST_TIME 92 10275
TEST_TIME 93 10206
TEST_TIME 94 10137
TEST_TIME 95 10111
TEST_TIME 96 10068
TEST_TIME 97 10080
TEST_TIME 98 10053
TEST_TIME 99 10018
TEST_TIME 100 9977
TEST_TIME 101 9936
TEST_TIME 102 9905
TEST_TIME 103 9866
TEST_TIME 104 9850
TEST_TIME 105 9858
TEST_TIME 106 9815
TEST_TIME 107 9873
TEST_TIME 108 9887
TEST_TIME 109 9903
TEST_TIME 110 9887
TEST_TIME 111 9911
TEST_TIME 112 9896
TEST_TIME 113 9920
TEST_TIME 114 9939
TEST_TIME 115 9993
TEST_TIME 116 10022
TEST_TIME 117 9959
TEST_TIME 118 9932
TEST_TIME 119 9881
TEST_TIME 120 9860

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-10-2020 16:29:30 to 13-10-2020 16:29:33.

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 1641887 1641887 0 0.00 0 31638 5 0 0
doc_list_biud 1641886 1641886 0 0.00 0 54909 22 0 0
sp_add_invoice_to_stock 5430 5296 134 2.46 21 153785 15346 0 134
sp_cancel_adding_invoice 6017 6017 0 0.00 3 48802 651 0 0
sp_cancel_client_order 1748 1748 0 0.00 4 8779 281 0 0
sp_cancel_customer_reserve 594633 594633 0 0.00 1 54911 49 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 2 5993 111 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 3379 67 0 0
sp_cancel_supplier_invoice 6695 6693 2 0.02 2 15477 625 0 2
sp_cancel_supplier_order 446 446 0 0.00 6 949922 81762 0 0
sp_cancel_write_off 11423 11423 0 0.00 1 20068 92 0 0
sp_client_order 21500 21500 0 0.00 3 16883 298 0 0
sp_customer_reserve 497157 497157 0 0.00 0 69748 151 0 0
sp_fill_shopping_cart 32866 32866 0 0.00 1 11041 34 0 0
sp_fill_shopping_cart_clo_res 497141 497141 0 0.00 0 16339 12 0 0
sp_get_clo_for_invoice 5280 5280 0 0.00 0 26370 259 0 0
sp_kill_cost_storno 18523 18523 0 0.00 0 6232 44 0 0
sp_kill_qstorno_ret_qs2qd 1207828 1207828 0 0.00 0 54906 26 0 0
sp_kill_qty_storno 636479 636479 0 0.00 0 54909 57 0 0
sp_lock_selected_doc 21427 21427 0 0.00 0 7749 6 0 0
sp_make_cost_storno 28949 28949 0 0.00 0 30924 52 0 0
sp_multiply_rows_for_pdistr 28949 28949 0 0.00 0 6597 24 0 0
sp_multiply_rows_for_qdistr 21500 21500 0 0.00 0 13062 149 0 0
sp_pay_from_customer 7343 7343 0 0.00 4 14815 105 0 0
sp_pay_to_supplier 6087 6087 0 0.00 5 10801 124 0 0
sp_payment_common 13430 13430 0 0.00 1 14098 35 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 8764 245 0 0
sp_qd_handle_on_invoice_upd_sts 11297 11297 0 0.00 0 15061 257 0 0
sp_qd_handle_on_reserve_upd_sts 10239 10239 0 0.00 0 15152 47 0 0
sp_reserve_write_off 10239 10239 0 0.00 6 15230 155 0 0
sp_supplier_invoice 5737 5737 0 0.00 6 81314 2566 0 0
sp_supplier_order 5640 5640 0 0.00 5 36089 1091 0 0
srv_aggregate_perf_data 105 105 0 0.00 3306 323100 19884 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2905 112169 48032 0 0
srv_find_qd_qs_mism 1131934 1131934 0 0.00 0 12571 4 0 0
srv_make_invnt_saldo 565 565 0 0.00 29 76866 1267 0 0
srv_make_money_saldo 782 782 0 0.00 1 4818 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 136402 427284 240049 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5297 5297 0 0.00 0 10070 131 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 7003 108 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 1605 9 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 6309 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 660 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 212 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 579 11 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 26321 1439 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 4386 12 0 0
t$perf-norm:sp_client_order 21500 21500 0 0.00 0 5556 15 0 0
t$perf-norm:sp_customer_reserve 1086 1086 0 0.00 0 1580 5 0 0
t$perf-norm:sp_pay_from_customer 7343 7343 0 0.00 0 6644 12 0 0
t$perf-norm:sp_pay_to_supplier 6087 6087 0 0.00 0 5114 10 0 0
t$perf-norm:sp_reserve_write_off 10239 10239 0 0.00 0 7548 11 0 0
t$perf-norm:sp_supplier_invoice 5737 5737 0 0.00 0 9803 17 0 0
t$perf-norm:sp_supplier_order 5640 5640 0 0.00 0 6985 15 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 17 0 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 461 11 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 651 15 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 2792 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10818 10550 268 2.47 0 6615 17 0 268
v_all_customers 39486 39486 0 0.00 0 49 0 0 0
v_all_suppliers 31690 31690 0 0.00 0 202 1 0 0
v_all_wares 43000 43000 0 0.00 0 285 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 3485 12 0 0
v_cancel_client_order 3496 3496 0 0.00 0 3865 7 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 5774 23 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 126 2 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2307 10 0 0
v_cancel_supplier_invoice 866 862 4 0.46 0 3301 20 0 4
v_cancel_supplier_order 892 892 0 0.00 0 2788 9 0 0
v_cancel_write_off 2118 2118 0 0.00 0 391 10 0 0
v_max_id_clo_ord 5640 5640 0 0.00 0 1260 1 0 0
v_max_id_clo_res 1086 1086 0 0.00 0 2825 10 0 0
v_max_id_ord_sup 5726 5726 0 0.00 0 3230 4 0 0
v_max_non_paid_invoice 1608 1608 0 0.00 0 3987 5 0 0
v_max_non_paid_realizn 3701 3701 0 0.00 0 1929 3 0 0
v_min_id_clo_ord 5640 5640 0 0.00 0 2975 6 0 0
v_min_id_clo_res 1086 1086 0 0.00 0 8238 35 0 0
v_min_id_ord_sup 5726 5726 0 0.00 0 5239 6 0 0
v_min_non_paid_invoice 6087 6087 0 0.00 0 6044 26 0 0
v_min_non_paid_realizn 7343 7343 0 0.00 0 6041 23 0 0
v_our_firm 10798 10798 0 0.00 0 28 1 0 0
v_reserve_write_off 20478 20478 0 0.00 0 3533 6 0 0
x_lock_depdocs_on_canc_invoice 12279 12279 0 0.00 0 14611 105 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 30901 369 0 0
x_make_qstorno_1000_1200 5640 5640 0 0.00 1 36081 1021 0 0
x_make_qstorno_1200_2000 5737 5737 0 0.00 3 81223 2450 0 0
x_make_qstorno_2100_3300 497157 497157 0 0.00 0 69746 137 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2020 16:29:34 to 13-10-2020 16:29:38.

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
13:57:01 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:03 23110 8192000000 10376948832 10696519680 1428985424 14.852 100 90 180 0 1465094640 6347856 29761360
13:59:31 17192 8192000000 10444653520 10776059904 1501751312 15.491 100 92 184 0 1538923392 8360176 28811904
14:00:54 15443 8192000000 10486160960 10830376960 1543659472 15.855 100 80 160 1 1578040288 9806400 24574416
14:02:17 11651 8192000000 10559127904 10916720640 1622454208 16.531 100 84 168 1 1659750992 12173184 25123600
14:03:42 5352 8192000000 10658029632 11030958080 1719130352 17.345 100 82 164 0 1755073520 12022912 23920256
14:04:48 3384 8192000000 10718154832 11097403392 1774709120 17.806 100 77 148 2 1807813600 12717024 20387456
14:05:54 5179 8192000000 10760556496 11148967936 1824716240 18.216 100 87 174 1 1863180512 14415600 24048672
14:07:00 1406 8192000000 10808735616 11197775872 1866293392 18.554 100 96 190 1 1905609920 12873280 26443248
14:08:02 6668 8192000000 10796729552 11210678272 1872668224 18.606 100 43 84 1 1893049792 8608720 11772848
14:09:09 12172 8192000000 10789614688 11219996672 1880195216 18.667 100 18 36 4 1891985616 7213120 4577280
14:10:24 59002 8192000000 10806612256 11241811968 1895561952 18.791 100 26 52 2 1909578688 5689120 8327616
14:12:24 6286 8192000000 10823456528 11254317056 1904457632 18.862 100 32 60 1 1919323200 6684016 8181552
14:13:33 6245 8192000000 10827916528 11261026304 1911997664 18.923 100 27 54 1 1926283744 5863584 8422496
14:14:42 58224 8192000000 10828784800 11257696256 1906319632 18.877 100 28 56 3 1920257584 4950960 8986992
14:16:46 4909 8192000000 10791108304 11208015872 1849366112 18.417 99 28 56 2 1863962304 5572128 9024064
14:17:52 7569 8192000000 10737729456 11156594688 1798419936 18.001 98 17 34 0 1808435120 5530736 4484448
14:19:00 11248 8192000000 10676258080 11080589312 1673425952 16.962 94 14 28 2 1683105296 5320128 4359216
14:20:12 35615 8192000000 10710290560 11128377344 1768216000 17.752 98 35 68 1 1783998176 4822112 10960064
14:21:49 6337 8192000000 10754283424 11181559808 1842430304 18.361 100 25 50 1 1856038608 6764528 6843776
14:22:56 9375 8192000000 10789449168 11209314304 1866058784 18.552 100 27 54 1 1880185568 5420272 8706512
14:24:09 8948 8192000000 10795291712 11224756224 1877611232 18.646 100 20 40 3 1888868960 5395664 5862064
14:25:19 36211 8192000000 10798198432 11232251904 1888160240 18.731 100 37 74 1 1905581648 6571120 10850288
14:26:59 3759 8192000000 10825219408 11253583872 1904174768 18.860 100 37 73 2 1920928528 6743216 10010544
14:28:09 8491 8192000000 10880174608 11300302848 1941852992 19.162 100 62 123 0 1970673616 11128080 17692544
14:29:23 10974 8192000000 10902709440 11319898112 1956503632 19.278 100 63 121 2 1989608480 15597552 17507296
14:30:42 26128 8192000000 10898381200 11337457664 1962130256 19.323 100 43 85 4 1998822080 24268496 12423328
14:32:13 28118 8192000000 10926347952 11352543232 1973576272 19.414 100 61 122 2 2017059664 26126848 17356544
14:33:46 13978 8192000000 10925252992 11361239040 1964734864 19.344 99 48 96 1 2004811504 25066640 15010000
14:35:07 3993 8192000000 10940496992 11367817216 1978675600 19.454 100 56 112 1 2025851760 31260848 15915312
14:36:14 4674 8192000000 10923417792 11344601088 1945659136 19.192 99 64 128 0 1996344128 32143168 18541824
14:37:27 2903 8192000000 10910661184 11322654720 1939081664 19.139 100 73 145 0 1998683200 36894032 22707504
14:38:36 9268 8192000000 10914128352 11333660672 1930880736 19.074 100 59 116 2 1993075952 43000320 19194896
14:39:57 9466 8192000000 10895201744 11320127488 1918693200 18.976 99 47 94 1 1973020576 38651408 15675968
14:41:11 4870 8192000000 10873896544 11302313984 1916284432 18.957 100 43 84 0 1958505392 28978800 13242160
14:42:21 7658 8192000000 10883349280 11307302912 1925112720 19.028 100 51 101 1 1970140736 28970800 16057216
14:43:36 5586 8192000000 10906509776 11328909312 1916535168 18.959 99 58 115 1 1970985536 37236576 17213792
14:44:46 16274 8192000000 10915345392 11355168768 1941511920 19.159 100 31 62 1 2000063984 47968032 10584032
14:46:09 22306 8192000000 10940493488 11377205248 1956874016 19.281 100 38 76 1 2023726992 53987552 12865424
14:47:38 10256 8192000000 10934221360 11376754688 1949598320 19.223 100 35 70 0 2024559184 62099056 12861808
14:48:59 17555 8192000000 10913954336 11355254784 1923889584 19.018 99 30 61 1 1981682656 47701968 10091104
14:50:20 45951 8192000000 10909872208 11360649216 1927542032 19.047 100 20 40 1 1989488352 54332960 7613360
14:52:08 57130 8192000000 10874564224 11354542080 1939851440 19.146 100 11 22 0 1966436624 23304048 3281136
14:54:11 30942 8192000000 10890349216 11335532544 1951053248 19.235 100 22 44 1 1965090016 8491248 5545520
14:55:46 8718 8192000000 10905815600 11339169792 1957943376 19.290 100 58 119 3 1983753136 8774016 17035744
14:56:54 100019 8192000000 10904039536 11369541632 1948850224 19.217 99 18 34 1 1965037424 11778640 4408560
15:00:41 111393 8192000000 10935800944 11384942592 1973316784 19.412 99 29 58 2 1995349456 13065664 8967008
15:03:35 59819 8192000000 10928652608 11399024640 1974795440 19.423 99 20 39 3 2000319344 19138768 6385136
15:05:36 85350 8192000000 10954806992 11416752128 2011767856 19.715 100 23 45 4 2033832416 15323664 6740896
15:08:03 88847 8192000000 10932639408 11399331840 2000916176 19.630 100 17 31 3 2016133024 10918080 4298768
15:10:35 46289 8192000000 10910712544 11383205888 1982376656 19.484 99 10 20 0 1997539616 12553200 2609760
15:12:27 53849 8192000000 10912811088 11399446528 1963754640 19.336 98 21 43 1 1980787584 10615920 6417024
15:14:49 91963 8192000000 10899513328 11387146240 1942783440 19.169 97 9 18 1 1964170752 18726576 2660736
15:17:22 58294 8192000000 10901668336 11380940800 1966792560 19.360 99 8 16 0 1977450976 8699376 1959040
15:19:20 103809 8192000000 10890278128 11378827264 1941178496 19.156 98 13 26 0 1952104448 7750880 3175072
15:22:05 36577 8192000000 10895914272 11367460864 1949886080 19.226 99 29 57 1 1965962336 7733760 8342496
15:23:42 72357 8192000000 10908425008 11365650432 1928672256 19.056 98 14 30 3 1940773856 8116288 3985312
15:25:55 56609 8192000000 10869415056 11357499392 1880696000 18.671 96 18 35 1 1905098704 19447904 4954800
15:27:55 48476 8192000000 10883579888 11352035328 1899356080 18.821 97 15 30 1 1922166768 17991600 4819088
15:29:50 48893 8192000000 10884795584 11354267648 1947185808 19.204 100 21 42 1 1966844944 13307248 6351888
15:31:45 41559 8192000000 10883718992 11333021696 1881586272 18.678 96 28 55 0 1899835040 10878432 7370336
15:33:27 71915 8192000000 10865390608 11342565376 1885992448 18.713 97 10 20 1 1909317696 20357632 2967616
15:37:19 105591 8192000000 10831601968 11281383424 1819818352 18.176 95 26 52 0 1835600304 8992096 6789856
15:40:05 54085 8192000000 10828992544 11291770880 1879654784 18.662 98 25 50 0 1895285568 8681872 6948912
15:42:13 49444 8192000000 10848679456 11317374976 1907665072 18.888 99 19 38 0 1919852000 7269392 4917536
15:44:02 57336 8192000000 10845408768 11323871232 1918381376 18.974 99 10 19 2 1933442320 11815840 3245104
15:47:26 68416 8192000000 10855460704 11328581632 1899557520 18.823 98 20 40 1 1923205568 17534752 6113296
15:49:37 76789 8192000000 10867670560 11335188480 1896866288 18.801 98 20 40 0 1912190368 9668832 5655248
15:51:59 55803 8192000000 10885678080 11355648000 1953655968 19.256 100 21 43 2 1975188528 15331840 6200720
15:54:03 26548 8192000000 10917205008 11370029056 1968499312 19.374 100 45 90 0 1997044688 15707056 12838320
15:55:30 112169 8192000000 10897171072 11365761024 1909882800 18.906 97 10 20 2 1930295424 17723280 2689344
15:58:24 92799 8192000000 10877704192 11337596928 1889392544 18.741 96 8 16 0 1903193872 11763296 2038032
16:01:01 32237 8192000000 10892626128 11360489472 1951160000 19.236 99 35 69 1 1968516208 8183344 9172864
16:02:40 61102 8192000000 10903810160 11387973632 1963708704 19.336 99 14 25 2 1989987472 22650192 3628576
16:04:42 61440 8192000000 10910799888 11381764096 1985578544 19.509 100 12 25 0 1995699728 6611808 3509376
16:06:47 68831 8192000000 10894722928 11369295872 1945151056 19.188 98 11 22 0 1954349136 6287856 2910224
16:09:10 62504 8192000000 10866026848 11354206208 1928853136 19.058 98 6 12 1 1937771744 7338784 1579824
16:11:34 61582 8192000000 10865881584 11331883008 1856229296 18.473 95 18 35 1 1871475952 10324992 4921664
16:13:36 78560 8192000000 10874657920 11349950464 1917506256 18.967 98 17 34 3 1934064528 11198304 5359968
16:15:59 37282 8192000000 10876283536 11325186048 1861127408 18.512 95 32 64 2 1880207888 10061408 9019072
16:17:45 32116 8192000000 10860519664 11305975808 1843668736 18.371 96 35 70 1 1863403856 10424272 9310848
16:19:23 64108 8192000000 10861071968 11329064960 1898436112 18.814 99 29 58 1 1923305360 16826752 8042496
16:21:33 79492 8192000000 10850984384 11321167872 1804411824 18.050 93 11 22 0 1825688416 18425760 2850832
16:24:05 84913 8192000000 10848046896 11319869440 1883111936 18.690 98 11 22 0 1897730896 11677616 2941344
16:26:56 29701 8192000000 9875673248 10248826880 1004560480 10.923 52 2 4 0 1013861584 9149264 151840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-10-2020 16:29:40 to 13-10-2020 16:29:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 134 v_add_invoice_to_stock 335544517
user_exc 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-10-2020 16:29:56 to 13-10-2020 16:29:58.

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          250014
MON$OLDEST_ACTIVE               250240
MON$OLDEST_SNAPSHOT             250240
MON$NEXT_TRANSACTION            250240
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                       2051744
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 ---
:82: 
:83: mscs-test-161.budzdorov.ru    Tue Oct 13 16:27:41 2020
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Tue Oct 13 16:27:43 2020
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Tue Oct 13 16:27:43 2020
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:93: 
--- end of diff output ---

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

13.10.20 16:29:59. 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-13 16:30:01 No severe PSQL-related problems occured
13.10.20 16:30:02. End of report.