Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.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 16629832 kB
MemAvailable 30964240 kB
Buffers 0 kB
Cached 15059596 kB
SwapCached 6064 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-20 21:51:56.3330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/38518
TRN_ID                          223632
ATT_ID                          576
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: 8921.37 31141 1070564
customer order: creation 164.16 402 19699
customer order: refuse 14.06 561 1687
order to supplier: creation 44.04 1609 5285
order to supplier: removal 3.10 157947 372
invoice (draft): creation 43.73 4400 5248
invoice (draft): removal 50.08 1151 6009
invoice accept: apply 41.13 23999 4935
invoice accept: cancel 45.46 1251 5455
customer reserve: creation 3805.37 232 456644
customer reserve: removal 4425.12 84 531014
realization accept: apply 81.37 263 9764
realization accept: cancel 79.72 167 9566
payment from customer: creation 56.77 138 6812
payment from customer: removal 5.52 133 662
payment to supplier: creation 45.89 167 5507
payment to supplier: removal 3.36 129 403
service: total inventory turnovers 4.88 1504 585
service: total monetary turnovers 6.94 68 833
service: aggregate perf. data 0.67 31333 80
service: refresh index statistics 0.03 397290 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2020 21:53:46 to 20-08-2020 21:53:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1768
TEST_TIME 2 2043
TEST_TIME 3 2916
TEST_TIME 4 3603
TEST_TIME 5 3470
TEST_TIME 6 3417
TEST_TIME 7 3439
TEST_TIME 8 3573
TEST_TIME 9 3579
TEST_TIME 10 3613
TEST_TIME 11 4568
TEST_TIME 12 4898
TEST_TIME 13 5211
TEST_TIME 14 5199
TEST_TIME 15 5257
TEST_TIME 16 5538
TEST_TIME 17 5570
TEST_TIME 18 5844
TEST_TIME 19 5886
TEST_TIME 20 6419
TEST_TIME 21 6621
TEST_TIME 22 6509
TEST_TIME 23 6681
TEST_TIME 24 6864
TEST_TIME 25 7025
TEST_TIME 26 7036
TEST_TIME 27 7393
TEST_TIME 28 7757
TEST_TIME 29 7746
TEST_TIME 30 7773
TEST_TIME 31 7701
TEST_TIME 32 8018
TEST_TIME 33 8256
TEST_TIME 34 8508
TEST_TIME 35 8639
TEST_TIME 36 8992
TEST_TIME 37 9007
TEST_TIME 38 8996
TEST_TIME 39 9196
TEST_TIME 40 9271
TEST_TIME 41 9648
TEST_TIME 42 9737
TEST_TIME 43 9780
TEST_TIME 44 9781
TEST_TIME 45 9686
TEST_TIME 46 9659
TEST_TIME 47 9580
TEST_TIME 48 9492
TEST_TIME 49 9508
TEST_TIME 50 9454
TEST_TIME 51 9350
TEST_TIME 52 9504
TEST_TIME 53 9429
TEST_TIME 54 9370
TEST_TIME 55 9320
TEST_TIME 56 9189
TEST_TIME 57 9172
TEST_TIME 58 9152
TEST_TIME 59 9119
TEST_TIME 60 9006
TEST_TIME 61 9028
TEST_TIME 62 9020
TEST_TIME 63 8955
TEST_TIME 64 8947
TEST_TIME 65 9095
TEST_TIME 66 9075
TEST_TIME 67 9092
TEST_TIME 68 9129
TEST_TIME 69 9075
TEST_TIME 70 9129
TEST_TIME 71 9063
TEST_TIME 72 9057
TEST_TIME 73 9013
TEST_TIME 74 8956
TEST_TIME 75 8899
TEST_TIME 76 8866
TEST_TIME 77 8856
TEST_TIME 78 8803
TEST_TIME 79 8774
TEST_TIME 80 8885
TEST_TIME 81 8831
TEST_TIME 82 8915
TEST_TIME 83 8920
TEST_TIME 84 8962
TEST_TIME 85 8953
TEST_TIME 86 8994
TEST_TIME 87 8948
TEST_TIME 88 9022
TEST_TIME 89 9042
TEST_TIME 90 9012
TEST_TIME 91 9096
TEST_TIME 92 9034
TEST_TIME 93 9038
TEST_TIME 94 9050
TEST_TIME 95 8982
TEST_TIME 96 8946
TEST_TIME 97 8918
TEST_TIME 98 8860
TEST_TIME 99 8829
TEST_TIME 100 8906
TEST_TIME 101 8866
TEST_TIME 102 8828
TEST_TIME 103 8855
TEST_TIME 104 8904
TEST_TIME 105 8887
TEST_TIME 106 8941
TEST_TIME 107 8944
TEST_TIME 108 8952
TEST_TIME 109 8985
TEST_TIME 110 9012
TEST_TIME 111 9014
TEST_TIME 112 9014
TEST_TIME 113 9038
TEST_TIME 114 9008
TEST_TIME 115 8975
TEST_TIME 116 9009
TEST_TIME 117 8966
TEST_TIME 118 8969
TEST_TIME 119 8949
TEST_TIME 120 8928

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-08-2020 21:53:50 to 20-08-2020 21:53:53.

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 1490878 1490878 0 0.00 0 39891 9 0 0
doc_list_biud 1490878 1490878 0 0.00 0 63905 38 0 0
sp_add_invoice_to_stock 5102 4935 167 3.27 35 202736 24000 0 167
sp_cancel_adding_invoice 5455 5455 0 0.00 4 83767 1251 0 0
sp_cancel_client_order 1687 1687 0 0.00 4 18452 562 0 0
sp_cancel_customer_reserve 531014 531014 0 0.00 1 60947 85 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 7080 133 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 3096 130 0 0
sp_cancel_supplier_invoice 6024 6009 15 0.24 2 63911 1152 0 15
sp_cancel_supplier_order 372 372 0 0.00 13 1446840 157948 0 0
sp_cancel_write_off 9566 9566 0 0.00 1 9083 168 0 0
sp_client_order 19699 19699 0 0.00 4 39693 402 0 0
sp_customer_reserve 456644 456644 0 0.00 0 83478 232 0 0
sp_fill_shopping_cart 30227 30227 0 0.00 1 19396 48 0 0
sp_fill_shopping_cart_clo_res 456626 456626 0 0.00 0 16243 16 0 0
sp_get_clo_for_invoice 4917 4917 0 0.00 0 29063 370 0 0
sp_kill_cost_storno 16086 16086 0 0.00 0 36996 108 0 0
sp_kill_qstorno_ret_qs2qd 1077975 1077975 0 0.00 0 63902 45 0 0
sp_kill_qty_storno 568784 568784 0 0.00 0 63904 99 0 0
sp_lock_selected_doc 20271 20271 0 0.00 0 14123 7 0 0
sp_make_cost_storno 27000 27000 0 0.00 0 39879 79 0 0
sp_multiply_rows_for_pdistr 27000 27000 0 0.00 0 23680 41 0 0
sp_multiply_rows_for_qdistr 19699 19699 0 0.00 0 8270 154 0 0
sp_pay_from_customer 6812 6812 0 0.00 4 12466 139 0 0
sp_pay_to_supplier 5507 5507 0 0.00 6 23715 167 0 0
sp_payment_common 12319 12319 0 0.00 1 10247 30 0 0
sp_qd_handle_on_cancel_clo 1687 1687 0 0.00 0 18445 529 0 0
sp_qd_handle_on_invoice_upd_sts 10372 10372 0 0.00 0 37335 529 0 0
sp_qd_handle_on_reserve_upd_sts 9764 9764 0 0.00 0 19832 96 0 0
sp_reserve_write_off 9764 9764 0 0.00 6 19846 263 0 0
sp_supplier_invoice 5248 5248 0 0.00 7 118159 4401 0 0
sp_supplier_order 5285 5285 0 0.00 5 47075 1609 0 0
srv_aggregate_perf_data 80 80 0 0.00 3156 294731 31333 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1370 134904 37983 0 0
srv_find_qd_qs_mism 1023145 1023145 0 0.00 0 37018 7 0 0
srv_make_invnt_saldo 585 585 0 0.00 20 62541 1504 0 0
srv_make_money_saldo 833 833 0 0.00 1 2945 68 0 0
srv_recalc_idx_stat 4 4 0 0.00 238580 602789 397291 0 0
t$perf-abend:sp_add_invoice_to_stock 167 167 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 4936 4936 0 0.00 0 14452 277 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 9322 160 0 0
t$perf-norm:sp_cancel_client_order 1687 1687 0 0.00 0 3746 20 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 772 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 2265 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 1912 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1616 31 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 60705 3296 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 2855 14 0 0
t$perf-norm:sp_client_order 19699 19699 0 0.00 0 27558 31 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 812 7 0 0
t$perf-norm:sp_pay_from_customer 6812 6812 0 0.00 0 11097 16 0 0
t$perf-norm:sp_pay_to_supplier 5507 5507 0 0.00 0 6559 13 0 0
t$perf-norm:sp_reserve_write_off 9764 9764 0 0.00 0 8881 15 0 0
t$perf-norm:sp_supplier_invoice 5248 5248 0 0.00 0 3752 30 0 0
t$perf-norm:sp_supplier_order 5285 5285 0 0.00 0 8501 34 0 0
t$perf-norm:srv_aggregate_perf_data 80 80 0 0.00 0 1373 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 353 8 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 1580 46 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 1293 37 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 34 12 0 0
v_add_invoice_to_stock 10164 9830 334 3.28 0 8261 26 0 334
v_all_customers 36480 36480 0 0.00 0 24 1 0 0
v_all_suppliers 28808 28808 0 0.00 0 25 1 0 0
v_all_wares 39398 39398 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 3535 17 0 0
v_cancel_client_order 3374 3374 0 0.00 0 1147 4 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 4919 19 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 6692 13 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 3088 14 0 0
v_cancel_supplier_invoice 878 848 30 3.41 0 2518 26 0 30
v_cancel_supplier_order 744 744 0 0.00 0 4882 22 0 0
v_cancel_write_off 2008 2008 0 0.00 0 1113 14 0 0
v_max_id_clo_ord 5285 5285 0 0.00 0 2084 2 0 0
v_max_id_clo_res 984 984 0 0.00 0 1153 10 0 0
v_max_id_ord_sup 5243 5243 0 0.00 0 2334 3 0 0
v_max_non_paid_invoice 1631 1631 0 0.00 0 3859 6 0 0
v_max_non_paid_realizn 3437 3437 0 0.00 0 2106 5 0 0
v_min_id_clo_ord 5285 5285 0 0.00 0 4551 12 0 0
v_min_id_clo_res 984 984 0 0.00 0 4658 13 0 0
v_min_id_ord_sup 5243 5243 0 0.00 0 9035 11 0 0
v_min_non_paid_invoice 5507 5507 0 0.00 0 5575 14 0 0
v_min_non_paid_realizn 6812 6812 0 0.00 0 6360 18 0 0
v_our_firm 9668 9668 0 0.00 0 20 1 0 0
v_reserve_write_off 19528 19528 0 0.00 0 7801 7 0 0
x_lock_depdocs_on_canc_invoice 11040 11040 0 0.00 0 13223 171 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 13479 583 0 0
x_make_qstorno_1000_1200 5285 5285 0 0.00 2 46983 1492 0 0
x_make_qstorno_1200_2000 5248 5248 0 0.00 3 109321 4236 0 0
x_make_qstorno_2100_3300 456644 456644 0 0.00 0 83434 210 0 0

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

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:21:59 2 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
19:23:00 6397 8192000000 10339620176 10653949952 1403969200 14.630 100 90 178 1 1438873552 5646624 29257728
19:24:14 28220 8192000000 10409732768 10738737152 1469485360 15.209 100 92 184 0 1507431888 8349280 29597248
19:25:52 16269 8192000000 10467616528 10807480320 1528519696 15.724 100 86 172 0 1565877312 10347376 27010240
19:27:14 20432 8192000000 10517246448 10864697344 1576859424 16.141 100 83 166 0 1613626416 11052576 25714416
19:28:39 11639 8192000000 10571156816 10930692096 1634473424 16.633 100 83 165 1 1671377696 12223264 24681008
19:29:54 9210 8192000000 10627924544 10996142080 1688910208 17.092 100 78 156 2 1723733840 12351024 22472608
19:31:07 5611 8192000000 10688019744 11065774080 1754575120 17.639 100 77 153 3 1789451456 12585872 22290464
19:32:17 3693 8192000000 10748123152 11129188352 1804715120 18.053 100 78 157 2 1838873568 11564496 22593952
19:33:24 2585 8192000000 10778669472 11162259456 1840839456 18.348 100 81 157 1 1876225488 13290400 22095632
19:34:28 1069 8192000000 10802731344 11187425280 1863264944 18.530 100 93 182 1 1901379456 13446096 24668416
19:35:35 11078 8192000000 10805346880 11195981824 1867312896 18.563 100 63 124 1 1896868848 12564464 16991488
19:36:47 6881 8192000000 10782058432 11200069632 1871790400 18.599 100 28 56 2 1887439760 8094928 7554432
19:37:56 9292 8192000000 10784621840 11208777728 1877871984 18.648 100 25 50 2 1891413040 6349120 7191936
19:39:06 9629 8192000000 10794165968 11221721088 1885312816 18.708 100 21 40 2 1896757296 5175536 6268944
19:40:17 64200 8192000000 10805842848 11239325696 1897750992 18.808 100 34 68 2 1913678560 5068672 10858896
19:42:22 8023 8192000000 10823397600 11248574464 1903881280 18.857 100 16 33 2 1913897568 5579664 4436624
19:43:31 7908 8192000000 10797230256 11224252416 1856503696 18.475 98 29 58 2 1871852384 6533280 8815408
19:44:42 20839 8192000000 10738029456 11151630336 1721196704 17.362 92 30 60 1 1734715424 5440912 8077808
19:46:09 9413 8192000000 10735409392 11155402752 1796084384 17.982 98 37 74 1 1814681824 8052144 10545296
19:47:23 5516 8192000000 10673621728 11082223616 1716539360 17.323 96 19 38 2 1727956240 6112672 5304208
19:48:30 8640 8192000000 10699409872 11107950592 1781641312 17.863 100 26 52 3 1795523200 6375584 7506304
19:49:42 14076 8192000000 10736291920 11155300352 1824123408 18.211 100 30 61 0 1839568576 6389872 9055296
19:51:00 22303 8192000000 10736233776 11157999616 1826073264 18.227 100 32 65 3 1848970944 13860832 9036848
19:52:27 33836 8192000000 10788013568 11188396032 1852703504 18.444 100 50 100 1 1874923680 8776304 13443872
19:54:22 14590 8192000000 10826721360 11232731136 1886801024 18.720 100 60 120 0 1918483264 14648832 17033408
19:55:50 25978 8192000000 10839368848 11253084160 1905501776 18.871 100 38 76 2 1929192992 12350992 11340224
19:57:29 10777 8192000000 10858882048 11272167424 1922790512 19.009 100 49 98 2 1950550224 14079952 13679760
19:58:47 49150 8192000000 10856880256 11285028864 1925940272 19.034 100 40 80 1 1953939184 16246544 11752368
20:00:42 10539 8192000000 10889589424 11297521664 1931272096 19.077 100 55 110 2 1969939680 20180464 18487120
20:01:59 10380 8192000000 10901582832 11321167872 1947723152 19.208 100 53 104 3 1992939024 29256528 15959344
20:03:12 7215 8192000000 10916616128 11332108288 1958251776 19.292 100 64 127 0 2000035168 22854448 18928944
20:04:25 8118 8192000000 10930543840 11349360640 1975984016 19.433 100 68 132 0 2021146336 25915520 19246800
20:05:42 7617 8192000000 10946394432 11359707136 1967949440 19.369 99 84 164 4 2020908832 28632144 24327248
20:06:56 1427 8192000000 10973849936 11387940864 1997314912 19.602 100 73 145 0 2055849904 37048448 21486544
20:08:03 8336 8192000000 10975880864 11399725056 2003482624 19.650 100 66 132 2 2066131328 41913152 20735552
20:09:21 4483 8192000000 10973366016 11399016448 2002340512 19.641 100 61 122 0 2061882496 39837744 19704240
20:10:31 1480 8192000000 10979406576 11392864256 1994871872 19.582 100 82 163 2 2059364208 39809360 24682976
20:11:41 3245 8192000000 10991878336 11409539072 2008479680 19.690 100 74 147 2 2071833040 41667904 21685456
20:12:56 12284 8192000000 10971978544 11389947904 1995384256 19.586 100 70 139 0 2051083664 34467904 21231504
20:14:22 5168 8192000000 10987954800 11404050432 2003703984 19.652 100 74 148 1 2064500752 38372832 22423936
20:15:38 6016 8192000000 10986575504 11397230592 1982959584 19.488 99 88 177 2 2052732400 42605392 27167424
20:16:51 8165 8192000000 10952568400 11380457472 1980140384 19.466 100 60 121 2 2042577392 43449824 18987184
20:18:13 3230 8192000000 10962144128 11376357376 1972959824 19.409 100 84 169 1 2044662128 45368016 26334288
20:19:23 4952 8192000000 10951842288 11364712448 1966495504 19.358 100 61 122 0 2026774816 41132592 19146720
20:20:34 2744 8192000000 10935720864 11350564864 1950440272 19.230 99 70 139 2 2012169712 38717456 23011984
20:21:44 2353 8192000000 10953683344 11358904320 1949380736 19.222 99 82 164 0 2021960048 46905776 25673536
20:22:54 4414 8192000000 10992549536 11405471744 1984815008 19.503 100 80 156 2 2066802656 56870672 25116976
20:24:04 2998 8192000000 10994353136 11411349504 1993244848 19.569 100 73 145 2 2073759872 57913664 22601360
20:25:16 1369 8192000000 10995200208 11414425600 2002329424 19.641 100 75 151 1 2075040736 49142304 23569008
20:26:30 20359 8192000000 10984318400 11429597184 2005275040 19.664 100 38 76 0 2067909744 49688576 12946128
20:28:06 16635 8192000000 10947035104 11395035136 1953313536 19.253 99 26 52 1 2015537344 53694048 8529760
20:29:26 43918 8192000000 10939582208 11387027456 1951141584 19.236 99 26 53 3 2005719152 46027968 8549600
20:31:14 18549 8192000000 10918642704 11363774464 1914464480 18.942 98 21 42 1 1966478256 45010688 7003088
20:32:39 44476 8192000000 10884715328 11354980352 1934810832 19.105 99 14 28 0 1960087424 21036400 4240192
20:34:26 123455 8192000000 10882231248 11356131328 1912283536 18.925 98 16 32 0 1933536464 16947152 4305776
20:37:38 100981 8192000000 10892850048 11345133568 1879352976 18.660 96 13 26 1 1893879696 10592144 3934576
20:40:20 74266 8192000000 10904914640 11347120128 1923475760 19.015 98 30 60 1 1944994336 12583952 8934624
20:42:39 47172 8192000000 10892793184 11342290944 1955828704 19.273 100 38 76 1 1979128912 12567200 10733008
20:44:42 119348 8192000000 10892657552 11324424192 1845440448 18.385 94 28 56 2 1864061744 10668320 7952976
20:47:43 59281 8192000000 10894752720 11322896384 1915818800 18.953 98 52 105 2 1938220816 7390736 15011280
20:49:44 41779 8192000000 10901130912 11344334848 1923545424 19.015 98 47 93 1 1952767184 15900336 13321424
20:51:35 27082 8192000000 10903505808 11342389248 1950756288 19.232 100 41 82 3 1984402512 21496384 12149840
20:53:17 10601 8192000000 10936366128 11381596160 1987354000 19.523 100 54 109 0 2025061904 22176656 15531248
20:54:35 37952 8192000000 10911851824 11356585984 1931632144 19.080 98 44 87 0 1969185952 24030592 13523216
20:56:21 68028 8192000000 10844882752 11315175424 1892099984 18.763 97 16 31 2 1917871872 21106320 4665568
20:58:29 25989 8192000000 10844859520 11296378880 1895877872 18.793 98 26 53 2 1926288496 22340496 8070128
20:59:59 30083 8192000000 10840789424 11298983936 1878928688 18.656 97 20 40 1 1894497264 10000064 5568512
21:02:03 67305 8192000000 10866399792 11332452352 1947503232 19.207 100 12 24 0 1961732544 10972800 3256512
21:04:15 30101 8192000000 10855124752 11319685120 1920180032 18.988 99 6 12 0 1928848256 7059120 1609104
21:05:48 39667 8192000000 10832211952 11285917696 1868485008 18.572 96 14 28 0 1882561680 10462928 3613744
21:07:29 63566 8192000000 10829814192 11300990976 1907725296 18.888 100 11 21 0 1927906304 16855152 3325856
21:09:58 55754 8192000000 10850306832 11307450368 1865411872 18.547 96 20 40 2 1887758176 16118224 6228080
21:12:01 37534 8192000000 10844155936 11302768640 1881383184 18.676 97 18 36 1 1898035648 10781264 5871200
21:13:41 60286 8192000000 10838521968 11301507072 1878904784 18.656 97 9 18 1 1893191616 11816032 2470800
21:15:44 92705 8192000000 10859780240 11324063744 1889937280 18.745 97 28 56 2 1915205696 16943392 8325024
21:18:19 10217 8192000000 10862021408 11302391808 1909147568 18.900 99 43 85 0 1930963648 9982960 11833120
21:19:51 79398 8192000000 10821614944 11280773120 1823668608 18.208 95 25 50 2 1845984496 15245296 7070592
21:22:10 109258 8192000000 10843445632 11303063552 1894215120 18.780 98 13 26 0 1915573760 17963328 3395312
21:25:08 91600 8192000000 10849522704 11329732608 1915195472 18.948 99 16 32 2 1935006720 15845184 3966064
21:27:50 28479 8192000000 10848258944 11314053120 1930818512 19.073 100 14 28 1 1945484944 10919968 3746464
21:29:19 86106 8192000000 10864755408 11327082496 1894458976 18.782 97 19 38 1 1911975440 11992016 5524448
21:31:47 19444 8192000000 10858017984 11326291968 1907971520 18.890 98 15 30 0 1923392512 11226592 4194400
21:33:12 31690 8192000000 10884445664 11342094336 1947556912 19.207 100 34 68 2 1974668976 17717968 9394096
21:34:55 88494 8192000000 10855541664 11313229824 1844000352 18.373 95 11 22 2 1864029024 17401488 2627184
21:37:24 43316 8192000000 10876301712 11345825792 1953210640 19.252 100 24 48 1 1970858384 11028432 6619312
21:39:13 51728 8192000000 10873766464 11328274432 1913752480 18.937 98 27 54 1 1933388608 11695312 7940816
21:41:09 93605 8192000000 10829679216 11292618752 1790753152 17.938 92 20 40 0 1815988768 19796176 5439440
21:43:43 37564 8192000000 10883317392 11342479360 1932906400 19.090 99 27 52 2 1950076112 10302400 6867312
21:45:35 134903 8192000000 10869545024 11351023616 1917625984 18.968 98 22 44 2 1942392096 18376880 6389232
21:48:52 14405 8192000000 10893006144 11348713472 1934989056 19.107 99 39 77 0 1958152640 11472352 11691232
21:50:08 54801 8192000000 10871742032 11349303296 1951325824 19.237 100 12 24 1 1966237744 11678416 3233504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-08-2020 21:53:59 to 20-08-2020 21:54:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 167 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:02, from 20-08-2020 21:54:14 to 20-08-2020 21:54:16.

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          221709
MON$OLDEST_ACTIVE               223866
MON$OLDEST_SNAPSHOT             223866
MON$NEXT_TRANSACTION            223866
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                       2063584
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.33357 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33357 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33357 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33357 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 ---
:52: 
:53: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:06 2020
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:59: 
:60: 
:61: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:67: 
:68: 
:69: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Thu Aug 20 21:52:08 2020
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:99: 
--- end of diff output ---

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

20.08.20 21:54:18. 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-20 21:54:20 NO_SEVERE_ERRORS_FOUND
20.08.20 21:54:22. End of report.