Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.08.2020 21:53
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 16841852 kB
MemAvailable 30997936 kB
Buffers 0 kB
Cached 14869480 kB
SwapCached 6004 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-17 21:51:59.2370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/52698
TRN_ID                          229088
ATT_ID                          610
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: 8665.77 25723 1039892
customer order: creation 171.14 363 20537
customer order: refuse 14.31 547 1717
order to supplier: creation 45.40 1534 5448
order to supplier: removal 3.18 154321 381
invoice (draft): creation 44.48 4102 5338
invoice (draft): removal 46.98 1315 5638
invoice accept: apply 41.46 24822 4975
invoice accept: cancel 42.44 1345 5093
customer reserve: creation 3817.12 242 458054
customer reserve: removal 4152.41 89 498289
realization accept: apply 83.09 247 9971
realization accept: cancel 76.49 163 9179
payment from customer: creation 57.97 140 6956
payment from customer: removal 5.38 114 646
payment to supplier: creation 47.81 165 5737
payment to supplier: removal 3.51 116 421
service: total inventory turnovers 4.83 1527 580
service: total monetary turnovers 6.93 48 832
service: aggregate perf. data 0.80 28766 96
service: refresh index statistics 0.03 294491 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-08-2020 21:54:12 to 17-08-2020 21:54:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1868
TEST_TIME 2 1950
TEST_TIME 3 2404
TEST_TIME 4 3606
TEST_TIME 5 4673
TEST_TIME 6 4298
TEST_TIME 7 4410
TEST_TIME 8 4414
TEST_TIME 9 4563
TEST_TIME 10 4453
TEST_TIME 11 4443
TEST_TIME 12 4500
TEST_TIME 13 4766
TEST_TIME 14 4727
TEST_TIME 15 4745
TEST_TIME 16 4761
TEST_TIME 17 4943
TEST_TIME 18 4938
TEST_TIME 19 5105
TEST_TIME 20 5248
TEST_TIME 21 5265
TEST_TIME 22 5704
TEST_TIME 23 5797
TEST_TIME 24 5782
TEST_TIME 25 6081
TEST_TIME 26 6095
TEST_TIME 27 6142
TEST_TIME 28 6082
TEST_TIME 29 6163
TEST_TIME 30 6174
TEST_TIME 31 6195
TEST_TIME 32 6261
TEST_TIME 33 6516
TEST_TIME 34 6575
TEST_TIME 35 7202
TEST_TIME 36 7325
TEST_TIME 37 7274
TEST_TIME 38 7300
TEST_TIME 39 7629
TEST_TIME 40 7803
TEST_TIME 41 7675
TEST_TIME 42 7829
TEST_TIME 43 8607
TEST_TIME 44 8697
TEST_TIME 45 8683
TEST_TIME 46 8738
TEST_TIME 47 8842
TEST_TIME 48 8723
TEST_TIME 49 8694
TEST_TIME 50 8712
TEST_TIME 51 8668
TEST_TIME 52 8651
TEST_TIME 53 8677
TEST_TIME 54 8587
TEST_TIME 55 8505
TEST_TIME 56 8450
TEST_TIME 57 8466
TEST_TIME 58 8467
TEST_TIME 59 8422
TEST_TIME 60 8329
TEST_TIME 61 8327
TEST_TIME 62 8366
TEST_TIME 63 8315
TEST_TIME 64 8376
TEST_TIME 65 8443
TEST_TIME 66 8491
TEST_TIME 67 8594
TEST_TIME 68 8603
TEST_TIME 69 8543
TEST_TIME 70 8588
TEST_TIME 71 8599
TEST_TIME 72 8529
TEST_TIME 73 8480
TEST_TIME 74 8464
TEST_TIME 75 8382
TEST_TIME 76 8347
TEST_TIME 77 8345
TEST_TIME 78 8345
TEST_TIME 79 8401
TEST_TIME 80 8409
TEST_TIME 81 8457
TEST_TIME 82 8462
TEST_TIME 83 8477
TEST_TIME 84 8470
TEST_TIME 85 8570
TEST_TIME 86 8599
TEST_TIME 87 8654
TEST_TIME 88 8623
TEST_TIME 89 8677
TEST_TIME 90 8713
TEST_TIME 91 8703
TEST_TIME 92 8673
TEST_TIME 93 8612
TEST_TIME 94 8694
TEST_TIME 95 8664
TEST_TIME 96 8659
TEST_TIME 97 8656
TEST_TIME 98 8609
TEST_TIME 99 8614
TEST_TIME 100 8576
TEST_TIME 101 8551
TEST_TIME 102 8545
TEST_TIME 103 8539
TEST_TIME 104 8593
TEST_TIME 105 8575
TEST_TIME 106 8587
TEST_TIME 107 8633
TEST_TIME 108 8643
TEST_TIME 109 8661
TEST_TIME 110 8639
TEST_TIME 111 8663
TEST_TIME 112 8709
TEST_TIME 113 8710
TEST_TIME 114 8747
TEST_TIME 115 8764
TEST_TIME 116 8773
TEST_TIME 117 8713
TEST_TIME 118 8660
TEST_TIME 119 8694
TEST_TIME 120 8673

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-08-2020 21:54:16 to 17-08-2020 21:54:19.

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 1462605 1462605 0 0.00 0 20205 9 0 0
doc_list_biud 1462604 1462604 0 0.00 0 28405 39 0 0
sp_add_invoice_to_stock 5072 4975 97 1.91 41 222197 24822 0 97
sp_cancel_adding_invoice 5093 5093 0 0.00 5 118322 1346 0 0
sp_cancel_client_order 1717 1717 0 0.00 4 22248 547 0 0
sp_cancel_customer_reserve 498289 498289 0 0.00 1 28410 90 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 3 2500 114 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 2391 116 0 0
sp_cancel_supplier_invoice 5645 5638 7 0.12 2 20618 1316 0 7
sp_cancel_supplier_order 381 381 0 0.00 5 1467481 154321 0 0
sp_cancel_write_off 9179 9179 0 0.00 1 10883 164 0 0
sp_client_order 20537 20537 0 0.00 3 22345 363 0 0
sp_customer_reserve 458054 458054 0 0.00 0 45959 243 0 0
sp_fill_shopping_cart 31321 31321 0 0.00 1 14380 47 0 0
sp_fill_shopping_cart_clo_res 458036 458036 0 0.00 0 16896 17 0 0
sp_get_clo_for_invoice 4957 4957 0 0.00 0 30537 409 0 0
sp_kill_cost_storno 15339 15339 0 0.00 0 20200 102 0 0
sp_kill_qstorno_ret_qs2qd 1011776 1011776 0 0.00 0 28400 48 0 0
sp_kill_qty_storno 535225 535225 0 0.00 0 28404 105 0 0
sp_lock_selected_doc 20491 20491 0 0.00 0 6725 5 0 0
sp_make_cost_storno 27621 27621 0 0.00 0 18687 78 0 0
sp_multiply_rows_for_pdistr 27621 27621 0 0.00 0 5757 39 0 0
sp_multiply_rows_for_qdistr 20537 20537 0 0.00 0 15797 158 0 0
sp_pay_from_customer 6956 6956 0 0.00 4 10569 141 0 0
sp_pay_to_supplier 5737 5737 0 0.00 4 22305 166 0 0
sp_payment_common 12693 12693 0 0.00 1 10559 30 0 0
sp_qd_handle_on_cancel_clo 1717 1717 0 0.00 0 19225 497 0 0
sp_qd_handle_on_invoice_upd_sts 10050 10050 0 0.00 0 20763 508 0 0
sp_qd_handle_on_reserve_upd_sts 9971 9971 0 0.00 0 17855 68 0 0
sp_reserve_write_off 9971 9971 0 0.00 6 24755 247 0 0
sp_supplier_invoice 5338 5338 0 0.00 6 74597 4103 0 0
sp_supplier_order 5448 5448 0 0.00 5 31742 1534 0 0
srv_aggregate_perf_data 96 96 0 0.00 2416 489501 28766 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 1075 152471 36335 0 0
srv_find_qd_qs_mism 992039 992039 0 0.00 0 12081 8 0 0
srv_make_invnt_saldo 580 580 0 0.00 41 171184 1527 0 0
srv_make_money_saldo 832 832 0 0.00 1 3595 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 108018 590005 294492 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4975 4975 0 0.00 0 19380 309 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 5466 147 0 0
t$perf-norm:sp_cancel_client_order 1717 1717 0 0.00 0 1086 12 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 741 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 4399 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 2217 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1057 18 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 46805 2914 0 0
t$perf-norm:sp_cancel_write_off 927 927 0 0.00 0 6864 17 0 0
t$perf-norm:sp_client_order 20537 20537 0 0.00 0 7236 29 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 2034 13 0 0
t$perf-norm:sp_pay_from_customer 6956 6956 0 0.00 0 4666 12 0 0
t$perf-norm:sp_pay_to_supplier 5737 5737 0 0.00 0 4409 11 0 0
t$perf-norm:sp_reserve_write_off 9971 9971 0 0.00 0 8195 13 0 0
t$perf-norm:sp_supplier_invoice 5338 5338 0 0.00 0 5356 32 0 0
t$perf-norm:sp_supplier_order 5448 5448 0 0.00 0 4606 28 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 662 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 4307 100 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 1508 51 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 1778 47 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10100 9906 194 1.92 0 26171 32 0 194
v_all_customers 37316 37316 0 0.00 0 25 1 0 0
v_all_suppliers 29564 29564 0 0.00 0 26 1 0 0
v_all_wares 41074 41074 0 0.00 0 105 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 21503 39 0 0
v_cancel_client_order 3434 3434 0 0.00 0 22200 15 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 2125 7 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 6483 11 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 492 5 0 0
v_cancel_supplier_invoice 810 796 14 1.72 0 1570 20 0 14
v_cancel_supplier_order 762 762 0 0.00 0 2175 13 0 0
v_cancel_write_off 1854 1854 0 0.00 0 3424 21 0 0
v_max_id_clo_ord 5448 5448 0 0.00 0 10586 6 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 987 9 0 0
v_max_id_ord_sup 5336 5336 0 0.00 0 4597 4 0 0
v_max_non_paid_invoice 1739 1739 0 0.00 0 205 3 0 0
v_max_non_paid_realizn 3649 3649 0 0.00 0 3772 5 0 0
v_min_id_clo_ord 5448 5448 0 0.00 0 14162 14 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 5753 11 0 0
v_min_id_ord_sup 5336 5336 0 0.00 0 3498 4 0 0
v_min_non_paid_invoice 5737 5737 0 0.00 0 7720 17 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 5542 14 0 0
v_our_firm 10372 10372 0 0.00 0 26 1 0 0
v_reserve_write_off 19942 19942 0 0.00 0 24631 11 0 0
x_lock_depdocs_on_canc_invoice 10333 10333 0 0.00 0 19275 197 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 11439 639 0 0
x_make_qstorno_1000_1200 5448 5448 0 0.00 1 31676 1403 0 0
x_make_qstorno_1200_2000 5338 5338 0 0.00 3 74592 3940 0 0
x_make_qstorno_2100_3300 458054 458054 0 0.00 0 45958 220 0 0

