Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2020 15: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 16731320 kB
MemAvailable 30917332 kB
Buffers 0 kB
Cached 14913632 kB
SwapCached 6040 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 15:52:54.9790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/34778
TRN_ID                          222600
ATT_ID                          561
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: 8522.79 32437 1022735
customer order: creation 161.20 402 19344
customer order: refuse 14.60 534 1752
order to supplier: creation 42.93 1571 5152
order to supplier: removal 3.08 136155 369
invoice (draft): creation 43.47 4457 5216
invoice (draft): removal 47.88 1103 5745
invoice accept: apply 39.66 22170 4759
invoice accept: cancel 43.31 1087 5197
customer reserve: creation 3603.18 220 432382
customer reserve: removal 4243.08 75 509170
realization accept: apply 77.43 254 9291
realization accept: cancel 81.01 151 9721
payment from customer: creation 56.43 157 6772
payment from customer: removal 5.24 126 629
payment to supplier: creation 45.33 184 5440
payment to supplier: removal 3.33 141 399
service: total inventory turnovers 4.33 1522 519
service: total monetary turnovers 6.55 59 786
service: aggregate perf. data 0.74 23944 89
service: refresh index statistics 0.03 454420 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2020 15:54:13 to 20-08-2020 15:54:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1930
TEST_TIME 2 3234
TEST_TIME 3 3604
TEST_TIME 4 3743
TEST_TIME 5 3479
TEST_TIME 6 4029
TEST_TIME 7 3820
TEST_TIME 8 3880
TEST_TIME 9 3712
TEST_TIME 10 4368
TEST_TIME 11 4247
TEST_TIME 12 4586
TEST_TIME 13 4552
TEST_TIME 14 4609
TEST_TIME 15 4840
TEST_TIME 16 4786
TEST_TIME 17 4899
TEST_TIME 18 5701
TEST_TIME 19 5791
TEST_TIME 20 6035
TEST_TIME 21 6418
TEST_TIME 22 6791
TEST_TIME 23 6780
TEST_TIME 24 6974
TEST_TIME 25 7206
TEST_TIME 26 7550
TEST_TIME 27 8050
TEST_TIME 28 8025
TEST_TIME 29 8033
TEST_TIME 30 7967
TEST_TIME 31 8677
TEST_TIME 32 8606
TEST_TIME 33 8556
TEST_TIME 34 8410
TEST_TIME 35 8311
TEST_TIME 36 8435
TEST_TIME 37 8496
TEST_TIME 38 8995
TEST_TIME 39 9111
TEST_TIME 40 9226
TEST_TIME 41 9384
TEST_TIME 42 9386
TEST_TIME 43 9311
TEST_TIME 44 9248
TEST_TIME 45 9130
TEST_TIME 46 8992
TEST_TIME 47 8919
TEST_TIME 48 8939
TEST_TIME 49 8964
TEST_TIME 50 8888
TEST_TIME 51 8922
TEST_TIME 52 8877
TEST_TIME 53 8775
TEST_TIME 54 8739
TEST_TIME 55 8698
TEST_TIME 56 8608
TEST_TIME 57 8658
TEST_TIME 58 8649
TEST_TIME 59 8602
TEST_TIME 60 8526
TEST_TIME 61 8493
TEST_TIME 62 8475
TEST_TIME 63 8494
TEST_TIME 64 8476
TEST_TIME 65 8379
TEST_TIME 66 8352
TEST_TIME 67 8416
TEST_TIME 68 8386
TEST_TIME 69 8450
TEST_TIME 70 8407
TEST_TIME 71 8469
TEST_TIME 72 8470
TEST_TIME 73 8442
TEST_TIME 74 8523
TEST_TIME 75 8505
TEST_TIME 76 8520
TEST_TIME 77 8529
TEST_TIME 78 8732
TEST_TIME 79 8689
TEST_TIME 80 8749
TEST_TIME 81 8686
TEST_TIME 82 8587
TEST_TIME 83 8579
TEST_TIME 84 8556
TEST_TIME 85 8507
TEST_TIME 86 8504
TEST_TIME 87 8500
TEST_TIME 88 8465
TEST_TIME 89 8465
TEST_TIME 90 8419
TEST_TIME 91 8350
TEST_TIME 92 8315
TEST_TIME 93 8335
TEST_TIME 94 8367
TEST_TIME 95 8410
TEST_TIME 96 8416
TEST_TIME 97 8401
TEST_TIME 98 8494
TEST_TIME 99 8513
TEST_TIME 100 8489
TEST_TIME 101 8524
TEST_TIME 102 8567
TEST_TIME 103 8619
TEST_TIME 104 8576
TEST_TIME 105 8606
TEST_TIME 106 8637
TEST_TIME 107 8663
TEST_TIME 108 8608
TEST_TIME 109 8573
TEST_TIME 110 8534
TEST_TIME 111 8550
TEST_TIME 112 8506
TEST_TIME 113 8503
TEST_TIME 114 8483
TEST_TIME 115 8472
TEST_TIME 116 8504
TEST_TIME 117 8479
TEST_TIME 118 8522
TEST_TIME 119 8491
TEST_TIME 120 8528

