Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.08.2020 18:52
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 369f65bbd7e5442bbb810a88d29f958d
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-957.5.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2019-11-01 09:39
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.03
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32644004 kB
MemFree 16787568 kB
MemAvailable 30962524 kB
Buffers 0 kB
Cached 14885740 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 18:51:57.2810
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/50702
TRN_ID                          225376
ATT_ID                          582
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: 9002.11 29994 1080253
customer order: creation 166.32 375 19958
customer order: refuse 14.29 504 1715
order to supplier: creation 44.54 1472 5345
order to supplier: removal 3.39 150367 407
invoice (draft): creation 43.51 4327 5221
invoice (draft): removal 51.21 1159 6145
invoice accept: apply 39.92 22891 4790
invoice accept: cancel 47.19 1242 5663
customer reserve: creation 3707.37 222 444884
customer reserve: removal 4594.49 86 551339
realization accept: apply 81.66 222 9799
realization accept: cancel 83.93 162 10071
payment from customer: creation 56.48 142 6777
payment from customer: removal 5.33 89 639
payment to supplier: creation 47.18 159 5662
payment to supplier: removal 3.33 92 400
service: total inventory turnovers 4.61 1343 553
service: total monetary turnovers 6.60 50 792
service: aggregate perf. data 0.74 25268 89
service: refresh index statistics 0.03 389706 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-08-2020 18:53:09 to 17-08-2020 18:53:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1793
TEST_TIME 2 2226
TEST_TIME 3 3553
TEST_TIME 4 3727
TEST_TIME 5 4287
TEST_TIME 6 4292
TEST_TIME 7 4532
TEST_TIME 8 4416
TEST_TIME 9 4568
TEST_TIME 10 4537
TEST_TIME 11 4629
TEST_TIME 12 4648
TEST_TIME 13 5029
TEST_TIME 14 5023
TEST_TIME 15 4956
TEST_TIME 16 4798
TEST_TIME 17 4787
TEST_TIME 18 4815
TEST_TIME 19 4990
TEST_TIME 20 5120
TEST_TIME 21 5198
TEST_TIME 22 5385
TEST_TIME 23 5448
TEST_TIME 24 5487
TEST_TIME 25 5710
TEST_TIME 26 5899
TEST_TIME 27 6051
TEST_TIME 28 6543
TEST_TIME 29 6457
TEST_TIME 30 6541
TEST_TIME 31 6731
TEST_TIME 32 7308
TEST_TIME 33 7418
TEST_TIME 34 7418
TEST_TIME 35 7378
TEST_TIME 36 7650
TEST_TIME 37 8085
TEST_TIME 38 8237
TEST_TIME 39 8393
TEST_TIME 40 9182
TEST_TIME 41 9204
TEST_TIME 42 9049
TEST_TIME 43 8992
TEST_TIME 44 9156
TEST_TIME 45 9056
TEST_TIME 46 8956
TEST_TIME 47 8877
TEST_TIME 48 8754
TEST_TIME 49 8766
TEST_TIME 50 8797
TEST_TIME 51 8708
TEST_TIME 52 8639
TEST_TIME 53 8645
TEST_TIME 54 8583
TEST_TIME 55 8524
TEST_TIME 56 8524
TEST_TIME 57 8590
TEST_TIME 58 8759
TEST_TIME 59 8747
TEST_TIME 60 8707
TEST_TIME 61 8726
TEST_TIME 62 8655
TEST_TIME 63 8653
TEST_TIME 64 8678
TEST_TIME 65 8754
TEST_TIME 66 8758
TEST_TIME 67 8771
TEST_TIME 68 8797
TEST_TIME 69 8844
TEST_TIME 70 8819
TEST_TIME 71 8828
TEST_TIME 72 8870
TEST_TIME 73 8839
TEST_TIME 74 8874
TEST_TIME 75 8915
TEST_TIME 76 8932
TEST_TIME 77 8965
TEST_TIME 78 9039
TEST_TIME 79 9043
TEST_TIME 80 8934
TEST_TIME 81 8890
TEST_TIME 82 8936
TEST_TIME 83 8871
TEST_TIME 84 8861
TEST_TIME 85 8880
TEST_TIME 86 8847
TEST_TIME 87 8804
TEST_TIME 88 8847
TEST_TIME 89 8803
TEST_TIME 90 8766
TEST_TIME 91 8806
TEST_TIME 92 8851
TEST_TIME 93 8818
TEST_TIME 94 8857
TEST_TIME 95 8883
TEST_TIME 96 8874
TEST_TIME 97 8886
TEST_TIME 98 8941
TEST_TIME 99 8918
TEST_TIME 100 8940
TEST_TIME 101 9065
TEST_TIME 102 9064
TEST_TIME 103 9040
TEST_TIME 104 9110
TEST_TIME 105 9117
TEST_TIME 106 9139
TEST_TIME 107 9099
TEST_TIME 108 9063
TEST_TIME 109 9089
TEST_TIME 110 9045
TEST_TIME 111 9031
TEST_TIME 112 9022
TEST_TIME 113 9036
TEST_TIME 114 9007
TEST_TIME 115 9017
TEST_TIME 116 8994
TEST_TIME 117 8956
TEST_TIME 118 8986
TEST_TIME 119 8973
TEST_TIME 120 9004

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-08-2020 18:53:12 to 17-08-2020 18:53:15.

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 1490346 1490346 0 0.00 0 40583 8 0 0
doc_list_biud 1490346 1490346 0 0.00 0 88417 40 0 0
sp_add_invoice_to_stock 4909 4790 119 2.42 56 197879 22891 0 119
sp_cancel_adding_invoice 5663 5663 0 0.00 4 72844 1243 0 0
sp_cancel_client_order 1715 1715 0 0.00 4 7136 504 0 0
sp_cancel_customer_reserve 551339 551339 0 0.00 1 49343 86 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 2 2052 89 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 3845 93 0 0
sp_cancel_supplier_invoice 6161 6145 16 0.25 2 88470 1160 0 16
sp_cancel_supplier_order 407 407 0 0.00 9 1404909 150368 0 0
sp_cancel_write_off 10071 10071 0 0.00 1 9202 163 0 0
sp_client_order 19958 19958 0 0.00 4 41861 375 0 0
sp_customer_reserve 444884 444884 0 0.00 0 93483 222 0 0
sp_fill_shopping_cart 30506 30506 0 0.00 1 13150 43 0 0
sp_fill_shopping_cart_clo_res 444880 444880 0 0.00 0 16378 15 0 0
sp_get_clo_for_invoice 4786 4786 0 0.00 0 17902 337 0 0
sp_kill_cost_storno 16773 16773 0 0.00 0 12202 93 0 0
sp_kill_qstorno_ret_qs2qd 1119301 1119301 0 0.00 0 88415 46 0 0
sp_kill_qty_storno 589925 589925 0 0.00 0 88416 100 0 0
sp_lock_selected_doc 20171 20171 0 0.00 0 7660 6 0 0
sp_make_cost_storno 27024 27024 0 0.00 0 40581 67 0 0
sp_multiply_rows_for_pdistr 27024 27024 0 0.00 0 7300 37 0 0
sp_multiply_rows_for_qdistr 19958 19958 0 0.00 0 24680 160 0 0
sp_pay_from_customer 6777 6777 0 0.00 4 40589 142 0 0
sp_pay_to_supplier 5662 5662 0 0.00 5 19297 159 0 0
sp_payment_common 12439 12439 0 0.00 1 10830 33 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 7131 466 0 0
sp_qd_handle_on_invoice_upd_sts 10449 10449 0 0.00 0 31229 515 0 0
sp_qd_handle_on_reserve_upd_sts 9799 9799 0 0.00 0 21965 76 0 0
sp_reserve_write_off 9799 9799 0 0.00 6 21989 222 0 0
sp_supplier_invoice 5221 5221 0 0.00 7 93954 4327 0 0
sp_supplier_order 5345 5345 0 0.00 6 47383 1473 0 0
srv_aggregate_perf_data 89 89 0 0.00 2534 171523 25269 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1105 153684 40430 0 0
srv_find_qd_qs_mism 1033404 1033404 0 0.00 0 9746 7 0 0
srv_make_invnt_saldo 553 553 0 0.00 44 69592 1343 0 0
srv_make_money_saldo 792 792 0 0.00 0 4717 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 234066 526693 389707 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4790 4790 0 0.00 0 17636 279 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 3479 141 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 6225 23 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 4682 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 5970 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 810 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 359 359 0 0.00 0 1388 30 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 35690 2764 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 2524 10 0 0
t$perf-norm:sp_client_order 19958 19958 0 0.00 0 25451 26 0 0
t$perf-norm:sp_customer_reserve 931 931 0 0.00 0 1153 9 0 0
t$perf-norm:sp_pay_from_customer 6777 6777 0 0.00 0 4506 11 0 0
t$perf-norm:sp_pay_to_supplier 5662 5662 0 0.00 0 22867 20 0 0
t$perf-norm:sp_reserve_write_off 9799 9799 0 0.00 0 10202 13 0 0
t$perf-norm:sp_supplier_invoice 5221 5221 0 0.00 0 7761 29 0 0
t$perf-norm:sp_supplier_order 5345 5345 0 0.00 0 6069 25 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 1363 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2808 64 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 2753 57 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 6549 42 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 7 0 0
v_add_invoice_to_stock 9810 9572 238 2.42 0 8437 20 0 238
v_all_customers 36372 36372 0 0.00 0 34 1 0 0
v_all_suppliers 29490 29490 0 0.00 0 19 1 0 0
v_all_wares 39916 39916 0 0.00 0 190 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 426 7 0 0
v_cancel_client_order 3430 3430 0 0.00 0 4422 9 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 2011 7 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 950 4 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 575 4 0 0
v_cancel_supplier_invoice 750 718 32 4.26 0 2246 19 0 32
v_cancel_supplier_order 814 814 0 0.00 0 8153 24 0 0
v_cancel_write_off 1966 1966 0 0.00 0 3574 18 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 1630 2 0 0
v_max_id_clo_res 931 931 0 0.00 0 2526 13 0 0
v_max_id_ord_sup 5204 5204 0 0.00 0 4445 4 0 0
v_max_non_paid_invoice 1465 1465 0 0.00 0 404 3 0 0
v_max_non_paid_realizn 3544 3544 0 0.00 0 3841 6 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 1904 7 0 0
v_min_id_clo_res 931 931 0 0.00 0 3878 12 0 0
v_min_id_ord_sup 5204 5204 0 0.00 0 3848 5 0 0
v_min_non_paid_invoice 5662 5662 0 0.00 0 6124 18 0 0
v_min_non_paid_realizn 6777 6777 0 0.00 0 8710 21 0 0
v_our_firm 10010 10010 0 0.00 0 22 1 0 0
v_reserve_write_off 19598 19598 0 0.00 0 11307 7 0 0
x_lock_depdocs_on_canc_invoice 11449 11449 0 0.00 0 17754 166 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 5187 474 0 0
x_make_qstorno_1000_1200 5345 5345 0 0.00 1 47359 1365 0 0
x_make_qstorno_1200_2000 5221 5221 0 0.00 3 90320 4163 0 0
x_make_qstorno_2100_3300 444884 444884 0 0.00 0 93058 202 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-08-2020 18:53:16 to 17-08-2020 18:53:20.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
16:22:00 1 8192000000 8798382336 8808857600 5057328 0.061 1 1 2 0 5175376 55488 62560
16:23:00 37319 8192000000 10360305200 10678358016 1413239888 14.713 100 94 188 1 1449639232 5895904 30503440
16:24:45 12558 8192000000 10433948576 10763378688 1486636672 15.359 100 87 174 1 1522363264 8017424 27709168
16:26:03 24573 8192000000 10475726064 10817191936 1534684736 15.778 100 83 166 1 1570013472 9449280 25879456
16:27:33 18651 8192000000 10539686096 10891759616 1595823008 16.304 100 78 156 1 1629620400 10607264 23190128
16:28:59 12001 8192000000 10607855408 10974568448 1673846992 16.966 100 72 144 0 1707362032 12735104 20779936
16:30:13 6843 8192000000 10647029536 11020341248 1719197680 17.346 100 78 155 2 1753199152 11866800 22134672
16:31:23 10774 8192000000 10694083568 11073667072 1758963504 17.676 100 67 131 2 1788862208 11878192 18020512
16:32:36 5218 8192000000 10741794672 11127304192 1810948768 18.104 100 72 144 0 1842192720 11244016 19999936
16:33:47 3403 8192000000 10774797184 11159584768 1837860208 18.323 100 84 167 3 1874695632 13344032 23491392
16:34:52 1383 8192000000 10791395328 11180863488 1853831280 18.453 100 89 178 0 1891442880 12980608 24630992
16:35:58 17300 8192000000 10805406224 11193110528 1863881680 18.535 100 69 134 1 1894828144 11331264 19615200
16:37:17 7929 8192000000 10800831296 11201024000 1867862448 18.567 100 36 72 0 1884485168 7186192 9436528
16:38:27 8316 8192000000 10771370864 11197792256 1870389696 18.587 100 25 48 3 1884280688 5660464 8230528
16:39:39 11141 8192000000 10779963248 11207688192 1876835760 18.640 100 24 48 2 1890133728 6760656 6537312
16:40:53 20509 8192000000 10792440560 11212783616 1867869680 18.567 99 30 58 1 1882249568 6343440 8036448
16:42:16 23024 8192000000 10793993616 11227500544 1883990720 18.697 100 17 34 1 1894427248 5474112 4962416
16:43:42 8997 8192000000 10802089312 11228389376 1886139776 18.715 100 25 50 2 1898891104 5714768 7036560
16:44:54 8299 8192000000 10778666944 11205021696 1821575184 18.191 97 18 35 1 1836142576 9359472 5207920
16:46:03 9097 8192000000 10692984368 11103784960 1686674448 17.073 93 11 22 2 1695766336 5773984 3317904
16:47:21 28231 8192000000 10676175312 11083014144 1662600032 16.871 92 24 46 3 1674902896 5473056 6829808
16:48:50 5506 8192000000 10689744368 11098599424 1757321648 17.662 98 35 70 1 1773835088 6891600 9621840
16:49:56 9645 8192000000 10725039408 11142582272 1811962368 18.112 100 20 40 0 1823407200 5862512 5582320
16:51:12 102610 8192000000 10779866096 11197308928 1847017248 18.398 100 27 53 2 1860916304 5075152 8823904
16:54:07 22385 8192000000 10813411376 11234062336 1887311504 18.724 100 36 70 1 1904778944 7748864 9718576
16:55:35 20187 8192000000 10841492064 11258060800 1911285440 18.917 100 50 100 2 1934289360 9277200 13726720
16:57:06 30242 8192000000 10867742672 11290943488 1925246528 19.029 100 43 86 3 1950422544 13104400 12071616
16:58:40 25100 8192000000 10864084224 11295510528 1939875376 19.146 100 33 66 0 1961737360 12080832 9781152
17:00:14 9516 8192000000 10912712736 11323842560 1959229184 19.300 100 67 134 1 1998054048 18521552 20303312
17:01:30 21541 8192000000 10915725888 11335974912 1970143216 19.387 100 55 109 3 2004905520 18385552 16376752
17:02:55 8624 8192000000 10923065632 11346259968 1977095376 19.442 100 46 90 1 2011349968 19410240 14844352
17:04:08 17650 8192000000 10942965440 11374813184 1992480144 19.563 100 48 96 1 2034460416 27339568 14640704
17:05:35 14640 8192000000 10942315824 11380772864 2000560416 19.627 100 51 98 1 2041937824 26488768 14888640
17:06:55 11090 8192000000 10969251232 11400409088 2012546272 19.722 100 57 114 1 2059167776 29430688 17190816
17:08:16 8636 8192000000 10977714912 11407298560 2017471440 19.760 100 50 98 4 2064425600 31480544 15473616
17:09:40 11787 8192000000 10991894720 11416240128 2014871408 19.740 100 68 136 0 2076038176 39970640 21196128
17:11:07 12734 8192000000 11005187648 11427069952 2025263008 19.821 100 66 132 1 2082757968 37347152 20147808
17:12:32 4469 8192000000 11020214864 11441684480 2036845392 19.912 100 83 164 2 2103577712 42097328 24634992
17:13:43 2766 8192000000 11021684704 11442905088 2036626592 19.911 100 80 158 0 2108305728 46270816 25408320
17:14:57 2772 8192000000 11011649904 11423285248 2019267536 19.774 100 87 174 0 2094145424 47694176 27183712
17:16:05 2241 8192000000 10996884624 11412312064 1985056784 19.505 98 77 154 1 2063187312 52914112 25216416
17:17:15 2866 8192000000 10992663440 11403399168 1992599776 19.564 100 83 163 0 2072998816 55108384 25290656
17:18:26 2069 8192000000 10971629472 11387265024 1960701120 19.312 99 82 162 1 2041374096 55264688 25408288
17:19:35 1104 8192000000 10991717232 11400462336 1988002224 19.528 100 92 183 1 2074045824 57924864 28118736
17:20:46 3123 8192000000 10993032304 11401592832 1987867744 19.527 100 86 172 2 2071724192 56230192 27626256
17:22:00 1647 8192000000 11013519456 11423420416 1998484528 19.611 100 87 174 0 2088345744 62223584 27637632
17:23:09 3182 8192000000 11014954576 11430612992 2006113536 19.671 100 78 156 0 2095588848 64496464 24978848
17:24:16 11983 8192000000 10969750080 11408752640 1990365296 19.547 99 41 79 0 2054033328 48879232 14788800
17:25:33 9163 8192000000 10974317984 11405062144 1986656896 19.517 100 54 111 3 2053954928 50390992 16907040
17:26:52 20109 8192000000 10972652544 11392622592 1973789744 19.416 100 71 141 1 2054008176 57871888 22346544
17:28:16 16773 8192000000 10955140448 11401039872 1972548624 19.406 100 40 80 1 2042248816 55524688 14175504
17:29:38 19434 8192000000 10901059056 11363336192 1913216864 18.932 98 23 46 2 1965670784 45049232 7404688
17:31:24 28233 8192000000 10855000272 11301896192 1889161440 18.739 97 29 58 1 1912669056 14910832 8596784
17:33:57 31513 8192000000 10881557248 11327758336 1913438192 18.934 98 42 79 1 1938602848 13168032 11996624
17:35:31 133252 8192000000 10828470128 11273912320 1830033152 18.260 94 12 24 3 1846108688 13056224 3019312
17:38:46 153684 8192000000 10875688688 11334262784 1884927776 18.705 97 30 59 1 1902400800 8466896 9006128
17:42:35 45800 8192000000 10888616096 11330310144 1937820672 19.129 100 40 80 1 1961377376 12351824 11204880
17:44:27 2836 8192000000 10883269552 11318235136 1938108560 19.132 100 62 124 1 1973864400 16686512 19069328
17:45:35 24316 8192000000 10892047712 11345072128 1949756528 19.225 100 31 63 0 1977282912 18306464 9219920
17:47:01 26353 8192000000 10907153648 11349958656 1928280400 19.053 98 42 82 2 1962373280 22561472 11531408
17:48:31 29750 8192000000 10907083696 11337195520 1946935264 19.202 100 66 131 1 1985158192 18140416 20082512
17:50:03 59093 8192000000 10877822912 11336065024 1936467440 19.119 99 11 22 0 1960526416 20826384 3232592
17:52:10 59124 8192000000 10872541888 11336699904 1907687616 18.888 97 14 28 0 1930971584 19199536 4084432
17:54:10 57856 8192000000 10881715312 11349225472 1943686144 19.176 100 14 28 0 1964223824 16598576 3939104
17:56:12 80889 8192000000 10886115376 11337515008 1923971776 19.019 99 24 48 0 1948779952 17487568 7320608
17:58:50 50676 8192000000 10886091280 11344248832 1924705392 19.025 98 14 28 1 1939050896 10663328 3682176
18:00:43 41865 8192000000 10865364560 11312840704 1874983856 18.625 96 38 76 2 1896367888 10910720 10473312
18:02:31 57994 8192000000 10879154480 11349200896 1939601040 19.144 99 17 34 1 1952777568 8675200 4501328
18:04:49 29718 8192000000 10883115648 11329179648 1912705152 18.928 98 43 85 2 1934095760 9150160 12240448
18:06:24 95871 8192000000 10847930240 11291185152 1836916128 18.316 94 13 26 0 1853816320 13256464 3643728
18:09:03 47243 8192000000 10856126528 11314749440 1887889488 18.729 98 9 18 1 1901967456 11364064 2713904
18:11:02 98930 8192000000 10870026256 11324432384 1907011904 18.883 98 31 62 1 1926905248 10978112 8915232
18:13:48 74510 8192000000 10866770272 11319119872 1870634160 18.589 96 34 68 1 1891754928 12182672 8938096
18:16:09 23614 8192000000 10862527616 11308032000 1887611648 18.727 97 62 125 1 1917221392 12430224 17179520
18:17:47 61374 8192000000 10861834192 11329236992 1917792928 18.969 99 10 20 1 1941075328 20263616 3018784
18:19:51 60790 8192000000 10852772448 11311566848 1892257296 18.764 98 11 22 1 1911138144 15378592 3502256
18:22:08 75162 8192000000 10849672704 11320385536 1894710800 18.784 98 18 36 1 1909780688 9788528 5281360
18:25:29 101011 8192000000 10846853648 11311906816 1828726768 18.249 94 8 16 0 1840259264 9158960 2373536
18:29:13 58432 8192000000 10871436544 11331506176 1928992592 19.059 99 30 58 0 1949766992 13299712 7474688
18:31:15 76323 8192000000 10906610208 11374010368 1942520592 19.167 99 27 53 2 1966576176 16887616 7167968
18:33:36 100357 8192000000 10895044432 11363991552 1944110192 19.180 99 16 30 0 1964832768 16528720 4193856
18:36:43 87588 8192000000 10869461584 11315507200 1780735728 17.856 91 11 21 2 1792491616 9259760 2496128
18:39:11 65278 8192000000 10880051872 11341541376 1932046224 19.083 99 20 40 1 1945047520 7517632 5483664
18:41:43 62515 8192000000 10896726240 11359002624 1955279152 19.268 100 21 42 0 1984034512 22452224 6303136
18:43:46 47445 8192000000 10856321632 11326570496 1897391472 18.805 98 21 42 2 1916640560 13369216 5879872
18:46:12 50663 8192000000 10857503728 11328933888 1924160832 19.020 99 12 24 0 1944829552 17164528 3504192
18:48:04 45841 8192000000 10859576800 11331940352 1927973104 19.051 99 12 24 0 1942055376 10709648 3372624
18:49:56 61540 8192000000 10860156320 11318136832 1862585056 18.524 95 26 52 1 1877690016 7694032 7410928

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 18:53:22 to 17-08-2020 18:53:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 119 v_add_invoice_to_stock 335544517
user_exc 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-08-2020 18:53:36 to 17-08-2020 18:53:38.

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          224791
MON$OLDEST_ACTIVE               225708
MON$OLDEST_SNAPSHOT             225708
MON$NEXT_TRANSACTION            225708
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                       2043168
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 ---
:71: 
:72: mscs-test-161.budzdorov.ru    Mon Aug 17 18:52:00 2020
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
:75: 
:76: mscs-test-161.budzdorov.ru    Mon Aug 17 18:52:00 2020
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Mon Aug 17 18:52:03 2020
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Mon Aug 17 18:52:03 2020
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Mon Aug 17 18:52:03 2020
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:90: 
:91: 
:92: mscs-test-161.budzdorov.ru    Mon Aug 17 18:52:03 2020
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:94: 
--- end of diff output ---

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

17.08.20 18:53:40. 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 18:53:42 NO_SEVERE_ERRORS_FOUND
17.08.20 18:53:44. End of report.