Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.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 17122712 kB
MemAvailable 30923092 kB
Buffers 0 kB
Cached 14531444 kB
SwapCached 6016 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 00:51:54.3720
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/53628
TRN_ID                          220404
ATT_ID                          504
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: 8786.74 22388 1054409
customer order: creation 165.04 371 19805
customer order: refuse 14.53 512 1743
order to supplier: creation 44.06 1576 5287
order to supplier: removal 3.29 124807 395
invoice (draft): creation 44.76 4096 5371
invoice (draft): removal 49.03 1099 5883
invoice accept: apply 40.42 20795 4851
invoice accept: cancel 44.10 981 5292
customer reserve: creation 3741.67 204 449000
customer reserve: removal 4350.19 70 522023
realization accept: apply 82.30 214 9876
realization accept: cancel 79.31 150 9517
payment from customer: creation 59.28 126 7113
payment from customer: removal 5.55 96 666
payment to supplier: creation 47.88 141 5745
payment to supplier: removal 3.24 85 389
service: total inventory turnovers 4.52 1503 542
service: total monetary turnovers 6.78 33 814
service: aggregate perf. data 0.78 28827 93
service: refresh index statistics 0.03 262075 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2020 00:53:01 to 20-08-2020 00:53:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3389
TEST_TIME 2 4263
TEST_TIME 3 4421
TEST_TIME 4 4087
TEST_TIME 5 4319
TEST_TIME 6 4439
TEST_TIME 7 5136
TEST_TIME 8 5277
TEST_TIME 9 5302
TEST_TIME 10 5563
TEST_TIME 11 5539
TEST_TIME 12 5600
TEST_TIME 13 5434
TEST_TIME 14 5341
TEST_TIME 15 5305
TEST_TIME 16 5346
TEST_TIME 17 5340
TEST_TIME 18 5372
TEST_TIME 19 5473
TEST_TIME 20 5544
TEST_TIME 21 5655
TEST_TIME 22 5879
TEST_TIME 23 6207
TEST_TIME 24 6586
TEST_TIME 25 6892
TEST_TIME 26 7212
TEST_TIME 27 7262
TEST_TIME 28 7209
TEST_TIME 29 7256
TEST_TIME 30 7540
TEST_TIME 31 8166
TEST_TIME 32 8229
TEST_TIME 33 8348
TEST_TIME 34 8339
TEST_TIME 35 8766
TEST_TIME 36 9031
TEST_TIME 37 9111
TEST_TIME 38 9084
TEST_TIME 39 8950
TEST_TIME 40 8900
TEST_TIME 41 8852
TEST_TIME 42 9138
TEST_TIME 43 8928
TEST_TIME 44 8877
TEST_TIME 45 8879
TEST_TIME 46 8790
TEST_TIME 47 8790
TEST_TIME 48 8696
TEST_TIME 49 8626
TEST_TIME 50 8592
TEST_TIME 51 8563
TEST_TIME 52 8636
TEST_TIME 53 8701
TEST_TIME 54 8752
TEST_TIME 55 8680
TEST_TIME 56 8652
TEST_TIME 57 8669
TEST_TIME 58 8655
TEST_TIME 59 8688
TEST_TIME 60 8802
TEST_TIME 61 8782
TEST_TIME 62 8771
TEST_TIME 63 8852
TEST_TIME 64 8807
TEST_TIME 65 8797
TEST_TIME 66 8854
TEST_TIME 67 8949
TEST_TIME 68 8929
TEST_TIME 69 9001
TEST_TIME 70 8987
TEST_TIME 71 9085
TEST_TIME 72 9141
TEST_TIME 73 9204
TEST_TIME 74 9095
TEST_TIME 75 9022
TEST_TIME 76 8986
TEST_TIME 77 8905
TEST_TIME 78 8851
TEST_TIME 79 8892
TEST_TIME 80 8881
TEST_TIME 81 8846
TEST_TIME 82 8828
TEST_TIME 83 8821
TEST_TIME 84 8820
TEST_TIME 85 8753
TEST_TIME 86 8795
TEST_TIME 87 8767
TEST_TIME 88 8746
TEST_TIME 89 8777
TEST_TIME 90 8757
TEST_TIME 91 8804
TEST_TIME 92 8787
TEST_TIME 93 8839
TEST_TIME 94 8885
TEST_TIME 95 8894
TEST_TIME 96 8987
TEST_TIME 97 8975
TEST_TIME 98 8992
TEST_TIME 99 9020
TEST_TIME 100 8986
TEST_TIME 101 9048
TEST_TIME 102 9036
TEST_TIME 103 9013
TEST_TIME 104 8951
TEST_TIME 105 8956
TEST_TIME 106 8931
TEST_TIME 107 8940
TEST_TIME 108 8917
TEST_TIME 109 8890
TEST_TIME 110 8867
TEST_TIME 111 8859
TEST_TIME 112 8812
TEST_TIME 113 8803
TEST_TIME 114 8781
TEST_TIME 115 8767
TEST_TIME 116 8777
TEST_TIME 117 8782
TEST_TIME 118 8783
TEST_TIME 119 8824
TEST_TIME 120 8808

