Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.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 17531792 kB
MemAvailable 31595616 kB
Buffers 0 kB
Cached 14203144 kB
SwapCached 3048 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-25 01:26:59
DTS_END                         2020-10-25 01:26:59.3110
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/43988
TRN_ID                          250137
ATT_ID                          673
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: 9591.35 19995 1150962
customer order: creation 177.43 341 21292
customer order: refuse 14.53 310 1744
order to supplier: creation 45.80 1126 5496
order to supplier: removal 3.58 85862 430
invoice (draft): creation 48.07 2968 5768
invoice (draft): removal 56.29 555 6755
invoice accept: apply 42.54 16893 5105
invoice accept: cancel 50.46 699 6055
customer reserve: creation 3902.52 169 468302
customer reserve: removal 4941.83 50 593019
realization accept: apply 85.69 165 10283
realization accept: cancel 89.97 105 10796
payment from customer: creation 60.12 102 7214
payment from customer: removal 5.56 76 667
payment to supplier: creation 50.69 124 6083
payment to supplier: removal 3.49 62 419
service: total inventory turnovers 5.08 912 610
service: total monetary turnovers 6.79 18 815
service: aggregate perf. data 0.88 21508 105
service: refresh index statistics 0.03 267847 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-10-2020 01:28:47 to 25-10-2020 01:28:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5285
TEST_TIME 2 4582
TEST_TIME 3 4058
TEST_TIME 4 5317
TEST_TIME 5 5263
TEST_TIME 6 5431
TEST_TIME 7 5867
TEST_TIME 8 5761
TEST_TIME 9 5986
TEST_TIME 10 6162
TEST_TIME 11 6042
TEST_TIME 12 6121
TEST_TIME 13 6455
TEST_TIME 14 6949
TEST_TIME 15 6818
TEST_TIME 16 7303
TEST_TIME 17 8102
TEST_TIME 18 8351
TEST_TIME 19 8685
TEST_TIME 20 8699
TEST_TIME 21 9540
TEST_TIME 22 10404
TEST_TIME 23 11179
TEST_TIME 24 11152
TEST_TIME 25 10987
TEST_TIME 26 10642
TEST_TIME 27 10730
TEST_TIME 28 10551
TEST_TIME 29 10478
TEST_TIME 30 10399
TEST_TIME 31 10510
TEST_TIME 32 10490
TEST_TIME 33 10351
TEST_TIME 34 10340
TEST_TIME 35 10319
TEST_TIME 36 10205
TEST_TIME 37 10182
TEST_TIME 38 10292
TEST_TIME 39 10311
TEST_TIME 40 10306
TEST_TIME 41 10333
TEST_TIME 42 10451
TEST_TIME 43 10515
TEST_TIME 44 10426
TEST_TIME 45 10525
TEST_TIME 46 10414
TEST_TIME 47 10561
TEST_TIME 48 10420
TEST_TIME 49 10337
TEST_TIME 50 10192
TEST_TIME 51 10118
TEST_TIME 52 9993
TEST_TIME 53 10012
TEST_TIME 54 10022
TEST_TIME 55 10004
TEST_TIME 56 10088
TEST_TIME 57 10011
TEST_TIME 58 10096
TEST_TIME 59 10009
TEST_TIME 60 10004
TEST_TIME 61 10103
TEST_TIME 62 10036
TEST_TIME 63 10079
TEST_TIME 64 10197
TEST_TIME 65 10272
TEST_TIME 66 10295
TEST_TIME 67 10296
TEST_TIME 68 10320
TEST_TIME 69 10275
TEST_TIME 70 10151
TEST_TIME 71 10125
TEST_TIME 72 10101
TEST_TIME 73 10078
TEST_TIME 74 10018
TEST_TIME 75 9951
TEST_TIME 76 9924
TEST_TIME 77 9825
TEST_TIME 78 9797
TEST_TIME 79 9859
TEST_TIME 80 9936
TEST_TIME 81 9901
TEST_TIME 82 9903
TEST_TIME 83 9921
TEST_TIME 84 9943
TEST_TIME 85 9995
TEST_TIME 86 9981
TEST_TIME 87 10009
TEST_TIME 88 10029
TEST_TIME 89 10011
TEST_TIME 90 10054
TEST_TIME 91 10007
TEST_TIME 92 9934
TEST_TIME 93 9896
TEST_TIME 94 9854
TEST_TIME 95 9801
TEST_TIME 96 9753
TEST_TIME 97 9763
TEST_TIME 98 9740
TEST_TIME 99 9782
TEST_TIME 100 9742
TEST_TIME 101 9732
TEST_TIME 102 9701
TEST_TIME 103 9739
TEST_TIME 104 9752
TEST_TIME 105 9774
TEST_TIME 106 9751
TEST_TIME 107 9813
TEST_TIME 108 9790
TEST_TIME 109 9852
TEST_TIME 110 9808
TEST_TIME 111 9841
TEST_TIME 112 9755
TEST_TIME 113 9709
TEST_TIME 114 9691
TEST_TIME 115 9664
TEST_TIME 116 9616
TEST_TIME 117 9646
TEST_TIME 118 9643
TEST_TIME 119 9598
TEST_TIME 120 9596

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-10-2020 01:28:51 to 25-10-2020 01:28:54.

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 1585364 1585364 0 0.00 0 15269 6 0 0
doc_list_biud 1585364 1585364 0 0.00 0 39936 23 0 0
sp_add_invoice_to_stock 5241 5105 136 2.59 43 162749 16893 0 136
sp_cancel_adding_invoice 6055 6055 0 0.00 5 83768 700 0 0
sp_cancel_client_order 1744 1744 0 0.00 4 10039 311 0 0
sp_cancel_customer_reserve 593019 593019 0 0.00 1 30963 50 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 6814 76 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 2 6337 63 0 0
sp_cancel_supplier_invoice 6765 6755 10 0.14 1 9465 556 0 10
sp_cancel_supplier_order 430 430 0 0.00 10 857532 85863 0 0
sp_cancel_write_off 10796 10796 0 0.00 1 17763 105 0 0
sp_client_order 21292 21292 0 0.00 3 30746 342 0 0
sp_customer_reserve 468302 468302 0 0.00 0 50775 170 0 0
sp_fill_shopping_cart 32553 32553 0 0.00 1 33991 36 0 0
sp_fill_shopping_cart_clo_res 468294 468294 0 0.00 0 24627 13 0 0
sp_get_clo_for_invoice 5097 5097 0 0.00 0 16322 270 0 0
sp_kill_cost_storno 17937 17937 0 0.00 0 5289 43 0 0
sp_kill_qstorno_ret_qs2qd 1204019 1204019 0 0.00 0 30957 27 0 0
sp_kill_qty_storno 634179 634179 0 0.00 0 39929 57 0 0
sp_lock_selected_doc 21175 21175 0 0.00 0 8339 7 0 0
sp_make_cost_storno 28677 28677 0 0.00 0 15004 44 0 0
sp_multiply_rows_for_pdistr 28677 28677 0 0.00 0 15259 26 0 0
sp_multiply_rows_for_qdistr 21292 21292 0 0.00 0 14238 162 0 0
sp_pay_from_customer 7214 7214 0 0.00 4 13424 103 0 0
sp_pay_to_supplier 6083 6083 0 0.00 5 15281 124 0 0
sp_payment_common 13297 13297 0 0.00 1 11607 32 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 0 10029 267 0 0
sp_qd_handle_on_invoice_upd_sts 11152 11152 0 0.00 0 39924 262 0 0
sp_qd_handle_on_reserve_upd_sts 10283 10283 0 0.00 0 30418 58 0 0
sp_reserve_write_off 10283 10283 0 0.00 6 30488 166 0 0
sp_supplier_invoice 5768 5768 0 0.00 6 73078 2968 0 0
sp_supplier_order 5496 5496 0 0.00 5 31582 1126 0 0
srv_aggregate_perf_data 105 105 0 0.00 2669 797349 21509 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1253 150433 49282 0 0
srv_find_qd_qs_mism 1102585 1102585 0 0.00 0 12838 5 0 0
srv_make_invnt_saldo 610 610 0 0.00 29 26389 912 0 0
srv_make_money_saldo 815 815 0 0.00 0 624 18 0 0
srv_recalc_idx_stat 4 4 0 0.00 197756 338111 267848 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5105 5105 0 0.00 0 15724 128 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 2823 66 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 3309 10 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 3770 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 4467 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 727 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 2065 16 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 29160 1338 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 1906 9 0 0
t$perf-norm:sp_client_order 21292 21292 0 0.00 0 15791 13 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 3957 10 0 0
t$perf-norm:sp_pay_from_customer 7214 7214 0 0.00 0 6986 9 0 0
t$perf-norm:sp_pay_to_supplier 6083 6083 0 0.00 0 5688 8 0 0
t$perf-norm:sp_reserve_write_off 10283 10283 0 0.00 0 5861 9 0 0
t$perf-norm:sp_supplier_invoice 5768 5768 0 0.00 0 7199 17 0 0
t$perf-norm:sp_supplier_order 5496 5496 0 0.00 0 6565 13 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 160 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 610 610 0 0.00 0 623 11 0 0
t$perf-norm:srv_make_money_saldo 815 815 0 0.00 0 3688 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10466 10194 272 2.59 0 19656 24 0 272
v_all_customers 39224 39224 0 0.00 0 16 0 0 0
v_all_suppliers 31298 31298 0 0.00 0 24 1 0 0
v_all_wares 42584 42584 0 0.00 0 151 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 588 5 0 0
v_cancel_client_order 3488 3488 0 0.00 0 2519 5 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 2521 7 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 228 3 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1271 4 0 0
v_cancel_supplier_invoice 884 864 20 2.26 0 1684 18 0 20
v_cancel_supplier_order 860 860 0 0.00 0 1494 6 0 0
v_cancel_write_off 2052 2052 0 0.00 0 1295 12 0 0
v_max_id_clo_ord 5495 5495 0 0.00 0 4509 2 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 4965 15 0 0
v_max_id_ord_sup 5766 5766 0 0.00 0 5500 5 0 0
v_max_non_paid_invoice 1695 1695 0 0.00 0 1974 4 0 0
v_max_non_paid_realizn 3681 3681 0 0.00 0 5239 5 0 0
v_min_id_clo_ord 5495 5495 0 0.00 0 10062 9 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 3639 15 0 0
v_min_id_ord_sup 5766 5766 0 0.00 0 5736 10 0 0
v_min_non_paid_invoice 6083 6083 0 0.00 0 11603 24 0 0
v_min_non_paid_realizn 7214 7214 0 0.00 0 7090 19 0 0
v_our_firm 10426 10426 0 0.00 0 29 1 0 0
v_reserve_write_off 20566 20566 0 0.00 0 6840 5 0 0
x_lock_depdocs_on_canc_invoice 12378 12378 0 0.00 0 22676 110 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 6645 277 0 0
x_make_qstorno_1000_1200 5496 5496 0 0.00 2 31539 1053 0 0
x_make_qstorno_1200_2000 5768 5768 0 0.00 2 73013 2842 0 0
x_make_qstorno_2100_3300 468302 468302 0 0.00 0 50774 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-10-2020 01:28:55 to 25-10-2020 01:28:59.

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:03 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:04 20193 8192000000 10356862880 10673524736 1407426336 14.661 100 89 178 0 1442785680 6085808 29273536
22:59:27 38389 8192000000 10419408800 10752339968 1470648704 15.219 100 89 178 1 1508530464 8729952 29151808
23:01:20 13852 8192000000 10484181584 10831155200 1544574880 15.863 100 78 156 0 1579041888 9687680 24779328
23:02:37 12745 8192000000 10589959456 10953805824 1645929312 16.730 100 79 158 0 1680740272 11360464 23450496
23:03:53 7275 8192000000 10654659072 11035090944 1724521888 17.390 100 81 160 0 1760327696 13129584 22676224
23:05:02 3300 8192000000 10732566080 11111424000 1793061728 17.957 100 83 164 1 1829940528 13451200 23427600
23:06:08 2163 8192000000 10790257952 11176796160 1843791392 18.372 100 92 182 0 1882720192 14140352 24788448
23:07:10 1465 8192000000 10819223152 11211026432 1873827760 18.615 100 92 184 2 1912737840 12408912 26501168
23:08:12 5677 8192000000 10800908416 11222003712 1886156688 18.715 100 42 84 0 1907383008 9865472 11360848
23:09:21 24616 8192000000 10811347424 11238490112 1889826288 18.744 100 35 70 2 1907566304 8592816 9147200
23:10:50 6245 8192000000 10831749088 11250868224 1895099840 18.787 100 34 68 0 1911857600 6942480 9815280
23:12:01 7138 8192000000 10817746368 11253592064 1903139648 18.852 100 20 40 2 1914482544 5698816 5644080
23:13:09 52113 8192000000 10830553872 11263741952 1912938992 18.930 100 33 66 1 1928784752 5833232 10012528
23:15:06 7463 8192000000 10813320432 11245727744 1871121856 18.593 98 14 29 1 1880257184 5105760 4029568
23:16:16 11155 8192000000 10765626816 11191017472 1816654432 18.150 97 17 34 0 1826654208 5145168 4854608
23:17:29 7232 8192000000 10692957600 11114651648 1732795120 17.459 96 23 46 0 1745469776 5340848 7333808
23:18:36 43620 8192000000 10679456880 11093200896 1611336560 16.436 89 24 46 1 1623603392 4853056 7413776
23:20:20 8101 8192000000 10711160528 11128098816 1793158960 17.958 100 25 50 1 1806429824 5690064 7580800
23:21:31 7992 8192000000 10722643696 11146747904 1813960288 18.128 100 26 52 3 1826831664 5760176 7111200
23:22:43 67979 8192000000 10757525056 11185938432 1843929168 18.373 100 17 34 1 1853913968 5238720 4746080
23:24:55 7251 8192000000 10770635760 11198472192 1856977216 18.479 100 20 40 2 1868172800 5781856 5413728
23:26:05 10751 8192000000 10777406864 11214049280 1869510560 18.580 100 24 48 1 1882373760 5954288 6908912
23:27:21 2895 8192000000 10833408960 11245142016 1898432992 18.814 100 48 97 6 1918873584 6903568 13537024
23:28:27 41065 8192000000 10847285952 11273158656 1918201344 18.972 100 43 86 0 1939072656 9246640 11624672
23:31:16 12865 8192000000 10893092016 11319685120 1952774224 19.249 100 49 98 2 1981374768 14803136 13797408
23:32:37 23553 8192000000 10902342512 11333500928 1961876192 19.321 100 55 110 1 1997567328 19886528 15804608
23:34:12 3365 8192000000 10896900848 11318738944 1943841888 19.177 99 51 102 0 1982678784 23850208 14986688
23:35:21 12443 8192000000 10906408320 11332063232 1950802448 19.233 100 52 104 0 1994645760 28140496 15702816
23:36:46 2417 8192000000 10935091184 11339976704 1962058816 19.322 100 78 151 2 2013858800 28802672 22997312
23:37:54 5436 8192000000 10925141536 11349381120 1945810976 19.193 99 58 116 1 2003619808 39506736 18302096
23:39:04 5719 8192000000 10908552128 11321503744 1921986592 19.003 99 67 134 2 1986212576 42995312 21230672
23:40:13 4263 8192000000 10930119888 11342024704 1932921648 19.090 99 68 136 1 2002982912 48718336 21342928
23:41:23 9593 8192000000 10962778800 11386519552 1975235072 19.427 100 67 132 0 2046059168 50217760 20606336
23:42:38 1250 8192000000 10998876016 11404926976 1990356064 19.547 100 86 171 0 2075537776 58310704 26871008
23:43:42 11640 8192000000 10929601664 11370987520 1961378032 19.317 100 35 69 0 2019884000 46544544 11961424
23:45:17 12513 8192000000 10921903776 11375976448 1958563856 19.295 100 28 56 1 2016992608 49230208 9198544
23:46:52 54270 8192000000 10909681920 11357200384 1928190736 19.052 99 24 48 3 1994210640 57439168 8580736
23:48:48 27601 8192000000 10879132752 11332567040 1894777488 18.784 98 16 32 2 1937902096 38129680 4994928
23:50:19 20141 8192000000 10865956992 11308830720 1912691200 18.928 99 22 45 0 1926402480 7397552 6313728
23:51:47 150433 8192000000 10852832560 11322728448 1893853488 18.777 97 33 66 2 1913900496 11014800 9032208
23:55:19 75976 8192000000 10885938320 11335680000 1919107792 18.980 98 34 66 0 1939053280 10390576 9554912
23:58:19 15328 8192000000 10873745488 11341291520 1930708224 19.073 98 15 30 0 1940990624 6586624 3695776
23:59:37 32498 8192000000 10888180864 11352989696 1975263328 19.427 100 15 30 0 1988023280 8237360 4522592
00:01:10 41930 8192000000 10899851712 11375472640 1982461600 19.484 100 14 28 0 1992388608 6709744 3217264
00:02:53 45537 8192000000 10926206880 11380797440 1992384112 19.563 100 37 73 1 2011991776 9296048 10311616
00:04:39 93175 8192000000 10935092880 11408920576 1985165840 19.506 99 19 38 1 2006554448 16312480 5076128
00:07:13 26934 8192000000 10946671040 11403366400 2008625808 19.691 100 35 70 0 2031663552 13139344 9898400
00:09:18 84915 8192000000 10922301200 11374784512 1919703344 18.984 96 27 54 0 1943311040 16154880 7452816
00:11:45 31308 8192000000 10926798912 11367505920 1944286800 19.181 97 46 92 0 1969159136 11776064 13096272
00:13:19 47143 8192000000 10903325792 11358199808 1935523776 19.111 98 27 55 0 1954406112 11719648 7162688
00:15:08 114790 8192000000 10904797520 11371122688 1968698880 19.375 99 23 46 2 1982439376 7349264 6391232
00:18:06 46308 8192000000 10912674752 11386220544 1987231360 19.522 100 12 24 0 2006554128 15902688 3420080
00:20:14 50491 8192000000 10891716256 11361677312 1923077856 19.011 97 22 44 1 1947397184 17497488 6821840
00:22:22 70638 8192000000 10843448000 11301072896 1771062480 17.776 90 15 30 1 1785262848 9878256 4322112
00:24:33 61749 8192000000 10835569216 11289497600 1821038704 18.186 94 9 18 0 1833949440 10455344 2455392
00:26:39 39755 8192000000 10868248144 11323207680 1930995376 19.075 100 28 56 3 1948859760 10546800 7317584
00:28:23 48598 8192000000 10877155424 11328372736 1893701904 18.776 97 25 49 1 1911250256 10898992 6649360
00:30:16 68144 8192000000 10866827536 11338506240 1917750816 18.969 99 7 14 1 1943099376 23488480 1860080
00:32:29 32041 8192000000 10882808752 11342331904 1921971984 19.003 99 27 54 1 1950592448 20914432 7706032
00:34:02 75311 8192000000 10860973888 11340201984 1899012448 18.818 98 10 20 0 1913363296 11766672 2584176
00:36:25 57173 8192000000 10858642048 11306962944 1910697840 18.912 98 45 90 0 1931983408 8377600 12907968
00:38:24 39277 8192000000 10861029872 11325022208 1931492592 19.079 99 31 62 1 1949223872 8736384 8994896
00:40:09 41667 8192000000 10855608992 11325612032 1917733872 18.969 98 18 36 0 1931941184 8461328 5745984
00:41:52 83076 8192000000 10878809056 11345154048 1897798832 18.809 97 16 32 0 1916444592 13511712 5134048
00:44:15 78768 8192000000 10884411584 11356090368 1914781040 18.945 98 12 23 0 1935920640 17773488 3366112
00:46:35 82466 8192000000 10891882736 11366395904 1961491520 19.318 100 11 22 3 1976491632 11777648 3222464
00:48:58 42500 8192000000 10899672880 11367211008 1960380176 19.309 99 34 68 0 1980641472 10894128 9367168
00:50:48 71860 8192000000 10901445712 11375878144 1973615872 19.414 100 8 16 0 1991585408 15797168 2172368
00:53:06 73352 8192000000 10908263136 11376041984 1972898416 19.408 99 17 32 1 1992597120 14820256 4878448
00:55:26 39313 8192000000 10936520768 11395346432 1996255040 19.593 100 26 52 2 2017644960 14115232 7274688
00:57:09 100172 8192000000 10921665136 11380350976 1944368352 19.182 97 18 36 0 1958563648 9324144 4871152
01:00:06 80756 8192000000 10910866944 11389034496 1948443648 19.214 97 15 30 0 1962002480 9117392 4441440
01:02:31 73656 8192000000 10905761024 11386757120 1975974832 19.433 99 18 36 1 1992533760 11233856 5325072
01:04:53 61616 8192000000 10909540352 11390255104 1956187504 19.276 98 5 10 0 1966811488 9231248 1392736
01:06:59 51656 8192000000 10897623136 11376406528 1931938400 19.082 97 14 28 0 1948436960 12313040 4185520
01:09:10 62835 8192000000 10870118704 11333853184 1855365824 18.466 94 26 52 0 1875652464 12926592 7360048
01:11:13 59257 8192000000 10882253600 11339616256 1893442592 18.774 97 21 42 0 1908292592 9276512 5573488
01:13:15 61891 8192000000 10884473120 11346436096 1897246544 18.804 97 23 46 2 1924465936 20776624 6442768
01:15:29 83904 8192000000 10862002048 11318693888 1835765792 18.306 94 13 26 0 1850520064 11417360 3336912
01:17:56 107818 8192000000 10817651008 11273768960 1855522656 18.467 96 13 25 1 1868729728 9807664 3399408
01:20:47 46461 8192000000 10828281248 11294945280 1888561488 18.734 98 26 52 4 1910730400 14548064 7620848
01:22:42 83138 8192000000 10834625552 11318800384 1888718432 18.735 98 13 26 1 1909482976 16488048 4276496
01:25:07 45925 8192000000 10851572288 11319361536 1919489824 18.983 99 18 36 1 1933548976 8373760 5685392
01:26:53 22824 8192000000 9258599136 9522376704 414869424 4.820 22 2 4 0 423568672 8279648 419600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 25-10-2020 01:29:01 to 25-10-2020 01:29:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 136 v_add_invoice_to_stock 335544517
user_exc 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-10-2020 01:29:16 to 25-10-2020 01:29:18.

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          250136
MON$OLDEST_ACTIVE               250631
MON$OLDEST_SNAPSHOT             250631
MON$NEXT_TRANSACTION            250631
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                       2027168
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.33380 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33380 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33380 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33380 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Sun Oct 25 01:27:19 2020
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Oct 25 01:27:26 2020
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

25.10.20 01:29:19. 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-25 01:29:21 No severe PSQL-related problems occured
25.10.20 01:29:23. End of report.