Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2020 19:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17380104 kB
MemAvailable 31565240 kB
Buffers 0 kB
Cached 14280472 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-15 19:26:58
DTS_END                         2020-10-15 19:26:58.2420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/36108
TRN_ID                          250209
ATT_ID                          676
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: 9699.93 22949 1163992
customer order: creation 179.05 357 21486
customer order: refuse 15.26 294 1831
order to supplier: creation 47.22 1248 5666
order to supplier: removal 3.73 83908 448
invoice (draft): creation 46.83 2859 5620
invoice (draft): removal 55.43 666 6651
invoice accept: apply 43.61 17734 5233
invoice accept: cancel 49.93 711 5992
customer reserve: creation 4055.54 177 486665
customer reserve: removal 4898.07 51 587768
realization accept: apply 86.13 147 10336
realization accept: cancel 85.38 106 10246
payment from customer: creation 61.84 122 7421
payment from customer: removal 5.79 57 695
payment to supplier: creation 50.15 107 6018
payment to supplier: removal 3.45 111 414
service: total inventory turnovers 4.63 1189 555
service: total monetary turnovers 6.87 34 824
service: aggregate perf. data 0.99 17377 119
service: refresh index statistics 0.03 331715 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2020 19:28:03 to 15-10-2020 19:28:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2352
TEST_TIME 2 2998
TEST_TIME 3 4128
TEST_TIME 4 5471
TEST_TIME 5 6163
TEST_TIME 6 6032
TEST_TIME 7 6177
TEST_TIME 8 6492
TEST_TIME 9 6854
TEST_TIME 10 6878
TEST_TIME 11 7500
TEST_TIME 12 7471
TEST_TIME 13 7417
TEST_TIME 14 7528
TEST_TIME 15 7804
TEST_TIME 16 8454
TEST_TIME 17 8265
TEST_TIME 18 8595
TEST_TIME 19 8644
TEST_TIME 20 9496
TEST_TIME 21 9658
TEST_TIME 22 10199
TEST_TIME 23 10608
TEST_TIME 24 12001
TEST_TIME 25 11954
TEST_TIME 26 11699
TEST_TIME 27 11714
TEST_TIME 28 11653
TEST_TIME 29 11557
TEST_TIME 30 11468
TEST_TIME 31 11655
TEST_TIME 32 11366
TEST_TIME 33 11135
TEST_TIME 34 10993
TEST_TIME 35 10886
TEST_TIME 36 10918
TEST_TIME 37 10755
TEST_TIME 38 10950
TEST_TIME 39 10808
TEST_TIME 40 10795
TEST_TIME 41 10640
TEST_TIME 42 10633
TEST_TIME 43 10452
TEST_TIME 44 10332
TEST_TIME 45 10393
TEST_TIME 46 10261
TEST_TIME 47 10236
TEST_TIME 48 10252
TEST_TIME 49 10494
TEST_TIME 50 10384
TEST_TIME 51 10411
TEST_TIME 52 10340
TEST_TIME 53 10452
TEST_TIME 54 10473
TEST_TIME 55 10407
TEST_TIME 56 10476
TEST_TIME 57 10368
TEST_TIME 58 10390
TEST_TIME 59 10434
TEST_TIME 60 10332
TEST_TIME 61 10281
TEST_TIME 62 10179
TEST_TIME 63 10192
TEST_TIME 64 10139
TEST_TIME 65 10127
TEST_TIME 66 10039
TEST_TIME 67 10030
TEST_TIME 68 10091
TEST_TIME 69 10035
TEST_TIME 70 9990
TEST_TIME 71 10092
TEST_TIME 72 10077
TEST_TIME 73 10088
TEST_TIME 74 10052
TEST_TIME 75 10141
TEST_TIME 76 10220
TEST_TIME 77 10180
TEST_TIME 78 10119
TEST_TIME 79 10288
TEST_TIME 80 10195
TEST_TIME 81 10113
TEST_TIME 82 10132
TEST_TIME 83 10059
TEST_TIME 84 10059
TEST_TIME 85 9997
TEST_TIME 86 9949
TEST_TIME 87 9999
TEST_TIME 88 9918
TEST_TIME 89 9890
TEST_TIME 90 9811
TEST_TIME 91 9785
TEST_TIME 92 9770
TEST_TIME 93 9750
TEST_TIME 94 9740
TEST_TIME 95 9813
TEST_TIME 96 9833
TEST_TIME 97 9835
TEST_TIME 98 9812
TEST_TIME 99 9841
TEST_TIME 100 9907
TEST_TIME 101 9945
TEST_TIME 102 9977
TEST_TIME 103 9958
TEST_TIME 104 9970
TEST_TIME 105 10011
TEST_TIME 106 9930
TEST_TIME 107 9888
TEST_TIME 108 9866
TEST_TIME 109 9871
TEST_TIME 110 9865
TEST_TIME 111 9818
TEST_TIME 112 9793
TEST_TIME 113 9760
TEST_TIME 114 9690
TEST_TIME 115 9689
TEST_TIME 116 9716
TEST_TIME 117 9720
TEST_TIME 118 9699
TEST_TIME 119 9697
TEST_TIME 120 9706

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-10-2020 19:28:06 to 15-10-2020 19:28:10.

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 1616625 1616625 0 0.00 0 17464 6 0 0
doc_list_biud 1616620 1616620 0 0.00 0 29097 23 0 0
sp_add_invoice_to_stock 5330 5233 97 1.81 41 152188 17734 0 97
sp_cancel_adding_invoice 5992 5992 0 0.00 5 71077 712 0 0
sp_cancel_client_order 1831 1831 0 0.00 4 17977 294 0 0
sp_cancel_customer_reserve 587768 587768 0 0.00 1 29099 51 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 3372 57 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 2 10573 112 0 0
sp_cancel_supplier_invoice 6658 6651 7 0.10 2 19089 667 0 7
sp_cancel_supplier_order 448 448 0 0.00 4 901175 83909 0 0
sp_cancel_write_off 10246 10246 0 0.00 1 21624 106 0 0
sp_client_order 21486 21486 0 0.00 4 20335 358 0 0
sp_customer_reserve 486665 486665 0 0.00 0 98511 177 0 0
sp_fill_shopping_cart 32763 32763 0 0.00 1 11524 33 0 0
sp_fill_shopping_cart_clo_res 486659 486659 0 0.00 0 12529 12 0 0
sp_get_clo_for_invoice 5224 5224 0 0.00 0 31890 332 0 0
sp_kill_cost_storno 17347 17347 0 0.00 0 8853 46 0 0
sp_kill_qstorno_ret_qs2qd 1192881 1192881 0 0.00 0 29094 28 0 0
sp_kill_qty_storno 628490 628490 0 0.00 0 29097 60 0 0
sp_lock_selected_doc 21564 21564 0 0.00 0 6742 4 0 0
sp_make_cost_storno 28997 28997 0 0.00 0 11095 46 0 0
sp_multiply_rows_for_pdistr 28996 28996 0 0.00 0 8855 26 0 0
sp_multiply_rows_for_qdistr 21486 21486 0 0.00 0 12898 173 0 0
sp_pay_from_customer 7421 7421 0 0.00 4 21000 122 0 0
sp_pay_to_supplier 6018 6018 0 0.00 5 12213 108 0 0
sp_payment_common 13439 13439 0 0.00 1 20993 30 0 0
sp_qd_handle_on_cancel_clo 1831 1831 0 0.00 0 9054 260 0 0
sp_qd_handle_on_invoice_upd_sts 11211 11211 0 0.00 0 14836 269 0 0
sp_qd_handle_on_reserve_upd_sts 10335 10335 0 0.00 0 10691 45 0 0
sp_reserve_write_off 10336 10336 0 0.00 6 11430 147 0 0
sp_supplier_invoice 5620 5620 0 0.00 7 81350 2859 0 0
sp_supplier_order 5666 5666 0 0.00 5 36118 1249 0 0
srv_aggregate_perf_data 119 119 0 0.00 2823 168667 17378 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1592 162445 46794 0 0
srv_find_qd_qs_mism 1115287 1115287 0 0.00 0 17461 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 42 66466 1190 0 0
srv_make_money_saldo 824 824 0 0.00 0 12407 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 214429 404834 331715 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5234 5234 0 0.00 0 28245 184 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 4767 85 0 0
t$perf-norm:sp_cancel_client_order 1831 1831 0 0.00 0 5224 21 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 1582 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 7500 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 2371 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 462 462 0 0.00 0 12112 48 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 24287 1386 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 3922 13 0 0
t$perf-norm:sp_client_order 21487 21487 0 0.00 0 14617 21 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 6207 15 0 0
t$perf-norm:sp_pay_from_customer 7421 7421 0 0.00 0 15289 18 0 0
t$perf-norm:sp_pay_to_supplier 6018 6018 0 0.00 0 14254 14 0 0
t$perf-norm:sp_reserve_write_off 10337 10337 0 0.00 0 11789 11 0 0
t$perf-norm:sp_supplier_invoice 5620 5620 0 0.00 0 13812 28 0 0
t$perf-norm:sp_supplier_order 5667 5667 0 0.00 0 6684 17 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 321 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2412 41 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 4462 30 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 1111 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10632 10438 194 1.82 0 12834 18 0 194
v_all_customers 39248 39248 0 0.00 0 296 1 0 0
v_all_suppliers 31146 31146 0 0.00 0 36 1 0 0
v_all_wares 42968 42968 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 161 5 0 0
v_cancel_client_order 3662 3662 0 0.00 0 17840 11 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 3294 7 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 2537 6 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 10495 24 0 0
v_cancel_supplier_invoice 938 924 14 1.49 0 1387 15 0 14
v_cancel_supplier_order 896 896 0 0.00 0 12835 24 0 0
v_cancel_write_off 2022 2022 0 0.00 0 4302 17 0 0
v_max_id_clo_ord 5660 5660 0 0.00 0 132 1 0 0
v_max_id_clo_res 978 978 0 0.00 0 5623 21 0 0
v_max_id_ord_sup 5619 5619 0 0.00 0 5609 8 0 0
v_max_non_paid_invoice 1724 1724 0 0.00 0 5157 5 0 0
v_max_non_paid_realizn 3874 3874 0 0.00 0 1362 3 0 0
v_min_id_clo_ord 5660 5660 0 0.00 0 2165 4 0 0
v_min_id_clo_res 978 978 0 0.00 0 4899 13 0 0
v_min_id_ord_sup 5619 5619 0 0.00 0 5215 8 0 0
v_min_non_paid_invoice 6018 6018 0 0.00 0 11792 15 0 0
v_min_non_paid_realizn 7421 7421 0 0.00 0 14382 19 0 0
v_our_firm 10814 10814 0 0.00 0 14 1 0 0
v_reserve_write_off 20668 20668 0 0.00 0 11200 7 0 0
x_lock_depdocs_on_canc_invoice 12181 12181 0 0.00 0 10169 93 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 2756 244 0 0
x_make_qstorno_1000_1200 5666 5666 0 0.00 2 36107 1182 0 0
x_make_qstorno_1200_2000 5620 5620 0 0.00 2 81334 2740 0 0
x_make_qstorno_2100_3300 486665 486665 0 0.00 0 98510 163 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
16:57:01 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:03 23967 8192000000 10374794784 10696851456 1425727200 14.823 100 89 178 2 1461423568 6332752 29363616
16:59:31 19888 8192000000 10451001584 10785734656 1507141984 15.538 100 85 170 0 1543173520 8663840 27367696
17:00:54 12558 8192000000 10498344480 10843140096 1554762128 15.951 100 86 173 1 1591884896 10481504 26641264
17:02:10 11711 8192000000 10573541264 10930696192 1627816016 16.576 100 79 158 0 1663402688 11348912 24237760
17:03:25 7280 8192000000 10635024832 11007733760 1698676464 17.174 100 81 161 2 1735313040 13526912 23109664
17:04:33 6249 8192000000 10707146736 11086225408 1763315760 17.712 100 77 153 1 1797298752 12438672 21544320
17:05:41 10715 8192000000 10760127488 11153559552 1824439216 18.214 100 77 153 0 1860209408 13949856 21820336
17:06:53 1478 8192000000 10823015856 11212455936 1878161808 18.650 100 92 182 2 1919773904 14731616 26880480
17:07:55 5338 8192000000 10794921952 11216359424 1876365552 18.636 100 33 66 1 1894209792 8170896 9673344
17:09:03 17805 8192000000 10791899488 11224334336 1890939568 18.753 100 32 63 2 1907221168 7403776 8877824
17:10:23 14710 8192000000 10807764960 11240783872 1895506096 18.790 100 19 38 1 1915604048 15079072 5018880
17:11:41 51488 8192000000 10824798944 11254681600 1901204640 18.836 100 24 47 0 1917383360 9542784 6635936
17:13:34 9821 8192000000 10834059056 11265114112 1911705712 18.920 100 24 46 2 1924539824 5715216 7118896
17:14:46 7074 8192000000 10804441040 11240783872 1866569184 18.557 98 25 49 1 1879200464 5857504 6773776
17:15:55 54964 8192000000 10746120720 11164807168 1665719456 16.897 89 29 57 2 1679208224 5891440 7597328
17:17:51 5219 8192000000 10713623648 11128557568 1761157104 17.694 97 31 60 1 1775492320 5538640 8796576
17:18:58 12208 8192000000 10700564608 11108827136 1734639792 17.474 97 28 56 0 1748866768 5209936 9017040
17:20:13 15837 8192000000 10706636544 11123875840 1761569056 17.697 97 19 38 0 1772120528 5543552 5007920
17:21:29 17340 8192000000 10736235568 11163013120 1809586032 18.092 99 13 26 2 1818789184 5570880 3632272
17:22:46 7511 8192000000 10764140544 11192922112 1850796640 18.429 100 19 38 0 1862198736 5519440 5882656
17:23:54 11380 8192000000 10773611872 11210932224 1866181232 18.553 100 9 18 1 1874240272 5296512 2762528
17:25:09 33429 8192000000 10783215536 11220520960 1875549648 18.629 100 16 32 1 1885956032 4701184 5705200
17:27:21 29804 8192000000 10842738064 11261837312 1909993328 18.907 100 47 93 2 1931494016 7992672 13508016
17:28:54 12581 8192000000 10854533632 11283333120 1932400912 19.086 100 40 78 1 1952223472 8502288 11320272
17:30:10 14854 8192000000 10879690288 11313299456 1945979136 19.194 100 39 76 1 1966146912 9136272 11031504
17:31:29 4682 8192000000 10894418784 11321810944 1954738080 19.264 100 45 90 2 1979515536 11713856 13063600
17:32:41 8010 8192000000 10915685424 11344945152 1971218304 19.395 100 57 113 1 2004260384 16641136 16400944
17:33:55 6032 8192000000 10918616896 11345575936 1966668560 19.359 100 67 132 1 2006464432 20924368 18871504
17:35:05 5907 8192000000 10920222000 11344437248 1968348848 19.372 100 62 121 1 2010038384 23205696 18483840
17:36:18 3573 8192000000 10892363408 11316334592 1913810336 18.937 98 53 106 2 1957195168 27319488 16065344
17:37:25 5839 8192000000 10911395488 11333586944 1945803088 19.193 100 62 124 1 1995193760 29987696 19402976
17:38:43 3310 8192000000 10931277792 11349839872 1951963920 19.242 99 73 145 0 2006321952 31974944 22383088
17:39:56 10034 8192000000 10930481776 11350982656 1919063872 18.979 97 59 118 4 1977863696 39625648 19174176
17:41:14 5348 8192000000 10970213840 11377807360 1974112576 19.418 100 79 156 2 2046636176 48234032 24289568
17:42:25 1590 8192000000 10963229360 11376328704 1967670336 19.367 100 80 161 0 2045954656 52800640 25483680
17:43:30 5141 8192000000 10963894352 11378925568 1974907168 19.424 100 72 143 0 2044707744 46812864 22987712
17:44:38 15614 8192000000 10965010176 11401453568 1984361584 19.499 100 36 72 0 2045551456 49283856 11906016
17:45:57 15404 8192000000 10933506544 11369091072 1932547744 19.087 99 36 72 0 2006377920 60891360 12938816
17:47:17 21192 8192000000 10932325376 11383140352 1920418000 18.990 98 32 64 3 1988930208 58109600 10402608
17:48:44 22566 8192000000 10918410368 11376848896 1937238128 19.125 100 20 40 1 1996317168 51900880 7178160
17:50:11 43617 8192000000 10875699696 11351076864 1938707056 19.136 100 15 30 0 1979256608 35057648 5491904
17:52:05 47225 8192000000 10857326416 11296927744 1860700320 18.509 96 32 65 0 1880919280 9939024 10279936
17:53:55 90148 8192000000 10862848688 11324145664 1930567680 19.071 100 19 36 2 1953675216 16876976 6230560
17:56:44 162445 8192000000 10900981632 11350441984 1921534800 18.999 98 9 18 1 1936731664 13136688 2060176
18:00:34 133702 8192000000 10920189904 11381084160 1942899568 19.170 98 36 70 2 1963867472 11791312 9176592
18:03:49 29533 8192000000 10924497424 11385442304 1987305744 19.522 100 49 96 4 2015943024 14229296 14407984
18:05:29 16106 8192000000 10913712800 11368734720 1979967760 19.464 100 31 62 2 1994073184 5397152 8708272
18:06:48 63068 8192000000 10896617296 11373445120 1966467664 19.357 99 12 24 1 1979693536 10012800 3213072
18:09:00 88962 8192000000 10926705728 11380609024 1972371904 19.404 99 22 44 0 1986804560 8599936 5832720
18:11:31 25688 8192000000 10897757040 11384418304 1952370976 19.245 98 11 22 1 1963457424 8318608 2767840
18:12:57 83215 8192000000 10921339568 11402526720 1958229984 19.292 98 12 24 2 1977429456 15711168 3488304
18:15:20 61481 8192000000 10909342352 11363078144 1917856672 18.970 97 18 36 0 1933245856 10184336 5204848
18:17:22 38711 8192000000 10899944256 11359629312 1935735488 19.113 98 36 72 1 1961073200 15161200 10176512
18:19:03 70383 8192000000 10852956208 11330838528 1810414192 18.099 92 22 44 0 1832215392 14800112 7001088
18:21:24 19216 8192000000 10878011888 11325808640 1918126992 18.972 99 34 68 1 1940506688 13008000 9371696
18:22:48 36471 8192000000 10866270928 11313266688 1881021664 18.673 97 13 26 0 1892082720 7230368 3830688
18:24:28 31777 8192000000 10892060112 11351404544 1931034912 19.075 99 41 82 0 1961319392 18130400 12154080
18:26:11 112563 8192000000 10872016384 11348254720 1935861008 19.114 99 39 78 4 1957742416 10977104 10904304
18:29:16 36022 8192000000 10877012400 11358695424 1936762688 19.121 98 23 46 1 1952855456 9737088 6355680
18:31:08 60329 8192000000 10873811088 11348893696 1923130000 19.012 98 10 20 3 1933508128 7796288 2581840
18:34:04 80615 8192000000 10856601520 11324280832 1830098576 18.260 94 8 16 0 1842533632 10105344 2329712
18:36:30 63035 8192000000 10876950288 11336740864 1859494832 18.499 95 23 46 1 1883671040 17272608 6903600
18:38:37 56893 8192000000 10885941296 11344097280 1907809104 18.889 98 30 60 1 1926924192 9953904 9161184
18:40:51 75888 8192000000 10896960288 11369742336 1949228896 19.220 100 27 54 1 1984151664 27480816 7441952
18:43:11 46897 8192000000 10897085744 11347968000 1934516608 19.103 99 20 40 0 1950251616 10004368 5730640
18:45:03 42278 8192000000 10913415504 11398180864 1971203408 19.395 100 22 42 2 1999319264 22328432 5787424
18:46:46 69641 8192000000 10914798640 11364536320 1960780592 19.312 99 17 33 0 1975426288 10717632 3928064
18:49:02 51794 8192000000 10899369664 11367026688 1972957344 19.409 100 22 45 2 1988861952 9934096 5970512
18:51:02 51772 8192000000 10904716640 11390480384 1987123696 19.521 100 7 14 0 2000481376 11377600 1980080
18:52:54 58736 8192000000 10910480640 11398017024 1971086880 19.394 99 13 26 1 1994551872 19223472 4241520
18:54:59 53721 8192000000 10913915424 11393937408 1994491264 19.579 100 22 44 0 2007216144 6062064 6662816
18:57:01 59973 8192000000 10880384000 11360493568 1915803472 18.953 96 11 22 0 1924691840 6051152 2837216
18:59:05 44565 8192000000 10915613488 11386208256 1940963504 19.154 97 22 44 3 1956920448 9866864 6090080
19:00:51 42625 8192000000 10892518016 11365146624 1914088336 18.939 96 19 36 1 1931555632 12420304 5046992
19:03:05 55090 8192000000 10895973840 11357048832 1925716880 19.033 98 17 34 0 1940334448 9535520 5082048
19:05:02 45651 8192000000 10903682720 11379277824 1960139568 19.307 100 21 41 4 1982642000 16246816 6255616
19:06:58 81808 8192000000 10907458992 11395391488 1939300048 19.141 98 10 20 1 1964017952 21678176 3039728
19:10:14 29722 8192000000 10903522128 11357310976 1945751552 19.193 99 31 62 1 1965652384 11491056 8409776
19:11:54 107323 8192000000 10883538592 11351179264 1957410304 19.285 100 27 54 1 1976720624 11690272 7620048
19:14:43 29357 8192000000 10862745664 11321450496 1906291344 18.877 97 35 70 1 1925014480 8631312 10091824
19:16:18 92822 8192000000 10871022896 11363778560 1955790608 19.273 100 10 19 0 1970081984 11589104 2702272
19:18:51 64227 8192000000 10861149824 11334565888 1899404048 18.821 97 13 26 3 1912717824 10028000 3285776
19:20:56 61412 8192000000 10882280992 11362263040 1954132400 19.259 100 25 50 1 1979220448 17233056 7854992
19:22:58 89076 8192000000 10876093152 11352600576 1896650608 18.799 97 10 20 2 1907835456 8438736 2746112
19:25:27 50889 8192000000 10886990192 11348549632 1934377968 19.102 99 44 88 0 1962150016 14710032 13062016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 97 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

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

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          250208
MON$OLDEST_ACTIVE               252152
MON$OLDEST_SNAPSHOT             252152
MON$NEXT_TRANSACTION            252152
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                       2042336
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 ---
:187: 
:188: mscs-test-161.budzdorov.ru    Thu Oct 15 19:27:00 2020
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:190: 
:191: 
:192: mscs-test-161.budzdorov.ru    Thu Oct 15 19:27:06 2020
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:194: 
:195: 
:196: mscs-test-161.budzdorov.ru    Thu Oct 15 19:27:08 2020
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:198: 
--- end of diff output ---

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

15.10.20 19:28:35. 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-15 19:28:37 No severe PSQL-related problems occured
15.10.20 19:28:39. End of report.