Performance per minute, chart


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

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 1469112 1469112 0 0.00 0 24012 7 0 0
doc_list_biud 1469110 1469110 0 0.00 0 58466 33 0 0
sp_add_invoice_to_stock 4976 4851 125 2.51 37 197402 20795 0 125
sp_cancel_adding_invoice 5292 5292 0 0.00 5 56322 982 0 0
sp_cancel_client_order 1743 1743 0 0.00 4 16343 513 0 0
sp_cancel_customer_reserve 522023 522023 0 0.00 1 58468 71 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 3796 97 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 2 3419 85 0 0
sp_cancel_supplier_invoice 5896 5883 13 0.22 2 32432 1100 0 13
sp_cancel_supplier_order 395 395 0 0.00 7 1124987 124808 0 0
sp_cancel_write_off 9517 9517 0 0.00 1 29792 151 0 0
sp_client_order 19805 19805 0 0.00 3 28505 371 0 0
sp_customer_reserve 449000 449000 0 0.00 0 171900 204 0 0
sp_fill_shopping_cart 30463 30463 0 0.00 1 16183 44 0 0
sp_fill_shopping_cart_clo_res 448986 448986 0 0.00 0 12633 15 0 0
sp_get_clo_for_invoice 4812 4812 0 0.00 0 21246 272 0 0
sp_kill_cost_storno 15864 15864 0 0.00 0 13656 85 0 0
sp_kill_qstorno_ret_qs2qd 1059841 1059841 0 0.00 0 58460 39 0 0
sp_kill_qty_storno 559541 559541 0 0.00 0 58466 85 0 0
sp_lock_selected_doc 20360 20360 0 0.00 0 9522 4 0 0
sp_make_cost_storno 27546 27546 0 0.00 0 23984 60 0 0
sp_multiply_rows_for_pdistr 27546 27546 0 0.00 0 8455 34 0 0
sp_multiply_rows_for_qdistr 19805 19805 0 0.00 0 12588 161 0 0
sp_pay_from_customer 7113 7113 0 0.00 4 8984 126 0 0
sp_pay_to_supplier 5745 5745 0 0.00 5 11178 141 0 0
sp_payment_common 12858 12858 0 0.00 1 11168 31 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 16335 475 0 0
sp_qd_handle_on_invoice_upd_sts 10104 10104 0 0.00 0 29718 416 0 0
sp_qd_handle_on_reserve_upd_sts 9876 9876 0 0.00 0 26380 77 0 0
sp_reserve_write_off 9876 9876 0 0.00 6 27738 215 0 0
sp_supplier_invoice 5371 5371 0 0.00 7 94094 4096 0 0
sp_supplier_order 5287 5287 0 0.00 6 35214 1576 0 0
srv_aggregate_perf_data 93 93 0 0.00 2810 494394 28828 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2199 155556 40410 0 0
srv_find_qd_qs_mism 1007373 1007373 0 0.00 0 20138 6 0 0
srv_make_invnt_saldo 542 542 0 0.00 43 145436 1503 0 0
srv_make_money_saldo 814 814 0 0.00 1 1645 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 93433 480199 262075 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4851 4851 0 0.00 0 16254 224 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 9734 163 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 1843 14 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 2148 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 1001 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 711 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 596 12 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 29381 2405 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 2261 12 0 0
t$perf-norm:sp_client_order 19805 19805 0 0.00 0 9025 23 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 2526 7 0 0
t$perf-norm:sp_pay_from_customer 7113 7113 0 0.00 0 4455 12 0 0
t$perf-norm:sp_pay_to_supplier 5745 5745 0 0.00 0 3437 10 0 0
t$perf-norm:sp_reserve_write_off 9876 9876 0 0.00 0 14500 15 0 0
t$perf-norm:sp_supplier_invoice 5371 5371 0 0.00 0 4958 24 0 0
t$perf-norm:sp_supplier_order 5287 5287 0 0.00 0 4652 23 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 1567 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 51 2 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 3653 44 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 2524 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 9872 9622 250 2.53 0 5661 20 0 250
v_all_customers 36740 36740 0 0.00 0 96 1 0 0
v_all_suppliers 30000 30000 0 0.00 0 33 1 0 0
v_all_wares 39610 39610 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1823 10 0 0
v_cancel_client_order 3486 3486 0 0.00 0 3428 6 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 3778 12 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 816 3 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 3393 12 0 0
v_cancel_supplier_invoice 802 776 26 3.24 0 3460 24 0 26
v_cancel_supplier_order 790 790 0 0.00 0 2504 7 0 0
v_cancel_write_off 1962 1962 0 0.00 0 3654 22 0 0
v_max_id_clo_ord 5287 5287 0 0.00 0 374 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 3055 16 0 0
v_max_id_ord_sup 5371 5371 0 0.00 0 9042 10 0 0
v_max_non_paid_invoice 1403 1403 0 0.00 0 6667 8 0 0
v_max_non_paid_realizn 3606 3606 0 0.00 0 4550 9 0 0
v_min_id_clo_ord 5287 5287 0 0.00 0 5715 8 0 0
v_min_id_clo_res 992 992 0 0.00 0 8605 12 0 0
v_min_id_ord_sup 5371 5371 0 0.00 0 3534 7 0 0
v_min_non_paid_invoice 5745 5745 0 0.00 0 11165 20 0 0
v_min_non_paid_realizn 7113 7113 0 0.00 0 8658 16 0 0
v_our_firm 9884 9884 0 0.00 0 54 1 0 0
v_reserve_write_off 19752 19752 0 0.00 0 5576 7 0 0
x_lock_depdocs_on_canc_invoice 10787 10787 0 0.00 0 16727 142 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 12595 461 0 0
x_make_qstorno_1000_1200 5287 5287 0 0.00 2 35205 1478 0 0
x_make_qstorno_1200_2000 5371 5371 0 0.00 3 91995 3936 0 0
x_make_qstorno_2100_3300 449000 449000 0 0.00 0 171897 185 0 0

