Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.10.2020 01:28
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 17486296 kB
MemAvailable 31651876 kB
Buffers 0 kB
Cached 14228332 kB
SwapCached 5716 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-11 01:26:56
DTS_END                         2020-10-11 01:26:56.7650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/44172
TRN_ID                          259149
ATT_ID                          713
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: 9880.01 20218 1185601
customer order: creation 188.21 289 22585
customer order: refuse 16.43 268 1971
order to supplier: creation 51.18 1154 6141
order to supplier: removal 3.58 86449 429
invoice (draft): creation 50.86 2842 6103
invoice (draft): removal 54.72 667 6566
invoice accept: apply 45.88 16368 5506
invoice accept: cancel 48.98 631 5878
customer reserve: creation 4245.36 163 509443
customer reserve: removal 4860.33 50 583239
realization accept: apply 92.10 143 11052
realization accept: cancel 79.45 105 9534
payment from customer: creation 66.19 100 7943
payment from customer: removal 6.32 66 758
payment to supplier: creation 53.43 107 6411
payment to supplier: removal 3.88 59 465
service: total inventory turnovers 5.15 1020 618
service: total monetary turnovers 6.93 26 832
service: aggregate perf. data 1.03 18180 123
service: refresh index statistics 0.03 275674 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-10-2020 01:28:33 to 11-10-2020 01:28:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2759
TEST_TIME 2 3806
TEST_TIME 3 4169
TEST_TIME 4 4893
TEST_TIME 5 5008
TEST_TIME 6 5072
TEST_TIME 7 5617
TEST_TIME 8 6108
TEST_TIME 9 5964
TEST_TIME 10 6383
TEST_TIME 11 6862
TEST_TIME 12 7218
TEST_TIME 13 7721
TEST_TIME 14 7996
TEST_TIME 15 7950
TEST_TIME 16 8617
TEST_TIME 17 8819
TEST_TIME 18 8739
TEST_TIME 19 9563
TEST_TIME 20 9732
TEST_TIME 21 9961
TEST_TIME 22 10263
TEST_TIME 23 11108
TEST_TIME 24 11823
TEST_TIME 25 11895
TEST_TIME 26 11739
TEST_TIME 27 11627
TEST_TIME 28 11639
TEST_TIME 29 11508
TEST_TIME 30 11169
TEST_TIME 31 11004
TEST_TIME 32 10891
TEST_TIME 33 10737
TEST_TIME 34 10669
TEST_TIME 35 10711
TEST_TIME 36 10888
TEST_TIME 37 10802
TEST_TIME 38 10644
TEST_TIME 39 10541
TEST_TIME 40 10700
TEST_TIME 41 10583
TEST_TIME 42 10631
TEST_TIME 43 10625
TEST_TIME 44 10648
TEST_TIME 45 10581
TEST_TIME 46 10659
TEST_TIME 47 10631
TEST_TIME 48 10568
TEST_TIME 49 10663
TEST_TIME 50 10547
TEST_TIME 51 10595
TEST_TIME 52 10614
TEST_TIME 53 10630
TEST_TIME 54 10452
TEST_TIME 55 10360
TEST_TIME 56 10270
TEST_TIME 57 10336
TEST_TIME 58 10306
TEST_TIME 59 10257
TEST_TIME 60 10226
TEST_TIME 61 10095
TEST_TIME 62 10072
TEST_TIME 63 10063
TEST_TIME 64 10038
TEST_TIME 65 10133
TEST_TIME 66 10100
TEST_TIME 67 10129
TEST_TIME 68 10127
TEST_TIME 69 10182
TEST_TIME 70 10148
TEST_TIME 71 10175
TEST_TIME 72 10218
TEST_TIME 73 10279
TEST_TIME 74 10227
TEST_TIME 75 10280
TEST_TIME 76 10310
TEST_TIME 77 10253
TEST_TIME 78 10149
TEST_TIME 79 10183
TEST_TIME 80 10093
TEST_TIME 81 10148
TEST_TIME 82 10106
TEST_TIME 83 10068
TEST_TIME 84 10032
TEST_TIME 85 9986
TEST_TIME 86 9988
TEST_TIME 87 9963
TEST_TIME 88 10007
TEST_TIME 89 10007
TEST_TIME 90 9966
TEST_TIME 91 10011
TEST_TIME 92 10027
TEST_TIME 93 10021
TEST_TIME 94 10041
TEST_TIME 95 10047
TEST_TIME 96 10080
TEST_TIME 97 10151
TEST_TIME 98 10104
TEST_TIME 99 10060
TEST_TIME 100 9995
TEST_TIME 101 9965
TEST_TIME 102 9956
TEST_TIME 103 9898
TEST_TIME 104 9860
TEST_TIME 105 9897
TEST_TIME 106 9880
TEST_TIME 107 9886
TEST_TIME 108 9882
TEST_TIME 109 9878
TEST_TIME 110 9848
TEST_TIME 111 9946
TEST_TIME 112 9914
TEST_TIME 113 9947
TEST_TIME 114 9950
TEST_TIME 115 9919
TEST_TIME 116 9966
TEST_TIME 117 9956
TEST_TIME 118 9948
TEST_TIME 119 9872
TEST_TIME 120 9881
TEST_TIME 121 9799

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-10-2020 01:28:37 to 11-10-2020 01:28:40.

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 1658078 1658078 0 0.00 0 24658 6 0 0
doc_list_biud 1658078 1658078 0 0.00 0 23860 22 0 0
sp_add_invoice_to_stock 5599 5506 93 1.66 43 149777 16369 0 93
sp_cancel_adding_invoice 5878 5878 0 0.00 4 50986 631 0 0
sp_cancel_client_order 1971 1971 0 0.00 3 10330 268 0 0
sp_cancel_customer_reserve 583239 583239 0 0.00 1 23864 51 0 0
sp_cancel_pay_from_customer 758 758 0 0.00 2 8234 66 0 0
sp_cancel_pay_to_supplier 465 465 0 0.00 3 5246 59 0 0
sp_cancel_supplier_invoice 6568 6566 2 0.03 1 13838 668 0 2
sp_cancel_supplier_order 429 429 0 0.00 7 922040 86450 0 0
sp_cancel_write_off 9534 9534 0 0.00 1 9486 106 0 0
sp_client_order 22585 22585 0 0.00 3 19952 290 0 0
sp_customer_reserve 509443 509443 0 0.00 0 87801 163 0 0
sp_fill_shopping_cart 34809 34809 0 0.00 1 11462 36 0 0
sp_fill_shopping_cart_clo_res 509437 509437 0 0.00 0 13700 13 0 0
sp_get_clo_for_invoice 5500 5500 0 0.00 0 25084 296 0 0
sp_kill_cost_storno 16635 16635 0 0.00 0 6531 46 0 0
sp_kill_qstorno_ret_qs2qd 1183007 1183007 0 0.00 0 23856 27 0 0
sp_kill_qty_storno 624169 624169 0 0.00 0 23859 59 0 0
sp_lock_selected_doc 22894 22894 0 0.00 0 9985 6 0 0
sp_make_cost_storno 30906 30906 0 0.00 0 24658 43 0 0
sp_multiply_rows_for_pdistr 30906 30906 0 0.00 0 7430 23 0 0
sp_multiply_rows_for_qdistr 22585 22585 0 0.00 0 13082 121 0 0
sp_pay_from_customer 7943 7943 0 0.00 4 14818 101 0 0
sp_pay_to_supplier 6411 6411 0 0.00 4 24662 107 0 0
sp_payment_common 14354 14354 0 0.00 1 9598 28 0 0
sp_qd_handle_on_cancel_clo 1971 1971 0 0.00 0 7844 244 0 0
sp_qd_handle_on_invoice_upd_sts 11378 11378 0 0.00 0 21037 261 0 0
sp_qd_handle_on_reserve_upd_sts 11052 11052 0 0.00 0 18532 44 0 0
sp_reserve_write_off 11052 11052 0 0.00 6 18562 144 0 0
sp_supplier_invoice 6103 6103 0 0.00 6 81270 2843 0 0
sp_supplier_order 6141 6141 0 0.00 5 30901 1155 0 0
srv_aggregate_perf_data 124 123 1 0.80 2552 199322 18180 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1361 128033 48407 0 0
srv_find_qd_qs_mism 1133343 1133343 0 0.00 0 12278 5 0 0
srv_make_invnt_saldo 618 618 0 0.00 12 101475 1020 0 0
srv_make_money_saldo 832 832 0 0.00 1 8070 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 213991 326119 275675 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5506 5506 0 0.00 0 11114 142 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 1 2007 69 0 0
t$perf-norm:sp_cancel_client_order 1971 1971 0 0.00 0 4746 12 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 2593 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 758 758 0 0.00 0 2370 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 465 465 0 0.00 0 1729 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 455 455 0 0.00 0 860 12 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 18308 1227 0 0
t$perf-norm:sp_cancel_write_off 1105 1105 0 0.00 0 5629 18 0 0
t$perf-norm:sp_client_order 22585 22585 0 0.00 0 8095 12 0 0
t$perf-norm:sp_customer_reserve 1091 1091 0 0.00 0 2684 11 0 0
t$perf-norm:sp_pay_from_customer 7943 7943 0 0.00 0 3459 7 0 0
t$perf-norm:sp_pay_to_supplier 6411 6411 0 0.00 0 5507 9 0 0
t$perf-norm:sp_reserve_write_off 11052 11052 0 0.00 0 5862 10 0 0
t$perf-norm:sp_supplier_invoice 6103 6103 0 0.00 0 8535 17 0 0
t$perf-norm:sp_supplier_order 6141 6141 0 0.00 0 8264 13 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 206 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 618 618 0 0.00 0 2446 17 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 3884 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 45 13 0 0
v_add_invoice_to_stock 11186 11000 186 1.66 0 3055 15 0 186
v_all_customers 41176 41176 0 0.00 0 83 0 0 0
v_all_suppliers 33360 33360 0 0.00 0 19 1 0 0
v_all_wares 45170 45170 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 2997 9 0 0
v_cancel_client_order 3942 3942 0 0.00 0 1098 3 0 0
v_cancel_customer_prepayment 1516 1516 0 0.00 0 2396 5 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 2327 6 0 0
v_cancel_payment_to_supplier 930 930 0 0.00 0 1182 4 0 0
v_cancel_supplier_invoice 914 910 4 0.43 0 8340 28 0 4
v_cancel_supplier_order 858 858 0 0.00 0 1700 6 0 0
v_cancel_write_off 2210 2210 0 0.00 0 7337 19 0 0
v_max_id_clo_ord 6135 6135 0 0.00 0 393 1 0 0
v_max_id_clo_res 1091 1091 0 0.00 0 5236 16 0 0
v_max_id_ord_sup 6089 6089 0 0.00 0 5336 4 0 0
v_max_non_paid_invoice 1955 1955 0 0.00 0 290 2 0 0
v_max_non_paid_realizn 4242 4242 0 0.00 0 904 3 0 0
v_min_id_clo_ord 6135 6135 0 0.00 0 3917 4 0 0
v_min_id_clo_res 1091 1091 0 0.00 0 1081 3 0 0
v_min_id_ord_sup 6089 6089 0 0.00 0 5010 6 0 0
v_min_non_paid_invoice 6411 6411 0 0.00 0 4843 14 0 0
v_min_non_paid_realizn 7943 7943 0 0.00 0 6138 18 0 0
v_our_firm 11396 11396 0 0.00 0 17 1 0 0
v_reserve_write_off 22104 22104 0 0.00 0 9003 5 0 0
x_lock_depdocs_on_canc_invoice 11989 11989 0 0.00 0 21906 103 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 6708 289 0 0
x_make_qstorno_1000_1200 6141 6141 0 0.00 2 30892 1092 0 0
x_make_qstorno_1200_2000 6103 6103 0 0.00 3 81263 2703 0 0
x_make_qstorno_2100_3300 509443 509443 0 0.00 0 87798 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-10-2020 01:28:41 to 11-10-2020 01:28:45.

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:00 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:01 17936 8192000000 10363886976 10684719104 1408421680 14.670 100 85 170 1 1442545280 5868544 28255056
22:59:26 8176 8192000000 10434455872 10765119488 1487909568 15.371 100 87 172 1 1527469968 12056688 27503712
23:00:47 17547 8192000000 10473980144 10818199552 1529588160 15.733 100 80 160 0 1564130688 9367792 25174736
23:02:12 10383 8192000000 10569745920 10930896896 1623563120 16.540 100 72 144 1 1656324192 11474416 21286656
23:03:28 5960 8192000000 10656710512 11025776640 1713372896 17.297 100 80 160 1 1749292784 12967168 22952720
23:04:36 3699 8192000000 10706420832 11088642048 1773209008 17.793 100 75 150 2 1806383312 12305872 20868432
23:05:42 3078 8192000000 10777066656 11162300416 1824458688 18.214 100 86 173 1 1861841360 12987824 24394848
23:06:47 1710 8192000000 10813673360 11199770624 1865578176 18.548 100 92 183 0 1905758832 14310080 25870576
23:07:49 10193 8192000000 10795755056 11201327104 1865857936 18.551 100 42 84 0 1886951312 8612336 12481040
23:09:00 10973 8192000000 10782864464 11207794688 1868211008 18.570 100 20 41 0 1878665488 5147008 5307472
23:10:17 9695 8192000000 10799548464 11232395264 1889072176 18.738 100 23 46 4 1901503968 5610720 6821072
23:11:27 25592 8192000000 10810286576 11245309952 1899942864 18.826 100 21 43 0 1910751840 5133904 5675072
23:12:53 13101 8192000000 10828466432 11254284288 1901611504 18.839 100 21 42 1 1912856672 5471792 5773376
23:14:06 7039 8192000000 10833606752 11268014080 1914909616 18.946 100 27 54 3 1928983264 6274816 7798832
23:15:14 6915 8192000000 10803237744 11239231488 1878539456 18.653 99 25 50 0 1892257328 6359568 7358304
23:16:21 62315 8192000000 10735298864 11164991488 1816089104 18.146 97 30 60 2 1834851104 9664848 9097152
23:18:24 5952 8192000000 10734491824 11154890752 1805475808 18.059 98 27 54 3 1819851424 6324752 8050864
23:19:32 7110 8192000000 10669650528 11071287296 1688269648 17.087 95 31 62 2 1703499456 5253984 9975824
23:20:42 9344 8192000000 10718929104 11141922816 1783049584 17.875 98 16 32 0 1793175952 5929072 4197296
23:21:55 35555 8192000000 10736140208 11167801344 1815380640 18.140 99 17 34 4 1825303824 5514144 4409040
23:23:31 3733 8192000000 10788923936 11206844416 1859457424 18.499 100 40 78 2 1880504992 10045456 11002112
23:24:35 13442 8192000000 10775294736 11206492160 1865221920 18.546 100 24 46 2 1877464304 5470288 6772096
23:25:51 13313 8192000000 10779490224 11213500416 1867344928 18.563 100 14 27 0 1877141712 5538704 4258080
23:27:05 50894 8192000000 10834457408 11258433536 1900245392 18.828 100 38 76 0 1917344544 6869184 10229968
23:29:01 19473 8192000000 10860784560 11286364160 1933910288 19.098 100 41 79 1 1953505312 8941696 10653328
23:30:25 31126 8192000000 10880100240 11316203520 1948028432 19.211 100 39 78 0 1973491440 14761200 10701808
23:32:06 6882 8192000000 10922968592 11336163328 1968288816 19.372 100 57 113 1 2000676416 16916208 15471392
23:33:17 6992 8192000000 10917465376 11343155200 1957695536 19.288 99 53 106 0 1994727936 22167424 14864976
23:34:33 7232 8192000000 10951292128 11378302976 2002562560 19.643 100 53 102 2 2041226672 23228560 15435552
23:35:44 13255 8192000000 10939085872 11373182976 1964170416 19.339 98 50 100 1 2009411488 29445120 15795952
23:37:00 9040 8192000000 10959491344 11379519488 1985906336 19.511 100 60 121 1 2042436272 38444784 18085152
23:38:14 3329 8192000000 10959036848 11379879936 1989336528 19.539 100 74 149 1 2045935328 33913632 22685168
23:39:22 3650 8192000000 10957132720 11365531648 1970495296 19.389 100 82 164 0 2037881120 42359968 25025856
23:40:30 4423 8192000000 10952423008 11370573824 1958118448 19.291 99 73 143 1 2025633760 44960816 22554496
23:41:40 1361 8192000000 10965488320 11368103936 1952030704 19.243 99 86 172 1 2033039776 54683776 26325296
23:42:46 10429 8192000000 10951516320 11386728448 1966809488 19.360 100 49 97 0 2040286304 57180544 16296272
23:44:16 15267 8192000000 10931764016 11372830720 1949656784 19.224 100 43 86 3 2021401888 56906320 14838784
23:45:43 11255 8192000000 10915003072 11359793152 1920883840 18.994 98 39 78 1 1983091152 50083792 12123520
23:47:00 22016 8192000000 10914220288 11361882112 1930159968 19.068 99 23 46 1 1988052096 50194992 7697136
23:48:29 38924 8192000000 10904813696 11362299904 1931115392 19.076 100 18 36 0 1989931664 51977424 6838848
23:51:21 49979 8192000000 10893880624 11330940928 1927945696 19.050 99 26 52 1 1942441216 7636944 6858576
23:53:12 61107 8192000000 10890223072 11341479936 1934883024 19.106 99 20 40 0 1952839232 12307472 5648736
23:55:31 89231 8192000000 10855210592 11314311168 1852685472 18.444 95 19 38 0 1866444336 8370384 5388480
23:58:06 54102 8192000000 10891971152 11354157056 1936977824 19.123 98 19 38 3 1954228560 11472688 5778048
00:00:30 36960 8192000000 10930663792 11390869504 1995928432 19.591 100 44 88 1 2024848096 15347600 13572064
00:02:15 53667 8192000000 10915982176 11395801088 1993813392 19.574 100 17 34 0 2008432720 9198224 5421104
00:04:09 59014 8192000000 10932720688 11408297984 1996980752 19.599 100 10 20 0 2012728288 12903616 2843920
00:06:10 75564 8192000000 10926854480 11408445440 1983667440 19.494 99 5 10 0 2000270528 15239856 1363232
00:08:32 53005 8192000000 10921687472 11394154496 1998608336 19.612 100 24 48 2 2013856720 8641984 6606400
00:10:25 57003 8192000000 10906051344 11365683200 1928834224 19.058 97 19 38 1 1945139776 10478272 5827280
00:13:03 44858 8192000000 10925676656 11384549376 1969037648 19.378 99 31 62 1 1991155184 12107888 10009648
00:14:50 68200 8192000000 10915368336 11399479296 1991331488 19.554 100 15 30 0 2006744624 11258976 4154160
00:17:03 41121 8192000000 10926873952 11396259840 1952431936 19.246 98 24 48 0 1969950672 10843232 6675504
00:18:50 124852 8192000000 10922688480 11371012096 1914237904 18.941 96 19 38 1 1936257584 16463520 5556160
00:21:56 48839 8192000000 10911023936 11376500736 1971158176 19.395 99 25 50 1 1987414608 9407440 6848992
00:23:49 61780 8192000000 10848296144 11333951488 1881647584 18.678 96 16 32 0 1897342624 10769312 4925728
00:25:51 70227 8192000000 10864957632 11329490944 1834857936 18.299 94 13 26 2 1846013168 7718144 3437088
00:28:10 81966 8192000000 10847214144 11312033792 1854213248 18.456 96 7 14 0 1870397648 14407184 1777216
00:30:33 54993 8192000000 10862053088 11319144448 1861112624 18.512 96 16 32 0 1880828224 15016144 4699456
00:32:28 39315 8192000000 10834742768 11301232640 1866620624 18.557 97 21 42 1 1880268576 7670640 5977312
00:34:10 36212 8192000000 10856673824 11295850496 1846356512 18.393 95 38 76 0 1867088528 9800240 10931776
00:35:51 17273 8192000000 10868161008 11320672256 1929536304 19.063 100 38 76 1 1949767584 9482928 10748352
00:37:09 9119 8192000000 10906724784 11346878464 1953186416 19.252 100 60 118 0 1981768256 12567296 16014544
00:38:19 57410 8192000000 10863214400 11330633728 1874329920 18.619 96 12 24 0 1890789648 13205232 3254496
00:40:18 62281 8192000000 10891994304 11335352320 1855576464 18.467 95 36 72 0 1878831776 13343184 9912128
00:42:21 53991 8192000000 10887518656 11338194944 1908803536 18.897 98 12 24 2 1924419344 12130464 3485344
00:44:16 124024 8192000000 10870872624 11344740352 1935519520 19.111 99 19 38 0 1951701840 10176000 6006320
00:47:22 35402 8192000000 10874488784 11365658624 1958545424 19.294 100 17 34 1 1974204496 10442288 5216784
00:50:11 51917 8192000000 10874464384 11347521536 1941275376 19.157 99 20 41 0 1954397840 7212928 5909536
00:52:26 77328 8192000000 10897891696 11369893888 1944352944 19.181 99 22 44 0 1960749280 10256016 6140320
00:54:43 40793 8192000000 10877270768 11351662592 1939841440 19.145 99 9 18 1 1955118592 12672464 2604688
00:56:26 51880 8192000000 10888501216 11356581888 1933556960 19.095 98 16 32 1 1949923776 11608832 4757984
00:58:18 51843 8192000000 10914206512 11362807808 1935614208 19.112 98 29 57 0 1951244464 8114432 7515824
01:00:14 119634 8192000000 10905060864 11375030272 1892885280 18.769 96 18 36 0 1911500352 13909856 4705216
01:03:18 83996 8192000000 10884870864 11357392896 1919002448 18.979 98 7 14 0 1937692368 16818160 1871760
01:05:42 114935 8192000000 10851042032 11315085312 1899973616 18.826 97 22 44 1 1915105824 8134320 6997888
01:08:38 41499 8192000000 10852903120 11321978880 1911883376 18.922 99 12 24 1 1930469984 15092192 3494416
01:10:21 81752 8192000000 10831684912 11316932608 1821790256 18.192 95 12 25 1 1842110448 16985888 3334304
01:12:46 65798 8192000000 10834483440 11317059584 1875463056 18.628 97 14 28 1 1891793280 12060864 4269360
01:14:53 41943 8192000000 10855945456 11319980032 1882795648 18.688 97 28 57 4 1898516592 7288432 8432512
01:16:38 78365 8192000000 10839558208 11317460992 1868931200 18.576 97 15 30 1 1888298496 14450912 4916384
01:19:02 25494 8192000000 10875144912 11328688128 1920724752 18.993 99 42 84 0 1943717248 10810288 12182208
01:20:33 40866 8192000000 10896035088 11337953280 1922016288 19.003 99 25 50 1 1937657984 8723488 6918208
01:22:15 128032 8192000000 10886105872 11349860352 1901210400 18.836 97 20 40 1 1918912704 12474944 5227360
01:26:36 28070 8192000000 9288470352 9566953472 446658656 5.170 24 3 6 0 451254400 4179312 416432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-10-2020 01:28:47 to 11-10-2020 01:29:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 93 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 11-10-2020 01:29:02 to 11-10-2020 01:29:04.

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          259706
MON$OLDEST_ACTIVE               259707
MON$OLDEST_SNAPSHOT             259707
MON$NEXT_TRANSACTION            259707
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                       2035872
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 ---
--- end of diff output ---

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

11.10.20 01:29:05. 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-11 01:29:08 No severe PSQL-related problems occured
11.10.20 01:29:09. End of report.