Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2020 00: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 16761260 kB
MemAvailable 31027248 kB
Buffers 0 kB
Cached 14941460 kB
SwapCached 6272 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-12 00:51:56.5510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(50:14) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/51946
TRN_ID                          217071
ATT_ID                          539
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: 8622.07 34487 1034648
customer order: creation 153.85 387 18462
customer order: refuse 13.46 514 1615
order to supplier: creation 42.07 1533 5048
order to supplier: removal 3.10 181508 372
invoice (draft): creation 41.24 4590 4949
invoice (draft): removal 50.21 1411 6025
invoice accept: apply 37.19 25921 4463
invoice accept: cancel 45.49 1239 5459
customer reserve: creation 3473.13 251 416776
customer reserve: removal 4480.18 94 537622
realization accept: apply 74.74 234 8969
realization accept: cancel 87.88 168 10545
payment from customer: creation 54.22 143 6506
payment from customer: removal 5.03 98 603
payment to supplier: creation 44.67 184 5360
payment to supplier: removal 3.38 113 405
service: total inventory turnovers 4.78 1364 574
service: total monetary turnovers 6.63 52 795
service: aggregate perf. data 0.81 19370 97
service: refresh index statistics 0.03 450569 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-08-2020 00:53:33 to 12-08-2020 00:53:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4369
TEST_TIME 2 3470
TEST_TIME 3 3588
TEST_TIME 4 3510
TEST_TIME 5 3537
TEST_TIME 6 3625
TEST_TIME 7 3456
TEST_TIME 8 3643
TEST_TIME 9 3522
TEST_TIME 10 3777
TEST_TIME 11 3904
TEST_TIME 12 4540
TEST_TIME 13 4645
TEST_TIME 14 4872
TEST_TIME 15 4783
TEST_TIME 16 4965
TEST_TIME 17 4972
TEST_TIME 18 4912
TEST_TIME 19 5123
TEST_TIME 20 5383
TEST_TIME 21 5309
TEST_TIME 22 5682
TEST_TIME 23 5821
TEST_TIME 24 5972
TEST_TIME 25 5965
TEST_TIME 26 5983
TEST_TIME 27 6089
TEST_TIME 28 6133
TEST_TIME 29 6310
TEST_TIME 30 6645
TEST_TIME 31 6708
TEST_TIME 32 6746
TEST_TIME 33 7048
TEST_TIME 34 7065
TEST_TIME 35 6938
TEST_TIME 36 7178
TEST_TIME 37 7215
TEST_TIME 38 7692
TEST_TIME 39 8140
TEST_TIME 40 9148
TEST_TIME 41 9365
TEST_TIME 42 9491
TEST_TIME 43 9371
TEST_TIME 44 9253
TEST_TIME 45 9178
TEST_TIME 46 9180
TEST_TIME 47 9132
TEST_TIME 48 9240
TEST_TIME 49 9140
TEST_TIME 50 9063
TEST_TIME 51 9130
TEST_TIME 52 9032
TEST_TIME 53 8890
TEST_TIME 54 8802
TEST_TIME 55 8799
TEST_TIME 56 8852
TEST_TIME 57 8812
TEST_TIME 58 8776
TEST_TIME 59 8719
TEST_TIME 60 8659
TEST_TIME 61 8592
TEST_TIME 62 8597
TEST_TIME 63 8532
TEST_TIME 64 8527
TEST_TIME 65 8696
TEST_TIME 66 8640
TEST_TIME 67 8703
TEST_TIME 68 8691
TEST_TIME 69 8716
TEST_TIME 70 8745
TEST_TIME 71 8770
TEST_TIME 72 8782
TEST_TIME 73 8788
TEST_TIME 74 8792
TEST_TIME 75 8799
TEST_TIME 76 8772
TEST_TIME 77 8730
TEST_TIME 78 8806
TEST_TIME 79 8820
TEST_TIME 80 8794
TEST_TIME 81 8821
TEST_TIME 82 8860
TEST_TIME 83 8860
TEST_TIME 84 8930
TEST_TIME 85 8839
TEST_TIME 86 8807
TEST_TIME 87 8825
TEST_TIME 88 8751
TEST_TIME 89 8703
TEST_TIME 90 8733
TEST_TIME 91 8753
TEST_TIME 92 8712
TEST_TIME 93 8668
TEST_TIME 94 8640
TEST_TIME 95 8648
TEST_TIME 96 8665
TEST_TIME 97 8642
TEST_TIME 98 8651
TEST_TIME 99 8739
TEST_TIME 100 8726
TEST_TIME 101 8733
TEST_TIME 102 8705
TEST_TIME 103 8739
TEST_TIME 104 8765
TEST_TIME 105 8799
TEST_TIME 106 8779
TEST_TIME 107 8821
TEST_TIME 108 8817
TEST_TIME 109 8822
TEST_TIME 110 8831
TEST_TIME 111 8755
TEST_TIME 112 8743
TEST_TIME 113 8701
TEST_TIME 114 8722
TEST_TIME 115 8689
TEST_TIME 116 8665
TEST_TIME 117 8626
TEST_TIME 118 8593
TEST_TIME 119 8607
TEST_TIME 120 8634

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2020 00:53:36 to 12-08-2020 00:53:39.

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 1421033 1421033 0 0.00 0 57055 9 0 0
doc_list_biud 1421033 1421033 0 0.00 0 29630 45 0 0
sp_add_invoice_to_stock 4551 4463 88 1.93 26 212439 25922 0 88
sp_cancel_adding_invoice 5459 5459 0 0.00 3 74108 1239 0 0
sp_cancel_client_order 1615 1615 0 0.00 4 7582 515 0 0
sp_cancel_customer_reserve 537622 537622 0 0.00 1 29637 95 0 0
sp_cancel_pay_from_customer 603 603 0 0.00 2 3862 98 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 3863 114 0 0
sp_cancel_supplier_invoice 6028 6025 3 0.04 2 18317 1412 0 3
sp_cancel_supplier_order 372 372 0 0.00 6 1531403 181508 0 0
sp_cancel_write_off 10545 10545 0 0.00 1 18756 168 0 0
sp_client_order 18462 18462 0 0.00 4 23661 387 0 0
sp_customer_reserve 416776 416776 0 0.00 0 118791 251 0 0
sp_fill_shopping_cart 28459 28459 0 0.00 1 18489 50 0 0
sp_fill_shopping_cart_clo_res 416759 416759 0 0.00 0 14924 17 0 0
sp_get_clo_for_invoice 4441 4441 0 0.00 0 35550 393 0 0
sp_kill_cost_storno 17012 17012 0 0.00 0 35198 108 0 0
sp_kill_qstorno_ret_qs2qd 1092186 1092186 0 0.00 0 29627 51 0 0
sp_kill_qty_storno 575048 575048 0 0.00 0 29630 110 0 0
sp_lock_selected_doc 18677 18677 0 0.00 0 9055 6 0 0
sp_make_cost_storno 25276 25276 0 0.00 0 56951 74 0 0
sp_multiply_rows_for_pdistr 25276 25276 0 0.00 0 7335 38 0 0
sp_multiply_rows_for_qdistr 18462 18462 0 0.00 0 19575 170 0 0
sp_pay_from_customer 6506 6506 0 0.00 4 13861 143 0 0
sp_pay_to_supplier 5360 5360 0 0.00 4 22318 185 0 0
sp_payment_common 11866 11866 0 0.00 1 13104 36 0 0
sp_qd_handle_on_cancel_clo 1615 1615 0 0.00 0 6848 478 0 0
sp_qd_handle_on_invoice_upd_sts 9900 9900 0 0.00 0 21357 535 0 0
sp_qd_handle_on_reserve_upd_sts 8969 8969 0 0.00 0 16662 81 0 0
sp_reserve_write_off 8969 8969 0 0.00 6 16680 235 0 0
sp_supplier_invoice 4949 4949 0 0.00 7 85769 4590 0 0
sp_supplier_order 5048 5048 0 0.00 6 29401 1534 0 0
srv_aggregate_perf_data 97 97 0 0.00 2080 196772 19371 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1427 152729 42304 0 0
srv_find_qd_qs_mism 991612 991612 0 0.00 0 15951 7 0 0
srv_make_invnt_saldo 574 574 0 0.00 46 115539 1364 0 0
srv_make_money_saldo 795 795 0 0.00 1 7686 53 0 0
srv_recalc_idx_stat 3 3 0 0.00 417300 496320 450570 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4463 4463 0 0.00 0 24450 280 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 0 3481 184 0 0
t$perf-norm:sp_cancel_client_order 1615 1615 0 0.00 0 2588 24 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 1674 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 603 603 0 0.00 0 1338 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1468 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 339 339 0 0.00 0 1406 25 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 44937 3263 0 0
t$perf-norm:sp_cancel_write_off 919 919 0 0.00 0 2617 13 0 0
t$perf-norm:sp_client_order 18462 18462 0 0.00 0 7254 26 0 0
t$perf-norm:sp_customer_reserve 899 899 0 0.00 0 2047 8 0 0
t$perf-norm:sp_pay_from_customer 6506 6506 0 0.00 0 7268 14 0 0
t$perf-norm:sp_pay_to_supplier 5360 5360 0 0.00 0 8022 12 0 0
t$perf-norm:sp_reserve_write_off 8969 8969 0 0.00 0 8027 14 0 0
t$perf-norm:sp_supplier_invoice 4949 4949 0 0.00 0 12858 28 0 0
t$perf-norm:sp_supplier_order 5048 5048 0 0.00 0 5416 27 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 1013 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3714 80 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 2621 46 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 889 28 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9058 8882 176 1.94 0 8411 21 0 176
v_all_customers 34020 34020 0 0.00 0 29 1 0 0
v_all_suppliers 28198 28198 0 0.00 0 26 1 0 0
v_all_wares 36924 36924 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 1847 12 0 0
v_cancel_client_order 3230 3230 0 0.00 0 3930 7 0 0
v_cancel_customer_prepayment 1206 1206 0 0.00 0 410 5 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 1151 5 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 3840 15 0 0
v_cancel_supplier_invoice 684 678 6 0.87 0 1301 18 0 6
v_cancel_supplier_order 744 744 0 0.00 0 2750 10 0 0
v_cancel_write_off 1838 1838 0 0.00 0 9418 22 0 0
v_max_id_clo_ord 5048 5048 0 0.00 0 599 2 0 0
v_max_id_clo_res 899 899 0 0.00 0 2934 10 0 0
v_max_id_ord_sup 4949 4949 0 0.00 0 12389 9 0 0
v_max_non_paid_invoice 1258 1258 0 0.00 0 2797 6 0 0
v_max_non_paid_realizn 3292 3292 0 0.00 0 6463 6 0 0
v_min_id_clo_ord 5048 5048 0 0.00 0 5952 11 0 0
v_min_id_clo_res 899 899 0 0.00 0 7527 13 0 0
v_min_id_ord_sup 4949 4949 0 0.00 0 8227 17 0 0
v_min_non_paid_invoice 5360 5360 0 0.00 0 7041 24 0 0
v_min_non_paid_realizn 6506 6506 0 0.00 0 13100 26 0 0
v_our_firm 9332 9332 0 0.00 0 22 1 0 0
v_reserve_write_off 17938 17938 0 0.00 0 5218 7 0 0
x_lock_depdocs_on_canc_invoice 11145 11145 0 0.00 0 9565 173 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 5889 577 0 0
x_make_qstorno_1000_1200 5048 5048 0 0.00 2 29369 1410 0 0
x_make_qstorno_1200_2000 4949 4949 0 0.00 3 85729 4414 0 0
x_make_qstorno_2100_3300 416776 416776 0 0.00 0 118790 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2020 00:53:40 to 12-08-2020 00:53:44.

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:21:59 1 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
22:23:00 25215 8192000000 10351249984 10669588480 1407547456 14.662 100 81 162 0 1438504496 4932992 26024048
22:24:29 21277 8192000000 10415208304 10738884608 1472368528 15.235 100 91 182 0 1509139408 7415168 29355712
22:26:02 19272 8192000000 10462475936 10796875776 1512617040 15.586 100 84 169 1 1547976400 8762240 26597120
22:27:26 23814 8192000000 10530268384 10884091904 1578058352 16.151 100 80 159 0 1612283968 9698848 24526768
22:28:55 10894 8192000000 10598435760 10966261760 1659935152 16.848 100 76 153 0 1694470752 11873072 22662528
22:30:10 11331 8192000000 10658627344 11032915968 1725586880 17.399 100 70 140 2 1756390544 11156640 19647024
22:31:24 9574 8192000000 10699304464 11080601600 1765599936 17.731 100 73 146 1 1798912432 12711664 20600832
22:32:35 2375 8192000000 10749597968 11134529536 1816259792 18.147 100 76 151 1 1850884624 12623984 22000848
22:33:40 3107 8192000000 10784549776 11168276480 1846663088 18.395 100 81 159 3 1881143040 12224560 22255392
22:34:46 1069 8192000000 10806954064 11189420032 1866887376 18.559 100 95 191 2 1906609312 13133200 26588736
22:35:49 17828 8192000000 10792355680 11191525376 1859512560 18.499 100 51 103 1 1881743072 7130144 15100368
22:37:08 6967 8192000000 10785877536 11198406656 1865136480 18.545 100 35 70 0 1881709920 6718192 9855248
22:38:15 12637 8192000000 10789315216 11206156288 1873942240 18.616 100 14 28 2 1883418416 5833520 3642656
22:39:29 11996 8192000000 10786216016 11211694080 1880333760 18.668 100 27 53 1 1893978144 5748016 7896368
22:40:43 52403 8192000000 10798111472 11225403392 1890873712 18.753 100 50 101 1 1912408880 5833072 15702096
22:42:37 5765 8192000000 10806954352 11231559680 1892981280 18.770 100 27 54 1 1906964336 6000352 7982704
22:43:45 4731 8192000000 10756173616 11182452736 1838750528 18.331 99 26 52 3 1853327712 7146624 7430560
22:44:52 8868 8192000000 10742180816 11155804160 1770840672 17.774 96 30 60 2 1789490976 10196704 8453600
22:46:01 16885 8192000000 10730921056 11157086208 1771504768 17.779 96 16 32 2 1782061696 6257184 4299744
22:47:21 4721 8192000000 10711133312 11114881024 1775237904 17.810 99 40 80 2 1793627888 6991232 11398752
22:48:30 7762 8192000000 10704006784 11108974592 1766632928 17.739 99 23 45 1 1779411488 5699008 7079552
22:49:43 16778 8192000000 10749110864 11153395712 1811122992 18.105 99 40 79 2 1829604192 5847008 12634192
22:51:00 3286 8192000000 10791177536 11177086976 1842373488 18.360 100 79 160 0 1870706064 4742736 23589840
22:52:06 37172 8192000000 10783401408 11206049792 1870118544 18.585 100 38 74 4 1887148624 6028848 11001232
22:53:51 8298 8192000000 10816774688 11236245504 1891672320 18.759 100 41 83 1 1913789184 9910976 12205888
22:55:14 6256 8192000000 10858533872 11270074368 1917076912 18.963 100 53 104 3 1947513216 15653248 14783056
22:56:29 19053 8192000000 10860880816 11278245888 1921502912 18.999 100 40 80 1 1950977728 17838096 11636720
22:57:51 62490 8192000000 10863262528 11290841088 1928873616 19.058 100 31 62 1 1957905152 19426720 9604816
23:00:03 6325 8192000000 10899931392 11314352128 1945374880 19.190 100 54 107 1 1984344208 23108400 15860928
23:01:17 16866 8192000000 10914873920 11336613888 1957725056 19.288 100 68 136 1 2006884688 29433824 19725808
23:02:50 11410 8192000000 10917935712 11342274560 1964136320 19.339 100 63 126 1 2012912992 30071136 18705536
23:04:07 10655 8192000000 10928535696 11350355968 1972436928 19.405 100 70 140 1 2021380944 28366352 20577664
23:05:27 27916 8192000000 10930409616 11368968192 1984211440 19.498 100 51 100 0 2026217344 26178176 15827728
23:07:05 12362 8192000000 10960779232 11388309504 1998743216 19.613 100 61 122 0 2051667552 33706576 19217760
23:08:25 5757 8192000000 10962126784 11391324160 1998979632 19.615 100 63 125 1 2052153728 34185696 18988400
23:09:40 2998 8192000000 10989425984 11403407360 2006259968 19.672 100 77 153 3 2072597808 42524640 23813200
23:10:52 5361 8192000000 11016606048 11433963520 2026773760 19.833 100 81 162 3 2096354192 45194128 24386304
23:12:07 11642 8192000000 11005710976 11428474880 2019037888 19.773 100 81 161 1 2089646928 46166224 24442816
23:13:29 8558 8192000000 11005665136 11438911488 2024508256 19.816 100 64 128 0 2092230304 47645936 20076112
23:14:46 3580 8192000000 11022892848 11448664064 2032171904 19.876 100 76 152 2 2106165120 50743136 23250080
23:15:55 3376 8192000000 11014845184 11434061824 2014942496 19.740 100 89 178 1 2099547488 57012464 27592528
23:17:09 1590 8192000000 11028087424 11440902144 2025152736 19.821 100 90 181 0 2112853296 59685536 28015024
23:18:21 3320 8192000000 11034047264 11441242112 2015125776 19.742 100 90 178 1 2109377776 65738368 28513632
23:19:34 2134 8192000000 11014276912 11421614080 1991271280 19.554 99 88 176 1 2091757168 71725072 28760816
23:20:44 4590 8192000000 11032228864 11440807936 2001532816 19.635 100 89 179 1 2109652784 79671744 28448224
23:21:55 1425 8192000000 11023715312 11435184128 2007577632 19.682 100 87 175 2 2104493520 69473344 27442544
23:23:06 5445 8192000000 11028555664 11442241536 2008085808 19.686 100 73 143 0 2099683024 67930736 23666480
23:24:25 4508 8192000000 11026254512 11451478016 1998157968 19.608 99 65 128 1 2093454480 74470160 20826352
23:25:39 11296 8192000000 11012099632 11451863040 2012621840 19.722 100 50 98 3 2103790528 74655344 16513344
23:27:10 6750 8192000000 10982556160 11420471296 1963014464 19.330 100 54 108 3 2072150544 90043440 19092640
23:28:28 22767 8192000000 10976060016 11425271808 1953910592 19.258 100 38 76 1 2068098176 99808640 14378944
23:30:16 26901 8192000000 10927306832 11387412480 1927690496 19.048 100 25 50 1 2019545808 81711344 10143968
23:31:57 8576 8192000000 10903565584 11327492096 1905133776 18.868 99 59 110 6 1958496176 35387024 17975376
23:33:07 16500 8192000000 10871547888 11329273856 1930822720 19.073 100 34 67 1 1955481440 14330608 10328112
23:34:28 20435 8192000000 10881590128 11328516096 1917722176 18.969 99 37 72 0 1944963568 16945984 10295408
23:37:06 93842 8192000000 10858651424 11324813312 1897898464 18.809 98 15 30 1 1924583200 21877920 4806816
23:39:40 77942 8192000000 10837703312 11284455424 1871947072 18.600 97 16 31 1 1883584272 7518144 4119056
23:42:21 152729 8192000000 10854757840 11310018560 1896051936 18.795 98 24 48 0 1912846320 9529200 7265184
23:46:04 117129 8192000000 10879475024 11341971456 1935012176 19.107 99 21 39 2 1949874928 9006816 5855936
23:49:03 69593 8192000000 10910592752 11364880384 1958665472 19.295 99 34 68 1 1981333648 12277744 10390432
23:51:20 62680 8192000000 10938849008 11366408192 1956029968 19.274 99 24 48 0 1973429856 10700176 6699712
23:53:25 21305 8192000000 10951710048 11399503872 2001829280 19.637 100 43 86 1 2032976176 19000976 12145920
23:55:09 85438 8192000000 10904852272 11379380224 1941644768 19.160 97 31 62 4 1981792688 31104352 9043568
23:57:43 15504 8192000000 10899736960 11366477824 1947987472 19.210 99 14 28 2 1978790336 26406928 4395936
23:58:59 52271 8192000000 10897867072 11378114560 1967752000 19.368 100 13 26 0 1995278976 23426304 4100672
00:00:56 97021 8192000000 10917916656 11384496128 1974291728 19.419 100 11 22 1 1994415888 16620624 3503536
00:03:33 133409 8192000000 10892710016 11360784384 1906981920 18.882 96 8 16 0 1922679776 13672432 2025424
00:07:06 43558 8192000000 10921693184 11358003200 1959463744 19.302 99 43 81 2 1979945152 8249552 12231856
00:08:53 27658 8192000000 10913687760 11368566784 1977701712 19.446 100 40 80 1 2003260480 14160624 11398144
00:10:29 55663 8192000000 10887139008 11352121344 1948795680 19.217 99 18 36 1 1963246688 9411136 5039872
00:12:29 31456 8192000000 10904532416 11360829440 1963068368 19.330 100 43 86 0 1990723776 15726400 11929008
00:14:10 92765 8192000000 10872400512 11335008256 1834153760 18.293 93 19 38 2 1855103904 15418784 5531360
00:16:54 77987 8192000000 10869835216 11317551104 1874003568 18.617 96 25 50 1 1888554320 7428512 7122240
00:19:25 51643 8192000000 10857784864 11314098176 1894737504 18.784 97 20 40 0 1908736528 8170384 5828640
00:21:18 110557 8192000000 10862680400 11341111296 1908770784 18.897 98 11 22 0 1929920048 18106624 3042640
00:24:15 49422 8192000000 10841414000 11311824896 1855531344 18.467 95 9 18 1 1866261056 8377152 2352560
00:26:29 101755 8192000000 10832597776 11301134336 1813102656 18.121 94 13 26 1 1825361568 9101936 3156976
00:29:43 106768 8192000000 10838434960 11303645184 1794737856 17.971 92 16 32 1 1815150384 15989264 4423264
00:32:30 29161 8192000000 10841462624 11289088000 1878624560 18.654 98 31 63 1 1897347808 10223920 8499328
00:34:01 46468 8192000000 10857158528 11308863488 1909404000 18.902 99 26 51 0 1926851872 10635600 6812272
00:36:25 145319 8192000000 10869525040 11330408448 1861845696 18.518 96 8 16 0 1877949968 14186288 1917984
00:39:50 25598 8192000000 10881048096 11323125760 1922657552 19.008 99 30 60 1 1939586304 9158224 7770528
00:41:19 127134 8192000000 10861762416 11325849600 1907888624 18.890 98 26 52 0 1927372080 11973472 7509984
00:44:28 13747 8192000000 10868514160 11332329472 1941123376 19.156 100 21 42 1 1960438192 13519232 5795584
00:46:14 85048 8192000000 10870439648 11339677696 1949877392 19.226 100 13 26 0 1961137712 7683040 3577280
00:49:15 68175 8192000000 10892450560 11372793856 1969171920 19.379 100 16 32 0 1989687312 15745440 4769952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:13, from 12-08-2020 00:53:46 to 12-08-2020 00:53:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 88 v_add_invoice_to_stock 335544517
user_exc 3 v_cancel_supplier_invoice 335544517

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

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          213524
MON$OLDEST_ACTIVE               217186
MON$OLDEST_SNAPSHOT             217186
MON$NEXT_TRANSACTION            217186
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                       2056544
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 ---
:85: 
:86: mscs-test-161.budzdorov.ru    Wed Aug 12 00:52:05 2020
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Wed Aug 12 00:52:09 2020
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
--- end of diff output ---

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

12.08.20 00:54:04. 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-12 00:54:06 NO_SEVERE_ERRORS_FOUND
12.08.20 00:54:08. End of report.