Completed "Performance in DETAILS". Done for 00:00:03, from 17-08-2020 21:54:20 to 17-08-2020 21:54:23.

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:02 1 8192000000 8798382336 8808857600 5057328 0.061 1 1 2 0 5175376 55488 62560
19:23:03 19987 8192000000 10339844960 10653798400 1399487392 14.590 100 85 170 0 1432974752 5772448 27714912
19:24:30 20225 8192000000 10406320416 10730405888 1458169696 15.110 100 92 184 0 1495796320 7833696 29792928
19:25:58 19691 8192000000 10448430560 10783858688 1505405376 15.523 100 86 172 0 1541932960 9516320 27011264
19:27:20 22426 8192000000 10534806608 10883772416 1592295696 16.273 100 79 158 1 1626583968 10660848 23627424
19:28:49 11182 8192000000 10595807680 10959003648 1662238256 16.868 100 76 152 2 1696346560 12143872 21964432
19:30:03 7497 8192000000 10648441456 11017879552 1709081504 17.261 100 71 143 2 1741570704 12477040 20012160
19:31:13 5949 8192000000 10693522400 11067850752 1751723664 17.616 100 78 155 1 1786267264 13290032 21253568
19:32:22 4985 8192000000 10728543472 11111976960 1796089584 17.982 100 76 151 1 1829566384 12759552 20717248
19:33:31 2527 8192000000 10775423920 11158011904 1834921504 18.299 100 85 173 0 1871496016 12003136 24571376
19:34:37 2608 8192000000 10797824528 11188383744 1858881952 18.494 100 85 167 3 1895458848 12443376 24133520
19:35:42 16226 8192000000 10803579088 11196747776 1866265504 18.554 100 69 138 0 1896514656 11439360 18809792
19:37:00 3311 8192000000 10790550016 11201679360 1870352208 18.587 100 45 88 3 1891516896 8322560 12842128
19:38:05 6471 8192000000 10808891152 11220824064 1888885296 18.737 100 38 75 4 1907275568 7277264 11113008
19:39:12 5216 8192000000 10802029632 11229196288 1893324448 18.773 100 29 58 0 1907688480 6168848 8195184
19:40:18 60802 8192000000 10823604272 11245215744 1900364752 18.829 100 36 72 5 1915363584 4511632 10487200
19:42:23 7219 8192000000 10790746000 11212455936 1859293312 18.498 99 19 36 2 1873047264 8907568 4846384
19:43:31 5366 8192000000 10790502720 11214651392 1846681456 18.395 98 35 70 2 1864077536 7014048 10382032
19:44:38 16814 8192000000 10761294080 11193171968 1838908976 18.332 99 18 36 0 1850077904 5993936 5174992
19:45:58 45228 8192000000 10681072816 11089543168 1578671616 16.157 86 29 57 4 1591869552 4234464 8963472
19:47:43 5844 8192000000 10693136064 11115958272 1777032992 17.825 99 21 42 2 1792666928 9946208 5687728
19:48:51 6940 8192000000 10715984480 11138641920 1807443136 18.075 100 25 50 2 1825185184 10859552 6882496
19:49:58 18704 8192000000 10756140992 11169587200 1833678160 18.289 100 29 58 1 1848464112 6067776 8718176
19:51:17 26339 8192000000 10773880928 11185500160 1846896464 18.397 100 23 46 4 1859001984 6289936 5815584
19:52:52 26792 8192000000 10809741008 11221553152 1881308880 18.676 100 47 91 1 1902381248 8308624 12763744
19:54:23 13943 8192000000 10849217584 11253702656 1903445376 18.854 100 61 119 1 1932708656 12431792 16831488
19:55:48 26703 8192000000 10831142560 11264405504 1913675904 18.936 100 27 54 2 1931145392 9351712 8117776
19:57:21 10824 8192000000 10866887024 11289042944 1937902592 19.130 100 43 86 2 1958457216 8730816 11823808
19:58:33 60088 8192000000 10873462032 11308736512 1942872912 19.170 100 35 70 2 1964190720 10666528 10651280
20:00:37 16483 8192000000 10903716400 11322658816 1963763088 19.336 100 59 118 0 1992313808 11994432 16556288
20:02:02 8630 8192000000 10923953184 11348131840 1972340320 19.404 100 60 117 1 2009830400 20755088 16734992
20:03:15 11489 8192000000 10932219760 11359772672 1978669632 19.454 100 52 102 1 2014567648 21361200 14536816
20:04:36 13206 8192000000 10909114992 11350962176 1971287568 19.396 100 36 69 0 1999141776 17935440 9918768
20:05:55 5568 8192000000 10948744128 11369009152 1990001552 19.544 100 76 150 2 2037260144 24017728 23240864
20:07:09 7559 8192000000 10945514240 11376197632 1998498352 19.611 100 59 116 1 2041530352 25327280 17704720
20:08:21 4075 8192000000 10960845344 11383107584 2001705104 19.636 100 77 154 1 2050384832 25404944 23274784
20:09:30 6121 8192000000 10972268864 11386605568 2001983072 19.638 100 72 140 2 2050379600 27233808 21162720
20:10:43 7955 8192000000 10969310992 11395403776 2007962736 19.685 100 62 122 2 2054662400 28464864 18234800
20:11:57 2054 8192000000 10974266576 11390517248 2005546784 19.666 100 79 154 0 2055500992 27582112 22372096
20:13:04 3444 8192000000 10994820432 11415052288 2019920096 19.780 100 83 166 0 2080394720 35005008 25469616
20:14:19 13775 8192000000 10958743248 11381350400 1992924560 19.567 100 73 146 1 2048630464 33444944 22260960
20:15:40 4422 8192000000 10962404912 11384344576 1977792016 19.447 99 64 126 0 2032396000 34953792 19650192
20:16:52 4839 8192000000 10950300800 11365875712 1978364624 19.452 100 78 152 1 2034640624 33042544 23233456
20:18:06 4481 8192000000 10922105808 11333931008 1936279712 19.117 99 78 158 1 1996093648 36709552 23104384
20:19:18 3424 8192000000 10965544272 11378421760 1981375264 19.476 100 89 176 2 2052555408 44766208 26413936
20:20:33 1070 8192000000 10992812864 11396943872 1988621856 19.533 99 92 184 0 2066524608 49542352 28360400
20:21:44 3728 8192000000 10989079984 11391803392 1983991760 19.496 100 91 182 1 2067400656 54341712 29067184
20:22:58 2643 8192000000 11004695808 11411648512 1996362800 19.594 100 92 185 1 2087316112 61735504 29217808
20:24:09 2598 8192000000 11013787680 11420659712 1995630528 19.588 100 90 176 0 2089901904 63996592 30274784
20:25:18 7803 8192000000 10988341568 11404886016 1987150784 19.521 100 66 128 2 2067723872 60200032 20373056
20:26:36 9621 8192000000 10985066688 11418210304 1995732560 19.589 100 53 106 0 2076270096 62956256 17581280
20:28:00 13581 8192000000 10944548000 11383377920 1952992448 19.250 98 38 76 0 2011779072 46083184 12703440
20:29:18 6924 8192000000 10971722016 11404201984 1981148672 19.474 100 55 109 3 2053602400 54011376 18442352
20:30:39 14053 8192000000 10951762144 11403952128 1954560208 19.263 99 31 62 1 2023397072 57965888 10870976
20:31:55 46215 8192000000 10931870992 11384045568 1952374800 19.245 100 19 39 2 2012128144 52289600 7463744
20:33:45 1137 8192000000 10970172960 11390676992 1960741840 19.312 100 87 172 0 2048137392 59282800 28112752
20:34:48 24739 8192000000 10908806384 11386494976 1945064800 19.187 99 12 24 0 1981979248 33257392 3657056
20:36:18 29116 8192000000 10900988592 11354116096 1908646064 18.896 97 43 86 1 1937467376 16197600 12623712
20:37:49 96111 8192000000 10870204864 11339636736 1893945792 18.778 97 8 16 0 1911212688 15089120 2177776
20:40:33 126653 8192000000 10875624384 11327950848 1901970272 18.842 97 20 40 1 1920245024 12516832 5757920
20:43:40 48240 8192000000 10874991824 11332972544 1916886192 18.962 98 26 52 2 1933936384 10255856 6794336
20:45:29 152470 8192000000 10896036304 11337658368 1884583664 18.702 96 31 62 1 1903816784 9716832 9516288
20:49:04 46426 8192000000 10913746096 11371298816 1970789072 19.392 100 25 50 2 1987430000 9243552 7397376
20:50:57 70626 8192000000 10899252576 11362336768 1945560912 19.191 98 28 57 4 1969515072 15465424 8488736
20:53:14 20335 8192000000 10907672704 11356741632 1955726192 19.272 98 39 77 1 1987341632 20484032 11131408
20:54:39 15015 8192000000 10928307424 11377065984 1970226320 19.387 100 33 67 0 2002875904 23446048 9203536
20:56:00 32505 8192000000 10961532784 11397046272 1991258224 19.554 100 62 124 0 2031738176 23269792 17210160
20:57:34 43844 8192000000 10904510192 11372883968 1940493600 19.151 98 23 44 3 1965455456 17637888 7323968
20:59:21 32571 8192000000 10878882864 11342282752 1937614704 19.128 99 15 30 1 1952493264 10648288 4230272
21:00:55 49616 8192000000 10869597296 11334172672 1910535376 18.911 98 13 26 1 1930692064 16281392 3875296
21:03:33 81927 8192000000 10859397920 11332157440 1922237968 19.005 98 15 30 0 1933106624 6650480 4218176
21:05:59 58680 8192000000 10853723952 11320348672 1906324464 18.877 98 11 22 0 1916761328 7423280 3013584
21:08:02 49080 8192000000 10849032704 11317858304 1928237792 19.053 100 11 22 1 1943409808 12111776 3060240
21:09:54 49953 8192000000 10857499952 11328872448 1930007536 19.067 100 11 22 1 1949185376 15494752 3683088
21:11:48 56702 8192000000 10833140512 11306991616 1874028176 18.617 97 13 26 0 1887762816 9706848 4027792
21:13:49 21001 8192000000 10848211856 11304402944 1916412768 18.958 100 19 38 0 1929104912 7468256 5223888
21:15:12 39444 8192000000 10851206272 11304091648 1866695392 18.558 97 34 67 2 1890719808 14448880 9575536
21:16:53 67227 8192000000 10854833568 11329015808 1886175872 18.715 98 15 30 0 1904634048 14381360 4076816
21:19:00 26665 8192000000 10864114400 11303636992 1881010976 18.673 98 47 92 2 1899344384 5338544 12994864
21:20:28 43814 8192000000 10840716352 11304505344 1881941696 18.681 98 35 70 0 1910037040 18654816 9440528
21:22:16 51698 8192000000 10865082720 11309936640 1879245248 18.659 97 26 52 0 1897868784 11492448 7131088
21:24:13 111281 8192000000 10867360432 11328741376 1929888000 19.066 100 17 34 0 1948121776 13220352 5013424
21:28:24 74466 8192000000 10870866992 11346862080 1958271696 19.292 100 13 25 2 1972137216 9823792 4041728
21:31:24 37199 8192000000 10840253280 11305631744 1904799456 18.865 98 17 34 1 1917144864 6928160 5417248
21:33:05 75897 8192000000 10867183648 11328241664 1935054848 19.107 100 11 22 0 1947819824 9972704 2792272
21:35:29 59751 8192000000 10874877104 11339702272 1913305584 18.933 98 9 17 1 1927362000 11476608 2579808
21:37:34 62901 8192000000 10887098048 11356372992 1939046704 19.139 99 21 42 1 1958856704 13788336 6021664
21:39:37 100916 8192000000 10895564016 11346907136 1936984688 19.123 99 14 28 0 1952178928 11386208 3808032
21:42:19 51888 8192000000 10876565120 11342954496 1901307424 18.837 97 19 38 0 1916862752 10411184 5144144
21:44:13 25810 8192000000 10871006960 11322806272 1895670448 18.791 97 23 46 1 1910414000 8259120 6484432
21:45:39 42763 8192000000 10872791184 11330838528 1923407376 19.014 99 15 29 3 1939387504 11882288 4097840
21:47:33 114860 8192000000 10844419760 11311095808 1911809760 18.921 100 16 32 0 1939404144 22900208 4694176
21:50:50 49564 8192000000 10831531104 11298390016 1882270368 18.683 98 14 28 1 1894562384 8023904 4268112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-08-2020 21:54:25 to 17-08-2020 21:54:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 97 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-08-2020 21:54:40 to 17-08-2020 21:54:42.

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          228236
MON$OLDEST_ACTIVE               229209
MON$OLDEST_SNAPSHOT             229209
MON$NEXT_TRANSACTION            229209
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                       2047456
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 ---
:100: 
:101: mscs-test-161.budzdorov.ru    Mon Aug 17 21:52:13 2020
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Mon Aug 17 21:52:26 2020
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Mon Aug 17 21:52:26 2020
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Mon Aug 17 21:52:26 2020
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:115: 
:116: 
:117: mscs-test-161.budzdorov.ru    Mon Aug 17 21:52:27 2020
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:119: 
--- end of diff output ---

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

17.08.20 21:54:44. 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-17 21:54:46 NO_SEVERE_ERRORS_FOUND
17.08.20 21:54:48. End of report.