Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.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 17568828 kB
MemAvailable 31565180 kB
Buffers 0 kB
Cached 14171212 kB
SwapCached 3064 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-30 01:27:02
DTS_END                         2020-10-30 01:27:02.2190
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/60216
TRN_ID                          243991
ATT_ID                          687
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: 8991.07 20511 1078928
customer order: creation 171.71 369 20605
customer order: refuse 15.02 333 1802
order to supplier: creation 45.51 1314 5461
order to supplier: removal 3.51 100616 421
invoice (draft): creation 46.13 3603 5535
invoice (draft): removal 49.82 818 5978
invoice accept: apply 41.65 17893 4998
invoice accept: cancel 45.57 805 5468
customer reserve: creation 3800.69 179 456083
customer reserve: removal 4478.98 62 537477
realization accept: apply 84.86 183 10183
realization accept: cancel 78.44 112 9413
payment from customer: creation 59.74 110 7169
payment from customer: removal 5.35 73 642
payment to supplier: creation 48.58 119 5829
payment to supplier: removal 3.29 82 395
service: total inventory turnovers 4.74 996 569
service: total monetary turnovers 6.62 19 794
service: aggregate perf. data 0.85 18886 102
service: refresh index statistics 0.03 263650 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-10-2020 01:28:57 to 30-10-2020 01:28:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11518
TEST_TIME 2 7977
TEST_TIME 3 7921
TEST_TIME 4 7293
TEST_TIME 5 7137
TEST_TIME 6 7012
TEST_TIME 7 6945
TEST_TIME 8 6969
TEST_TIME 9 6882
TEST_TIME 10 6652
TEST_TIME 11 6656
TEST_TIME 12 6792
TEST_TIME 13 6979
TEST_TIME 14 6921
TEST_TIME 15 7161
TEST_TIME 16 7216
TEST_TIME 17 7193
TEST_TIME 18 8182
TEST_TIME 19 8532
TEST_TIME 20 8730
TEST_TIME 21 9263
TEST_TIME 22 9377
TEST_TIME 23 9550
TEST_TIME 24 9784
TEST_TIME 25 10314
TEST_TIME 26 10343
TEST_TIME 27 10522
TEST_TIME 28 10584
TEST_TIME 29 11232
TEST_TIME 30 11127
TEST_TIME 31 11010
TEST_TIME 32 10795
TEST_TIME 33 10623
TEST_TIME 34 10443
TEST_TIME 35 10266
TEST_TIME 36 10288
TEST_TIME 37 10085
TEST_TIME 38 9959
TEST_TIME 39 9933
TEST_TIME 40 9870
TEST_TIME 41 9707
TEST_TIME 42 9611
TEST_TIME 43 9587
TEST_TIME 44 9515
TEST_TIME 45 9503
TEST_TIME 46 9497
TEST_TIME 47 9350
TEST_TIME 48 9330
TEST_TIME 49 9389
TEST_TIME 50 9361
TEST_TIME 51 9410
TEST_TIME 52 9439
TEST_TIME 53 9486
TEST_TIME 54 9544
TEST_TIME 55 9586
TEST_TIME 56 9640
TEST_TIME 57 9579
TEST_TIME 58 9597
TEST_TIME 59 9634
TEST_TIME 60 9673
TEST_TIME 61 9648
TEST_TIME 62 9666
TEST_TIME 63 9723
TEST_TIME 64 9619
TEST_TIME 65 9554
TEST_TIME 66 9474
TEST_TIME 67 9480
TEST_TIME 68 9415
TEST_TIME 69 9352
TEST_TIME 70 9286
TEST_TIME 71 9181
TEST_TIME 72 9306
TEST_TIME 73 9253
TEST_TIME 74 9233
TEST_TIME 75 9234
TEST_TIME 76 9200
TEST_TIME 77 9222
TEST_TIME 78 9203
TEST_TIME 79 9265
TEST_TIME 80 9235
TEST_TIME 81 9269
TEST_TIME 82 9255
TEST_TIME 83 9299
TEST_TIME 84 9314
TEST_TIME 85 9353
TEST_TIME 86 9371
TEST_TIME 87 9466
TEST_TIME 88 9461
TEST_TIME 89 9417
TEST_TIME 90 9354
TEST_TIME 91 9357
TEST_TIME 92 9340
TEST_TIME 93 9316
TEST_TIME 94 9282
TEST_TIME 95 9232
TEST_TIME 96 9179
TEST_TIME 97 9207
TEST_TIME 98 9210
TEST_TIME 99 9214
TEST_TIME 100 9195
TEST_TIME 101 9222
TEST_TIME 102 9286
TEST_TIME 103 9277
TEST_TIME 104 9318
TEST_TIME 105 9324
TEST_TIME 106 9300
TEST_TIME 107 9309
TEST_TIME 108 9312
TEST_TIME 109 9346
TEST_TIME 110 9345
TEST_TIME 111 9281
TEST_TIME 112 9233
TEST_TIME 113 9193
TEST_TIME 114 9209
TEST_TIME 115 9155
TEST_TIME 116 9108
TEST_TIME 117 9061
TEST_TIME 118 9054
TEST_TIME 119 9046
TEST_TIME 120 8995

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-10-2020 01:29:01 to 30-10-2020 01:29:04.

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 1503128 1503128 0 0.00 0 18024 6 0 0
doc_list_biud 1503128 1503128 0 0.00 0 43253 28 0 0
sp_add_invoice_to_stock 5114 4998 116 2.26 47 181036 17893 0 116
sp_cancel_adding_invoice 5468 5468 0 0.00 5 74472 805 0 0
sp_cancel_client_order 1802 1802 0 0.00 4 16732 334 0 0
sp_cancel_customer_reserve 537477 537477 0 0.00 1 43254 62 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 2 5869 73 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 4298 82 0 0
sp_cancel_supplier_invoice 5986 5978 8 0.13 2 42689 818 0 8
sp_cancel_supplier_order 421 421 0 0.00 6 999884 100617 0 0
sp_cancel_write_off 9413 9413 0 0.00 1 7570 113 0 0
sp_client_order 20605 20605 0 0.00 4 39010 369 0 0
sp_customer_reserve 456083 456083 0 0.00 0 66137 179 0 0
sp_fill_shopping_cart 31588 31588 0 0.00 1 21373 42 0 0
sp_fill_shopping_cart_clo_res 456077 456077 0 0.00 0 15102 14 0 0
sp_get_clo_for_invoice 4992 4992 0 0.00 0 34228 358 0 0
sp_kill_cost_storno 15918 15918 0 0.00 0 11361 59 0 0
sp_kill_qstorno_ret_qs2qd 1090766 1090766 0 0.00 0 43251 34 0 0
sp_kill_qty_storno 575734 575734 0 0.00 0 43253 72 0 0
sp_lock_selected_doc 20952 20952 0 0.00 0 7740 5 0 0
sp_make_cost_storno 28173 28173 0 0.00 0 15063 46 0 0
sp_multiply_rows_for_pdistr 28173 28173 0 0.00 0 9733 29 0 0
sp_multiply_rows_for_qdistr 20605 20605 0 0.00 0 38961 174 0 0
sp_pay_from_customer 7169 7169 0 0.00 4 10329 111 0 0
sp_pay_to_supplier 5829 5829 0 0.00 4 12370 119 0 0
sp_payment_common 12998 12998 0 0.00 1 10325 25 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 0 9290 296 0 0
sp_qd_handle_on_invoice_upd_sts 10460 10460 0 0.00 0 23199 316 0 0
sp_qd_handle_on_reserve_upd_sts 10183 10183 0 0.00 0 17569 71 0 0
sp_reserve_write_off 10183 10183 0 0.00 6 17584 183 0 0
sp_supplier_invoice 5535 5535 0 0.00 5 90544 3603 0 0
sp_supplier_order 5461 5461 0 0.00 5 48886 1314 0 0
srv_aggregate_perf_data 102 102 0 0.00 3102 107842 18887 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 922 145729 45764 0 0
srv_find_qd_qs_mism 1032422 1032422 0 0.00 0 18004 5 0 0
srv_make_invnt_saldo 569 569 0 0.00 28 31949 997 0 0
srv_make_money_saldo 794 794 0 0.00 1 595 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 101252 357130 263651 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4998 4998 0 0.00 0 17697 165 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 9270 92 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 3545 12 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 8966 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 8740 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 2006 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 363 14 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 20760 1378 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 17000 49 0 0
t$perf-norm:sp_client_order 20605 20605 0 0.00 0 26011 19 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 6486 12 0 0
t$perf-norm:sp_pay_from_customer 7169 7169 0 0.00 0 20360 13 0 0
t$perf-norm:sp_pay_to_supplier 5829 5829 0 0.00 0 4000 10 0 0
t$perf-norm:sp_reserve_write_off 10183 10183 0 0.00 0 8954 12 0 0
t$perf-norm:sp_supplier_invoice 5535 5535 0 0.00 0 6620 21 0 0
t$perf-norm:sp_supplier_order 5461 5461 0 0.00 0 6537 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 214 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 281 5 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 2913 24 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 777 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 5 0 0
v_add_invoice_to_stock 10216 9984 232 2.27 0 5602 20 0 232
v_all_customers 37566 37566 0 0.00 0 22 1 0 0
v_all_suppliers 30596 30596 0 0.00 0 19 1 0 0
v_all_wares 41210 41210 0 0.00 0 146 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 3598 15 0 0
v_cancel_client_order 3604 3604 0 0.00 0 11943 11 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 2125 8 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 6521 10 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 4290 13 0 0
v_cancel_supplier_invoice 782 766 16 2.04 0 3058 16 0 16
v_cancel_supplier_order 842 842 0 0.00 0 2622 10 0 0
v_cancel_write_off 1944 1944 0 0.00 0 6449 19 0 0
v_max_id_clo_ord 5458 5458 0 0.00 0 1003 1 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 2107 14 0 0
v_max_id_ord_sup 5525 5525 0 0.00 0 5998 7 0 0
v_max_non_paid_invoice 1514 1514 0 0.00 0 1484 4 0 0
v_max_non_paid_realizn 3799 3799 0 0.00 0 2339 3 0 0
v_min_id_clo_ord 5458 5458 0 0.00 0 13521 9 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 3372 14 0 0
v_min_id_ord_sup 5525 5525 0 0.00 0 6366 8 0 0
v_min_non_paid_invoice 5829 5829 0 0.00 0 8821 17 0 0
v_min_non_paid_realizn 7169 7169 0 0.00 0 10320 15 0 0
v_our_firm 10384 10384 0 0.00 0 78 1 0 0
v_reserve_write_off 20366 20366 0 0.00 0 11369 9 0 0
x_lock_depdocs_on_canc_invoice 11063 11063 0 0.00 0 16537 120 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 5618 333 0 0
x_make_qstorno_1000_1200 5461 5461 0 0.00 2 46598 1233 0 0
x_make_qstorno_1200_2000 5535 5535 0 0.00 2 90337 3445 0 0
x_make_qstorno_2100_3300 456083 456083 0 0.00 0 66135 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-10-2020 01:29:05 to 30-10-2020 01:29:09.

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:05 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:06 20933 8192000000 10360838432 10679361536 1418623728 14.760 100 91 182 0 1454394448 6207888 29562832
22:59:34 28711 8192000000 10439401568 10768674816 1489248704 15.382 100 89 177 0 1526098576 8173648 28676224
23:01:08 12800 8192000000 10485007280 10828111872 1537784336 15.804 100 78 155 1 1571524608 9782752 23957520
23:02:29 14248 8192000000 10568230416 10928316416 1627947008 16.577 100 81 162 0 1663231328 10341552 24942768
23:03:47 8320 8192000000 10646160688 11015335936 1701720368 17.200 100 74 147 2 1734676128 11523696 21432064
23:04:57 4203 8192000000 10704675712 11081945088 1757957456 17.667 100 82 161 1 1794089568 13733632 22398480
23:06:04 6296 8192000000 10751021344 11134705664 1806745520 18.069 100 84 168 1 1844232176 14512528 22974128
23:07:12 1821 8192000000 10801614992 11187707904 1854175584 18.456 100 88 176 1 1893207504 14688320 24343600
23:08:15 2875 8192000000 10779005824 11195113472 1864550320 18.540 100 51 96 1 1887123968 8459184 14114464
23:09:19 4967 8192000000 10799017280 11216154624 1879480672 18.661 100 42 83 3 1900783264 9758640 11543952
23:10:28 18204 8192000000 10786895120 11229192192 1888057456 18.730 100 24 46 5 1900800640 6429648 6313536
23:11:47 8995 8192000000 10803868336 11239858176 1894588512 18.783 100 22 45 0 1907285968 6773584 5923872
23:12:57 5253 8192000000 10820892144 11253235712 1906600976 18.879 100 33 66 3 1922023696 6670928 8751792
23:14:06 4778 8192000000 10824357328 11261890560 1913942928 18.938 100 30 60 1 1930031776 6196352 9892496
23:15:14 21766 8192000000 10806498784 11254980608 1895896064 18.793 99 30 65 2 1909552256 5613776 8042416
23:17:04 5793 8192000000 10764956576 11196321792 1811866784 18.111 96 29 58 1 1825861888 6357648 7637456
23:18:11 7626 8192000000 10712205712 11126292480 1724151344 17.387 95 35 73 0 1739995344 6013264 9830736
23:19:20 9472 8192000000 10696865920 11110236160 1780239536 17.851 100 19 38 1 1792095792 5718272 6137984
23:20:30 32035 8192000000 10683493392 11095117824 1671655232 16.947 93 28 53 0 1684818512 5712576 7450704
23:22:02 6425 8192000000 10763125088 11169808384 1814908048 18.136 99 30 56 0 1828698912 5793632 7997232
23:23:10 10765 8192000000 10780212720 11201187840 1856671616 18.476 100 26 52 1 1869718096 5317264 7729216
23:24:22 57529 8192000000 10801534192 11216105472 1866889920 18.559 100 31 62 0 1881522256 5050416 9581920
23:26:20 7038 8192000000 10788034848 11220512768 1880780192 18.671 100 29 55 1 1894191344 5757744 7653408
23:27:29 921 8192000000 10867495824 11270680576 1924063536 19.019 100 81 163 2 1954670096 8385952 22220608
23:28:36 7903 8192000000 10877751472 11285168128 1933022224 19.091 100 71 141 4 1964527120 11330048 20174848
23:29:46 39336 8192000000 10861945264 11299676160 1940107744 19.148 100 34 68 0 1961176016 11203808 9864464
23:31:33 9667 8192000000 10903410560 11322576896 1959465120 19.302 100 64 126 0 1991606784 14000096 18141568
23:32:48 13300 8192000000 10890946000 11324440576 1943531104 19.175 99 45 90 1 1972180848 15350944 13298800
23:34:11 11163 8192000000 10933585200 11363692544 1982963520 19.488 100 58 116 2 2020735232 21012256 16759456
23:35:30 13179 8192000000 10932985552 11373408256 1992352608 19.562 100 36 72 0 2023220912 19442320 11425984
23:36:58 5708 8192000000 10934234096 11364655104 1983107056 19.489 100 54 106 2 2021617568 22939072 15571440
23:38:10 4363 8192000000 10936174816 11360731136 1976952752 19.441 99 63 125 2 2021979040 26019616 19006672
23:39:17 4005 8192000000 10950464528 11367907328 1971094816 19.394 99 76 149 2 2022202576 28754192 22353568
23:40:27 2447 8192000000 10942627600 11354525696 1959428288 19.301 99 88 175 2 2020283536 33917536 26937712
23:41:38 2874 8192000000 10942373088 11355594752 1948663232 19.216 99 71 138 2 2008739952 38043440 22033280
23:42:47 2313 8192000000 10930616176 11331596288 1939651456 19.144 99 86 170 1 2010523136 43943248 26928432
23:43:54 7641 8192000000 10910904848 11328942080 1925928784 19.034 100 56 112 1 1988475984 43631776 18915424
23:45:06 1837 8192000000 10959143088 11366551552 1957968720 19.290 100 86 172 2 2038126800 52652688 27505392
23:46:12 11510 8192000000 10931267584 11371032576 1955470336 19.270 100 39 78 1 2017096240 48073520 13552384
23:47:31 5492 8192000000 10911226976 11345506304 1927143168 19.044 100 47 94 1 1996111888 53948560 15020160
23:48:42 3065 8192000000 10885132688 11306577920 1898891584 18.817 99 50 95 2 1950832720 36826800 15114336
23:49:47 16223 8192000000 10875154832 11321147392 1922554992 19.007 100 28 56 0 1965690496 34105968 9029536
23:51:09 9924 8192000000 10892598000 11323092992 1916090352 18.956 100 41 80 1 1975651344 46305584 13255408
23:52:24 38078 8192000000 10888033552 11347316736 1920253616 18.989 99 20 40 2 1967350496 39533008 7563872
23:54:08 24841 8192000000 10872362800 11323531264 1885512624 18.710 98 20 40 3 1927871056 35541120 6817312
23:55:33 49722 8192000000 10867042608 11325067264 1884652608 18.703 98 20 40 1 1927233008 36221264 6359136
23:57:53 35454 8192000000 10866648480 11320041472 1940575200 19.151 100 38 76 2 1960914224 9627584 10711440
23:59:29 109329 8192000000 10879316928 11334438912 1918865712 18.978 98 24 48 0 1932283296 7006880 6410704
00:02:37 145729 8192000000 10903264448 11347054592 1944633792 19.184 99 30 58 2 1968307632 14235296 9438544
00:06:05 8953 8192000000 10909923216 11355693056 1971526832 19.398 100 52 104 0 1994678624 9084544 14067248
00:07:21 37116 8192000000 10896878608 11368628224 1978843152 19.456 100 11 21 1 1992597856 11101760 2652944
00:08:59 10232 8192000000 10941691488 11387772928 1995990912 19.591 100 33 68 1 2014716672 9601664 9124096
00:10:10 48411 8192000000 10909307088 11373830144 1957695456 19.288 98 12 24 0 1971030960 9932880 3402624
00:12:00 32486 8192000000 10927678016 11391029248 1978509104 19.453 99 19 38 0 2001410624 17233472 5668048
00:13:40 53103 8192000000 10927344880 11401240576 1999289296 19.617 100 9 18 1 2016338656 14275680 2773680
00:15:33 90747 8192000000 10935374176 11395231744 2001891008 19.638 100 28 55 0 2026109792 15939776 8279008
00:18:04 64644 8192000000 10930247824 11421372416 2005919168 19.669 100 20 39 1 2030901200 18865968 6116064
00:20:46 104964 8192000000 10950970000 11418681344 1957925024 19.290 97 13 26 0 1979809776 18155776 3728976
00:23:31 94866 8192000000 10941660560 11388854272 1941083168 19.155 97 15 30 0 1965952864 20360352 4509344
00:26:06 40819 8192000000 10925725360 11371204608 1950424544 19.230 98 26 53 0 1966394640 8502608 7467488
00:27:50 50784 8192000000 10943470192 11397255168 1976310256 19.435 99 25 50 1 1993990112 10926768 6753088
00:29:44 82582 8192000000 10853563472 11305545728 1823273504 18.204 92 13 26 1 1839238160 12460400 3504256
00:32:09 59757 8192000000 10853133888 11304058880 1831940064 18.275 93 32 64 2 1848709728 7473376 9296288
00:34:14 34012 8192000000 10848102000 11319668736 1918900224 18.978 99 23 46 0 1934219472 9160208 6159040
00:35:51 42520 8192000000 10836732960 11301359616 1874914752 18.624 97 13 26 0 1884620848 5903664 3802432
00:37:37 95632 8192000000 10871481840 11344412672 1936044592 19.115 100 9 18 0 1956313104 17447776 2820736
00:40:38 71553 8192000000 10850748048 11327627264 1939453344 19.142 100 19 38 0 1955080160 9905648 5721168
00:42:50 53437 8192000000 10852337680 11324559360 1908133680 18.892 98 8 16 1 1918914768 8025840 2755248
00:44:43 58163 8192000000 10804590656 11258884096 1772286736 17.786 92 13 26 2 1786966496 10297136 4382624
00:46:45 81641 8192000000 10841866960 11303092224 1869680464 18.582 97 21 42 3 1888238768 12797056 5761248
00:49:09 29128 8192000000 10874605056 11328966656 1935173184 19.108 100 33 66 1 1957069376 12733888 9162304
00:50:39 23790 8192000000 10869692080 11319947264 1904177328 18.860 98 28 56 2 1920805920 8643872 7984720
00:52:03 50682 8192000000 10904271744 11365191680 1954886576 19.265 100 27 52 1 1973827904 11574208 7367120
00:53:55 76781 8192000000 10889437632 11350544384 1952873024 19.249 100 11 22 1 1980006064 24199360 2933680
00:56:12 112813 8192000000 10882416416 11334930432 1908931232 18.898 97 14 28 0 1919439984 6630000 3878752
00:59:08 36829 8192000000 10904458240 11373907968 1977915120 19.448 100 21 42 1 1994880544 11325376 5640048
01:00:52 61485 8192000000 10880661536 11364417536 1951175696 19.236 99 7 14 0 1965178848 12302976 1700176
01:02:54 79806 8192000000 10901795888 11373076480 1959722032 19.304 99 10 20 1 1973494704 10913008 2859664
01:05:14 50219 8192000000 10905918624 11365269504 1946220352 19.196 98 31 62 3 1965362656 9500000 9642304
01:07:11 71883 8192000000 10902859744 11377328128 1949426752 19.222 98 21 42 0 1970227520 14595680 6205088
01:09:24 87564 8192000000 10915636816 11388792832 1982034752 19.481 100 19 38 1 2006190368 18291600 5864016
01:12:06 99935 8192000000 10922330112 11396521984 1983648576 19.494 99 26 52 0 2003353312 12676064 7028672
01:14:47 54209 8192000000 10887515136 11352281088 1889900032 18.745 95 28 56 1 1909438272 11933952 7604288
01:16:42 128291 8192000000 10897387728 11371638784 1934331872 19.101 98 22 44 1 1950182720 10529280 5321568
01:20:12 39516 8192000000 10886424528 11334967296 1906432864 18.878 97 41 82 1 1927331568 8569392 12329312
01:21:56 59176 8192000000 10845254592 11321950208 1884280608 18.700 97 7 14 0 1895438144 9261008 1896528
01:23:56 89274 8192000000 10830783808 11299749888 1826937088 18.234 94 17 34 3 1844947840 12744672 5266080
01:26:44 30753 8192000000 9265246288 9539547136 432889744 5.019 23 9 16 1 444297792 8557808 2850240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 116 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-10-2020 01:29:26 to 30-10-2020 01:29:28.

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          244363
MON$OLDEST_ACTIVE               244364
MON$OLDEST_SNAPSHOT             244364
MON$NEXT_TRANSACTION            244364
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                       2023616
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.33382 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33382 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33382 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33382 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 ---
:73: 
:74: mscs-test-161.budzdorov.ru    Fri Oct 30 01:27:22 2020
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Fri Oct 30 01:27:23 2020
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:80: 
:81: 
:82: mscs-test-161.budzdorov.ru    Fri Oct 30 01:27:49 2020
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:84: 
--- end of diff output ---

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

30.10.20 01:29:29. 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-30 01:29:32 No severe PSQL-related problems occured
30.10.20 01:29:33. End of report.