Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.08.2020 18:52
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 369f65bbd7e5442bbb810a88d29f958d
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-957.5.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2019-11-01 09:39
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
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.03
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 32644004 kB
MemFree 16876896 kB
MemAvailable 30928448 kB
Buffers 0 kB
Cached 14774884 kB
SwapCached 6024 kB
Command: fdisk -l

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=fbc, val=/opt/fb30/bin
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=report_compress_cmd, val=/usr/bin/7za
param=host, val=localhost
param=port, val=3333
param=usr, val=SYSDBA
param=pwd, val=masterkey
param=tmpdir, val=/var/tmp/logs-oltp30
param=remove_isql_logs, val=if_no_severe_errors
param=working_mode, val=small_03
param=actions_todo_before_reconnect, val=300
param=max_cps, val=25
param=sleep_max, val=0
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=no_auto_undo, val=1
param=recalc_idx_min_interval, val=30
param=used_in_replication, val=0
param=separate_workers, val=1
param=update_conflict_percent, val=0
param=unit_selection_method, val=random
param=detailed_info, val=0
param=mon_unit_perf, val=2
param=mon_unit_list, val=//
param=mon_query_interval, val=60
param=halt_test_on_errors, val=/CK/
param=qmism_verify_bitset, val=1
param=warm_time, val=30
param=test_time, val=120
param=test_intervals, val=60
param=create_with_fw, val=sync
param=create_with_sweep, val=0
param=wait_if_not_exists, val=0
param=wait_after_create, val=0
param=init_docs, val=0
param=expected_workers, val=100
param=init_buff, val=10000
param=wait_for_copy, val=1
param=create_with_debug_objects, val=1
param=create_with_split_heavy_tabs, val=1
param=create_with_separate_qdistr_idx, val=0
param=create_with_compound_columns_order, val=most_selective_first
param=replace_instance, val=1
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=make_html, val=1
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=file_name_with_test_params, val=regular
param=file_name_this_host_info, val=no_host_info
param=gather_hardware_info, val=1
param=use_mtee, val=0
param=is_embed, val=0
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED.  
DTS_END                         2020-08-19 18:51:55.2730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/50052
TRN_ID                          225205
ATT_ID                          533
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: 8906.79 28521 1068815
customer order: creation 166.41 356 19969
customer order: refuse 14.37 529 1724
order to supplier: creation 43.29 1510 5195
order to supplier: removal 3.49 130779 419
invoice (draft): creation 45.08 3756 5410
invoice (draft): removal 49.37 1268 5924
invoice accept: apply 40.85 23107 4902
invoice accept: cancel 44.01 1014 5281
customer reserve: creation 3862.96 222 463555
customer reserve: removal 4350.27 78 522033
realization accept: apply 81.49 211 9779
realization accept: cancel 80.33 168 9640
payment from customer: creation 57.93 119 6951
payment from customer: removal 5.36 98 643
payment to supplier: creation 46.69 139 5603
payment to supplier: removal 2.98 72 357
service: total inventory turnovers 4.73 1352 568
service: total monetary turnovers 6.32 40 758
service: aggregate perf. data 0.83 27222 100
service: refresh index statistics 0.03 378384 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-08-2020 18:52:48 to 19-08-2020 18:52:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1703
TEST_TIME 2 2111
TEST_TIME 3 3541
TEST_TIME 4 3647
TEST_TIME 5 3376
TEST_TIME 6 3529
TEST_TIME 7 3840
TEST_TIME 8 4113
TEST_TIME 9 4674
TEST_TIME 10 4763
TEST_TIME 11 5030
TEST_TIME 12 5385
TEST_TIME 13 5733
TEST_TIME 14 6015
TEST_TIME 15 5987
TEST_TIME 16 6124
TEST_TIME 17 6390
TEST_TIME 18 6252
TEST_TIME 19 6156
TEST_TIME 20 6298
TEST_TIME 21 6217
TEST_TIME 22 6369
TEST_TIME 23 6545
TEST_TIME 24 6711
TEST_TIME 25 6854
TEST_TIME 26 6924
TEST_TIME 27 7199
TEST_TIME 28 7317
TEST_TIME 29 7685
TEST_TIME 30 7694
TEST_TIME 31 7628
TEST_TIME 32 7561
TEST_TIME 33 7573
TEST_TIME 34 7671
TEST_TIME 35 7794
TEST_TIME 36 7746
TEST_TIME 37 7984
TEST_TIME 38 8003
TEST_TIME 39 8571
TEST_TIME 40 9274
TEST_TIME 41 9294
TEST_TIME 42 9160
TEST_TIME 43 9173
TEST_TIME 44 9216
TEST_TIME 45 9216
TEST_TIME 46 9110
TEST_TIME 47 9102
TEST_TIME 48 9115
TEST_TIME 49 9055
TEST_TIME 50 8918
TEST_TIME 51 8998
TEST_TIME 52 8977
TEST_TIME 53 9000
TEST_TIME 54 8967
TEST_TIME 55 8882
TEST_TIME 56 8850
TEST_TIME 57 8848
TEST_TIME 58 8829
TEST_TIME 59 8890
TEST_TIME 60 8972
TEST_TIME 61 8989
TEST_TIME 62 8932
TEST_TIME 63 8965
TEST_TIME 64 8952
TEST_TIME 65 8861
TEST_TIME 66 8759
TEST_TIME 67 8784
TEST_TIME 68 8854
TEST_TIME 69 8814
TEST_TIME 70 8792
TEST_TIME 71 8766
TEST_TIME 72 8803
TEST_TIME 73 8773
TEST_TIME 74 8727
TEST_TIME 75 8912
TEST_TIME 76 8932
TEST_TIME 77 8909
TEST_TIME 78 8975
TEST_TIME 79 9017
TEST_TIME 80 9066
TEST_TIME 81 9095
TEST_TIME 82 9149
TEST_TIME 83 9138
TEST_TIME 84 9060
TEST_TIME 85 9081
TEST_TIME 86 9054
TEST_TIME 87 9031
TEST_TIME 88 8999
TEST_TIME 89 8993
TEST_TIME 90 9021
TEST_TIME 91 8980
TEST_TIME 92 8944
TEST_TIME 93 8906
TEST_TIME 94 8881
TEST_TIME 95 8896
TEST_TIME 96 8891
TEST_TIME 97 8892
TEST_TIME 98 8891
TEST_TIME 99 8954
TEST_TIME 100 8958
TEST_TIME 101 8917
TEST_TIME 102 8959
TEST_TIME 103 8954
TEST_TIME 104 8968
TEST_TIME 105 9046
TEST_TIME 106 9024
TEST_TIME 107 9040
TEST_TIME 108 9072
TEST_TIME 109 8994
TEST_TIME 110 8945
TEST_TIME 111 8929
TEST_TIME 112 8945
TEST_TIME 113 8887
TEST_TIME 114 8856
TEST_TIME 115 8897
TEST_TIME 116 8864
TEST_TIME 117 8880
TEST_TIME 118 8887
TEST_TIME 119 8908
TEST_TIME 120 8909

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-08-2020 18:52:52 to 19-08-2020 18:52:55.

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 1495896 1495896 0 0.00 0 22938 8 0 0
doc_list_biud 1495896 1495896 0 0.00 0 52136 36 0 0
sp_add_invoice_to_stock 4994 4902 92 1.84 39 223726 23107 0 92
sp_cancel_adding_invoice 5281 5281 0 0.00 5 93983 1015 0 0
sp_cancel_client_order 1724 1724 0 0.00 5 13032 530 0 0
sp_cancel_customer_reserve 522033 522033 0 0.00 1 52137 79 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 4019 98 0 0
sp_cancel_pay_to_supplier 357 357 0 0.00 3 2671 73 0 0
sp_cancel_supplier_invoice 5932 5924 8 0.13 1 38829 1269 0 8
sp_cancel_supplier_order 419 419 0 0.00 7 1178902 130780 0 0
sp_cancel_write_off 9640 9640 0 0.00 1 13159 169 0 0
sp_client_order 19969 19969 0 0.00 4 34643 357 0 0
sp_customer_reserve 463555 463555 0 0.00 0 48390 223 0 0
sp_fill_shopping_cart 30567 30567 0 0.00 1 14720 43 0 0
sp_fill_shopping_cart_clo_res 463551 463551 0 0.00 0 12226 16 0 0
sp_get_clo_for_invoice 4893 4893 0 0.00 0 20308 324 0 0
sp_kill_cost_storno 15921 15921 0 0.00 0 7054 94 0 0
sp_kill_qstorno_ret_qs2qd 1060049 1060049 0 0.00 0 52134 44 0 0
sp_kill_qty_storno 559691 559691 0 0.00 0 52136 94 0 0
sp_lock_selected_doc 20094 20094 0 0.00 0 7102 7 0 0
sp_make_cost_storno 27224 27224 0 0.00 0 22810 66 0 0
sp_multiply_rows_for_pdistr 27224 27224 0 0.00 0 6802 35 0 0
sp_multiply_rows_for_qdistr 19969 19969 0 0.00 0 26115 152 0 0
sp_pay_from_customer 6951 6951 0 0.00 4 17614 120 0 0
sp_pay_to_supplier 5603 5603 0 0.00 4 17342 139 0 0
sp_payment_common 12554 12554 0 0.00 1 10001 29 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 13025 492 0 0
sp_qd_handle_on_invoice_upd_sts 10172 10172 0 0.00 0 22270 439 0 0
sp_qd_handle_on_reserve_upd_sts 9779 9779 0 0.00 0 20682 68 0 0
sp_reserve_write_off 9779 9779 0 0.00 6 20809 212 0 0
sp_supplier_invoice 5410 5410 0 0.00 7 79158 3757 0 0
sp_supplier_order 5195 5195 0 0.00 6 47257 1510 0 0
srv_aggregate_perf_data 100 100 0 0.00 2497 567605 27222 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1393 160878 38161 0 0
srv_find_qd_qs_mism 1021022 1021022 0 0.00 0 14215 7 0 0
srv_make_invnt_saldo 568 568 0 0.00 71 57551 1352 0 0
srv_make_money_saldo 758 758 0 0.00 0 2240 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 279082 563232 378384 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 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 4902 4902 0 0.00 0 37595 252 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 0 3343 129 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 4166 19 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 1523 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 1365 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 357 357 0 0.00 0 1476 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 975 11 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 37267 2479 0 0
t$perf-norm:sp_cancel_write_off 895 895 0 0.00 0 1213 7 0 0
t$perf-norm:sp_client_order 19969 19969 0 0.00 0 8552 25 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 529 6 0 0
t$perf-norm:sp_pay_from_customer 6951 6951 0 0.00 0 20641 17 0 0
t$perf-norm:sp_pay_to_supplier 5603 5603 0 0.00 0 6810 13 0 0
t$perf-norm:sp_reserve_write_off 9779 9779 0 0.00 0 10105 15 0 0
t$perf-norm:sp_supplier_invoice 5410 5410 0 0.00 0 10505 28 0 0
t$perf-norm:sp_supplier_order 5195 5195 0 0.00 0 7683 26 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 806 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 1373 41 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 2298 52 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 1603 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 16 6 0 0
v_add_invoice_to_stock 9964 9780 184 1.84 0 8015 21 0 184
v_all_customers 36594 36594 0 0.00 0 34 1 0 0
v_all_suppliers 29742 29742 0 0.00 0 23 1 0 0
v_all_wares 39938 39938 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 2915 12 0 0
v_cancel_client_order 3448 3448 0 0.00 0 5048 8 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 2137 9 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 401 4 0 0
v_cancel_payment_to_supplier 714 714 0 0.00 0 246 3 0 0
v_cancel_supplier_invoice 766 750 16 2.08 0 317 15 0 16
v_cancel_supplier_order 838 838 0 0.00 0 989 9 0 0
v_cancel_write_off 1790 1790 0 0.00 0 1028 17 0 0
v_max_id_clo_ord 5195 5195 0 0.00 0 711 2 0 0
v_max_id_clo_res 961 961 0 0.00 0 1564 12 0 0
v_max_id_ord_sup 5401 5401 0 0.00 0 2380 2 0 0
v_max_non_paid_invoice 1328 1328 0 0.00 0 536 3 0 0
v_max_non_paid_realizn 3598 3598 0 0.00 0 4129 5 0 0
v_min_id_clo_ord 5195 5195 0 0.00 0 4068 7 0 0
v_min_id_clo_res 961 961 0 0.00 0 1450 6 0 0
v_min_id_ord_sup 5401 5401 0 0.00 0 6243 10 0 0
v_min_non_paid_invoice 5603 5603 0 0.00 0 9998 19 0 0
v_min_non_paid_realizn 6951 6951 0 0.00 0 6743 18 0 0
v_our_firm 10050 10050 0 0.00 0 18 1 0 0
v_reserve_write_off 19558 19558 0 0.00 0 5216 6 0 0
x_lock_depdocs_on_canc_invoice 10830 10830 0 0.00 0 20285 149 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 6046 451 0 0
x_make_qstorno_1000_1200 5195 5195 0 0.00 2 47251 1408 0 0
x_make_qstorno_1200_2000 5410 5410 0 0.00 3 79143 3594 0 0
x_make_qstorno_2100_3300 463555 463555 0 0.00 0 48389 202 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-08-2020 18:52:56 to 19-08-2020 18:53:00.

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:21:58 2 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
16:22:59 36761 8192000000 10352356272 10668445696 1407500240 14.662 100 91 182 1 1443903184 6425040 29977904
16:24:43 24005 8192000000 10416312464 10740125696 1460019680 15.126 100 88 176 0 1496950608 8630256 28300672
16:26:13 21249 8192000000 10479673616 10818068480 1533857104 15.770 100 84 166 2 1570088752 10114432 26117216
16:27:39 14691 8192000000 10528117904 10880753664 1587439392 16.232 100 72 144 3 1620395504 11049264 21906848
16:28:57 11525 8192000000 10587422032 10949967872 1650886000 16.772 100 82 162 1 1687615776 13130496 23599280
16:30:12 5719 8192000000 10653203328 11022217216 1716873824 17.326 100 76 150 3 1750381392 12065024 21442544
16:31:21 10081 8192000000 10695124528 11075551232 1760751600 17.691 100 72 144 0 1793663712 12686192 20225920
16:32:36 2167 8192000000 10757605040 11141541888 1818501888 18.165 100 87 175 2 1856080800 13105440 24473472
16:33:42 2399 8192000000 10782777072 11166556160 1842336576 18.360 100 89 176 0 1879208608 11790608 25081424
16:34:48 2141 8192000000 10797418672 11184246784 1863228992 18.529 100 83 166 0 1899372880 13078320 23065568
16:35:54 1406 8192000000 10813468960 11196166144 1867350528 18.563 100 92 183 1 1907815280 14614448 25850304
16:36:57 2435 8192000000 10795963072 11205459968 1873595520 18.613 100 53 106 1 1900076528 11995056 14485952
16:38:01 6321 8192000000 10809093824 11217526784 1885836192 18.712 100 53 105 2 1908479728 7138608 15504928
16:39:15 14308 8192000000 10825598608 11228315648 1891888960 18.761 100 55 109 3 1914518864 7923328 14706576
16:40:35 1480 8192000000 10830252512 11239759872 1897384384 18.805 100 61 118 1 1924094864 10405424 16305056
16:41:38 7132 8192000000 10809615088 11238797312 1897998112 18.810 100 18 36 0 1908489568 5177952 5313504
16:42:49 58314 8192000000 10790820992 11183845376 1798576832 18.002 95 43 87 0 1817392000 5015888 13799280
16:44:48 3432 8192000000 10755972128 11150888960 1781371760 17.861 96 55 112 2 1801868416 4662640 15834016
16:45:54 1474 8192000000 10776713840 11178233856 1822114720 18.195 98 67 134 2 1847691840 6331072 19246048
16:46:56 51308 8192000000 10688407968 11069849600 1737683824 17.499 97 51 98 3 1757805648 4950736 15171088
16:48:49 7747 8192000000 10692638048 11100176384 1764978944 17.726 98 29 58 0 1780151632 6507136 8665552
16:49:57 7606 8192000000 10731238048 11142676480 1812383168 18.115 100 25 49 2 1824625600 4747520 7494912
16:51:10 16911 8192000000 10743048880 11166900224 1840122144 18.342 100 35 71 2 1855737584 5220352 10395088
16:52:53 27808 8192000000 10804548816 11208974336 1869128256 18.577 100 50 100 0 1892886976 10194496 13564224
16:54:36 10768 8192000000 10819633440 11233005568 1888694640 18.735 100 47 92 0 1913849408 12025776 13128992
16:56:00 40426 8192000000 10832832768 11255029760 1905165760 18.868 100 48 95 3 1933251344 12950128 15135456
16:57:46 50836 8192000000 10842769616 11271839744 1917491504 18.967 100 37 74 2 1943546256 15243600 10811152
16:59:43 11755 8192000000 10859217904 11282014208 1921267136 18.997 100 33 65 1 1947955808 16453968 10234704
17:01:13 3393 8192000000 10885179824 11304325120 1941044224 19.155 100 64 127 1 1981415456 21762144 18609088
17:02:27 3246 8192000000 10910020544 11323990016 1959035408 19.298 100 74 148 2 2004514560 24267120 21212032
17:03:35 10467 8192000000 10924146176 11342630912 1965452064 19.349 100 66 132 0 2012382192 27066480 19863648
17:04:56 4925 8192000000 10930851728 11345534976 1969272160 19.380 100 72 142 1 2016672112 26393056 21006896
17:06:11 6754 8192000000 10947734992 11362496512 1982283440 19.483 100 69 138 2 2029859472 27130240 20445792
17:07:22 2972 8192000000 10941090352 11360706560 1978962160 19.456 100 74 148 0 2029509968 27685152 22862656
17:08:32 23440 8192000000 10919744848 11347689472 1967892944 19.369 100 52 104 0 2007591280 24450096 15248240
17:10:14 9140 8192000000 10949510016 11377020928 1992848928 19.566 100 55 110 0 2034909584 25037120 17023536
17:11:31 3359 8192000000 10951058080 11373432832 1979536864 19.461 99 63 118 2 2025263808 27274032 18452912
17:12:42 3447 8192000000 10955522704 11375218688 1976505008 19.437 99 65 131 2 2026983040 30462960 20015072
17:13:54 5095 8192000000 10967808960 11394383872 2001987840 19.638 100 66 131 1 2057000688 34441104 20571744
17:15:08 6502 8192000000 10941067200 11362721792 1970707632 19.391 100 74 148 0 2029109776 34221200 24180944
17:16:21 1392 8192000000 10966171696 11379539968 1973645968 19.414 99 80 160 1 2038043536 39380624 25016944
17:17:33 2387 8192000000 10967344016 11374960640 1975193136 19.427 100 87 174 0 2045916112 43776528 26946448
17:18:42 5940 8192000000 10957696832 11372998656 1971907968 19.401 99 77 152 4 2037246496 42343632 22994896
17:19:58 2662 8192000000 10987485248 11402268672 2000446560 19.626 100 88 176 1 2070954960 43600448 26907952
17:21:07 21431 8192000000 10956891968 11393638400 1987384496 19.523 100 49 96 1 2043464192 39910688 16169008
17:22:39 13221 8192000000 10962949280 11397267456 1986786016 19.518 99 40 81 1 2036785360 36917088 13082256
17:24:04 4050 8192000000 10960573616 11387469824 1985391840 19.507 100 56 112 2 2048788928 45237664 18159424
17:25:24 25598 8192000000 10952784256 11391881216 1977610384 19.446 100 32 64 0 2036544864 47448592 11485888
17:26:58 14971 8192000000 10958320224 11406794752 1982892496 19.488 100 32 64 1 2046628256 52652608 11083152
17:28:17 21063 8192000000 10953530704 11403247616 1974135136 19.418 100 33 65 3 2044406096 58609520 11661440
17:29:47 21622 8192000000 10953060288 11407810560 1969421040 19.381 100 28 55 1 2038839936 58701120 10717776
17:31:26 18439 8192000000 10895243072 11366391808 1960240704 19.308 100 17 34 1 1983539552 18079456 5219392
17:32:48 36757 8192000000 10887539152 11328593920 1915606992 18.952 98 46 93 3 1940964160 11094224 14262944
17:34:41 47063 8192000000 10864431152 11327000576 1914004832 18.939 99 13 26 2 1929116768 11527808 3584128
17:36:30 54163 8192000000 10866059488 11313807360 1894813984 18.785 98 15 30 1 1912143280 13100032 4229264
17:38:25 82967 8192000000 10844723552 11301351424 1884814096 18.704 98 29 58 3 1914629168 21819952 7995120
17:40:50 61826 8192000000 10855110864 11291353088 1897416944 18.806 98 44 89 2 1918706480 9428864 11860672
17:42:58 43915 8192000000 10872704016 11321753600 1906851408 18.881 98 35 69 0 1925153648 8426592 9875648
17:44:45 28726 8192000000 10872429760 11331297280 1940886784 19.154 100 31 62 0 1965618736 15658656 9073296
17:46:15 41247 8192000000 10880913888 11345752064 1951870656 19.241 100 21 42 2 1972957760 15192624 5894480
17:47:58 31302 8192000000 10885300656 11341455360 1943561280 19.175 100 23 46 0 1961016016 10957488 6497248
17:49:33 25493 8192000000 10903081728 11346620416 1950104784 19.227 100 34 68 5 1977555472 17918416 9532272
17:51:02 26655 8192000000 10892696992 11356733440 1960730416 19.312 100 29 56 3 1987871792 18397904 8743472
17:52:31 94191 8192000000 10834998864 11294822400 1877556976 18.645 96 10 20 1 1890992656 10607392 2828288
17:55:07 24988 8192000000 10874777216 11328675840 1950519856 19.231 100 36 72 1 1974450464 13833744 10096864
17:56:33 107200 8192000000 10887151728 11361644544 1959867056 19.305 100 16 32 0 1981879552 17353744 4658752
17:59:30 66254 8192000000 10860909120 11333345280 1898027904 18.810 97 7 14 0 1912111456 12570176 1513376
18:01:49 28805 8192000000 10869406560 11328368640 1935146544 19.108 99 25 49 0 1950741680 9109872 6485264
18:03:19 79841 8192000000 10888240144 11350376448 1901342176 18.837 97 13 26 0 1924124720 18868896 3913648
18:05:41 49957 8192000000 10876409584 11356962816 1921515840 18.999 98 21 42 2 1948438160 20307232 6615088
18:07:54 66010 8192000000 10889504128 11336630272 1919280704 18.981 99 12 23 1 1935811648 13185088 3345856
18:10:02 72145 8192000000 10868710464 11340423168 1894616128 18.783 97 16 32 1 1916340864 17465888 4258848
18:12:15 57243 8192000000 10879025920 11347697664 1896260688 18.796 97 13 26 1 1913048192 12621584 4165920
18:14:17 160878 8192000000 10869838352 11336790016 1916385056 18.958 98 31 62 1 1937184064 11727120 9071888
18:18:03 69867 8192000000 10864752592 11314081792 1918220160 18.973 99 41 82 0 1940118320 10454320 11443840
18:20:19 18406 8192000000 10884153424 11321262080 1941175824 19.156 100 61 121 5 1966970752 8598880 17196048
18:21:38 44456 8192000000 10858720848 11332964352 1916290144 18.957 98 10 20 0 1928981936 9726224 2965568
18:23:25 72897 8192000000 10877875056 11336318976 1949205568 19.220 100 10 20 0 1957259168 5603952 2449648
18:25:39 54393 8192000000 10865208560 11340038144 1932980048 19.091 99 8 16 0 1944814512 9650048 2184416
18:28:19 74580 8192000000 10873150400 11323641856 1889241648 18.740 97 34 68 2 1912697344 13973904 9481792
18:30:41 52678 8192000000 10873093984 11331297280 1928891360 19.058 99 28 56 0 1947704464 11151680 7661424
18:33:21 45160 8192000000 10870110288 11329044480 1920470416 18.991 99 26 52 1 1937816864 10422400 6924048
18:35:10 50376 8192000000 10877622784 11347398656 1956179440 19.276 100 25 50 0 1974390576 10854688 7356448
18:37:19 30071 8192000000 10886066688 11327635456 1907585488 18.887 98 38 75 0 1928641712 10932288 10123936
18:38:50 57028 8192000000 10883025360 11349069824 1921057696 18.995 98 21 42 0 1940035200 13320704 5656800
18:40:48 112626 8192000000 10860282944 11329654784 1883924736 18.697 96 25 50 2 1900160352 8748240 7487376
18:43:43 62055 8192000000 10870299584 11352383488 1939503760 19.143 99 10 20 0 1955700640 13310192 2886688
18:45:48 37271 8192000000 10865521648 11334950912 1946046704 19.195 100 18 37 1 1963897200 12543104 5307392
18:47:26 62993 8192000000 10848564912 11312693248 1907294944 18.885 98 13 26 2 1922875104 11434416 4145744
18:49:31 41960 8192000000 10875635408 11342053376 1948363888 19.213 100 16 32 0 1963488160 10206096 4918176
18:51:13 43176 8192000000 9414272288 9710485504 533662800 6.116 28 7 13 0 537569216 2319344 1587072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-08-2020 18:53:01 to 19-08-2020 18:53:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 92 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 19-08-2020 18:53:16 to 19-08-2020 18:53: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          225525
MON$OLDEST_ACTIVE               225526
MON$OLDEST_SNAPSHOT             225526
MON$NEXT_TRANSACTION            225526
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                       2030688
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.33356 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33356 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33356 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33356 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    Wed Aug 19 18:51:58 2020
:245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:246: 
:247: 
:248: mscs-test-161.budzdorov.ru    Wed Aug 19 18:51:58 2020
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:250: 
--- end of diff output ---

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

19.08.20 18:53:20. 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: 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-08-19 18:53:22 NO_SEVERE_ERRORS_FOUND
19.08.20 18:53:24. End of report.