Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.08.2020 00: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 16638344 kB
MemAvailable 30948428 kB
Buffers 0 kB
Cached 15027168 kB
SwapCached 5980 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 00:51:57.5150
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/40594
TRN_ID                          223591
ATT_ID                          526
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: 8570.11 32074 1028413
customer order: creation 162.53 367 19504
customer order: refuse 14.55 537 1746
order to supplier: creation 44.05 1473 5286
order to supplier: removal 3.12 173060 374
invoice (draft): creation 43.76 4498 5251
invoice (draft): removal 46.28 1394 5554
invoice accept: apply 41.00 25170 4920
invoice accept: cancel 41.75 1481 5010
customer reserve: creation 3765.18 245 451821
customer reserve: removal 4133.13 99 495975
realization accept: apply 79.85 237 9582
realization accept: cancel 68.28 170 8193
payment from customer: creation 57.77 142 6932
payment from customer: removal 5.86 164 703
payment to supplier: creation 47.29 161 5675
payment to supplier: removal 3.70 96 444
service: total inventory turnovers 4.47 1439 537
service: total monetary turnovers 6.91 63 829
service: aggregate perf. data 0.61 44117 73
service: refresh index statistics 0.03 386557 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-08-2020 00:53:04 to 19-08-2020 00:53:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1057
TEST_TIME 2 1822
TEST_TIME 3 3759
TEST_TIME 4 3584
TEST_TIME 5 3959
TEST_TIME 6 4766
TEST_TIME 7 4799
TEST_TIME 8 4980
TEST_TIME 9 5071
TEST_TIME 10 4920
TEST_TIME 11 4995
TEST_TIME 12 5414
TEST_TIME 13 5584
TEST_TIME 14 5340
TEST_TIME 15 5430
TEST_TIME 16 5354
TEST_TIME 17 5605
TEST_TIME 18 5506
TEST_TIME 19 5510
TEST_TIME 20 5604
TEST_TIME 21 5733
TEST_TIME 22 5737
TEST_TIME 23 5683
TEST_TIME 24 5782
TEST_TIME 25 5985
TEST_TIME 26 5970
TEST_TIME 27 6116
TEST_TIME 28 6192
TEST_TIME 29 6337
TEST_TIME 30 6322
TEST_TIME 31 6302
TEST_TIME 32 6384
TEST_TIME 33 6388
TEST_TIME 34 6462
TEST_TIME 35 6509
TEST_TIME 36 6607
TEST_TIME 37 6628
TEST_TIME 38 6857
TEST_TIME 39 7047
TEST_TIME 40 7268
TEST_TIME 41 7640
TEST_TIME 42 8026
TEST_TIME 43 7966
TEST_TIME 44 8217
TEST_TIME 45 8421
TEST_TIME 46 8431
TEST_TIME 47 8799
TEST_TIME 48 8934
TEST_TIME 49 8828
TEST_TIME 50 8790
TEST_TIME 51 8705
TEST_TIME 52 8674
TEST_TIME 53 8611
TEST_TIME 54 8580
TEST_TIME 55 8559
TEST_TIME 56 8579
TEST_TIME 57 8523
TEST_TIME 58 8608
TEST_TIME 59 8556
TEST_TIME 60 8595
TEST_TIME 61 8620
TEST_TIME 62 8527
TEST_TIME 63 8472
TEST_TIME 64 8452
TEST_TIME 65 8460
TEST_TIME 66 8487
TEST_TIME 67 8403
TEST_TIME 68 8310
TEST_TIME 69 8342
TEST_TIME 70 8313
TEST_TIME 71 8322
TEST_TIME 72 8291
TEST_TIME 73 8292
TEST_TIME 74 8407
TEST_TIME 75 8410
TEST_TIME 76 8428
TEST_TIME 77 8521
TEST_TIME 78 8558
TEST_TIME 79 8487
TEST_TIME 80 8447
TEST_TIME 81 8490
TEST_TIME 82 8475
TEST_TIME 83 8416
TEST_TIME 84 8410
TEST_TIME 85 8386
TEST_TIME 86 8381
TEST_TIME 87 8366
TEST_TIME 88 8318
TEST_TIME 89 8310
TEST_TIME 90 8255
TEST_TIME 91 8263
TEST_TIME 92 8275
TEST_TIME 93 8292
TEST_TIME 94 8311
TEST_TIME 95 8399
TEST_TIME 96 8402
TEST_TIME 97 8434
TEST_TIME 98 8490
TEST_TIME 99 8515
TEST_TIME 100 8492
TEST_TIME 101 8553
TEST_TIME 102 8586
TEST_TIME 103 8617
TEST_TIME 104 8602
TEST_TIME 105 8599
TEST_TIME 106 8558
TEST_TIME 107 8586
TEST_TIME 108 8568
TEST_TIME 109 8533
TEST_TIME 110 8576
TEST_TIME 111 8529
TEST_TIME 112 8535
TEST_TIME 113 8569
TEST_TIME 114 8522
TEST_TIME 115 8504
TEST_TIME 116 8515
TEST_TIME 117 8504
TEST_TIME 118 8505
TEST_TIME 119 8520
TEST_TIME 120 8572

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-08-2020 00:53:07 to 19-08-2020 00:53:10.

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 1444466 1444466 0 0.00 0 12639 9 0 0
doc_list_biud 1444465 1444465 0 0.00 0 34040 43 0 0
sp_add_invoice_to_stock 5097 4920 177 3.47 60 231626 25171 0 177
sp_cancel_adding_invoice 5010 5010 0 0.00 5 84620 1482 0 0
sp_cancel_client_order 1746 1746 0 0.00 4 10386 538 0 0
sp_cancel_customer_reserve 495975 495975 0 0.00 1 34043 99 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 2 11192 165 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 2 1782 97 0 0
sp_cancel_supplier_invoice 5569 5554 15 0.26 2 18560 1394 0 15
sp_cancel_supplier_order 374 374 0 0.00 8 1622591 173060 0 0
sp_cancel_write_off 8193 8193 0 0.00 1 15065 171 0 0
sp_client_order 19504 19504 0 0.00 4 12600 368 0 0
sp_customer_reserve 451821 451821 0 0.00 0 134933 245 0 0
sp_fill_shopping_cart 30040 30040 0 0.00 1 14615 46 0 0
sp_fill_shopping_cart_clo_res 451817 451817 0 0.00 0 28954 18 0 0
sp_get_clo_for_invoice 4914 4914 0 0.00 0 32355 365 0 0
sp_kill_cost_storno 14350 14350 0 0.00 0 10393 116 0 0
sp_kill_qstorno_ret_qs2qd 1006071 1006071 0 0.00 0 33958 53 0 0
sp_kill_qty_storno 531341 531341 0 0.00 0 34039 115 0 0
sp_lock_selected_doc 20147 20147 0 0.00 0 9740 5 0 0
sp_make_cost_storno 27097 27097 0 0.00 0 12638 77 0 0
sp_multiply_rows_for_pdistr 27096 27096 0 0.00 0 7783 42 0 0
sp_multiply_rows_for_qdistr 19504 19504 0 0.00 0 12591 154 0 0
sp_pay_from_customer 6932 6932 0 0.00 4 17239 142 0 0
sp_pay_to_supplier 5675 5675 0 0.00 4 16826 161 0 0
sp_payment_common 12607 12607 0 0.00 1 11662 31 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 10378 499 0 0
sp_qd_handle_on_invoice_upd_sts 9918 9918 0 0.00 0 21189 580 0 0
sp_qd_handle_on_reserve_upd_sts 9581 9581 0 0.00 0 13013 72 0 0
sp_reserve_write_off 9582 9582 0 0.00 7 14344 238 0 0
sp_supplier_invoice 5251 5251 0 0.00 7 109031 4498 0 0
sp_supplier_order 5286 5286 0 0.00 5 24169 1474 0 0
srv_aggregate_perf_data 73 73 0 0.00 2500 608115 44117 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1244 156376 38175 0 0
srv_find_qd_qs_mism 981028 981028 0 0.00 0 9804 7 0 0
srv_make_invnt_saldo 537 537 0 0.00 33 41848 1440 0 0
srv_make_money_saldo 829 829 0 0.00 1 9514 64 0 0
srv_recalc_idx_stat 4 4 0 0.00 217146 726033 386558 0 0
t$perf-abend:sp_add_invoice_to_stock 177 177 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4920 4920 0 0.00 0 15024 327 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 2105 145 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 4335 24 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 3317 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 3143 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 1889 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 351 351 0 0.00 0 1691 31 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 49980 3730 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 1725 10 0 0
t$perf-norm:sp_client_order 19504 19504 0 0.00 0 12986 32 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 1211 12 0 0
t$perf-norm:sp_pay_from_customer 6932 6932 0 0.00 0 10363 18 0 0
t$perf-norm:sp_pay_to_supplier 5675 5675 0 0.00 0 11608 18 0 0
t$perf-norm:sp_reserve_write_off 9582 9582 0 0.00 0 9366 20 0 0
t$perf-norm:sp_supplier_invoice 5251 5251 0 0.00 0 7949 37 0 0
t$perf-norm:sp_supplier_order 5286 5286 0 0.00 0 5718 34 0 0
t$perf-norm:srv_aggregate_perf_data 73 73 0 0.00 0 560 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 410 7 0 0
t$perf-norm:srv_make_invnt_saldo 537 537 0 0.00 0 1840 64 0 0
t$perf-norm:srv_make_money_saldo 829 829 0 0.00 0 5894 55 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 32 11 0 0
v_add_invoice_to_stock 10162 9808 354 3.48 0 4743 21 0 354
v_all_customers 35698 35698 0 0.00 0 42 1 0 0
v_all_suppliers 29136 29136 0 0.00 0 27 1 0 0
v_all_wares 39008 39008 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 750 10 0 0
v_cancel_client_order 3492 3492 0 0.00 0 2322 6 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 5897 19 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 1623 8 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 1163 6 0 0
v_cancel_supplier_invoice 732 702 30 4.09 0 2217 23 0 30
v_cancel_supplier_order 748 748 0 0.00 0 3634 15 0 0
v_cancel_write_off 1900 1900 0 0.00 0 1756 20 0 0
v_max_id_clo_ord 5283 5283 0 0.00 0 9565 4 0 0
v_max_id_clo_res 978 978 0 0.00 0 9774 22 0 0
v_max_id_ord_sup 5251 5251 0 0.00 0 4010 4 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 357 3 0 0
v_max_non_paid_realizn 3782 3782 0 0.00 0 7519 7 0 0
v_min_id_clo_ord 5283 5283 0 0.00 0 7838 9 0 0
v_min_id_clo_res 978 978 0 0.00 0 5766 18 0 0
v_min_id_ord_sup 5251 5251 0 0.00 0 3739 8 0 0
v_min_non_paid_invoice 5675 5675 0 0.00 0 7907 16 0 0
v_min_non_paid_realizn 6932 6932 0 0.00 0 8548 20 0 0
v_our_firm 9610 9610 0 0.00 0 28 1 0 0
v_reserve_write_off 19162 19162 0 0.00 0 3598 8 0 0
x_lock_depdocs_on_canc_invoice 10213 10213 0 0.00 0 10728 214 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 20411 642 0 0
x_make_qstorno_1000_1200 5286 5286 0 0.00 2 24119 1349 0 0
x_make_qstorno_1200_2000 5251 5251 0 0.00 4 106877 4337 0 0
x_make_qstorno_2100_3300 451821 451821 0 0.00 0 134928 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-08-2020 00:53:11 to 19-08-2020 00:53:15.

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:22:00 2 8192000000 8798382336 8808857600 5057328 0.061 1 1 2 0 5175376 55488 62560
22:23:01 26082 8192000000 10360192816 10679656448 1412998944 14.711 100 88 176 0 1448754112 6126656 29628512
22:24:31 12806 8192000000 10405561008 10733105152 1466091808 15.179 100 87 174 1 1502934176 8300160 28542208
22:25:45 22933 8192000000 10461028256 10800758784 1516970128 15.624 100 81 162 0 1552145568 9265200 25910240
22:27:13 16206 8192000000 10531881488 10879193088 1589484864 16.249 100 86 172 1 1626010112 10461520 26063728
22:28:34 14880 8192000000 10607849216 10968506368 1659437072 16.844 100 79 158 0 1694169184 11665744 23066368
22:29:52 7989 8192000000 10663191728 11029856256 1723472880 17.381 100 84 166 1 1760795456 12880656 24441920
22:31:03 5731 8192000000 10709615616 11080568832 1765355680 17.729 100 89 175 3 1803923920 14656080 23912160
22:32:11 12794 8192000000 10735982624 11113447424 1796346272 17.984 100 84 168 1 1834306064 14560192 23399600
22:33:27 2938 8192000000 10775351664 11155996672 1837631488 18.322 100 94 185 0 1876727728 13095552 26000688
22:34:33 3149 8192000000 10802309536 11184533504 1860286160 18.506 100 88 175 0 1898290720 13387296 24617264
22:35:39 3905 8192000000 10815348384 11202842624 1872257088 18.603 100 87 174 0 1909741872 12896656 24588128
22:36:48 11514 8192000000 10809899904 11208523776 1879121168 18.658 100 79 158 2 1913798272 11378384 23298720
22:38:03 1750 8192000000 10802147744 11213172736 1882031312 18.682 100 54 110 0 1907034144 9850432 15152400
22:39:10 2770 8192000000 10805319184 11224936448 1889787328 18.744 100 47 94 0 1912576048 8726176 14062544
22:40:13 32246 8192000000 10826291552 11231895552 1894388384 18.781 100 60 120 1 1919324528 7294208 17641936
22:41:46 7089 8192000000 10798896928 11231330304 1897081392 18.803 100 37 73 3 1914138272 6903152 10153728
22:42:53 30289 8192000000 10826781040 11245105152 1906767184 18.881 100 61 122 1 1931591616 6472768 18351664
22:44:26 5352 8192000000 10785603872 11215667200 1869845552 18.583 99 25 50 3 1883333136 6812352 6675232
22:45:35 7279 8192000000 10725066704 11126743040 1772202000 17.785 96 19 39 0 1782787408 5183104 5402304
22:46:46 40773 8192000000 10719288256 11103047680 1692682512 17.124 93 58 116 0 1715219824 5754736 16782576
22:48:27 6697 8192000000 10698306576 11097632768 1724524176 17.390 96 28 55 3 1737714864 5087392 8103296
22:49:35 10052 8192000000 10724864368 11139702784 1798292160 18.000 99 35 70 0 1815228208 7051536 9884512
22:50:48 15564 8192000000 10757986176 11171336192 1838753264 18.331 100 25 50 0 1852483568 5366528 8363776
22:52:07 49689 8192000000 10791764752 11194675200 1859431952 18.499 100 52 103 0 1880719696 7673248 13614496
22:54:17 13119 8192000000 10814346784 11226976256 1879276000 18.659 100 52 104 2 1908581792 14814064 14491728
22:55:35 32533 8192000000 10831712112 11247976448 1903854320 18.857 100 50 100 1 1928630864 11114208 13662336
22:57:08 23973 8192000000 10840340864 11263381504 1911264720 18.917 100 49 96 0 1940623104 14626992 14731392
22:58:51 16235 8192000000 10853575136 11276300288 1922950336 19.010 100 43 85 1 1945622160 10865696 11806128
23:00:20 53967 8192000000 10859939200 11290259456 1937030512 19.123 100 36 70 0 1958501584 10228768 11242304
23:02:23 24213 8192000000 10899520784 11329982464 1953630224 19.255 100 40 80 0 1986997808 21308112 12059472
23:04:04 8406 8192000000 10922497824 11335880704 1972040080 19.402 100 64 125 1 2008865776 19394032 17431664
23:05:16 34252 8192000000 10915132816 11350331392 1979433984 19.460 100 39 78 1 2008242016 17406880 11401152
23:06:59 7907 8192000000 10929107856 11351072768 1985013248 19.504 100 65 130 1 2022327872 18725680 18588944
23:08:16 10031 8192000000 10921083984 11360542720 1985101568 19.505 100 39 76 1 2014576704 18076480 11398656
23:09:31 10223 8192000000 10952794032 11380592640 1997660448 19.604 100 50 100 2 2042762448 30494960 14607040
23:10:49 4079 8192000000 10969064256 11392139264 2007528208 19.682 100 82 161 2 2060690128 29181008 23980912
23:12:00 6539 8192000000 10974160768 11400536064 1998341680 19.610 99 52 101 3 2043482864 30242704 14898480
23:13:17 9336 8192000000 10974963872 11408465920 2002260960 19.641 99 58 116 1 2054719056 34953376 17504720
23:14:35 3019 8192000000 10986444512 11398922240 1998484352 19.611 99 75 150 1 2062312048 40989664 22838032
23:15:47 3382 8192000000 10981082256 11398254592 1996675792 19.597 100 82 162 1 2067575712 45919312 24980608
23:17:01 4629 8192000000 10971649312 11381309440 1986461184 19.516 99 89 177 3 2058089552 44951472 26676896
23:18:15 3736 8192000000 10978443920 11382116352 1975301376 19.427 99 84 169 0 2051105840 50121296 25683168
23:19:28 3381 8192000000 10975836480 11385958400 1984561616 19.501 100 83 163 1 2059211056 49636736 25012704
23:20:40 1322 8192000000 11006171696 11415146496 2011844160 19.716 100 93 183 4 2087329888 48068192 27417536
23:21:56 2382 8192000000 10998821328 11408293888 2004701968 19.660 100 87 173 0 2082017920 51051344 26264608
23:23:04 2280 8192000000 11018958240 11421708288 2006334528 19.673 99 89 177 2 2092101968 58692320 27075120
23:24:16 3363 8192000000 11033605408 11447431168 2027917088 19.842 100 86 168 3 2116806064 62132592 26756384
23:25:30 4062 8192000000 11038546224 11451682816 2027238176 19.837 100 88 172 0 2120241488 66265136 26738176
23:26:40 6531 8192000000 11032543552 11447480320 2013826704 19.732 100 78 156 0 2113223744 74346672 25050368
23:27:58 3900 8192000000 11060057200 11467333632 2035544768 19.902 100 97 194 2 2139391216 73140736 30705712
23:29:06 1243 8192000000 11045901200 11452182528 2010205552 19.703 100 95 189 2 2122743616 81602288 30935776
23:30:15 4288 8192000000 11026043008 11438628864 2007924944 19.685 100 78 155 4 2105666320 72178528 25562848
23:31:31 2524 8192000000 11006720912 11423354880 1983349648 19.491 99 70 140 0 2083827696 77389152 23088896
23:32:49 12712 8192000000 10972979360 11432292352 1971401216 19.397 99 33 66 2 2049452624 66452208 11599200
23:34:36 13044 8192000000 10940102464 11385323520 1972805296 19.408 100 33 67 1 2025772176 41548736 11418144
23:36:18 21815 8192000000 10914548144 11371327488 1973211200 19.411 100 31 62 2 2020162608 36902720 10048688
23:37:41 24137 8192000000 10931478096 11375747072 1965197248 19.347 100 32 64 2 2010069344 33969056 10903040
23:39:09 65140 8192000000 10886601376 11347963904 1903177664 18.852 97 15 28 2 1920008064 11534496 5295904
23:41:17 107124 8192000000 10890776160 11333459968 1867215312 18.562 95 8 16 0 1877319680 7912512 2191856
23:44:05 63255 8192000000 10866764704 11323211776 1906804320 18.881 98 21 42 1 1919617792 6732352 6081120
23:46:57 45120 8192000000 10869806624 11320926208 1917647760 18.968 99 7 14 0 1934700848 14891984 2161104
23:48:45 41507 8192000000 10861489536 11328212992 1937381664 19.126 100 28 56 0 1956250288 10819600 8049024
23:50:32 119394 8192000000 10859312976 11328937984 1841432448 18.352 94 8 17 0 1862133856 18541984 2159424
23:54:06 121681 8192000000 10893501088 11343319040 1927241088 19.045 98 30 60 0 1950729232 15040816 8447328
23:57:11 156376 8192000000 10864135664 11327504384 1888336672 18.732 96 29 57 4 1909500448 13389744 7774032
00:00:53 15075 8192000000 10881540048 11332665344 1926851568 19.042 99 38 76 0 1952420336 15117840 10450928
00:02:10 20612 8192000000 10897871728 11358502912 1942720320 19.168 99 21 42 1 1969156272 19835184 6600768
00:03:34 57112 8192000000 10910319248 11374182400 1961416544 19.317 99 45 89 0 1998476240 24014880 13044816
00:06:42 55588 8192000000 10896969376 11368976384 1953117120 19.251 99 23 46 2 1983468256 23311040 7040096
00:09:08 33632 8192000000 10885189120 11373678592 1958553552 19.295 100 10 19 1 1978994816 17599952 2841312
00:10:42 22796 8192000000 10867070160 11345145856 1926471728 19.039 99 16 28 0 1945720592 15111824 4137040
00:12:20 70740 8192000000 10869115984 11356831744 1952076384 19.243 100 17 34 2 1970036688 13462512 4497792
00:14:33 65432 8192000000 10868979056 11345608704 1923621712 19.016 98 10 20 1 1937301968 11051920 2628336
00:16:41 32800 8192000000 10872998592 11344613376 1924833712 19.026 98 26 51 1 1941660320 9080720 7745888
00:18:24 57600 8192000000 10864685984 11340660736 1902250912 18.844 97 15 30 0 1914536832 7785552 4500368
00:20:23 66333 8192000000 10857110928 11323654144 1918742496 18.977 98 23 46 2 1934357024 8384848 7229680
00:22:37 52684 8192000000 10845807968 11323236352 1888927408 18.737 97 10 20 1 1898678000 7222240 2528352
00:24:30 44075 8192000000 10865101520 11323461632 1890129040 18.747 97 22 44 1 1908187920 12374848 5684032
00:26:17 46958 8192000000 10839402640 11302514688 1859290912 18.498 96 15 30 0 1878006704 14167232 4548560
00:28:06 59884 8192000000 10883562672 11346849792 1924269264 19.021 99 28 56 2 1948953408 16580720 8103424
00:30:08 71419 8192000000 10861694160 11324653568 1869549168 18.581 96 17 34 3 1883254608 9172608 4532832
00:32:33 117794 8192000000 10857693264 11323891712 1850614912 18.427 95 12 24 0 1866622256 12472352 3534992
00:35:34 70543 8192000000 10876826528 11340275712 1895825312 18.793 97 27 52 1 1915129520 11611712 7692496
00:37:45 103888 8192000000 10847202560 11315412992 1863316208 18.530 96 15 30 1 1878226304 9905904 5004192
00:40:40 27029 8192000000 10872576464 11337433088 1944038096 19.179 100 36 72 2 1971374896 17014912 10321888
00:42:11 85205 8192000000 10864335824 11335352320 1891213424 18.756 97 6 12 0 1909827296 16871392 1742480
00:44:41 63361 8192000000 10849761776 11322195968 1923792976 19.017 99 14 28 1 1935146832 7524976 3828880
00:46:48 67746 8192000000 10878055408 11358035968 1958777024 19.296 100 15 30 0 1973094672 10060624 4257024
00:48:56 42754 8192000000 10882054080 11333709824 1925204928 19.029 99 36 72 1 1947495200 11770240 10520032
00:50:51 60685 8192000000 10863841168 11344306176 1926723888 19.041 99 15 29 3 1951640896 20545392 4371616

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 00:53:17 to 19-08-2020 00:53:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 177 v_add_invoice_to_stock 335544517
user_exc 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 19-08-2020 00:53:32 to 19-08-2020 00:53:33.

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          221811
MON$OLDEST_ACTIVE               223732
MON$OLDEST_SNAPSHOT             223732
MON$NEXT_TRANSACTION            223732
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                       2064160
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 ---
:213: 
:214: mscs-test-161.budzdorov.ru    Wed Aug 19 00:52:01 2020
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Wed Aug 19 00:52:02 2020
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
:221: 
:222: mscs-test-161.budzdorov.ru    Wed Aug 19 00:52:04 2020
:223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:224: 
--- end of diff output ---

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

19.08.20 00:53:35. 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 00:53:38 NO_SEVERE_ERRORS_FOUND
19.08.20 00:53:40. End of report.