Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.08.2020 21: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 16942924 kB
MemAvailable 30949704 kB
Buffers 0 kB
Cached 14706748 kB
SwapCached 6164 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-14 21:51:56.9900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/37276
TRN_ID                          228592
ATT_ID                          600
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: 8714.73 29263 1045768
customer order: creation 167.62 406 20114
customer order: refuse 13.98 474 1678
order to supplier: creation 44.18 1550 5302
order to supplier: removal 3.28 151710 393
invoice (draft): creation 45.24 4302 5429
invoice (draft): removal 48.74 1162 5849
invoice accept: apply 40.17 23271 4821
invoice accept: cancel 43.84 1333 5261
customer reserve: creation 3726.21 225 447145
customer reserve: removal 4285.65 90 514278
realization accept: apply 82.43 238 9891
realization accept: cancel 85.24 163 10229
payment from customer: creation 58.06 152 6967
payment from customer: removal 5.61 92 673
payment to supplier: creation 48.81 175 5857
payment to supplier: removal 3.58 106 429
service: total inventory turnovers 4.21 1668 505
service: total monetary turnovers 7.03 48 844
service: aggregate perf. data 0.83 21136 99
service: refresh index statistics 0.03 376955 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-08-2020 21:52:56 to 14-08-2020 21:52:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3052
TEST_TIME 2 2394
TEST_TIME 3 3440
TEST_TIME 4 3368
TEST_TIME 5 3736
TEST_TIME 6 4295
TEST_TIME 7 4094
TEST_TIME 8 4424
TEST_TIME 9 4430
TEST_TIME 10 4435
TEST_TIME 11 4577
TEST_TIME 12 4534
TEST_TIME 13 4439
TEST_TIME 14 4425
TEST_TIME 15 4599
TEST_TIME 16 4586
TEST_TIME 17 4623
TEST_TIME 18 4721
TEST_TIME 19 4829
TEST_TIME 20 4875
TEST_TIME 21 4855
TEST_TIME 22 5378
TEST_TIME 23 5515
TEST_TIME 24 5563
TEST_TIME 25 5664
TEST_TIME 26 5749
TEST_TIME 27 5714
TEST_TIME 28 6363
TEST_TIME 29 6608
TEST_TIME 30 7073
TEST_TIME 31 7329
TEST_TIME 32 7333
TEST_TIME 33 7470
TEST_TIME 34 7432
TEST_TIME 35 7640
TEST_TIME 36 8257
TEST_TIME 37 8349
TEST_TIME 38 8398
TEST_TIME 39 8414
TEST_TIME 40 9254
TEST_TIME 41 9053
TEST_TIME 42 9034
TEST_TIME 43 8938
TEST_TIME 44 8981
TEST_TIME 45 8936
TEST_TIME 46 8807
TEST_TIME 47 8768
TEST_TIME 48 8719
TEST_TIME 49 8713
TEST_TIME 50 8717
TEST_TIME 51 8685
TEST_TIME 52 8700
TEST_TIME 53 8818
TEST_TIME 54 8711
TEST_TIME 55 8850
TEST_TIME 56 8786
TEST_TIME 57 8767
TEST_TIME 58 8761
TEST_TIME 59 8655
TEST_TIME 60 8584
TEST_TIME 61 8599
TEST_TIME 62 8591
TEST_TIME 63 8573
TEST_TIME 64 8540
TEST_TIME 65 8524
TEST_TIME 66 8496
TEST_TIME 67 8487
TEST_TIME 68 8489
TEST_TIME 69 8470
TEST_TIME 70 8647
TEST_TIME 71 8715
TEST_TIME 72 8747
TEST_TIME 73 8673
TEST_TIME 74 8745
TEST_TIME 75 8813
TEST_TIME 76 8831
TEST_TIME 77 8925
TEST_TIME 78 8938
TEST_TIME 79 8886
TEST_TIME 80 8803
TEST_TIME 81 8816
TEST_TIME 82 8823
TEST_TIME 83 8801
TEST_TIME 84 8790
TEST_TIME 85 8748
TEST_TIME 86 8740
TEST_TIME 87 8752
TEST_TIME 88 8775
TEST_TIME 89 8737
TEST_TIME 90 8676
TEST_TIME 91 8622
TEST_TIME 92 8664
TEST_TIME 93 8616
TEST_TIME 94 8596
TEST_TIME 95 8620
TEST_TIME 96 8598
TEST_TIME 97 8685
TEST_TIME 98 8643
TEST_TIME 99 8726
TEST_TIME 100 8700
TEST_TIME 101 8744
TEST_TIME 102 8758
TEST_TIME 103 8724
TEST_TIME 104 8789
TEST_TIME 105 8822
TEST_TIME 106 8853
TEST_TIME 107 8834
TEST_TIME 108 8819
TEST_TIME 109 8792
TEST_TIME 110 8771
TEST_TIME 111 8730
TEST_TIME 112 8766
TEST_TIME 113 8722
TEST_TIME 114 8694
TEST_TIME 115 8659
TEST_TIME 116 8703
TEST_TIME 117 8721
TEST_TIME 118 8697
TEST_TIME 119 8707
TEST_TIME 120 8729

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-08-2020 21:52:59 to 14-08-2020 21:53:02.

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 1457643 1457643 0 0.00 0 17759 9 0 0
doc_list_biud 1457643 1457643 0 0.00 0 46742 40 0 0
sp_add_invoice_to_stock 4947 4821 126 2.54 37 210762 23271 0 126
sp_cancel_adding_invoice 5261 5261 0 0.00 4 98319 1333 0 0
sp_cancel_client_order 1678 1678 0 0.00 5 9581 475 0 0
sp_cancel_customer_reserve 514278 514278 0 0.00 1 46746 91 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 2 2841 92 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 2748 106 0 0
sp_cancel_supplier_invoice 5861 5849 12 0.20 2 26607 1162 0 12
sp_cancel_supplier_order 393 393 0 0.00 7 1563185 151711 0 0
sp_cancel_write_off 10229 10229 0 0.00 1 20073 164 0 0
sp_client_order 20114 20114 0 0.00 4 46464 406 0 0
sp_customer_reserve 447145 447145 0 0.00 0 73559 225 0 0
sp_fill_shopping_cart 30845 30845 0 0.00 1 26274 47 0 0
sp_fill_shopping_cart_clo_res 447133 447133 0 0.00 0 16754 18 0 0
sp_get_clo_for_invoice 4802 4802 0 0.00 0 23499 349 0 0
sp_kill_cost_storno 16592 16592 0 0.00 0 9861 100 0 0
sp_kill_qstorno_ret_qs2qd 1045027 1045027 0 0.00 0 46719 49 0 0
sp_kill_qty_storno 552381 552381 0 0.00 0 46741 105 0 0
sp_lock_selected_doc 20391 20391 0 0.00 0 10822 5 0 0
sp_make_cost_storno 27517 27517 0 0.00 0 17747 73 0 0
sp_multiply_rows_for_pdistr 27517 27517 0 0.00 0 7649 37 0 0
sp_multiply_rows_for_qdistr 20114 20114 0 0.00 0 46456 186 0 0
sp_pay_from_customer 6967 6967 0 0.00 4 36007 153 0 0
sp_pay_to_supplier 5857 5857 0 0.00 5 17786 175 0 0
sp_payment_common 12824 12824 0 0.00 1 36001 45 0 0
sp_qd_handle_on_cancel_clo 1678 1678 0 0.00 0 9576 450 0 0
sp_qd_handle_on_invoice_upd_sts 10063 10063 0 0.00 0 18890 514 0 0
sp_qd_handle_on_reserve_upd_sts 9891 9891 0 0.00 0 28191 84 0 0
sp_reserve_write_off 9891 9891 0 0.00 6 28267 238 0 0
sp_supplier_invoice 5429 5429 0 0.00 7 104541 4303 0 0
sp_supplier_order 5302 5302 0 0.00 5 31983 1551 0 0
srv_aggregate_perf_data 99 99 0 0.00 2295 260660 21136 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1219 109884 36620 0 0
srv_find_qd_qs_mism 998052 998052 0 0.00 0 14453 7 0 0
srv_make_invnt_saldo 505 505 0 0.00 38 135376 1669 0 0
srv_make_money_saldo 844 844 0 0.00 1 6115 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 258371 488176 376956 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 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4821 4821 0 0.00 0 20392 278 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 7210 178 0 0
t$perf-norm:sp_cancel_client_order 1678 1678 0 0.00 0 3138 24 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 3468 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 737 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 1094 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 4843 33 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 44501 2765 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 3823 10 0 0
t$perf-norm:sp_client_order 20114 20114 0 0.00 0 7247 24 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 7120 24 0 0
t$perf-norm:sp_pay_from_customer 6967 6967 0 0.00 0 7167 16 0 0
t$perf-norm:sp_pay_to_supplier 5857 5857 0 0.00 0 8340 16 0 0
t$perf-norm:sp_reserve_write_off 9891 9891 0 0.00 0 23412 19 0 0
t$perf-norm:sp_supplier_invoice 5429 5429 0 0.00 0 7832 28 0 0
t$perf-norm:sp_supplier_order 5302 5302 0 0.00 0 3145 24 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 396 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 1693 26 0 0
t$perf-norm:srv_make_invnt_saldo 505 505 0 0.00 0 1779 42 0 0
t$perf-norm:srv_make_money_saldo 844 844 0 0.00 0 4336 37 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 5 0 0
v_add_invoice_to_stock 9854 9602 252 2.55 0 7865 18 0 252
v_all_customers 36888 36888 0 0.00 0 140 1 0 0
v_all_suppliers 30046 30046 0 0.00 0 18 1 0 0
v_all_wares 40228 40228 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 540 8 0 0
v_cancel_client_order 3356 3356 0 0.00 0 1815 5 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 976 5 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 1478 8 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 2742 7 0 0
v_cancel_supplier_invoice 898 874 24 2.67 0 2744 18 0 24
v_cancel_supplier_order 786 786 0 0.00 0 2100 10 0 0
v_cancel_write_off 1920 1920 0 0.00 0 3089 18 0 0
v_max_id_clo_ord 5302 5302 0 0.00 0 722 2 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 5353 22 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 6554 4 0 0
v_max_non_paid_invoice 1565 1565 0 0.00 0 11018 10 0 0
v_max_non_paid_realizn 3583 3583 0 0.00 0 2487 4 0 0
v_min_id_clo_ord 5302 5302 0 0.00 0 5774 11 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 2750 7 0 0
v_min_id_ord_sup 5429 5429 0 0.00 0 8667 7 0 0
v_min_non_paid_invoice 5857 5857 0 0.00 0 9063 25 0 0
v_min_non_paid_realizn 6967 6967 0 0.00 0 24500 29 0 0
v_our_firm 10108 10108 0 0.00 0 21 1 0 0
v_reserve_write_off 19780 19780 0 0.00 0 3021 5 0 0
x_lock_depdocs_on_canc_invoice 10673 10673 0 0.00 0 18719 173 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 26379 623 0 0
x_make_qstorno_1000_1200 5302 5302 0 0.00 1 31915 1446 0 0
x_make_qstorno_1200_2000 5429 5429 0 0.00 4 104517 4131 0 0
x_make_qstorno_2100_3300 447145 447145 0 0.00 0 73526 203 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-08-2020 21:53:03 to 14-08-2020 21:53:07.

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
19:22:00 2 8192000000 8798382336 8808857600 5057328 0.061 1 1 2 0 5175376 55488 62560
19:23:01 39054 8192000000 10390381920 10712461312 1428138512 14.845 100 83 166 0 1462139328 6674032 27326784
19:24:47 12901 8192000000 10438483472 10774380544 1496018304 15.441 100 79 158 0 1530024096 8644896 25360896
19:26:03 16987 8192000000 10480766032 10818002944 1533036320 15.763 100 87 174 0 1570913792 10270976 27606496
19:27:24 13741 8192000000 10530226608 10880962560 1584181072 16.204 100 80 161 1 1620018432 10438752 25398608
19:28:46 9234 8192000000 10589270480 10946867200 1650216864 16.766 100 80 160 3 1685858848 11626464 24015520
19:30:03 7111 8192000000 10642731152 11012227072 1710714624 17.275 100 74 150 1 1744097552 12216720 21166208
19:31:13 6312 8192000000 10689400384 11064053760 1753329712 17.629 100 85 171 0 1791093120 13193904 24569504
19:32:22 3781 8192000000 10735241984 11122053120 1800475072 18.018 100 86 171 2 1838584832 13352496 24757264
19:33:28 2249 8192000000 10780194784 11160977408 1833700944 18.290 100 90 179 2 1873818096 14941072 25176080
19:34:34 1630 8192000000 10790827216 11180847104 1856103120 18.472 100 90 179 1 1894829200 12998048 25728032
19:35:39 3054 8192000000 10799663664 11193511936 1865419552 18.547 100 72 143 1 1895134400 9821216 19893632
19:36:46 7310 8192000000 10796974240 11197521920 1871855568 18.599 100 64 128 1 1901605488 10806240 18943680
19:37:56 5582 8192000000 10779753184 11202965504 1869734768 18.582 100 20 40 0 1881736864 6514496 5487600
19:39:05 12062 8192000000 10781810704 11212447744 1877245200 18.643 100 13 25 2 1886373232 5575264 3552768
19:40:20 9605 8192000000 10794957984 11223228416 1888925232 18.737 100 22 44 0 1901202624 5643072 6634320
19:41:31 60464 8192000000 10801620928 11234791424 1893032432 18.770 100 22 44 2 1905152480 5075440 7044608
19:43:33 5381 8192000000 10795160656 11222048768 1851768320 18.436 98 17 34 0 1862288880 5832224 4688336
19:44:40 12937 8192000000 10741498272 11168673792 1796974512 17.989 97 12 24 1 1806072736 5302128 3796096
19:46:00 53326 8192000000 10724526704 11150536704 1753360992 17.629 95 35 69 1 1768728592 5311728 10055872
19:47:55 7841 8192000000 10695665840 11111555072 1755617520 17.648 98 25 50 3 1769384672 6622688 7144464
19:49:06 7565 8192000000 10727021824 11141439488 1811217024 18.106 100 26 52 0 1825082208 5998912 7866272
19:50:14 8324 8192000000 10746268192 11160129536 1825696240 18.224 100 23 46 0 1837884080 5655584 6532256
19:51:27 27001 8192000000 10757006160 11174858752 1843607920 18.370 100 41 81 0 1861858240 6204416 12045904
19:53:07 64222 8192000000 10821348736 11236106240 1882444688 18.685 100 43 84 2 1902927264 8262368 12220208
19:55:19 13016 8192000000 10855915792 11268640768 1916254400 18.957 100 56 111 0 1946696592 15358496 15083696
19:56:51 11901 8192000000 10874875440 11283382272 1924680416 19.024 100 54 106 0 1959318400 18985840 15652144
19:58:09 39801 8192000000 10876460512 11302244352 1934631840 19.104 100 44 88 2 1970628512 23099984 12896688
19:59:55 10052 8192000000 10887199904 11313856512 1948333232 19.213 100 46 89 3 1984687488 22346096 14008160
20:01:11 41287 8192000000 10921909728 11344072704 1962931488 19.329 100 44 86 2 2002738000 26882576 12923936
20:03:13 24992 8192000000 10945376608 11374051328 1987290448 19.522 100 45 90 1 2033990176 32626768 14072960
20:04:42 8890 8192000000 10971798240 11394150400 2002972576 19.646 100 64 128 1 2060436608 38970560 18493472
20:05:59 3742 8192000000 11006757712 11420401664 2021590800 19.793 100 80 156 1 2093312336 48415328 23306208
20:07:10 5170 8192000000 11003161488 11415580672 2016771488 19.755 100 80 160 0 2086436448 46088592 23576368
20:08:23 6836 8192000000 11009621296 11429957632 2024446016 19.815 100 74 146 1 2097370800 50121248 22803536
20:09:35 4751 8192000000 11013657904 11436998656 2027520192 19.839 100 70 140 0 2104023216 54608624 21894400
20:10:51 8083 8192000000 11019387088 11446046720 2028490048 19.847 100 63 122 4 2106069216 58454880 19124288
20:12:24 3291 8192000000 11015173792 11434971136 2015580272 19.745 100 75 147 2 2103112048 63713088 23818688
20:13:35 2386 8192000000 11035869408 11444645888 2021837856 19.795 100 90 179 0 2111440864 62582096 27020912
20:14:47 3148 8192000000 11017441696 11431178240 2012353152 19.720 100 72 144 0 2096080112 61069280 22657680
20:15:58 3947 8192000000 10981665936 11400445952 1974753136 19.423 100 67 130 1 2062402416 66938208 20711072
20:17:14 2836 8192000000 10991098416 11394428928 1939165344 19.140 98 94 186 1 2039905056 71412560 29327152
20:18:28 6005 8192000000 11001868160 11407032320 1971987504 19.401 100 90 180 0 2082593696 81163312 29442880
20:19:44 4106 8192000000 10998503408 11410030592 1978100432 19.450 100 89 176 2 2084738592 77639024 28999136
20:20:57 4568 8192000000 10999645680 11409608704 1990203280 19.545 100 82 164 5 2080326000 63878352 26244368
20:22:06 13802 8192000000 10973479472 11404595200 1985559904 19.509 100 52 103 1 2056188384 54187296 16441184
20:23:34 14422 8192000000 10946559728 11383832576 1944209216 19.180 99 43 86 3 2015683952 57597904 13876832
20:24:54 1218 8192000000 10975854528 11392622592 1971291184 19.396 100 82 160 3 2049976080 53181408 25503488
20:26:03 10716 8192000000 10961524528 11409039360 1971506240 19.397 100 32 64 2 2046699104 64271488 10921376
20:27:19 18963 8192000000 10942297568 11399278592 1970028240 19.386 100 23 46 3 2030530112 52692368 7809504
20:29:01 25169 8192000000 10921149072 11381116928 1968423248 19.373 100 13 27 0 2012245472 38776112 5046112
20:30:33 4183 8192000000 10960485040 11391922176 1971477744 19.397 100 48 96 3 2031159360 45145072 14536544
20:31:40 62931 8192000000 10885666624 11326369792 1890298384 18.748 96 36 69 1 1909743808 9314096 10131328
20:33:51 39573 8192000000 10870047376 11309170688 1887634448 18.727 97 20 40 0 1899826848 6920432 5271968
20:35:37 35920 8192000000 10853696864 11310088192 1880527008 18.669 97 17 34 0 1897981520 12771984 4682528
20:37:21 71329 8192000000 10900238752 11340058624 1935300720 19.109 99 28 56 0 1949691008 6656352 7733936
20:39:34 17424 8192000000 10882174064 11327848448 1939502304 19.143 99 30 58 1 1961476128 13187392 8786432
20:41:02 8242 8192000000 10904255408 11342344192 1958557408 19.295 100 44 84 3 1986476784 15058112 12861264
20:42:15 48606 8192000000 10920361440 11366948864 1968423264 19.373 100 47 94 1 2003855328 21864128 13567936
20:44:55 34128 8192000000 10874037968 11353858048 1919978560 18.987 98 11 22 0 1945650608 22025808 3646240
20:46:31 31956 8192000000 10871801328 11310342144 1932401840 19.086 99 25 48 0 1945866864 6318816 7146208
20:48:04 31816 8192000000 10887647136 11343597568 1954969968 19.266 100 16 31 0 1968482192 9144464 4367760
20:49:39 52454 8192000000 10857747600 11321098240 1913104576 18.932 98 19 37 0 1926735184 8499712 5130896
20:51:31 73647 8192000000 10865233120 11329470464 1899786112 18.825 97 24 46 1 1921911312 14683328 7441872
20:53:54 53528 8192000000 10851337104 11302313984 1887353744 18.724 97 23 46 0 1902644736 9210304 6080688
20:56:22 31273 8192000000 10851697952 11303432192 1912146496 18.924 98 45 88 2 1934175680 9703152 12326032
20:58:09 51023 8192000000 10845245680 11312029696 1898011168 18.810 98 8 16 0 1909876800 9724448 2141184
21:00:01 52668 8192000000 10885350096 11339034624 1924390352 19.022 98 29 58 1 1949680624 17120400 8169872
21:01:55 61843 8192000000 10830031504 11293028352 1872654432 18.606 97 15 30 1 1895128880 17842592 4631856
21:03:59 47037 8192000000 10864140864 11298934784 1855936352 18.470 96 21 41 3 1868896448 7722160 5237936
21:05:50 56074 8192000000 10862265264 11325267968 1907516880 18.887 99 33 66 3 1943374016 26343072 9514064
21:07:51 109884 8192000000 10853141664 11326210048 1864493728 18.540 96 11 22 1 1886242608 18752384 2996496
21:10:52 101521 8192000000 10852007008 11298279424 1905960416 18.874 99 38 75 0 1926795536 10112912 10722208
21:14:21 12816 8192000000 10843581344 11297759232 1900021744 18.826 99 28 53 1 1920932688 13381760 7529184
21:15:37 58329 8192000000 10827536176 11293581312 1864691728 18.541 97 13 26 1 1883882272 15287616 3902928
21:18:45 47389 8192000000 10847352384 11319058432 1927018928 19.043 100 15 30 0 1944282928 12450112 4813888
21:20:32 50211 8192000000 10850432752 11317194752 1936307184 19.117 100 21 42 0 1950016608 6712848 6996576
21:22:24 15611 8192000000 10864031040 11320926208 1937815360 19.129 100 41 80 1 1963649904 15162128 10672416
21:23:47 50658 8192000000 10843889296 11316178944 1924942624 19.026 100 9 18 0 1940008256 13153872 1911760
21:25:47 72967 8192000000 10867011408 11331051520 1911481456 18.919 98 15 31 0 1925435136 10081760 3871920
21:28:00 84007 8192000000 10903977184 11368329216 1953122448 19.251 99 21 39 3 1967675040 9746704 4805888
21:30:25 82039 8192000000 10896385376 11364474880 1908904704 18.898 96 20 40 3 1933436608 19297072 5234832
21:32:49 88471 8192000000 10886975680 11348803584 1896270816 18.796 96 14 28 0 1911160176 11049136 3840224
21:35:18 30379 8192000000 10887164080 11337465856 1942842992 19.169 100 30 60 1 1964768048 13640064 8284992
21:36:51 82883 8192000000 10892098240 11365470208 1921039424 18.995 98 16 32 0 1936815328 10935520 4840384
21:39:16 75718 8192000000 10886853040 11330052096 1934641440 19.104 99 42 85 1 1957241408 10053536 12546432
21:41:33 71218 8192000000 10869830288 11323518976 1933702304 19.096 100 37 74 3 1961562880 17240032 10620544
21:43:44 51667 8192000000 10858541104 11334107136 1937270064 19.125 100 10 20 0 1952608144 12363664 2974416
21:45:57 73520 8192000000 10844142672 11308511232 1874481520 18.621 96 13 26 0 1890811984 12291200 4039264
21:48:14 58128 8192000000 10841675760 11317489664 1878335744 18.652 97 9 18 0 1892457456 11804640 2317072
21:50:12 27057 8192000000 10853930544 11301441536 1874298864 18.619 97 38 76 1 1895852784 10649056 10904864
21:51:39 22625 8192000000 9516923344 9826476032 664450256 7.502 35 8 16 0 669758288 3465552 1842480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 126 v_add_invoice_to_stock 335544517
user_exc 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-08-2020 21:53:23 to 14-08-2020 21:53:25.

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          228836
MON$OLDEST_ACTIVE               228837
MON$OLDEST_SNAPSHOT             228837
MON$NEXT_TRANSACTION            228837
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                       2028640
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.33352 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33352 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33352 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33352 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 ---
:240: 
:241: mscs-test-161.budzdorov.ru    Fri Aug 14 21:52:03 2020
:242:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:243: 
:244: 
:245: mscs-test-161.budzdorov.ru    Fri Aug 14 21:52:04 2020
:246:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:247: 
:248: 
:249: mscs-test-161.budzdorov.ru    Fri Aug 14 21:52:04 2020
:250:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:251: 
--- end of diff output ---

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

14.08.20 21:53:27. 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-14 21:53:29 NO_SEVERE_ERRORS_FOUND
14.08.20 21:53:31. End of report.