Completed "Performance in DETAILS". Done for 00:00:03, from 20-08-2020 00:53:09 to 20-08-2020 00:53:12.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
22:21:57 2 8192000000 8798382336 8808857600 5057328 0.061 1 1 2 0 5175376 55488 62560
22:22:58 27326 8192000000 10376344560 10696888320 1436044352 14.915 100 94 188 0 1472434416 6249696 30140368
22:24:34 16074 8192000000 10435714976 10765811712 1487232752 15.365 100 87 174 0 1523439088 8432016 27774320
22:25:58 22591 8192000000 10488978928 10829152256 1541053552 15.833 100 83 166 1 1577003200 10089152 25860496
22:27:25 16586 8192000000 10542798192 10891485184 1596579936 16.310 100 82 164 2 1632535120 11348144 24607040
22:28:46 9810 8192000000 10590129328 10946367488 1645031760 16.722 100 80 158 3 1680987168 12384480 23570928
22:30:01 8603 8192000000 10630496416 11007021056 1705086464 17.228 100 78 156 2 1739786112 12020000 22679648
22:31:12 4283 8192000000 10690977632 11071070208 1760367648 17.687 100 78 157 4 1793410624 10923072 22119904
22:32:20 4249 8192000000 10743353072 11126050816 1801529952 18.026 100 81 159 1 1836971984 13735920 21706112
22:33:28 4181 8192000000 10778840672 11160457216 1835060288 18.301 100 89 179 1 1873817872 13109744 25647840
22:34:37 1426 8192000000 10793734688 11177799680 1854792384 18.461 100 88 172 0 1891848784 12418304 24638096
22:35:40 3556 8192000000 10807602576 11191345152 1862150928 18.521 100 92 183 2 1902769008 14697920 25920160
22:36:48 3024 8192000000 10811765024 11202494464 1868419936 18.571 100 81 163 0 1903774064 12283808 23070320
22:37:56 4965 8192000000 10791615312 11199504384 1870402384 18.588 100 61 123 1 1896297568 8371984 17523200
22:39:06 3564 8192000000 10800343440 11213029376 1881900768 18.680 100 47 92 1 1904268576 9277872 13089936
22:40:15 10500 8192000000 10788509056 11212660736 1890630112 18.751 100 44 88 1 1910649504 6890672 13128720
22:41:26 24313 8192000000 10817998976 11238096896 1894188208 18.780 100 43 86 1 1914167712 7373120 12606384
22:42:51 2946 8192000000 10822310192 11242766336 1901089904 18.835 100 43 85 1 1921227872 8229472 11908496
22:43:59 5799 8192000000 10805754864 11233202176 1895346000 18.789 100 27 54 4 1910237552 5864976 9026576
22:45:06 57622 8192000000 10732714320 11144896512 1757229488 17.661 94 31 61 2 1771175904 5645088 8301328
22:47:06 5578 8192000000 10735408016 11146244096 1800515136 18.018 99 35 69 0 1816693200 6400064 9778000
22:48:17 7485 8192000000 10685158080 11101638656 1749708448 17.599 97 34 66 3 1764283664 4989760 9585456
22:49:28 55499 8192000000 10731800352 11126378496 1776915536 17.824 98 35 70 0 1792698608 4838640 10944432
22:51:25 3957 8192000000 10750011008 11159793664 1835586656 18.305 100 33 65 1 1850931840 6383104 8962080
22:52:37 5971 8192000000 10794581632 11191480320 1860055888 18.504 100 57 115 0 1886680432 9638016 16986528
22:53:50 17421 8192000000 10788105520 11202535424 1867555168 18.564 100 42 84 1 1889652944 9384064 12713712
22:55:09 19719 8192000000 10821897712 11233959936 1896955904 18.802 100 51 102 3 1922088256 10229088 14903264
22:56:31 25946 8192000000 10824224528 11248943104 1900855632 18.833 100 35 70 1 1923857984 12341232 10661120
22:58:02 45287 8192000000 10846377456 11272949760 1912067792 18.923 100 39 76 3 1938846448 15277136 11501520
23:00:01 12546 8192000000 10874399472 11297112064 1940314384 19.149 100 52 99 2 1971273008 15273968 15684656
23:01:18 15096 8192000000 10897370624 11323084800 1956936064 19.282 100 50 98 1 1988081392 16390192 14755136
23:02:39 12030 8192000000 10915497648 11332124672 1967273376 19.364 100 53 106 2 1999073040 15847584 15952080
23:04:03 14721 8192000000 10922722448 11355963392 1978905600 19.456 100 44 88 1 2010880976 18395872 13579504
23:05:24 13703 8192000000 10954191248 11381493760 2001387488 19.634 100 56 112 0 2045690592 27712640 16590464
23:06:42 7900 8192000000 10962206416 11393245184 2004538304 19.659 100 47 94 0 2046697312 27749552 14409456
23:07:57 2948 8192000000 10974358528 11401781248 2016459056 19.752 100 57 114 0 2067994928 33476192 18059680
23:09:07 2446 8192000000 10998499584 11412672512 2024073920 19.812 100 86 171 1 2085566432 35623632 25868880
23:10:14 4005 8192000000 11000955552 11422449664 2027432608 19.838 100 78 156 1 2092706528 40729344 24544576
23:11:29 4468 8192000000 11014028288 11426947072 2027863904 19.842 100 84 167 0 2099583984 45509776 26210304
23:12:43 2195 8192000000 11028589040 11441737728 2032775056 19.880 100 95 189 0 2110375728 48238240 29362432
23:13:52 12472 8192000000 11009980688 11448463360 2034869680 19.897 100 70 139 1 2104985344 47699264 22416400
23:15:09 3488 8192000000 11008829936 11436453888 2017546832 19.761 100 72 144 1 2095114560 53716272 23851456
23:16:20 4119 8192000000 11019996592 11439616000 2024338480 19.814 100 83 165 2 2096614384 47054992 25220912
23:17:31 16370 8192000000 10966268560 11416068096 1999135184 19.616 100 37 74 3 2058964848 47093312 12736352
23:19:30 8314 8192000000 10960475152 11396653056 1997515200 19.603 100 54 108 0 2054460432 39249216 17696016
23:20:47 7236 8192000000 10948690240 11383459840 1970433904 19.389 100 53 106 1 2039563808 51568512 17561392
23:22:01 8855 8192000000 10923462368 11361984512 1931987760 19.083 99 48 95 1 1995255136 47997120 15270256
23:23:16 31657 8192000000 10912910912 11364794368 1959208816 19.300 100 25 50 3 1998437920 31249184 7979920
23:24:53 41927 8192000000 10911813776 11361681408 1959957472 19.306 100 20 40 1 2003534144 36336624 7240048
23:26:40 36452 8192000000 10860874768 11308716032 1841762144 18.355 94 9 18 0 1865999792 21600256 2637392
23:29:02 35491 8192000000 10859688272 11303452672 1881747584 18.679 97 19 39 0 1895197120 7729200 5720336
23:30:53 45721 8192000000 10896922336 11348836352 1930811920 19.073 99 39 79 3 1959415520 17085728 11517872
23:32:46 123278 8192000000 10891890720 11365560320 1933914528 19.098 99 26 52 3 1975755104 34009936 7830640
23:36:11 72594 8192000000 10875472752 11324637184 1862007328 18.520 95 38 76 0 1883640112 11381296 10251488
23:38:36 155556 8192000000 10870318592 11324186624 1831862336 18.275 93 34 68 1 1860664864 18476912 10325616
23:42:27 23259 8192000000 10894843952 11328520192 1936133920 19.116 99 46 91 2 1969139728 19503296 13502512
23:44:18 51208 8192000000 10865809216 11345645568 1912351776 18.926 99 16 32 1 1943322848 26308112 4662960
23:46:18 29744 8192000000 10874716448 11334492160 1942847472 19.169 100 13 26 0 1961447728 14598592 4001664
23:47:52 93329 8192000000 10886162624 11340554240 1917128592 18.964 99 24 48 0 1947861184 23036112 7696480
23:51:56 53868 8192000000 10887418512 11341733888 1951730160 19.240 100 25 51 1 1967537968 9051680 6756128
23:53:50 32934 8192000000 10905262304 11363930112 1970854496 19.392 100 18 36 0 1986023536 10450640 4718400
23:55:32 32465 8192000000 10908672064 11366367232 1957687136 19.288 99 34 69 0 1977930192 10604240 9638816
23:57:11 30301 8192000000 10929805680 11382607872 1985287920 19.507 100 31 62 3 2011463792 17330544 8845328
23:58:50 99958 8192000000 10917548832 11363368960 1904586720 18.863 96 17 32 1 1922900896 14185424 4128752
00:01:31 59882 8192000000 10911208384 11375464448 1944001664 19.179 98 15 30 1 1959296704 11022128 4272912
00:03:35 99865 8192000000 10922364272 11402858496 1948160800 19.212 98 5 10 1 1975505392 25977680 1366912
00:06:15 75210 8192000000 10887788048 11360571392 1928449104 19.054 97 18 36 2 1941420960 7919088 5052768
00:08:46 4934 8192000000 10884471088 11311849472 1930248464 19.069 99 62 120 4 1953230352 6431888 16550000
00:09:55 20683 8192000000 10869967040 11322400768 1914601312 18.944 98 45 90 1 1941737616 14404576 12731728
00:11:26 25748 8192000000 10849584240 11319750656 1933355024 19.094 100 9 18 0 1943925008 7732752 2837232
00:12:55 11880 8192000000 10867762864 11318063104 1920501424 18.991 99 33 67 0 1939007040 9520624 8984992
00:14:10 64265 8192000000 10861667152 11302752256 1831709920 18.273 94 19 38 2 1848091328 10540384 5841024
00:16:38 66481 8192000000 10849982464 11320872960 1909775968 18.905 99 11 22 0 1924150544 11344336 3030240
00:18:47 79720 8192000000 10828160816 11303260160 1879821328 18.664 98 8 16 0 1889925152 7966512 2137312
00:21:06 85340 8192000000 10839045728 11302375424 1865058320 18.544 97 21 42 2 1888463360 17266688 6138352
00:23:33 15292 8192000000 10866127536 11311259648 1922506352 19.007 99 32 62 3 1942401120 10940288 8954480
00:24:54 46811 8192000000 10883565456 11338563584 1919776992 18.985 99 13 26 0 1944332144 20869264 3685888
00:26:42 53931 8192000000 10865623680 11314704384 1885173888 18.707 97 27 54 2 1907283216 13734944 8374384
00:28:57 54074 8192000000 10897069360 11362660352 1959222032 19.300 100 33 66 1 1980098656 11672240 9204384
00:30:53 61127 8192000000 10882005200 11337052160 1946761184 19.201 100 20 40 0 1962159264 9910160 5487920
00:32:56 78858 8192000000 10881100720 11329974272 1856539728 18.475 95 17 34 1 1875312880 14040896 4732256
00:35:16 80476 8192000000 10873429152 11336241152 1931130400 19.076 99 44 85 2 1955307904 10643152 13534352
00:37:44 53392 8192000000 10872749088 11345567744 1951703552 19.240 100 34 66 2 1972132768 11269872 9159344
00:39:38 36160 8192000000 10866118592 11328540672 1934396624 19.102 99 16 32 1 1946420208 7422560 4601024
00:41:16 51518 8192000000 10868310848 11338215424 1948336000 19.213 100 13 26 0 1960473520 8142448 3995072
00:43:13 64582 8192000000 10880120880 11348844544 1960205296 19.308 100 14 28 1 1971384448 7357328 3821824
00:45:31 59528 8192000000 10887107872 11356811264 1969809168 19.384 100 20 40 1 1987213376 11086528 6317680
00:47:46 37745 8192000000 10874898560 11349753856 1947143952 19.204 99 12 24 2 1960144592 9863120 3137520
00:49:51 82048 8192000000 10895135584 11355844608 1905135088 18.868 96 24 48 1 1923194896 11289296 6770512

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 00:53:14 to 20-08-2020 00:53:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 125 v_add_invoice_to_stock 335544517
user_exc 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-08-2020 00:53:29 to 20-08-2020 00:53:31.

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          220403
MON$OLDEST_ACTIVE               222608
MON$OLDEST_SNAPSHOT             222608
MON$NEXT_TRANSACTION            222608
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                       1999360
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 ---
:281: 
:282: mscs-test-161.budzdorov.ru    Thu Aug 20 00:51:57 2020
:283:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:284: 
:285: 
:286: mscs-test-161.budzdorov.ru    Thu Aug 20 00:51:57 2020
:287:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:288: 
:289: 
:290: mscs-test-161.budzdorov.ru    Thu Aug 20 00:51:57 2020
:291:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:292: 
:293: 
:294: mscs-test-161.budzdorov.ru    Thu Aug 20 00:51:57 2020
:295:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:296: 
:297: 
:298: mscs-test-161.budzdorov.ru    Thu Aug 20 00:52:01 2020
:299:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:300: 
--- end of diff output ---

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

20.08.20 00:53:33. 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 00:53:35 NO_SEVERE_ERRORS_FOUND
20.08.20 00:53:37. End of report.