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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 369f65bbd7e5442bbb810a88d29f958d
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-957.5.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2019-11-01 09:39
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.03
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32644004 kB
MemFree 16401748 kB
MemAvailable 30947436 kB
Buffers 0 kB
Cached 15248200 kB
SwapCached 6000 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-18 00:51:57.5010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/54268
TRN_ID                          219512
ATT_ID                          533
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: 8460.27 28005 1015232
customer order: creation 154.35 396 18522
customer order: refuse 13.62 547 1634
order to supplier: creation 42.58 1568 5109
order to supplier: removal 3.13 169983 375
invoice (draft): creation 41.69 4576 5003
invoice (draft): removal 48.24 1294 5789
invoice accept: apply 37.82 25256 4538
invoice accept: cancel 44.33 1267 5320
customer reserve: creation 3469.21 244 416305
customer reserve: removal 4332.52 92 519902
realization accept: apply 76.03 262 9124
realization accept: cancel 79.41 171 9529
payment from customer: creation 53.59 182 6431
payment from customer: removal 5.08 153 609
payment to supplier: creation 44.47 176 5336
payment to supplier: removal 2.61 150 313
service: total inventory turnovers 4.20 1658 504
service: total monetary turnovers 6.88 80 825
service: aggregate perf. data 0.50 51180 60
service: refresh index statistics 0.03 300859 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-08-2020 00:52:52 to 18-08-2020 00:52:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2618
TEST_TIME 2 3764
TEST_TIME 3 4282
TEST_TIME 4 4098
TEST_TIME 5 4474
TEST_TIME 6 4925
TEST_TIME 7 4882
TEST_TIME 8 4712
TEST_TIME 9 4659
TEST_TIME 10 4522
TEST_TIME 11 4542
TEST_TIME 12 4505
TEST_TIME 13 4479
TEST_TIME 14 4583
TEST_TIME 15 4826
TEST_TIME 16 4754
TEST_TIME 17 4813
TEST_TIME 18 4784
TEST_TIME 19 4921
TEST_TIME 20 4942
TEST_TIME 21 5020
TEST_TIME 22 5201
TEST_TIME 23 5234
TEST_TIME 24 5583
TEST_TIME 25 5769
TEST_TIME 26 6048
TEST_TIME 27 6339
TEST_TIME 28 6440
TEST_TIME 29 6473
TEST_TIME 30 6531
TEST_TIME 31 6639
TEST_TIME 32 6923
TEST_TIME 33 7118
TEST_TIME 34 7357
TEST_TIME 35 7356
TEST_TIME 36 7735
TEST_TIME 37 7815
TEST_TIME 38 7791
TEST_TIME 39 7942
TEST_TIME 40 7917
TEST_TIME 41 8072
TEST_TIME 42 8328
TEST_TIME 43 8942
TEST_TIME 44 8844
TEST_TIME 45 8938
TEST_TIME 46 8973
TEST_TIME 47 9149
TEST_TIME 48 9159
TEST_TIME 49 9151
TEST_TIME 50 9065
TEST_TIME 51 8986
TEST_TIME 52 8936
TEST_TIME 53 8957
TEST_TIME 54 8887
TEST_TIME 55 8799
TEST_TIME 56 8736
TEST_TIME 57 8699
TEST_TIME 58 8684
TEST_TIME 59 8673
TEST_TIME 60 8731
TEST_TIME 61 8707
TEST_TIME 62 8717
TEST_TIME 63 8609
TEST_TIME 64 8538
TEST_TIME 65 8542
TEST_TIME 66 8467
TEST_TIME 67 8442
TEST_TIME 68 8389
TEST_TIME 69 8465
TEST_TIME 70 8537
TEST_TIME 71 8551
TEST_TIME 72 8493
TEST_TIME 73 8505
TEST_TIME 74 8507
TEST_TIME 75 8425
TEST_TIME 76 8427
TEST_TIME 77 8434
TEST_TIME 78 8394
TEST_TIME 79 8379
TEST_TIME 80 8341
TEST_TIME 81 8301
TEST_TIME 82 8276
TEST_TIME 83 8209
TEST_TIME 84 8260
TEST_TIME 85 8361
TEST_TIME 86 8307
TEST_TIME 87 8303
TEST_TIME 88 8264
TEST_TIME 89 8241
TEST_TIME 90 8204
TEST_TIME 91 8209
TEST_TIME 92 8233
TEST_TIME 93 8216
TEST_TIME 94 8327
TEST_TIME 95 8355
TEST_TIME 96 8359
TEST_TIME 97 8338
TEST_TIME 98 8390
TEST_TIME 99 8428
TEST_TIME 100 8410
TEST_TIME 101 8529
TEST_TIME 102 8506
TEST_TIME 103 8489
TEST_TIME 104 8521
TEST_TIME 105 8552
TEST_TIME 106 8516
TEST_TIME 107 8468
TEST_TIME 108 8451
TEST_TIME 109 8457
TEST_TIME 110 8445
TEST_TIME 111 8403
TEST_TIME 112 8380
TEST_TIME 113 8355
TEST_TIME 114 8362
TEST_TIME 115 8348
TEST_TIME 116 8373
TEST_TIME 117 8429
TEST_TIME 118 8432
TEST_TIME 119 8481
TEST_TIME 120 8472

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-08-2020 00:52:56 to 18-08-2020 00:52:59.

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 1399539 1399539 0 0.00 0 46152 9 0 0
doc_list_biud 1399539 1399539 0 0.00 0 86921 44 0 0
sp_add_invoice_to_stock 4673 4538 135 2.88 30 218742 25256 0 135
sp_cancel_adding_invoice 5320 5320 0 0.00 5 96645 1267 0 0
sp_cancel_client_order 1634 1634 0 0.00 4 10973 548 0 0
sp_cancel_customer_reserve 519902 519902 0 0.00 1 44236 93 0 0
sp_cancel_pay_from_customer 609 609 0 0.00 2 5042 154 0 0
sp_cancel_pay_to_supplier 313 313 0 0.00 3 4710 151 0 0
sp_cancel_supplier_invoice 5796 5789 7 0.12 2 87012 1294 0 7
sp_cancel_supplier_order 375 375 0 0.00 8 1484117 169983 0 0
sp_cancel_write_off 9529 9529 0 0.00 1 24160 172 0 0
sp_client_order 18522 18522 0 0.00 4 18590 397 0 0
sp_customer_reserve 416305 416305 0 0.00 0 79557 245 0 0
sp_fill_shopping_cart 28629 28629 0 0.00 1 16355 54 0 0
sp_fill_shopping_cart_clo_res 416284 416284 0 0.00 0 20178 19 0 0
sp_get_clo_for_invoice 4514 4514 0 0.00 0 24405 428 0 0
sp_kill_cost_storno 15771 15771 0 0.00 0 12175 111 0 0
sp_kill_qstorno_ret_qs2qd 1055497 1055497 0 0.00 0 86919 50 0 0
sp_kill_qty_storno 556187 556187 0 0.00 0 86920 109 0 0
sp_lock_selected_doc 18833 18833 0 0.00 0 6860 5 0 0
sp_make_cost_storno 25405 25405 0 0.00 0 46045 81 0 0
sp_multiply_rows_for_pdistr 25405 25405 0 0.00 0 16361 45 0 0
sp_multiply_rows_for_qdistr 18522 18522 0 0.00 0 12654 164 0 0
sp_pay_from_customer 6431 6431 0 0.00 4 43599 183 0 0
sp_pay_to_supplier 5336 5336 0 0.00 5 12381 176 0 0
sp_payment_common 11767 11767 0 0.00 2 43591 53 0 0
sp_qd_handle_on_cancel_clo 1634 1634 0 0.00 0 10824 512 0 0
sp_qd_handle_on_invoice_upd_sts 9834 9834 0 0.00 0 30722 588 0 0
sp_qd_handle_on_reserve_upd_sts 9124 9124 0 0.00 0 18547 87 0 0
sp_reserve_write_off 9124 9124 0 0.00 6 18589 263 0 0
sp_supplier_invoice 5003 5003 0 0.00 7 96609 4577 0 0
sp_supplier_order 5109 5109 0 0.00 7 47147 1569 0 0
srv_aggregate_perf_data 60 60 0 0.00 3165 1042749 51180 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1595 129236 39000 0 0
srv_find_qd_qs_mism 971522 971522 0 0.00 0 24097 7 0 0
srv_make_invnt_saldo 504 504 0 0.00 28 103197 1658 0 0
srv_make_money_saldo 825 825 0 0.00 1 19964 81 0 0
srv_recalc_idx_stat 4 4 0 0.00 182933 432057 300860 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 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 4538 4538 0 0.00 0 6814 298 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 1 4247 133 0 0
t$perf-norm:sp_cancel_client_order 1634 1634 0 0.00 0 5986 32 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 808 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 609 609 0 0.00 0 1508 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 313 313 0 0.00 0 1240 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 348 348 0 0.00 0 2168 26 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 42443 3419 0 0
t$perf-norm:sp_cancel_write_off 892 892 0 0.00 0 2333 13 0 0
t$perf-norm:sp_client_order 18522 18522 0 0.00 0 6970 29 0 0
t$perf-norm:sp_customer_reserve 901 901 0 0.00 0 5520 12 0 0
t$perf-norm:sp_pay_from_customer 6431 6431 0 0.00 0 4182 12 0 0
t$perf-norm:sp_pay_to_supplier 5336 5336 0 0.00 0 4881 12 0 0
t$perf-norm:sp_reserve_write_off 9124 9124 0 0.00 0 5353 13 0 0
t$perf-norm:sp_supplier_invoice 5003 5003 0 0.00 0 5441 34 0 0
t$perf-norm:sp_supplier_order 5109 5109 0 0.00 0 5561 35 0 0
t$perf-norm:srv_aggregate_perf_data 60 60 0 0.00 0 768 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 9 1 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 4907 64 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 2368 42 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 14 6 0 0
v_add_invoice_to_stock 9298 9028 270 2.90 0 13741 29 0 270
v_all_customers 33906 33906 0 0.00 0 73 1 0 0
v_all_suppliers 28112 28112 0 0.00 0 31 1 0 0
v_all_wares 37042 37042 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 2632 13 0 0
v_cancel_client_order 3268 3268 0 0.00 0 6538 8 0 0
v_cancel_customer_prepayment 1218 1218 0 0.00 0 3925 11 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 455 4 0 0
v_cancel_payment_to_supplier 626 626 0 0.00 0 4698 22 0 0
v_cancel_supplier_invoice 710 696 14 1.97 0 1190 18 0 14
v_cancel_supplier_order 750 750 0 0.00 0 6135 19 0 0
v_cancel_write_off 1784 1784 0 0.00 0 5961 30 0 0
v_max_id_clo_ord 5109 5109 0 0.00 0 2171 3 0 0
v_max_id_clo_res 901 901 0 0.00 0 2088 13 0 0
v_max_id_ord_sup 4999 4999 0 0.00 0 3156 3 0 0
v_max_non_paid_invoice 1388 1388 0 0.00 0 6256 8 0 0
v_max_non_paid_realizn 3307 3307 0 0.00 0 3802 8 0 0
v_min_id_clo_ord 5109 5109 0 0.00 0 3266 10 0 0
v_min_id_clo_res 901 901 0 0.00 0 1918 10 0 0
v_min_id_ord_sup 4999 4999 0 0.00 0 12600 7 0 0
v_min_non_paid_invoice 5336 5336 0 0.00 0 11870 21 0 0
v_min_non_paid_realizn 6431 6431 0 0.00 0 20926 43 0 0
v_our_firm 9384 9384 0 0.00 0 26 1 0 0
v_reserve_write_off 18248 18248 0 0.00 0 6136 7 0 0
x_lock_depdocs_on_canc_invoice 10761 10761 0 0.00 0 27165 213 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 17544 676 0 0
x_make_qstorno_1000_1200 5109 5109 0 0.00 2 47128 1433 0 0
x_make_qstorno_1200_2000 5003 5003 0 0.00 3 96556 4390 0 0
x_make_qstorno_2100_3300 416305 416305 0 0.00 0 79556 220 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
22:22:00 2 8192000000 8798382336 8808857600 5057328 0.061 1 1 2 0 5175376 55488 62560
22:23:01 25624 8192000000 10371727088 10690850816 1431732512 14.877 100 87 174 0 1465571392 5641712 28197168
22:24:33 26667 8192000000 10441656800 10770051072 1490772112 15.396 100 91 182 0 1527670736 7994816 28903808
22:26:13 18904 8192000000 10495036000 10836697088 1555136736 15.954 100 89 178 2 1593059296 9858272 28064288
22:27:35 10982 8192000000 10549603088 10902822912 1612206480 16.444 100 77 154 0 1646124800 11257552 22660768
22:28:50 11052 8192000000 10629222656 10995609600 1683058000 17.043 100 80 160 0 1719117936 12895920 23164016
22:30:04 10432 8192000000 10677496512 11053318144 1743378512 17.547 100 79 158 1 1779501296 14037600 22085184
22:31:17 5391 8192000000 10708370384 11082829824 1773657264 17.797 100 89 179 0 1812145792 13604960 24883568
22:32:25 3204 8192000000 10753242256 11128438784 1808191600 18.081 100 86 172 0 1845460528 13013968 24254960
22:33:32 2071 8192000000 10780949632 11161632768 1844261984 18.375 100 94 188 1 1884150544 12762464 27126096
22:34:37 2623 8192000000 10802343472 11187752960 1857716432 18.485 100 76 152 0 1892381488 12758592 21906464
22:35:42 30838 8192000000 10806389040 11206160384 1872047936 18.601 100 69 136 0 1901776576 9560576 20168064
22:37:14 6410 8192000000 10777425648 11201581056 1871955808 18.600 100 25 50 2 1884601312 5729936 6915568
22:38:22 6270 8192000000 10796999648 11222368256 1885837296 18.712 100 26 52 1 1903463200 10862816 6763088
22:39:32 12558 8192000000 10803769312 11231014912 1896197568 18.796 100 19 38 2 1907171040 5508848 5464624
22:40:47 54690 8192000000 10845454400 11256631296 1902784800 18.849 100 18 36 2 1913674000 4674592 6214608
22:42:54 5039 8192000000 10804029088 11236958208 1882661648 18.687 99 23 43 5 1894700400 5700880 6337872
22:44:01 3305 8192000000 10795297776 11218931712 1865714256 18.550 99 29 55 2 1880388336 6594336 8079744
22:45:05 13941 8192000000 10750841952 11162992640 1781133472 17.859 96 15 30 0 1790472208 4982544 4356192
22:46:20 13407 8192000000 10741652624 11126013952 1779882016 17.849 97 54 107 1 1801795760 5964464 15949280
22:47:33 6361 8192000000 10714461136 11128422400 1786009952 17.899 99 27 54 0 1801649056 7556848 8082256
22:48:42 9343 8192000000 10693931248 11103522816 1739909760 17.518 97 23 46 4 1752530096 5510256 7110080
22:49:53 51836 8192000000 10745535296 11165814784 1833256384 18.286 100 34 68 4 1848889712 5492288 10141040
22:51:45 4714 8192000000 10758540224 11186061312 1852686256 18.444 100 28 56 2 1867013872 5273904 9053712
22:52:57 28484 8192000000 10810574224 11225407488 1885268800 18.708 100 62 124 0 1911784080 9218592 17296688
22:54:31 10947 8192000000 10838855712 11252768768 1907433488 18.886 100 56 112 3 1935244272 13258752 14552032
22:55:48 59883 8192000000 10872579040 11292516352 1917224032 18.965 100 36 72 2 1952252992 24325168 10703792
22:57:55 102598 8192000000 10869786416 11312967680 1931233888 19.077 100 23 44 3 1959011440 21481920 6295632
23:00:45 19810 8192000000 10887603728 11317456896 1953478896 19.254 100 35 70 1 1981593152 18377008 9737248
23:02:08 11195 8192000000 10913232336 11339980800 1969398064 19.381 100 60 120 2 2010074768 23037408 17639296
23:03:26 12809 8192000000 10921078912 11347693568 1974397520 19.420 100 53 105 1 2010480512 20935904 15147088
23:04:59 3752 8192000000 10945595664 11361284096 1989191952 19.537 100 64 128 0 2032627312 24654976 18780384
23:06:09 11559 8192000000 10963032032 11382784000 1996945408 19.599 100 58 116 2 2043783376 29195792 17642176
23:07:27 9925 8192000000 10970932240 11387932672 1987808304 19.526 99 86 170 2 2044948496 32065056 25075136
23:08:54 8830 8192000000 10992239648 11415117824 2020627680 19.785 100 67 134 1 2076527648 35612320 20287648
23:10:16 7564 8192000000 10972493200 11392401408 2000431344 19.626 100 71 142 2 2062424032 40226064 21766624
23:11:36 8241 8192000000 10981453904 11400798208 1996971360 19.599 100 70 138 1 2063366208 45698272 20696576
23:12:53 3582 8192000000 10988511568 11401281536 1985643040 19.509 99 88 173 1 2061266288 48355504 27267744
23:14:15 2212 8192000000 10973109376 11386814464 1984548144 19.501 99 82 163 1 2056862832 46706752 25607936
23:15:30 3175 8192000000 10965930768 11389702144 1975647072 19.430 99 63 126 2 2037039488 40934576 20457840
23:16:43 2514 8192000000 10969605152 11381977088 1979838032 19.463 100 91 183 0 2053402272 44676704 28887536
23:17:52 2570 8192000000 10948732288 11355983872 1944114448 19.180 99 78 155 0 2012301808 44214864 23972496
23:19:03 2877 8192000000 10952368480 11356794880 1961741072 19.320 100 94 187 2 2032629456 42471232 28417152
23:20:15 2164 8192000000 10988945216 11394236416 1989405216 19.539 100 89 178 1 2063340320 46094000 27841104
23:21:25 3526 8192000000 10961539792 11379699712 1970981024 19.393 100 75 150 0 2050411024 55406208 24023792
23:22:44 2382 8192000000 10967346416 11371552768 1960073696 19.307 100 88 172 1 2043736496 56400224 27262576
23:23:53 3926 8192000000 10979417472 11386077184 1972616736 19.406 100 87 173 0 2057106192 57000832 27488624
23:25:06 3045 8192000000 10986324768 11389132800 1973894672 19.416 100 91 181 3 2059014464 56304912 28814880
23:26:18 17569 8192000000 10974152480 11408015360 1985611824 19.509 100 43 86 1 2050885776 52008704 13265248
23:27:41 7136 8192000000 10955419872 11386769408 1955836176 19.273 99 53 104 0 2021224096 48992368 16395552
23:28:57 20880 8192000000 10940028032 11379171328 1948196688 19.212 99 32 64 1 2004505152 45226096 11082368
23:30:34 15377 8192000000 10946343136 11385049088 1951084352 19.235 100 36 72 0 2019689904 56362560 12242992
23:31:58 34022 8192000000 10962311504 11393884160 1918315472 18.973 98 37 74 0 1998261664 66744032 13202160
23:33:37 2482 8192000000 11003957440 11410845696 1982576752 19.485 100 96 188 0 2077574944 65523168 29475024
23:34:42 41191 8192000000 10934392784 11372515328 1966132832 19.355 100 35 67 1 2010182560 33115088 10934640
23:36:29 28529 8192000000 10910773920 11372589056 1949226640 19.220 99 15 30 3 1986334336 31691152 5416544
23:38:01 42233 8192000000 10904563008 11377729536 1964108880 19.339 100 9 18 0 1998941552 31796816 3035856
23:39:45 57442 8192000000 10908454992 11355549696 1951655072 19.240 99 42 84 1 1975101632 12206432 11240128
23:42:16 105585 8192000000 10900296544 11368366080 1971024288 19.394 100 13 26 1 1983895216 9362496 3508432
23:45:03 129235 8192000000 10904343072 11358646272 1929212672 19.061 98 27 55 2 1946056816 9134848 7709296
23:48:59 127248 8192000000 10890907584 11346345984 1908906480 18.898 97 27 54 2 1924049536 7699872 7443184
23:52:07 90580 8192000000 10889246160 11358109696 1927377088 19.046 98 18 36 0 1947547360 15601840 4568432
23:54:39 21498 8192000000 10869833856 11330756608 1944294544 19.181 100 24 47 2 1961000480 9948976 6756960
23:56:02 1593 8192000000 10905210544 11335073792 1954352896 19.261 100 74 148 0 1985852464 10054832 21444736
23:57:11 16501 8192000000 10906573888 11358875648 1952385168 19.245 99 29 58 1 1977195088 16569248 8240672
23:58:28 44597 8192000000 10911679968 11355799552 1943007952 19.171 99 44 88 2 1976394544 20098016 13288576
00:00:14 51365 8192000000 10873721152 11354566656 1933060096 19.091 99 17 34 0 1963753712 25254224 5439392
00:02:08 84801 8192000000 10855000512 11317186560 1867620208 18.565 96 15 30 0 1888233744 16194016 4419520
00:04:34 47959 8192000000 10874459664 11344273408 1947782432 19.209 100 12 23 3 1964046848 12854960 3409456
00:06:29 120355 8192000000 10878712320 11353714688 1935984688 19.115 99 10 20 0 1950227536 11875728 2367120
00:10:33 64570 8192000000 10879416304 11349483520 1961293696 19.316 100 17 34 0 1974023728 7845488 4884544
00:12:38 41055 8192000000 10888015072 11350433792 1962990464 19.330 100 17 32 0 1978038464 10452240 4595760
00:14:24 71810 8192000000 10871324512 11342471168 1888715536 18.735 96 20 40 1 1907722048 12610512 6396000
00:16:55 36323 8192000000 10841405424 11301568512 1870169696 18.586 96 19 37 0 1883764640 7930384 5664560
00:18:34 38055 8192000000 10857596656 11316162560 1927942208 19.050 99 43 85 1 1956768432 16934544 11891680
00:21:08 58390 8192000000 10859457376 11342594048 1940353552 19.150 100 4 8 0 1960758368 19703696 701120
00:23:23 55436 8192000000 10859966016 11323125760 1892244288 18.764 97 20 39 4 1908596928 10893904 5458736
00:25:21 85991 8192000000 10849602000 11314544640 1825055552 18.219 94 15 30 0 1837560208 8647760 3856896
00:27:48 49641 8192000000 10867154752 11312943104 1881307792 18.676 97 21 42 2 1895586992 8329280 5949920
00:29:39 65868 8192000000 10871189696 11336257536 1925174736 19.028 99 18 36 3 1944420240 14404960 4840544
00:31:46 16674 8192000000 10872776048 11319918592 1891590496 18.759 97 32 64 1 1913995776 13262192 9143088
00:33:03 50408 8192000000 10846703856 11305070592 1877036704 18.641 97 17 35 0 1895814160 13824112 4953344
00:34:56 8579 8192000000 10907562320 11325390848 1931077376 19.075 99 67 136 2 1960659696 10320384 19261936
00:36:24 43568 8192000000 10881583136 11337469952 1927806000 19.049 99 26 52 1 1948686672 13549616 7331056
00:38:15 114101 8192000000 10884801824 11353849856 1932273744 19.085 99 13 26 0 1957559232 21562016 3723472
00:41:27 40179 8192000000 10870937216 11340636160 1931051376 19.075 99 12 24 0 1949256752 14662640 3542736
00:43:09 50544 8192000000 10856593024 11321159680 1932155504 19.084 100 20 40 2 1951234704 13493840 5585360
00:45:35 77761 8192000000 10872266096 11339223040 1907032992 18.883 98 9 18 0 1925417440 15573856 2810592
00:47:54 73559 8192000000 10888695792 11365195776 1958656992 19.295 100 12 24 0 1974976944 12824064 3495888
00:50:24 95673 8192000000 9654720128 9986732032 788132592 8.776 40 7 14 0 799807616 9893712 1781312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-08-2020 00:53:05 to 18-08-2020 00:53:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 135 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 18-08-2020 00:53:20 to 18-08-2020 00:53:22.

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          218766
MON$OLDEST_ACTIVE               219743
MON$OLDEST_SNAPSHOT             219743
MON$NEXT_TRANSACTION            219743
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                       2091520
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 ---
:125: 
:126: mscs-test-161.budzdorov.ru    Tue Aug 18 00:52:07 2020
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Tue Aug 18 00:52:07 2020
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:132: 
:133: 
:134: mscs-test-161.budzdorov.ru    Tue Aug 18 00:52:07 2020
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:136: 
:137: 
:138: mscs-test-161.budzdorov.ru    Tue Aug 18 00:52:07 2020
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:140: 
:141: 
:142: mscs-test-161.budzdorov.ru    Tue Aug 18 00:52:08 2020
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:144: 
:145: 
:146: mscs-test-161.budzdorov.ru    Tue Aug 18 00:52:08 2020
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:148: 
--- end of diff output ---

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

18.08.20 00:53:24. 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-18 00:53:26 NO_SEVERE_ERRORS_FOUND
18.08.20 00:53:28. End of report.