Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.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 17568672 kB
MemAvailable 31552360 kB
Buffers 0 kB
Cached 14092316 kB
SwapCached 5448 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-16 19:26:59
DTS_END                         2020-10-16 19:26:59.1810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/52076
TRN_ID                          246742
ATT_ID                          677
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: 9664.82 19166 1159778
customer order: creation 175.77 330 21092
customer order: refuse 15.04 262 1805
order to supplier: creation 46.62 1207 5594
order to supplier: removal 3.57 86961 428
invoice (draft): creation 45.67 2940 5480
invoice (draft): removal 55.83 641 6700
invoice accept: apply 42.80 17906 5136
invoice accept: cancel 50.49 686 6059
customer reserve: creation 4008.15 177 480978
customer reserve: removal 4913.87 50 589664
realization accept: apply 84.57 168 10148
realization accept: cancel 92.38 92 11085
payment from customer: creation 59.90 127 7188
payment from customer: removal 5.99 54 719
payment to supplier: creation 48.80 144 5856
payment to supplier: removal 3.28 80 393
service: total inventory turnovers 4.86 1069 583
service: total monetary turnovers 6.31 34 757
service: aggregate perf. data 0.91 15858 109
service: refresh index statistics 0.03 254525 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2020 19:27:41 to 16-10-2020 19:27:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2768
TEST_TIME 2 4699
TEST_TIME 3 4364
TEST_TIME 4 4474
TEST_TIME 5 5474
TEST_TIME 6 6100
TEST_TIME 7 6573
TEST_TIME 8 6267
TEST_TIME 9 6339
TEST_TIME 10 6720
TEST_TIME 11 7074
TEST_TIME 12 7334
TEST_TIME 13 7596
TEST_TIME 14 7588
TEST_TIME 15 7908
TEST_TIME 16 7953
TEST_TIME 17 8104
TEST_TIME 18 8120
TEST_TIME 19 8601
TEST_TIME 20 9345
TEST_TIME 21 9111
TEST_TIME 22 9519
TEST_TIME 23 9506
TEST_TIME 24 9808
TEST_TIME 25 11248
TEST_TIME 26 11498
TEST_TIME 27 11322
TEST_TIME 28 10930
TEST_TIME 29 11130
TEST_TIME 30 10850
TEST_TIME 31 10831
TEST_TIME 32 10810
TEST_TIME 33 10663
TEST_TIME 34 10599
TEST_TIME 35 10435
TEST_TIME 36 10382
TEST_TIME 37 10196
TEST_TIME 38 10355
TEST_TIME 39 10261
TEST_TIME 40 10091
TEST_TIME 41 10131
TEST_TIME 42 10113
TEST_TIME 43 10127
TEST_TIME 44 10066
TEST_TIME 45 10009
TEST_TIME 46 9999
TEST_TIME 47 10102
TEST_TIME 48 10226
TEST_TIME 49 10178
TEST_TIME 50 10135
TEST_TIME 51 10135
TEST_TIME 52 10265
TEST_TIME 53 10284
TEST_TIME 54 10317
TEST_TIME 55 10283
TEST_TIME 56 10118
TEST_TIME 57 10095
TEST_TIME 58 10063
TEST_TIME 59 10021
TEST_TIME 60 9983
TEST_TIME 61 9956
TEST_TIME 62 9883
TEST_TIME 63 9797
TEST_TIME 64 9824
TEST_TIME 65 9808
TEST_TIME 66 9822
TEST_TIME 67 9775
TEST_TIME 68 9736
TEST_TIME 69 9906
TEST_TIME 70 9969
TEST_TIME 71 9926
TEST_TIME 72 9903
TEST_TIME 73 9927
TEST_TIME 74 9982
TEST_TIME 75 10086
TEST_TIME 76 10149
TEST_TIME 77 10222
TEST_TIME 78 10185
TEST_TIME 79 10069
TEST_TIME 80 10018
TEST_TIME 81 9967
TEST_TIME 82 9956
TEST_TIME 83 9910
TEST_TIME 84 9902
TEST_TIME 85 9858
TEST_TIME 86 9790
TEST_TIME 87 9791
TEST_TIME 88 9807
TEST_TIME 89 9804
TEST_TIME 90 9762
TEST_TIME 91 9790
TEST_TIME 92 9838
TEST_TIME 93 9816
TEST_TIME 94 9811
TEST_TIME 95 9840
TEST_TIME 96 9838
TEST_TIME 97 9829
TEST_TIME 98 9871
TEST_TIME 99 9848
TEST_TIME 100 9854
TEST_TIME 101 9883
TEST_TIME 102 9883
TEST_TIME 103 9859
TEST_TIME 104 9776
TEST_TIME 105 9781
TEST_TIME 106 9723
TEST_TIME 107 9734
TEST_TIME 108 9686
TEST_TIME 109 9628
TEST_TIME 110 9610
TEST_TIME 111 9669
TEST_TIME 112 9641
TEST_TIME 113 9633
TEST_TIME 114 9623
TEST_TIME 115 9599
TEST_TIME 116 9639
TEST_TIME 117 9661
TEST_TIME 118 9687
TEST_TIME 119 9654
TEST_TIME 120 9669

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-10-2020 19:27:44 to 16-10-2020 19:27:48.

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 1603888 1603888 0 0.00 0 34668 7 0 0
doc_list_biud 1603888 1603888 0 0.00 0 35963 23 0 0
sp_add_invoice_to_stock 5262 5136 126 2.39 30 179432 17906 0 126
sp_cancel_adding_invoice 6059 6059 0 0.00 5 76016 686 0 0
sp_cancel_client_order 1805 1805 0 0.00 4 11678 263 0 0
sp_cancel_customer_reserve 589664 589664 0 0.00 1 35966 50 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 4028 54 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 6917 81 0 0
sp_cancel_supplier_invoice 6708 6700 8 0.11 2 16837 642 0 8
sp_cancel_supplier_order 428 428 0 0.00 9 890314 86961 0 0
sp_cancel_write_off 11085 11085 0 0.00 1 13452 92 0 0
sp_client_order 21092 21092 0 0.00 4 37964 330 0 0
sp_customer_reserve 480978 480978 0 0.00 0 137476 177 0 0
sp_fill_shopping_cart 32164 32164 0 0.00 1 13096 35 0 0
sp_fill_shopping_cart_clo_res 480972 480972 0 0.00 0 14587 12 0 0
sp_get_clo_for_invoice 5130 5130 0 0.00 0 29465 277 0 0
sp_kill_cost_storno 18256 18256 0 0.00 0 5459 44 0 0
sp_kill_qstorno_ret_qs2qd 1197541 1197541 0 0.00 0 35956 27 0 0
sp_kill_qty_storno 631019 631019 0 0.00 0 35963 58 0 0
sp_lock_selected_doc 21214 21214 0 0.00 0 9700 6 0 0
sp_make_cost_storno 28322 28322 0 0.00 0 16732 60 0 0
sp_multiply_rows_for_pdistr 28322 28322 0 0.00 0 9765 24 0 0
sp_multiply_rows_for_qdistr 21092 21092 0 0.00 0 36790 149 0 0
sp_pay_from_customer 7188 7188 0 0.00 4 32036 127 0 0
sp_pay_to_supplier 5856 5856 0 0.00 4 33061 144 0 0
sp_payment_common 13044 13044 0 0.00 1 33048 50 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 0 11670 237 0 0
sp_qd_handle_on_invoice_upd_sts 11189 11189 0 0.00 0 14940 259 0 0
sp_qd_handle_on_reserve_upd_sts 10148 10148 0 0.00 0 11548 51 0 0
sp_reserve_write_off 10148 10148 0 0.00 6 16883 168 0 0
sp_supplier_invoice 5480 5480 0 0.00 6 90055 2941 0 0
sp_supplier_order 5594 5594 0 0.00 5 38734 1207 0 0
srv_aggregate_perf_data 109 109 0 0.00 2953 142856 15859 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2278 198199 50296 0 0
srv_find_qd_qs_mism 1110379 1110379 0 0.00 0 34668 6 0 0
srv_make_invnt_saldo 583 583 0 0.00 30 68516 1069 0 0
srv_make_money_saldo 757 757 0 0.00 0 5324 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 162772 303788 254526 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 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 5137 5137 0 0.00 0 15177 171 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 3514 85 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 12325 18 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 2117 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 2639 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 1688 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 6143 21 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 15970 1385 0 0
t$perf-norm:sp_cancel_write_off 1100 1100 0 0.00 0 2875 15 0 0
t$perf-norm:sp_client_order 21092 21092 0 0.00 0 9411 16 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 3375 6 0 0
t$perf-norm:sp_pay_from_customer 7188 7188 0 0.00 0 9160 11 0 0
t$perf-norm:sp_pay_to_supplier 5856 5856 0 0.00 0 5581 8 0 0
t$perf-norm:sp_reserve_write_off 10148 10148 0 0.00 0 8802 9 0 0
t$perf-norm:sp_supplier_invoice 5480 5480 0 0.00 0 7947 22 0 0
t$perf-norm:sp_supplier_order 5594 5594 0 0.00 0 8456 22 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 234 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 150 5 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 1176 14 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 659 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 6 0 0
v_add_invoice_to_stock 10510 10258 252 2.39 0 4462 17 0 252
v_all_customers 38428 38428 0 0.00 0 21 1 0 0
v_all_suppliers 30844 30844 0 0.00 0 23 1 0 0
v_all_wares 42184 42184 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 7777 16 0 0
v_cancel_client_order 3610 3610 0 0.00 0 3095 5 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 1783 7 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 1022 4 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 661 4 0 0
v_cancel_supplier_invoice 762 746 16 2.09 0 744 14 0 16
v_cancel_supplier_order 856 856 0 0.00 0 1464 8 0 0
v_cancel_write_off 2200 2200 0 0.00 0 2190 12 0 0
v_max_id_clo_ord 5593 5593 0 0.00 0 1312 1 0 0
v_max_id_clo_res 964 964 0 0.00 0 2288 12 0 0
v_max_id_ord_sup 5478 5478 0 0.00 0 8146 6 0 0
v_max_non_paid_invoice 1505 1505 0 0.00 0 5868 11 0 0
v_max_non_paid_realizn 3734 3734 0 0.00 0 9432 16 0 0
v_min_id_clo_ord 5593 5593 0 0.00 0 9909 9 0 0
v_min_id_clo_res 964 964 0 0.00 0 6913 10 0 0
v_min_id_ord_sup 5478 5478 0 0.00 0 3763 4 0 0
v_min_non_paid_invoice 5856 5856 0 0.00 0 33043 31 0 0
v_min_non_paid_realizn 7188 7188 0 0.00 0 32021 32 0 0
v_our_firm 10664 10664 0 0.00 0 19 1 0 0
v_reserve_write_off 20296 20296 0 0.00 0 3998 5 0 0
x_lock_depdocs_on_canc_invoice 12386 12386 0 0.00 0 26024 106 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 9745 275 0 0
x_make_qstorno_1000_1200 5594 5594 0 0.00 1 38691 1132 0 0
x_make_qstorno_1200_2000 5480 5480 0 0.00 3 90039 2820 0 0
x_make_qstorno_2100_3300 480978 480978 0 0.00 0 137475 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2020 19:27:49 to 16-10-2020 19:27:53.

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:02 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:04 24484 8192000000 10364026240 10686443520 1415388944 14.732 100 86 172 0 1450908640 6702384 28817312
16:59:36 22719 8192000000 10429635888 10760978432 1483912768 15.336 100 90 180 1 1521931344 8917536 29101040
17:01:02 13381 8192000000 10477947136 10823766016 1534564880 15.777 100 77 154 0 1568817936 10159632 24093424
17:02:18 11428 8192000000 10566754176 10924109824 1625155024 16.554 100 77 154 0 1659006656 10821296 23030336
17:03:37 6122 8192000000 10625262576 10993364992 1692668688 17.124 100 71 142 1 1725733072 12573328 20491056
17:04:47 3957 8192000000 10710396128 11087491072 1769474992 17.763 100 88 176 1 1807547728 12684608 25388128
17:05:53 4166 8192000000 10743100368 11129085952 1807236256 18.073 100 82 159 5 1842662544 12956624 22469664
17:07:00 1255 8192000000 10798571888 11187191808 1853507392 18.451 100 92 183 0 1892576688 12156368 26912928
17:08:02 24745 8192000000 10794934384 11195506688 1863249200 18.530 100 63 126 1 1887133264 5842320 18041744
17:09:27 9611 8192000000 10777081616 11207405568 1867601120 18.565 100 16 32 2 1877925424 5348304 4976000
17:10:37 7114 8192000000 10787603440 11224641536 1886111472 18.714 100 23 44 1 1899075472 6799904 6164096
17:11:44 15498 8192000000 10794072864 11234848768 1891745504 18.760 100 13 26 1 1901176080 5761552 3669024
17:13:05 16985 8192000000 10820956848 11250049024 1902523200 18.847 100 33 67 0 1918852048 6145040 10183808
17:14:25 22947 8192000000 10811242272 11256725504 1904248784 18.860 100 25 52 0 1918060000 6079520 7731696
17:15:49 3815 8192000000 10791664272 11221454848 1858997376 18.495 99 29 58 2 1878142192 11063504 8081312
17:16:56 8500 8192000000 10741443968 11165450240 1773946848 17.800 96 25 50 2 1787197584 6011552 7239184
17:18:08 69112 8192000000 10723648544 11137785856 1629362736 16.589 89 35 70 1 1645565968 4874544 11328688
17:20:20 10470 8192000000 10695173504 11108212736 1728201856 17.421 97 22 45 0 1740240128 5447728 6590544
17:21:31 5266 8192000000 10738946848 11153735680 1819287504 18.172 100 33 67 2 1835331696 5269552 10774640
17:22:37 11268 8192000000 10768685728 11194654720 1852017712 18.439 100 20 39 1 1863293600 5319616 5956272
17:23:48 34677 8192000000 10782947696 11206492160 1855439648 18.466 100 20 40 1 1866050032 5150848 5459536
17:25:27 4391 8192000000 10792719360 11217252352 1873180032 18.610 100 34 68 2 1887709984 4665328 9864624
17:26:34 5954 8192000000 10803524704 11228045312 1878622912 18.654 100 23 46 1 1891407824 5032304 7752608
17:27:43 15188 8192000000 10831888192 11252178944 1904010320 18.859 100 45 90 0 1924883040 8317760 12554960
17:29:01 20308 8192000000 10856071760 11284189184 1931022480 19.075 100 49 96 5 1953299664 9020816 13256368
17:30:44 37467 8192000000 10863509792 11310891008 1938136272 19.132 100 32 64 1 1961357568 12577552 10643744
17:32:28 25051 8192000000 10893936576 11321274368 1947961488 19.210 100 56 112 0 1975973744 11626928 16385328
17:33:56 23139 8192000000 10907462992 11338051584 1952001072 19.242 99 65 130 2 1988432576 16881600 19549904
17:35:24 2454 8192000000 10898479264 11325263872 1952418608 19.246 100 53 106 1 1988396592 19903360 16074624
17:36:28 2670 8192000000 10936614944 11356966912 1982006160 19.481 100 64 125 0 2022427920 22502480 17919280
17:37:35 2379 8192000000 10917913120 11332378624 1923518160 19.015 97 76 153 2 1972983440 27665616 21799664
17:38:41 4349 8192000000 10928440512 11349245952 1961774128 19.320 100 66 132 2 2010569184 27777184 21017872
17:39:50 15096 8192000000 10910309648 11339665408 1948321936 19.213 100 50 100 0 1994618944 31150752 15146256
17:41:13 2278 8192000000 10936277968 11359416320 1953310832 19.253 99 60 120 0 2008516704 35873552 19332320
17:42:17 3765 8192000000 10920614736 11357343744 1964720784 19.344 100 45 90 1 2015220720 35248400 15251536
17:43:24 6091 8192000000 10941022560 11368157184 1966088544 19.354 100 62 123 3 2027400144 41662016 19649584
17:44:39 3203 8192000000 10954196960 11367231488 1963858192 19.337 100 78 156 0 2036702528 48777008 24067328
17:45:48 12586 8192000000 10926272624 11357995008 1949483456 19.222 99 46 92 3 2010048352 44815120 15749776
17:47:06 12502 8192000000 10922122768 11362758656 1954230608 19.260 100 34 66 2 2006465504 40969472 11265424
17:48:22 26600 8192000000 10917093376 11358474240 1934401024 19.102 99 30 60 1 1987988576 44094608 9492944
17:49:50 27995 8192000000 10922432064 11378421760 1943930608 19.178 100 21 42 0 2003827552 51580048 8316896
17:51:33 35063 8192000000 10867711712 11329994752 1903642544 18.856 99 15 29 3 1958628032 49866560 5118928
17:53:09 81792 8192000000 10861151344 11310673920 1904588896 18.863 98 17 34 1 1924713920 14834928 5290096
17:55:40 55776 8192000000 10854084144 11316031488 1894718336 18.784 98 22 44 0 1913160992 12574464 5868192
17:57:38 42685 8192000000 10883076768 11340627968 1953345808 19.253 100 35 71 3 1973701648 10622656 9733184
17:59:33 63862 8192000000 10878398960 11348119552 1926367376 19.038 98 5 10 0 1938526432 10919408 1239648
18:01:49 198185 8192000000 10861863952 11331612672 1898149984 18.811 96 19 37 0 1919639984 16590256 4899744
18:06:08 50397 8192000000 10884025792 11344859136 1931221104 19.077 98 17 33 0 1950786992 15107536 4458352
18:08:15 57667 8192000000 10893621408 11372081152 1948558976 19.215 99 6 12 2 1967253152 16854848 1839328
18:10:13 54897 8192000000 10911611200 11377561600 1964992544 19.346 99 14 28 0 1982838368 14044752 3801072
18:12:19 58768 8192000000 10916821728 11377172480 1964122848 19.339 99 29 58 1 1984799200 12197168 8479184
18:14:21 62920 8192000000 10916007936 11372220416 1949000768 19.219 98 15 31 0 1964895632 11915696 3979168
18:16:30 25123 8192000000 10926512944 11390541824 1991561040 19.556 100 27 54 0 2014875776 15764672 7550064
18:17:55 41495 8192000000 10917106320 11388948480 1966815216 19.360 99 13 26 0 1985471184 15066656 3589312
18:19:38 109465 8192000000 10891030512 11376095232 1890342112 18.749 95 21 42 2 1920756288 23987696 6426480
18:22:27 66709 8192000000 10894438592 11366924288 1956527168 19.278 99 26 51 2 1977779200 13299792 7952240
18:24:59 57896 8192000000 10912294864 11375067136 1966298944 19.356 99 20 40 0 1981642144 9578464 5764736
18:27:21 54411 8192000000 10866938944 11341787136 1942557344 19.167 98 4 8 0 1952588496 9284864 746288
18:29:15 57864 8192000000 10863257664 11338731520 1897705376 18.808 96 14 26 0 1914349504 13086192 3557936
18:31:19 25365 8192000000 10833350816 11300253696 1870180240 18.586 97 11 22 1 1878433888 5579888 2673760
18:32:47 70991 8192000000 10881678384 11344367616 1913191840 18.932 98 19 38 0 1938938400 20219440 5527120
18:35:10 56332 8192000000 10827643024 11288313856 1838126448 18.326 95 16 31 2 1856770144 14480128 4163568
18:37:07 65420 8192000000 10841326144 11289702400 1842124640 18.358 96 24 48 0 1862369056 13680864 6563552
18:39:16 54000 8192000000 10838565264 11310526464 1894838960 18.785 99 25 50 2 1920739264 18490384 7409920
18:41:14 112476 8192000000 10850660240 11334197248 1910461840 18.910 100 10 20 0 1939653968 25869424 3322704
18:44:06 107053 8192000000 10852810048 11320025088 1915178176 18.948 99 13 26 0 1937246096 18568752 3499168
18:47:01 34821 8192000000 10862664096 11318321152 1929252800 19.061 100 34 67 0 1951080864 12406416 9421648
18:48:37 64437 8192000000 10849057216 11335692288 1922456800 19.007 99 7 14 0 1935896976 11595456 1844720
18:50:41 30187 8192000000 10894524544 11349291008 1956805232 19.281 100 35 65 1 1974173856 7495552 9873072
18:52:17 89732 8192000000 10860150688 11337138176 1917973648 18.971 98 18 36 1 1938470048 14827760 5668640
18:55:05 78163 8192000000 10897090928 11374329856 1965865216 19.353 100 5 10 0 1976689152 9760880 1063056
18:57:23 86144 8192000000 10908135488 11392925696 1988562224 19.532 100 11 22 0 2002672656 11136560 2973872
18:59:50 89409 8192000000 10900866112 11373244416 1936259392 19.117 97 19 38 2 1953908640 12568960 5080288
19:02:23 112897 8192000000 10905742912 11386314752 1881267440 18.675 95 13 26 0 1895612752 10269152 4076160
19:05:24 18912 8192000000 10929778992 11375919104 1976726112 19.439 100 67 133 0 2008201632 12821280 18654240
19:06:44 58720 8192000000 10909465104 11360722944 1940624832 19.152 98 36 72 1 1969635072 19012944 9997296
19:08:48 51827 8192000000 10909200128 11358449664 1952381424 19.245 99 31 60 2 1973358208 12856080 8120704
19:10:41 104154 8192000000 10877224224 11344592896 1903966432 18.858 97 28 56 2 1919413120 7192320 8254368
19:13:29 60511 8192000000 10858212016 11338813440 1902396048 18.846 97 6 12 0 1920626032 16622992 1606992
19:15:33 63394 8192000000 10883945888 11358920704 1922972976 19.011 98 16 34 1 1941814640 13989600 4852064
19:17:36 79628 8192000000 10846329728 11322949632 1820194624 18.179 93 5 10 0 1828647952 7052720 1400608
19:19:56 65015 8192000000 10875376896 11344203776 1950892336 19.234 100 25 50 1 1967320144 9358288 7069520
19:22:31 33115 8192000000 10864426080 11328786432 1873661520 18.614 96 21 42 0 1889550816 10186144 5703152
19:24:06 70686 8192000000 10836237264 11308888064 1844738592 18.379 95 11 22 3 1858755264 11240688 2775984
19:26:17 16820 8192000000 10864348160 11313274880 1909389680 18.902 99 41 82 0 1933012400 12264528 11358192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-10-2020 19:27:55 to 16-10-2020 19:28:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 126 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 16-10-2020 19:28:10 to 16-10-2020 19:28:12.

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          243953
MON$OLDEST_ACTIVE               246861
MON$OLDEST_SNAPSHOT             246861
MON$NEXT_TRANSACTION            246861
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                       2021440
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 ---
:243: 
:244: mscs-test-161.budzdorov.ru    Fri Oct 16 19:27:03 2020
:245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:246: 
:247: 
:248: mscs-test-161.budzdorov.ru    Fri Oct 16 19:27:03 2020
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:250: 
:251: 
:252: mscs-test-161.budzdorov.ru    Fri Oct 16 19:27:03 2020
:253:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:254: 
--- end of diff output ---

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

16.10.20 19:28:13. 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-16 19:28:15 No severe PSQL-related problems occured
16.10.20 19:28:17. End of report.