Performance per minute, chart


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

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1422457 1422457 0 0.00 0 24766 8 0 0
doc_list_biud 1422454 1422454 0 0.00 0 34059 35 0 0
sp_add_invoice_to_stock 4860 4759 101 2.07 60 277662 22171 0 101
sp_cancel_adding_invoice 5197 5197 0 0.00 6 71967 1088 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 20159 535 0 0
sp_cancel_customer_reserve 509170 509170 0 0.00 1 24100 76 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 3 10093 126 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 6131 142 0 0
sp_cancel_supplier_invoice 5755 5745 10 0.17 2 34085 1104 0 10
sp_cancel_supplier_order 369 369 0 0.00 7 1193149 136156 0 0
sp_cancel_write_off 9721 9721 0 0.00 1 15285 152 0 0
sp_client_order 19344 19344 0 0.00 4 27621 403 0 0
sp_customer_reserve 432382 432382 0 0.00 0 121325 220 0 0
sp_fill_shopping_cart 29687 29687 0 0.00 1 32665 52 0 0
sp_fill_shopping_cart_clo_res 432370 432370 0 0.00 0 17335 17 0 0
sp_get_clo_for_invoice 4746 4746 0 0.00 0 24754 388 0 0
sp_kill_cost_storno 15946 15946 0 0.00 0 8503 88 0 0
sp_kill_qstorno_ret_qs2qd 1034175 1034175 0 0.00 0 34037 41 0 0
sp_kill_qty_storno 545991 545991 0 0.00 0 34057 90 0 0
sp_lock_selected_doc 19538 19538 0 0.00 0 10295 9 0 0
sp_make_cost_storno 26249 26249 0 0.00 0 18024 76 0 0
sp_multiply_rows_for_pdistr 26249 26249 0 0.00 0 15356 39 0 0
sp_multiply_rows_for_qdistr 19344 19344 0 0.00 0 11126 166 0 0
sp_pay_from_customer 6772 6772 0 0.00 4 15886 158 0 0
sp_pay_to_supplier 5440 5440 0 0.00 4 23880 184 0 0
sp_payment_common 12212 12212 0 0.00 1 15876 42 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 11954 482 0 0
sp_qd_handle_on_invoice_upd_sts 9943 9943 0 0.00 0 21199 479 0 0
sp_qd_handle_on_reserve_upd_sts 9291 9291 0 0.00 0 20223 80 0 0
sp_reserve_write_off 9291 9291 0 0.00 6 20246 254 0 0
sp_supplier_invoice 5216 5216 0 0.00 6 129814 4457 0 0
sp_supplier_order 5152 5152 0 0.00 6 46453 1571 0 0
srv_aggregate_perf_data 89 89 0 0.00 2509 254991 23944 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1854 170835 43511 0 0
srv_find_qd_qs_mism 977618 977618 0 0.00 0 24764 6 0 0
srv_make_invnt_saldo 519 519 0 0.00 67 90913 1523 0 0
srv_make_money_saldo 786 786 0 0.00 1 7996 60 0 0
srv_recalc_idx_stat 3 3 0 0.00 276670 721805 454421 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4759 4759 0 0.00 0 42777 250 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 4665 122 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 1807 14 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 849 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 1419 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 1065 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 2052 28 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 41804 2488 0 0
t$perf-norm:sp_cancel_write_off 917 917 0 0.00 0 2862 11 0 0
t$perf-norm:sp_client_order 19344 19344 0 0.00 0 23412 23 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 8188 19 0 0
t$perf-norm:sp_pay_from_customer 6772 6772 0 0.00 0 5148 12 0 0
t$perf-norm:sp_pay_to_supplier 5440 5440 0 0.00 0 4791 15 0 0
t$perf-norm:sp_reserve_write_off 9291 9291 0 0.00 0 4748 14 0 0
t$perf-norm:sp_supplier_invoice 5216 5216 0 0.00 0 10997 29 0 0
t$perf-norm:sp_supplier_order 5152 5152 0 0.00 0 4403 24 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 690 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1477 29 0 0
t$perf-norm:srv_make_invnt_saldo 519 519 0 0.00 0 908 44 0 0
t$perf-norm:srv_make_money_saldo 786 786 0 0.00 0 993 31 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 340 114 0 0
v_add_invoice_to_stock 9692 9490 202 2.08 0 11292 21 0 202
v_all_customers 35612 35612 0 0.00 0 20 1 0 0
v_all_suppliers 28312 28312 0 0.00 0 96 1 0 0
v_all_wares 38688 38688 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 2656 14 0 0
v_cancel_client_order 3504 3504 0 0.00 0 12633 10 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 6386 11 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 2221 9 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 2101 10 0 0
v_cancel_supplier_invoice 788 768 20 2.53 0 13234 36 0 20
v_cancel_supplier_order 738 738 0 0.00 0 1781 9 0 0
v_cancel_write_off 1834 1834 0 0.00 0 2403 15 0 0
v_max_id_clo_ord 5148 5148 0 0.00 0 969 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 8421 24 0 0
v_max_id_ord_sup 5194 5194 0 0.00 0 8690 5 0 0
v_max_non_paid_invoice 1626 1626 0 0.00 0 368 3 0 0
v_max_non_paid_realizn 3567 3567 0 0.00 0 1344 5 0 0
v_min_id_clo_ord 5148 5148 0 0.00 0 8587 15 0 0
v_min_id_clo_res 990 990 0 0.00 0 1025 4 0 0
v_min_id_ord_sup 5194 5194 0 0.00 0 2030 4 0 0
v_min_non_paid_invoice 5440 5440 0 0.00 0 11788 26 0 0
v_min_non_paid_realizn 6772 6772 0 0.00 0 15873 33 0 0
v_our_firm 9486 9486 0 0.00 0 15 1 0 0
v_reserve_write_off 18582 18582 0 0.00 0 12568 8 0 0
x_lock_depdocs_on_canc_invoice 10558 10558 0 0.00 0 10243 155 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 10718 521 0 0
x_make_qstorno_1000_1200 5152 5152 0 0.00 2 46433 1448 0 0
x_make_qstorno_1200_2000 5216 5216 0 0.00 3 129788 4278 0 0
x_make_qstorno_2100_3300 432381 432381 0 0.00 0 121321 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-08-2020 15:54:20 to 20-08-2020 15:54:24.

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
13:22:58 1 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
13:23:59 25093 8192000000 10361255088 10681024512 1411088368 14.694 100 89 179 0 1445913408 5910384 28914656
13:25:30 14912 8192000000 10406723920 10733916160 1464122304 15.162 100 88 176 0 1500829264 8119840 28587120
13:26:53 24578 8192000000 10452701456 10788855808 1513596624 15.595 100 85 171 1 1550213280 9938048 26678608
13:28:21 29106 8192000000 10522199552 10871619584 1572848320 16.107 100 74 148 1 1605318448 9979360 22490768
13:29:56 14764 8192000000 10599340080 10956165120 1650730016 16.771 100 78 157 1 1686568672 12863872 22974784
13:31:17 5868 8192000000 10652502736 11017617408 1708912944 17.260 100 80 160 0 1745740704 14372160 22455600
13:32:25 5438 8192000000 10700511120 11074818048 1759304000 17.679 100 83 166 0 1797012880 13498848 24210032
13:33:33 3061 8192000000 10741620960 11119955968 1804156256 18.048 100 87 173 1 1841675696 13755520 23763920
13:34:39 2383 8192000000 10781979120 11164442624 1841411744 18.352 100 88 175 1 1878461392 12283488 24766160
13:35:45 1464 8192000000 10790550400 11181203456 1855723024 18.469 100 76 153 3 1889094000 11502528 21868448
13:36:50 1795 8192000000 10813750736 11201011712 1865803296 18.550 100 87 173 2 1902106528 12034400 24268832
13:37:54 8946 8192000000 10804369632 11202928640 1868245552 18.570 100 64 126 0 1897400608 11588208 17566848
13:39:07 2014 8192000000 10799762752 11212652544 1876598032 18.638 100 61 121 2 1903749280 10782704 16368544
13:40:11 3177 8192000000 10790548224 11209678848 1879961632 18.665 100 43 87 1 1900546560 7687072 12897856
13:41:16 53795 8192000000 10799416992 11226775552 1889703024 18.743 100 35 70 2 1905657264 5459696 10494544
13:43:17 6783 8192000000 10799611024 11231735808 1893063584 18.770 100 25 50 0 1906062368 6050352 6948432
13:44:24 8565 8192000000 10774005488 11194511360 1833182960 18.285 98 18 36 2 1843971680 5499728 5288992
13:45:34 51632 8192000000 10777313680 11192193024 1836668576 18.314 98 31 62 0 1850848992 5239584 8940832
13:47:28 7021 8192000000 10689363712 11099873280 1681494032 17.030 92 40 78 1 1703057312 9526880 12036400
13:48:40 8026 8192000000 10699312656 11090079744 1747505552 17.581 98 26 47 4 1758636960 4427952 6703456
13:49:49 9673 8192000000 10714197264 11124621312 1798430176 18.001 100 36 72 1 1814668672 5355488 10883008
13:51:00 20904 8192000000 10719327264 11135361024 1797421680 17.993 99 37 74 1 1813688336 5758896 10507760
13:52:23 7253 8192000000 10770013568 11185405952 1859407008 18.498 100 26 50 1 1871335872 4745984 7182880
13:53:38 18648 8192000000 10802294112 11215167488 1882262576 18.683 100 44 86 3 1903603984 8775344 12566064
13:55:05 29453 8192000000 10831877280 11249213440 1902488112 18.846 100 39 76 1 1924111952 10542656 11081184
13:56:37 100034 8192000000 10850916768 11280957440 1919645520 18.984 100 43 86 0 1945374224 12657568 13071136
14:00:04 48083 8192000000 10875090304 11306872832 1936291920 19.117 100 30 60 2 1960477616 14834608 9351088
14:02:00 13385 8192000000 10911252768 11331080192 1962149168 19.323 100 55 110 0 2002244448 23863968 16231312
14:03:25 3365 8192000000 10926917648 11337269248 1975602992 19.430 100 83 162 0 2016843376 19081248 22159136
14:04:34 5380 8192000000 10939550144 11362406400 1988916608 19.535 100 58 117 1 2027369520 21326880 17126032
14:05:45 11673 8192000000 10943467088 11370307584 1990528832 19.548 100 55 110 1 2036583120 29395824 16658464
14:07:02 8763 8192000000 10968681456 11387523072 2004604880 19.659 100 67 134 1 2057403248 33081904 19716464
14:08:20 5845 8192000000 10971777024 11386109952 1996837840 19.598 100 83 165 2 2059982704 37926432 25218432
14:09:33 6497 8192000000 10995877760 11405656064 2012185808 19.719 100 81 160 3 2080306096 43068048 25052240
14:10:46 4999 8192000000 10983713040 11400564736 2003553840 19.651 100 68 135 1 2065615824 41242864 20819120
14:11:59 4049 8192000000 10970574688 11388133376 1986865808 19.519 99 69 138 2 2045065136 37789280 20410048
14:13:10 3396 8192000000 11002197392 11418628096 2018614448 19.769 100 77 154 1 2084698416 42239728 23844240
14:14:20 3109 8192000000 10957949408 11383373824 1975273248 19.427 99 70 138 1 2034545104 37615904 21655952
14:15:32 2886 8192000000 10965400480 11375185920 1978396192 19.452 100 79 156 0 2040483552 37815728 24271632
14:16:41 4917 8192000000 10962884224 11371175936 1968823136 19.376 100 75 147 1 2039827392 48513408 22490848
14:17:53 1853 8192000000 10939751552 11342020608 1942395504 19.166 100 82 160 4 2014688608 46759424 25533680
14:19:02 5097 8192000000 10909014800 11333652480 1936285456 19.117 100 44 88 0 1995063776 44831808 13946512
14:20:11 4097 8192000000 10915398032 11331072000 1935634112 19.112 100 71 139 3 1994244208 36258496 22351600
14:21:22 5045 8192000000 10907838944 11337940992 1936158416 19.116 100 42 84 1 1992031392 42077472 13795504
14:22:42 4450 8192000000 10957616032 11365105664 1959249424 19.300 100 88 171 1 2034177728 48641312 26286992
14:23:49 22724 8192000000 10900159872 11342090240 1916692688 18.960 98 26 50 2 1961733616 36660752 8380176
14:25:14 46050 8192000000 10915767984 11350720512 1955271328 19.268 100 33 65 1 1998116288 32983264 9861696
14:27:11 23080 8192000000 10933453488 11359285248 1953106416 19.251 99 59 118 0 2013813440 43089264 17617760
14:28:42 13760 8192000000 10935497104 11383390208 1969004224 19.378 100 40 81 0 2032179584 49478592 13696768
14:30:02 24402 8192000000 10930993552 11389702144 1948573936 19.215 99 25 50 1 2008227056 50901168 8751952
14:31:32 50969 8192000000 10907875008 11380936704 1965255248 19.348 100 14 28 1 2009054240 39412096 4386896
14:33:26 33550 8192000000 10890221584 11341766656 1915734080 18.953 98 31 59 2 1944122432 19631312 8757040
14:35:17 37927 8192000000 10865791600 11320385536 1892817712 18.768 97 16 34 1 1911824000 14623328 4382960
14:37:12 26110 8192000000 10864987456 11320160256 1930704944 19.073 99 15 30 2 1946047456 10955280 4387232
14:38:41 76033 8192000000 10891473904 11354357760 1957176848 19.284 100 20 41 2 1979636560 16732384 5727328
14:41:25 94497 8192000000 10897041472 11351183360 1932813824 19.089 98 18 34 1 1953023504 15417664 4792016
14:44:00 52718 8192000000 10886167216 11348926464 1946327024 19.197 99 18 36 2 1967740624 15887072 5526528
14:46:42 85947 8192000000 10888808736 11327754240 1900537072 18.831 97 28 56 0 1916732576 7110160 9085344
14:49:13 170833 8192000000 10864775376 11304591360 1892527216 18.766 97 44 88 1 1921778976 16843776 12407984
14:53:05 51038 8192000000 10866991680 11309768704 1876298848 18.635 96 45 88 0 1903022032 13505968 13217216
14:54:57 56606 8192000000 10857111936 11327795200 1913553888 18.935 98 18 36 1 1931620256 13149744 4916624
14:56:58 42462 8192000000 10871900240 11347390464 1945257744 19.189 100 9 16 2 1963877536 15541312 3078480
14:58:44 35460 8192000000 10897377776 11342790656 1954051728 19.259 100 44 87 2 1980499760 13470592 12977440
15:00:22 104316 8192000000 10883266752 11362340864 1925513536 19.031 98 11 22 0 1945213984 16138928 3561520
15:03:07 146055 8192000000 10861595952 11329540096 1858013248 18.487 95 15 30 1 1878657216 16469728 4174240
15:06:34 59348 8192000000 10884950416 11345276928 1918066672 18.971 98 17 34 1 1933837792 10983248 4787872
15:08:33 79412 8192000000 10886354304 11357753344 1892995744 18.770 96 18 36 0 1920799664 21694784 6109136
15:10:56 64762 8192000000 10879343456 11329490944 1880761584 18.671 96 18 36 2 1896054544 10239408 5053552
15:13:05 71231 8192000000 10860907776 11316445184 1847750400 18.404 95 30 59 0 1864839280 8617328 8471552
15:15:59 94641 8192000000 10851500304 11309441024 1910082848 18.907 99 21 42 1 1924716480 9060720 5572912
15:18:35 41689 8192000000 10844227904 11311206400 1927938144 19.050 100 22 44 2 1943549296 9713664 5897488
15:20:19 21491 8192000000 10892478528 11326210048 1944312400 19.181 100 22 43 0 1957358848 6183024 6863424
15:21:43 52396 8192000000 10850439504 11313639424 1913826880 18.937 98 11 22 1 1925982736 9071728 3084128
15:23:36 72645 8192000000 10860299952 11326533632 1896950336 18.802 97 25 48 2 1916418816 12846208 6622272
15:26:01 65685 8192000000 10867280592 11336134656 1911776208 18.921 98 8 16 1 1928817776 14390656 2650912
15:28:08 22353 8192000000 10883245568 11344924672 1965431280 19.349 100 21 42 0 1979144960 7372544 6341136
15:29:37 52205 8192000000 10858964384 11331235840 1921541536 18.999 98 16 32 2 1939896848 13329008 5026304
15:31:37 36622 8192000000 10878413696 11343929344 1943256544 19.173 99 20 40 3 1960353616 11451808 5645264
15:33:17 45822 8192000000 10904800992 11350978560 1943105552 19.172 99 32 64 3 1964966048 13272784 8587712
15:35:05 77070 8192000000 10880667472 11345199104 1886370608 18.717 96 13 26 0 1902160752 12262672 3527472
15:37:24 27408 8192000000 10891685072 11339063296 1953195232 19.252 100 38 73 2 1978336960 15559920 9581808
15:38:54 42020 8192000000 10883386976 11350429696 1926008928 19.035 98 19 37 1 1943764736 12070224 5685584
15:40:43 97837 8192000000 10843240832 11295326208 1862748368 18.526 95 26 51 1 1876503120 6511024 7243728
15:43:29 55013 8192000000 10869709456 11336429568 1945325152 19.189 100 17 34 0 1960123792 9565344 5233296
15:45:30 47077 8192000000 10854062480 11318808576 1872865248 18.607 96 19 39 2 1889010960 10557184 5588528
15:47:20 71975 8192000000 10852656000 11330502656 1920971584 18.995 99 17 34 0 1939834128 13671696 5190848
15:49:34 67015 8192000000 10858844208 11309604864 1884899232 18.705 97 18 36 2 1903045552 12902528 5243792
15:51:47 72831 8192000000 10271883552 10681589760 1383684992 14.449 71 28 56 0 1406824320 15011312 8128016

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 15:54:25 to 20-08-2020 15:54:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 101 v_add_invoice_to_stock 335544517
user_exc 10 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          222891
MON$OLDEST_ACTIVE               222892
MON$OLDEST_SNAPSHOT             222892
MON$NEXT_TRANSACTION            222892
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                       2047904
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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Aug 20 15:53:16 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Aug 20 15:53:23 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Aug 20 15:53:23 2020
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Aug 20 15:53:25 2020
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
--- end of diff output ---

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

20.08.20 15:54:44. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-08-20 15:54:46 NO_SEVERE_ERRORS_FOUND
20.08.20 15:54:48. End of report.