Firebird 2.5 Performance Logs

Generated by E:\OLTP-EMUL\src\oltp_isql_run_worker.bat, ISQL session #1 of total launched 100. 2016-10-15 2:26:29.85.
Common Performance Final Results
  1. Test configuration
  2. Test Finish details
  3. Test workload details
  4. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score
  2. Performance, DYNAMIC, 10 intervals
  3. Performance, per MINUTE, since launch
  4. Performance, TRACE data for ISQL #1
  5. Performance, DETAILS per units
  6. MON$-analysis, per business units
  7. 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

Server and database settings

Server version: WI-V2.5.4.26856 Firebird 2.5
Server implementation: Firebird/x86-64/Windows NT
FB_ARCHITECTURE DB_NAME FORCED_WRITES SWEEP_INT PAGE_BUFFERS PAGE_SIZE
SuperClassic 2.5.4 D:\OLTP-EMUL\OLTP25-SMALL.FDB ON 0 384 8192

Test configuration settings

File: E:\OLTP-EMUL\src\oltp25_config.win
PARAM_NAME PARAM_VALUE
fbc E:\FB25.TMPINSTANCE\bin\
dbnm D:\OLTP-EMUL\oltp25-small.fdb
host localhost
port 3255
usr SYSDBA
pwd masterke
tmpdir e:\temp\logs.oltp25
working_mode small_03
warm_time 10
test_time 180
idle_time 0
use_mtee 0
is_embed 0
remove_isql_logs if_no_severe_errors
no_auto_undo 1
detailed_info 0
mon_unit_perf 0
trc_unit_perf 1
init_buff 32768
create_with_fw sync
create_with_sweep 0
wait_if_not_exists 0
wait_after_create 0
init_docs 0
wait_for_copy 0
create_with_debug_objects 1
create_with_split_heavy_tabs 1
create_with_separate_qdistr_idx 0
create_with_compound_columns_order most_selective_first
make_html 1
run_db_statistics 1
run_db_validation 1
file_name_with_test_params regular
file_name_this_host_info ak25
upload_report 1
max_snapshots_to_store 0

Test finish info

FINISH_STATE DTS_END FB_GDSCODE FB_MNEMONA STACK IP TRN_ID ATT_ID EXC_UNIT
NORMAL: TEST_TIME EXPIRED. 2016-10-15 02:26:13.0840 -1 <null> 127.0.0.1 75222 4209 2

Current test settings

CATEGORY SETTING VAL
COMMON TRACED_UNITS ,,
COMMON LOG_PK_VIOLATION 0
COMMON QMISM_VERIFY_BITSET 1
COMMON RECALC_IDX_MIN_INTERVAL 15
COMMON ENABLE_RESERVES_WHEN_ADD_INVOICE 1
COMMON ORDER_FOR_OUR_FIRM_PERCENT 25
COMMON ENABLE_FILL_PHRASES 0
COMMON RANDOM_SEEK_VIA_ROWS_LIMIT 0
COMMON C_INVOICE_MIN_PURCHASE 1000
COMMON C_INVOICE_MAX_PURCHASE 2000
COMMON C_INVOICE_MIN_PROFIT_PRC 35
COMMON C_INVOICE_MAX_PROFIT_PRC 80
COMMON C_MIN_COST_TO_BE_SPLITTED 1000
COMMON C_ROWS_TO_MULTIPLY 10
COMMON C_PAYMENT_FROM_CLIENT_MIN_TOTAL 1000
COMMON C_PAYMENT_FROM_CLIENT_MAX_TOTAL 5000
COMMON C_PAYMENT_TO_SUPPLIER_MIN_TOTAL 2000
COMMON C_PAYMENT_TO_SUPPLIER_MAX_TOTAL 15000
COMMON BUILD_WITH_SPLIT_HEAVY_TABS 1
COMMON BUILD_WITH_SEPAR_QDISTR_IDX 0
COMMON BUILD_WITH_QD_COMPOUND_ORDR MOST_SELECTIVE_FIRST
COMMON ENABLE_MON_QUERY 0
COMMON HALT_TEST_ON_ERRORS ,CK,
SMALL_03 C_WARES_MAX_ID 400
SMALL_03 C_CUSTOMER_DOC_MAX_ROWS 10
SMALL_03 C_SUPPLIER_DOC_MAX_ROWS 50
SMALL_03 C_CUSTOMER_DOC_MAX_QTY 15
SMALL_03 C_SUPPLIER_DOC_MAX_QTY 50
SMALL_03 C_NUMBER_OF_AGENTS 50

Index(es) for heavy-loaded table(s)

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WARE_SOP_ROP_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_DOC DOC_ID
XQD_1000_3300 XQD_1000_3300_WARE_SOP_ROP_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,SND_ID
XQD_1200_2000 XQD_1200_2000_WARE_SOP_ROP_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WARE_SOP_ROP_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WARE_SOP_ROP_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WARE_SOP_ROP_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,SND_ID

Performance reports

Performance in TOTAL:

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE JOB_BEG JOB_END
*** OVERALL *** for 180 minutes: 112.87 136854 20317 2016-10-14 23:26 2016-10-15 02:26
customer order: creation 9.87 22 1777 2016-10-14 23:26 2016-10-15 02:26
customer order: refuse 0.80 14 141 2016-10-14 23:26 2016-10-15 02:26
order to supplier: creation 2.69 2063 484 2016-10-14 23:26 2016-10-15 02:26
order to supplier: removal 0.04 329 7 2016-10-14 23:26 2016-10-15 02:26
invoice (draft): creation 2.61 1154 469 2016-10-14 23:26 2016-10-15 02:26
invoice (draft): removal 0.37 38 63 2016-10-14 23:26 2016-10-15 02:26
invoice accept: apply 0.47 2283941 85 2016-10-14 23:26 2016-10-15 02:26
invoice accept: cancel 0.13 255965 23 2016-10-14 23:26 2016-10-15 02:26
customer reserve: creation 79.04 37450 14227 2016-10-14 23:26 2016-10-15 02:26
customer reserve: removal 2.42 15959 434 2016-10-14 23:26 2016-10-15 02:26
realization accept: apply 4.79 24 863 2016-10-14 23:26 2016-10-15 02:26
realization accept: cancel 1.04 20 188 2016-10-14 23:26 2016-10-15 02:26
payment from customer: creation 3.47 47 625 2016-10-14 23:26 2016-10-15 02:26
payment from customer: removal 0.30 9 53 2016-10-14 23:26 2016-10-15 02:26
payment to supplier: creation 2.77 143 499 2016-10-14 23:26 2016-10-15 02:26
payment to supplier: removal 0.26 6 46 2016-10-14 23:26 2016-10-15 02:26
service: total inventory turnovers 1.17 2157 208 2016-10-14 23:26 2016-10-15 02:26
service: total monetary turnovers 0.65 6 116 2016-10-14 23:26 2016-10-15 02:26
service: refresh index statistics 0.06 876 9 2016-10-14 23:26 2016-10-15 02:26
Done for 3960 ms, from 2:26:41.75 to 2:26:45.71.

Performance in DYNAMIC:

ACTION ITRV_NO CNT_OK_PER_MINUTE CNT_ALL CNT_OK CNT_ERR ERR_PRC ITRV_BEG ITRV_END
interval # 1, overall 1 162 4349 2917 1432 32.93 23:26:13 23:44:12
interval # 2, overall 2 72 1306 1298 8 0.61 23:44:13 00:02:12
interval # 3, overall 3 105 2484 1889 595 23.95 00:02:13 00:20:12
interval # 4, overall 4 144 2659 2593 66 2.48 00:20:13 00:38:12
interval # 5, overall 5 118 2197 2128 69 3.14 00:38:13 00:56:12
interval # 6, overall 6 122 2360 2189 171 7.25 00:56:13 01:14:12
interval # 7, overall 7 95 1784 1711 73 4.09 01:14:13 01:32:12
interval # 8, overall 8 125 2523 2250 273 10.82 01:32:13 01:50:12
interval # 9, overall 9 93 1750 1677 73 4.17 01:50:13 02:08:12
interval # 10, overall 10 93 1846 1665 181 9.80 02:08:13 02:26:12
Done for 5720 ms, from 2:26:48.90 to 2:26:54.62.

Performance for every MINUTE:

TEST_PHASE MINUTE_SINCE_TEST_START AVG_ESTIMATED MIN_TO_AVG_RATIO MAX_TO_AVG_RATIO ROWS_AGGREGATED DISTINCT_ATTACHMENTS
WARM_TIME 1 2331.82 0.6578 1.3281 1222 99
WARM_TIME 2 1810.97 0.8553 1.1411 902 91
WARM_TIME 3 1574.25 0.8757 1.0959 900 90
WARM_TIME 4 1436.90 0.9007 1.0761 679 70
WARM_TIME 5 1302.35 0.9499 1.0488 671 57
WARM_TIME 6 1228.53 0.9267 1.0489 692 54
WARM_TIME 7 1146.98 0.9631 1.0381 554 37
WARM_TIME 8 1081.74 0.9632 1.0381 696 27
WARM_TIME 9 1020.15 0.9786 1.0269 409 13
WARM_TIME 10 960.79 0.9814 1.0132 199 11
TEST_TIME 11 892.99 0.9911 1.0092 169 5
TEST_TIME 12 828.62 0.9971 1.0029 63 2
TEST_TIME 13 772.34 0.9999 1.0000 2 1
TEST_TIME 15 675.51 0.9972 1.0036 60 2
TEST_TIME 16 643.70 0.9874 1.0070 127 3
TEST_TIME 17 617.40 0.9882 1.0090 183 2
TEST_TIME 18 589.38 0.9983 1.0017 43 1
TEST_TIME 22 484.54 0.9988 1.0012 28 1
TEST_TIME 24 446.64 0.9987 1.0013 33 1
TEST_TIME 26 418.46 0.9937 1.0045 100 2
TEST_TIME 27 407.90 0.9971 1.0028 71 1
TEST_TIME 28 394.53 0.9999 1.0001 3 1
TEST_TIME 30 370.18 0.9995 1.0004 13 1
TEST_TIME 31 358.58 0.9995 1.0005 14 1
TEST_TIME 36 310.44 0.9992 1.0008 20 1
TEST_TIME 40 280.84 0.9992 1.0008 19 1
TEST_TIME 45 252.43 0.9971 1.0029 68 1
TEST_TIME 47 242.66 0.9990 1.0010 26 1
TEST_TIME 48 237.96 0.9996 1.0004 10 1
TEST_TIME 50 229.52 0.9999 1.0000 3 1
TEST_TIME 51 225.45 0.9981 1.0019 44 1
TEST_TIME 52 222.66 0.9950 1.0025 35 2
TEST_TIME 55 212.72 0.9980 1.0019 51 1
TEST_TIME 56 209.42 0.9996 1.0003 9 1
TEST_TIME 57 205.94 0.9995 1.0005 13 1
TEST_TIME 58 205.98 0.9880 1.0077 82 3
TEST_TIME 59 206.18 0.9898 1.0064 74 3
TEST_TIME 60 204.44 0.9980 1.0107 17 2
TEST_TIME 62 201.57 0.9985 1.0015 43 1
TEST_TIME 63 199.07 0.9980 1.0017 50 1
TEST_TIME 64 198.10 0.9910 1.0020 24 2
TEST_TIME 67 192.52 0.9899 1.0099 126 3
TEST_TIME 69 190.53 0.9983 1.0016 49 1
TEST_TIME 70 188.76 0.9967 1.0033 93 1
TEST_TIME 71 187.85 0.9940 1.0053 15 2
TEST_TIME 72 188.54 0.9878 1.0117 70 5
TEST_TIME 73 188.53 0.9980 1.0028 48 2
TEST_TIME 74 188.24 0.9981 1.0018 50 3
TEST_TIME 75 187.23 0.9938 1.0061 74 3
TEST_TIME 76 187.17 0.9933 1.0020 39 4
TEST_TIME 77 186.82 0.9910 1.0014 13 3
TEST_TIME 78 184.88 0.9990 1.0009 30 2
TEST_TIME 79 184.30 0.9914 1.0030 83 2
TEST_TIME 80 182.99 0.9984 1.0015 51 2
TEST_TIME 82 181.22 0.9894 1.0123 204 3
TEST_TIME 83 181.53 0.9984 1.0015 50 2
TEST_TIME 84 184.44 0.9740 1.0098 143 5
TEST_TIME 85 184.16 0.9995 1.0006 21 2
TEST_TIME 86 183.89 0.9998 1.0001 6 1
TEST_TIME 88 180.88 1.0000 1.0000 2 1
TEST_TIME 89 179.11 0.9998 1.0001 6 1
TEST_TIME 91 175.52 0.9998 1.0002 6 1
TEST_TIME 92 173.65 1.0000 1.0000 1 1
TEST_TIME 93 174.54 0.9909 1.0024 29 2
TEST_TIME 94 174.89 1.0000 1.0000 2 1
TEST_TIME 95 173.27 0.9988 1.0011 44 1
TEST_TIME 97 171.81 0.9995 1.0005 19 1
TEST_TIME 98 170.68 0.9969 1.0034 13 2
TEST_TIME 99 172.04 0.9925 1.0033 56 2
TEST_TIME 101 170.95 0.9950 1.0022 23 2
TEST_TIME 102 170.79 0.9961 1.0022 75 3
TEST_TIME 103 171.44 0.9918 1.0030 41 3
TEST_TIME 104 170.57 0.9985 1.0013 56 1
TEST_TIME 105 171.89 0.9909 1.0035 115 2
TEST_TIME 106 170.93 0.9997 1.0004 14 1
TEST_TIME 107 170.89 0.9978 1.0123 43 4
TEST_TIME 108 173.15 0.9978 1.0021 82 3
TEST_TIME 109 173.70 0.9970 1.0022 20 2
TEST_TIME 112 171.82 0.9938 1.0043 55 2
TEST_TIME 113 172.23 0.9931 1.0031 84 2
TEST_TIME 114 171.27 1.0000 1.0000 1 1
TEST_TIME 115 170.33 0.9968 1.0093 43 2
TEST_TIME 116 170.90 0.9973 1.0031 66 2
TEST_TIME 117 170.05 0.9997 1.0003 16 1
TEST_TIME 120 166.16 0.9993 1.0006 28 1
TEST_TIME 121 164.98 0.9995 1.0006 23 2
TEST_TIME 122 164.69 0.9987 1.0009 37 1
TEST_TIME 123 164.92 0.9983 1.0097 55 3
TEST_TIME 124 166.05 0.9991 1.0013 22 3
TEST_TIME 125 165.71 0.9978 1.0020 80 4
TEST_TIME 127 166.18 0.9947 1.0029 100 3
TEST_TIME 132 162.52 0.9944 1.0012 34 2
TEST_TIME 133 162.60 0.9932 1.0055 27 3
TEST_TIME 134 162.99 0.9957 1.0034 33 2
TEST_TIME 135 163.46 0.9931 1.0078 105 6
TEST_TIME 136 165.17 0.9900 1.0096 151 5
TEST_TIME 138 164.81 0.9998 1.0001 6 1
TEST_TIME 139 163.70 0.9996 1.0003 16 1
TEST_TIME 140 163.24 0.9998 1.0001 8 1
TEST_TIME 142 161.66 0.9957 1.0024 101 3
TEST_TIME 143 162.02 1.0000 1.0000 1 1
TEST_TIME 144 162.06 0.9972 1.0021 54 2
TEST_TIME 145 161.82 0.9997 1.0003 16 2
TEST_TIME 147 159.71 1.0000 1.0000 1 1
TEST_TIME 148 158.96 1.0000 1.0000 2 1
TEST_TIME 149 158.31 0.9999 1.0001 6 1
TEST_TIME 151 159.34 0.9873 1.0112 82 4
TEST_TIME 153 160.18 1.0000 1.0000 2 1
TEST_TIME 155 160.19 0.9994 1.0006 32 1
TEST_TIME 156 159.92 0.9994 1.0005 29 2
TEST_TIME 157 159.44 0.9972 1.0075 58 3
TEST_TIME 158 160.54 0.9999 1.0001 6 1
TEST_TIME 159 160.02 0.9970 1.0058 37 3
TEST_TIME 160 160.53 0.9995 1.0004 27 1
TEST_TIME 161 159.75 0.9995 1.0004 28 1
TEST_TIME 162 158.88 0.9997 1.0003 7 1
TEST_TIME 163 159.14 0.9925 1.0022 75 2
TEST_TIME 164 161.34 0.9893 1.0117 56 6
TEST_TIME 165 163.00 0.9953 1.0020 68 5
TEST_TIME 167 161.45 0.9998 1.0001 10 1
TEST_TIME 170 159.47 0.9998 1.0001 7 1
TEST_TIME 171 159.50 1.0000 1.0000 2 1
TEST_TIME 172 158.68 0.9994 1.0006 35 1
TEST_TIME 174 158.38 0.9999 1.0001 8 1
TEST_TIME 175 157.73 0.9996 1.0009 26 2
TEST_TIME 176 157.85 0.9944 1.0065 57 3
TEST_TIME 179 159.20 0.9880 1.0073 183 4
TEST_TIME 180 160.70 0.9924 1.0085 123 3
TEST_TIME 181 162.14 0.9991 1.0009 62 2
TEST_TIME 182 162.78 0.9952 1.0037 20 3
TEST_TIME 183 163.73 0.9924 1.0047 75 5
TEST_TIME 184 163.64 0.9998 1.0001 12 1
TEST_TIME 185 165.30 0.9923 1.0026 45 4
TEST_TIME 186 165.70 0.9948 1.0045 125 3
TEST_TIME 187 167.00 0.9925 1.0053 111 5
TEST_TIME 188 167.17 0.9989 1.0005 26 2
TEST_TIME 189 167.30 0.9945 1.0005 27 2
TEST_TIME 190 166.73 0.9989 1.0007 116 100
Done for 7700 ms, from 2:26:54.75 to 2:27:02.45.

Performance from TRACE for ISQL instance #1:

TRACED_DATA ITRV_NO SP_CLIENT_ORDER SP_CANCEL_CLIENT_ORDER SP_SUPPLIER_ORDER SP_CANCEL_SUPPLIER_ORDER SP_SUPPLIER_INVOICE SP_CANCEL_SUPPLIER_INVOICE SP_ADD_INVOICE_TO_STOCK SP_CANCEL_ADDING_INVOICE SP_CUSTOMER_RESERVE SP_CANCEL_CUSTOMER_RESERVE SP_RESERVE_WRITE_OFF SP_CANCEL_WRITE_OFF SP_PAY_FROM_CUSTOMER SP_CANCEL_PAY_FROM_CUSTOMER SP_PAY_TO_SUPPLIER SP_CANCEL_PAY_TO_SUPPLIER SRV_MAKE_INVNT_SALDO SRV_MAKE_MONEY_SALDO SRV_RECALC_IDX_STAT ITRV_BEG ITRV_END
fetches per second 1 91419 93678 50676 <null> 104331 <null> 932 <null> 1858 557 114633 119588 154982 128666 266144 176533 168319 <null> <null> 23:26:13 23:44:12
fetches per second 5 <null> <null> <null> <null> <null> <null> 604 <null> <null> <null> <null> <null> <null> <null> 137798 <null> <null> <null> <null> 00:38:13 00:56:12
fetches per second 10 109167 124200 10138 <null> 33177 <null> 901 <null> <null> <null> 120087 131344 207141 <null> 472148 <null> <null> 2276087 <null> 02:08:13 02:26:12
marks per second 1 16670 8962 3963 <null> 7831 <null> 142 <null> 278 99 16319 14352 4931 13888 7277 17577 976 <null> <null> 23:26:13 23:44:12
marks per second 5 <null> <null> <null> <null> <null> <null> 90 <null> <null> <null> <null> <null> <null> <null> 2323 <null> <null> <null> <null> 00:38:13 00:56:12
marks per second 10 20740 10200 621 <null> 1799 <null> 93 <null> <null> <null> 14830 15620 4169 <null> 6819 <null> <null> 9362 <null> 02:08:13 02:26:12
reads/fetches*100 1 5 6 8 <null> 7 <null> 16 <null> 15 14 7 4 6 4 2 3 21 <null> <null> 23:26:13 23:44:12
reads/fetches*100 5 <null> <null> <null> <null> <null> <null> 20 <null> <null> <null> <null> <null> <null> <null> 3 <null> <null> <null> <null> 00:38:13 00:56:12
reads/fetches*100 10 3 3 9 <null> 6 <null> 17 <null> <null> <null> 6 6 6 <null> 3 <null> <null> 0 <null> 02:08:13 02:26:12
writes/marks*100 1 13 13 8 <null> 7 <null> 0 <null> 4 2 7 9 6 0 3 0 41 <null> <null> 23:26:13 23:44:12
writes/marks*100 5 <null> <null> <null> <null> <null> <null> 0 <null> <null> <null> <null> <null> <null> <null> 4 <null> <null> <null> <null> 00:38:13 00:56:12
writes/marks*100 10 12 14 9 <null> 9 <null> 0 <null> <null> <null> 8 9 9 <null> 4 <null> <null> 0 <null> 02:08:13 02:26:12
Done for 5090 ms, from 2:27:02.62 to 2:27:07.71.

Performance in DETAILS:

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL JOB_BEG JOB_END
doc_list_aiud 19598 17072 2526 12.88 0 2231 10 2526 2016-10-14 23:26 2016-10-15 02:26
doc_list_biud 19633 17071 2562 13.04 0 208886 584 2562 2016-10-14 23:26 2016-10-15 02:26
sp_add_invoice_to_stock 405 85 320 79.01 43322 9428053 2283941 320 2016-10-14 23:26 2016-10-15 02:26
sp_cancel_adding_invoice 47 23 24 51.06 281 1704974 255965 24 2016-10-14 23:26 2016-10-15 02:26
sp_cancel_client_order 150 141 9 6.00 0 47 14 9 2016-10-14 23:26 2016-10-15 02:26
sp_cancel_customer_reserve 2406 434 1972 81.96 0 123351 15959 1972 2016-10-14 23:26 2016-10-15 02:26
sp_cancel_pay_from_customer 54 53 1 1.85 0 31 9 1 2016-10-14 23:26 2016-10-15 02:26
sp_cancel_pay_to_supplier 47 46 1 2.12 0 16 6 1 2016-10-14 23:26 2016-10-15 02:26
sp_cancel_supplier_invoice 88 63 25 28.40 0 125 38 25 2016-10-14 23:26 2016-10-15 02:26
sp_cancel_supplier_order 33 7 26 78.78 31 687 329 26 2016-10-14 23:26 2016-10-15 02:26
sp_cancel_write_off 742 188 554 74.66 0 63 20 554 2016-10-14 23:26 2016-10-15 02:26
sp_client_order 1777 1777 0 0.00 0 156 22 0 2016-10-14 23:26 2016-10-15 02:26
sp_customer_reserve 14227 14227 0 0.00 47 150946 37450 0 2016-10-14 23:26 2016-10-15 02:26
sp_fill_shopping_cart 2730 2730 0 0.00 0 24992 525 0 2016-10-14 23:26 2016-10-15 02:26
sp_fill_shopping_cart_clo_res 14227 14227 0 0.00 0 78 2 0 2016-10-14 23:26 2016-10-15 02:26
sp_get_clo_for_invoice 86 86 0 0.00 0 125 29 0 2016-10-14 23:26 2016-10-15 02:26
sp_kill_cost_storno 863 310 553 64.07 0 31 1 553 2016-10-14 23:26 2016-10-15 02:26
sp_kill_qstorno_ret_qs2qd 5621 1126 4495 79.96 0 123319 6151 4495 2016-10-14 23:26 2016-10-15 02:26
sp_kill_qty_storno 4359 1804 2555 58.61 0 208886 5527 2555 2016-10-14 23:26 2016-10-15 02:26
sp_lock_selected_doc 1770 1367 403 22.76 0 16 0 403 2016-10-14 23:26 2016-10-15 02:26
sp_make_cost_storno 2073 2073 0 0.00 0 483 46 0 2016-10-14 23:26 2016-10-15 02:26
sp_multiply_rows_for_pdistr 2073 2073 0 0.00 0 47 2 0 2016-10-14 23:26 2016-10-15 02:26
sp_multiply_rows_for_qdistr 1777 1777 0 0.00 0 93 6 0 2016-10-14 23:26 2016-10-15 02:26
sp_pay_from_customer 625 625 0 0.00 16 202 47 0 2016-10-14 23:26 2016-10-15 02:26
sp_pay_to_supplier 499 499 0 0.00 62 531 143 0 2016-10-14 23:26 2016-10-15 02:26
sp_payment_common 1124 1124 0 0.00 0 109 12 0 2016-10-14 23:26 2016-10-15 02:26
sp_qd_handle_on_cancel_clo 150 141 9 6.00 0 16 5 9 2016-10-14 23:26 2016-10-15 02:26
sp_qd_handle_on_invoice_upd_sts 131 108 23 17.55 15 208886 28123 23 2016-10-14 23:26 2016-10-15 02:26
sp_qd_handle_on_reserve_upd_sts 863 863 0 0.00 0 109 5 0 2016-10-14 23:26 2016-10-15 02:26
sp_reserve_write_off 872 863 9 1.03 0 172 24 9 2016-10-14 23:26 2016-10-15 02:26
sp_supplier_invoice 469 469 0 0.00 16 20062 1154 0 2016-10-14 23:26 2016-10-15 02:26
sp_supplier_order 484 484 0 0.00 16 25008 2063 0 2016-10-14 23:26 2016-10-15 02:26
srv_find_qd_qs_mism 11386 8850 2536 22.27 0 2090 8 2536 2016-10-14 23:26 2016-10-15 02:26
srv_make_invnt_saldo 208 208 0 0.00 936 4446 2157 0 2016-10-14 23:26 2016-10-15 02:26
srv_make_money_saldo 116 116 0 0.00 0 31 6 0 2016-10-14 23:26 2016-10-15 02:26
srv_recalc_idx_stat 9 9 0 0.00 765 1045 876 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-abend:sp_add_invoice_to_stock 320 320 0 0.00 0 16 1 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-abend:sp_cancel_adding_invoice 62 62 0 0.00 0 156 29 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-abend:sp_cancel_client_order 9 9 0 0.00 0 16 3 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-abend:sp_cancel_customer_reserve 1 1 0 0.00 0 0 0 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-abend:sp_cancel_pay_from_customer 1 1 0 0.00 0 0 0 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-abend:sp_cancel_pay_to_supplier 1 1 0 0.00 16 16 16 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 16 1 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-abend:sp_cancel_supplier_order 298 298 0 0.00 0 125 18 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-abend:sp_cancel_write_off 1 1 0 0.00 0 0 0 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-abend:sp_reserve_write_off 9 9 0 0.00 0 0 0 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_add_invoice_to_stock 160 160 0 0.00 0 500 144 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_cancel_adding_invoice 23 23 0 0.00 0 281 50 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_cancel_client_order 141 141 0 0.00 0 16 1 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_cancel_customer_reserve 52 52 0 0.00 0 32 5 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_cancel_pay_from_customer 53 53 0 0.00 0 16 2 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_cancel_pay_to_supplier 46 46 0 0.00 0 16 1 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_cancel_supplier_invoice 12 12 0 0.00 0 15 3 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_cancel_supplier_order 7 7 0 0.00 0 31 15 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_cancel_write_off 85 85 0 0.00 0 16 2 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_client_order 1777 1777 0 0.00 0 47 2 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_customer_reserve 73 73 0 0.00 0 31 4 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_pay_from_customer 625 625 0 0.00 0 31 2 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_pay_to_supplier 499 499 0 0.00 0 31 2 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_reserve_write_off 863 863 0 0.00 0 47 2 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_supplier_invoice 469 469 0 0.00 0 47 3 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:sp_supplier_order 485 485 0 0.00 0 32 2 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:srv_make_invnt_saldo 209 209 0 0.00 0 31 0 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:srv_make_money_saldo 116 116 0 0.00 0 16 0 0 2016-10-14 23:26 2016-10-15 02:26
t$perf-norm:srv_recalc_idx_stat 9 9 0 0.00 0 16 8 0 2016-10-14 23:26 2016-10-15 02:26
v_add_invoice_to_stock 810 170 640 79.01 0 16 1 640 2016-10-14 23:26 2016-10-15 02:26
v_all_customers 2628 2628 0 0.00 0 16 0 0 2016-10-14 23:26 2016-10-15 02:26
v_all_suppliers 1906 1906 0 0.00 0 16 0 0 2016-10-14 23:26 2016-10-15 02:26
v_all_wares 3554 3554 0 0.00 0 16 0 0 2016-10-14 23:26 2016-10-15 02:26
v_cancel_adding_invoice 76 46 30 39.47 0 16 4 30 2016-10-14 23:26 2016-10-15 02:26
v_cancel_client_order 300 282 18 6.00 0 16 0 18 2016-10-14 23:26 2016-10-15 02:26
v_cancel_customer_prepayment 108 106 2 1.85 0 16 0 2 2016-10-14 23:26 2016-10-15 02:26
v_cancel_customer_reserve 106 104 2 1.88 0 16 0 2 2016-10-14 23:26 2016-10-15 02:26
v_cancel_payment_to_supplier 94 92 2 2.12 0 16 0 2 2016-10-14 23:26 2016-10-15 02:26
v_cancel_supplier_invoice 64 24 40 62.50 0 15 1 40 2016-10-14 23:26 2016-10-15 02:26
v_cancel_supplier_order 66 14 52 78.78 0 0 0 52 2016-10-14 23:26 2016-10-15 02:26
v_cancel_write_off 172 170 2 1.16 0 16 1 2 2016-10-14 23:26 2016-10-15 02:26
v_max_id_clo_ord 484 484 0 0.00 0 31 3 0 2016-10-14 23:26 2016-10-15 02:26
v_max_id_clo_res 73 73 0 0.00 0 16 1 0 2016-10-14 23:26 2016-10-15 02:26
v_max_id_ord_sup 469 469 0 0.00 0 31 2 0 2016-10-14 23:26 2016-10-15 02:26
v_max_non_paid_invoice 499 499 0 0.00 0 16 1 0 2016-10-14 23:26 2016-10-15 02:26
v_max_non_paid_realizn 625 625 0 0.00 0 16 4 0 2016-10-14 23:26 2016-10-15 02:26
v_min_id_clo_ord 484 484 0 0.00 0 826 34 0 2016-10-14 23:26 2016-10-15 02:26
v_min_id_clo_res 73 73 0 0.00 0 16 0 0 2016-10-14 23:26 2016-10-15 02:26
v_min_id_ord_sup 469 469 0 0.00 0 796 30 0 2016-10-14 23:26 2016-10-15 02:26
v_min_non_paid_invoice 499 499 0 0.00 0 32 4 0 2016-10-14 23:26 2016-10-15 02:26
v_min_non_paid_realizn 625 625 0 0.00 0 32 7 0 2016-10-14 23:26 2016-10-15 02:26
v_our_firm 926 926 0 0.00 0 16 0 0 2016-10-14 23:26 2016-10-15 02:26
v_reserve_write_off 1744 1726 18 1.03 0 16 0 18 2016-10-14 23:26 2016-10-15 02:26
x_lock_depdocs_on_canc_invoice 166 74 92 55.42 0 16 0 92 2016-10-14 23:26 2016-10-15 02:26
x_lock_depdocs_on_canc_sup_ord 33 7 26 78.78 0 0 0 26 2016-10-14 23:26 2016-10-15 02:26
x_make_qstorno_1000_1200 485 485 0 0.00 0 484 106 0 2016-10-14 23:26 2016-10-15 02:26
x_make_qstorno_1200_2000 469 469 0 0.00 0 593 120 0 2016-10-14 23:26 2016-10-15 02:26
x_make_qstorno_2100_3300 14227 14227 0 0.00 47 150931 37439 0 2016-10-14 23:26 2016-10-15 02:26
Done for 13230 ms, from 2:27:13.01 to 2:27:26.24.

Config param. mon_unit_perf=0, data from MON$ tables were NOT gathered.

Exceptions occured during test work:

FB_MNEMONA CNT UNIT FB_GDSCODE DTS_BEG DTS_END
deadlock 2 sp_kill_cost_storno 335544336 2016-10-14 23:26 2016-10-15 01:17
deadlock 2 sp_qd_handle_on_invoice_upd_sts 335544336 2016-10-14 23:26 2016-10-15 02:22
lock_conflict 2 sp_kill_cost_storno 335544345 2016-10-14 23:27 2016-10-15 01:09
lock_conflict 352 sp_lock_selected_doc 335544345 2016-10-14 23:26 2016-10-15 02:25
lock_conflict 9 sp_qd_handle_on_cancel_clo 335544345 2016-10-15 00:28 2016-10-15 02:19
lock_conflict 20 sp_qd_handle_on_invoice_upd_sts 335544345 2016-10-14 23:26 2016-10-15 02:01
lock_conflict 9 x_lock_depdocs_on_canc_invoice 335544345 2016-10-15 00:29 2016-10-15 02:22
lock_conflict 12 x_lock_depdocs_on_canc_sup_ord 335544345 2016-10-14 23:27 2016-10-15 02:26
Done for 2330 ms, from 2:27:26.60 to 2:27:28.93.

MON$DATABASE and FB VERSION info

MON$DATABASE_NAME D:\OLTP-EMUL\OLTP25-SMALL.FDB
MON$PAGE_SIZE 8192
MON$ODS_MAJOR 11
MON$ODS_MINOR 2
MON$OLDEST_TRANSACTION 82781
MON$OLDEST_ACTIVE 82782
MON$OLDEST_SNAPSHOT 82782
MON$NEXT_TRANSACTION 82782
MON$PAGE_BUFFERS 384
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 2015-12-11 10:02:47.3010
MON$PAGES 144884
MON$STAT_ID 1
MON$BACKUP_STATE 0
ISQL Version: WI-V2.5.4.26856 Firebird 2.5
Server version:
Firebird/x86-64/Windows NT (access method), version "WI-V2.5.4.26856 Firebird 2.5"
Firebird/x86-64/Windows NT (remote server), version "WI-V2.5.4.26856 Firebird 2.5/tcp (WIN-2BE1UI8LGDL)/P12"
Firebird/x86-64/Windows NT (remote interface), version "WI-V2.5.4.26856 Firebird 2.5/tcp (WIN-2BE1UI8LGDL)/P12"
on disk structure version 11.2

Database statistics, full


Database "D:\OLTP-EMUL\oltp25-small.fdb"
Database header page information:
Flags 0
Checksum 12345
Generation 87212
Page size 8192
ODS version 11.2
Oldest transaction 82783
Oldest active 82784
Oldest snapshot 82784
Next transaction 82785
Bumped transaction 1
Sequence number 0
Next attachment ID 4418
Implementation ID 24
Shadow count 0
Page buffers 0
Next header page 0
Database dialect 3
Creation date Dec 11, 2015 10:02:47
Attributes force write

Variable header data:
Sweep interval: 0
*END*


Database file sequence:
File D:\OLTP-EMUL\oltp25-small.fdb is the only file

Analyzing database pages ...
AGENTS (154)
Primary pointer page: 259, Index root page: 260
Average record length: 34.62, total records: 50
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 1, data page slots: 1, average fill: 32%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index AGENTS_ID_DESC (2)
Depth: 1, leaf buckets: 1, nodes: 50
Average data length: 7.10, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index AGENTS_IS_OUR_FIRM (4)
Depth: 1, leaf buckets: 1, nodes: 50
Average data length: 0.06, total dup: 48, max dup: 48
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index AGENTS_IS_SUPPLIER (3)
Depth: 1, leaf buckets: 1, nodes: 50
Average data length: 0.06, total dup: 48, max dup: 47
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index AGENTS_NAME_UNQ (1)
Depth: 1, leaf buckets: 1, nodes: 50
Average data length: 13.88, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PK_AGENTS (0)
Depth: 1, leaf buckets: 1, nodes: 50
Average data length: 7.10, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

BUSINESS_OPS (158)
Primary pointer page: 280, Index root page: 281
Average record length: 103.00, total records: 19
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 1, data page slots: 1, average fill: 28%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index BO_UNIT_UNQ (1)
Depth: 1, leaf buckets: 1, nodes: 19
Average data length: 14.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index BUSINESS_OPS_RND_WGTH (2)
Depth: 1, leaf buckets: 1, nodes: 19
Average data length: 0.84, total dup: 7, max dup: 3
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index RDB$1 (0)
Depth: 1, leaf buckets: 1, nodes: 19
Average data length: 1.63, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

DOC_DATA (141)
Primary pointer page: 199, Index root page: 200
Average record length: 45.06, total records: 417506
Average version length: 46.21, total versions: 9266, max versions: 1
Data pages: 4472, data page slots: 4472, average fill: 73%
Fill distribution:
0 - 19% = 1
20 - 39% = 21
40 - 59% = 104
60 - 79% = 4322
80 - 99% = 24

Index DOC_DATA_DOC_WARE_UNQ (1)
Depth: 3, leaf buckets: 1275, nodes: 417506
Average data length: 11.59, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 131
20 - 39% = 55
40 - 59% = 313
60 - 79% = 120
80 - 99% = 656

Index DOC_DATA_ID_DESC (2)
Depth: 3, leaf buckets: 1234, nodes: 417506
Average data length: 6.03, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 28
40 - 59% = 1204
60 - 79% = 2
80 - 99% = 0

Index FK_DOC_DATA_DOC_LIST (3)
Depth: 2, leaf buckets: 370, nodes: 417506
Average data length: 0.82, total dup: 364196, max dup: 49
Fill distribution:
0 - 19% = 1
20 - 39% = 28
40 - 59% = 92
60 - 79% = 20
80 - 99% = 229

DOC_LIST (140)
Primary pointer page: 195, Index root page: 196
Average record length: 58.95, total records: 66028
Average version length: 51.74, total versions: 4933, max versions: 5
Data pages: 854, data page slots: 854, average fill: 77%
Fill distribution:
0 - 19% = 1
20 - 39% = 19
40 - 59% = 77
60 - 79% = 274
80 - 99% = 483

Index DOC_LIST_ID_DESC (1)
Depth: 2, leaf buckets: 195, nodes: 66028
Average data length: 6.30, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 24
40 - 59% = 171
60 - 79% = 0
80 - 99% = 0

Index FK_DOC_LIST_AGENTS (2)
Depth: 2, leaf buckets: 56, nodes: 66028
Average data length: 0.01, total dup: 65978, max dup: 16260
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 40
60 - 79% = 9
80 - 99% = 7

Index PK_DOC_LIST (0)
Depth: 2, leaf buckets: 125, nodes: 66028
Average data length: 6.29, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 24
40 - 59% = 31
60 - 79% = 0
80 - 99% = 70

DOC_STATES (153)
Primary pointer page: 257, Index root page: 258
Average record length: 67.50, total records: 4
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 1, data page slots: 1, average fill: 4%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index DOC_STATES_MCODE_UNQ (1)
Depth: 1, leaf buckets: 1, nodes: 4
Average data length: 13.75, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index DOC_STATES_NAME_UNQ (2)
Depth: 1, leaf buckets: 1, nodes: 4
Average data length: 29.75, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PK_DOC_STATES (0)
Depth: 1, leaf buckets: 1, nodes: 4
Average data length: 1.50, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

FB_ERRORS (159)
Primary pointer page: 287, Index root page: 288
Average record length: 79.56, total records: 758
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 12, data page slots: 12, average fill: 75%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 4
80 - 99% = 7

Index FB_ERRORS_GDS_CODE (0)
Depth: 1, leaf buckets: 1, nodes: 758
Average data length: 1.03, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

INVNT_SALDO (147)
Primary pointer page: 216, Index root page: 217
Average record length: 62.35, total records: 400
Average version length: 13.09, total versions: 60077, max versions: 194
Data pages: 236, data page slots: 236, average fill: 96%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 0
80 - 99% = 235

Index PK_INVNT_SALDO (0)
Depth: 1, leaf buckets: 1, nodes: 400
Average data length: 7.22, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 0
80 - 99% = 0

INVNT_TURNOVER_LOG (157)
Primary pointer page: 278, Index root page: 279
Average record length: 0.22, total records: 106801
Average version length: 68.45, total versions: 106460, max versions: 1
Data pages: 1575, data page slots: 1575, average fill: 85%
Fill distribution:
0 - 19% = 2
20 - 39% = 16
40 - 59% = 71
60 - 79% = 233
80 - 99% = 1253

Index INVNT_TURNOVER_LOG_WARE_DD_ID (0)
Depth: 3, leaf buckets: 274, nodes: 106801
Average data length: 8.53, total dup: 10590, max dup: 4
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 95
60 - 79% = 120
80 - 99% = 58

MONEY_SALDO (143)
Primary pointer page: 208, Index root page: 209
Average record length: 15.92, total records: 49
Average version length: 9.00, total versions: 2926, max versions: 246
Data pages: 11, data page slots: 11, average fill: 86%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 0
80 - 99% = 10

Index PK_MONEY_SALDO (0)
Depth: 1, leaf buckets: 1, nodes: 49
Average data length: 7.10, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

MONEY_TURNOVER_LOG (142)
Primary pointer page: 206, Index root page: 207
Average record length: 0.21, total records: 4965
Average version length: 44.16, total versions: 4942, max versions: 1
Data pages: 53, data page slots: 53, average fill: 90%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 3
80 - 99% = 49

Index MONEY_TURNOVER_LOG_AGENT_OPTYPE (0)
Depth: 2, leaf buckets: 4, nodes: 4965
Average data length: 0.19, total dup: 4878, max dup: 1072
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 2
60 - 79% = 2
80 - 99% = 0

MON_LOG (164)
Primary pointer page: 303, Index root page: 304
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index MON_LOG_DTS (4)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index MON_LOG_GDSCODE (2)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index MON_LOG_ROWSET_DESC (1)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index MON_LOG_UNIT (3)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PK_MON_LOG (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

MON_LOG_TABLE_STATS (165)
Primary pointer page: 316, Index root page: 317
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index MON_LOG_TABLE_STATS_DTS (4)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index MON_LOG_TABLE_STATS_GDSCODE (2)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index MON_LOG_TABLE_STATS_ROWSET (1)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index MON_LOG_TABLE_STATS_TN_UNIT (3)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PK_MON_LOG_TABLE_STATS (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

OPTYPES (138)
Primary pointer page: 186, Index root page: 187
Average record length: 67.89, total records: 9
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 1, data page slots: 1, average fill: 9%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index OPTYPES_MCODE_UNQ (1)
Depth: 1, leaf buckets: 1, nodes: 9
Average data length: 4.56, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PK_OPTYPES (0)
Depth: 1, leaf buckets: 1, nodes: 9
Average data length: 2.11, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

PDISTR (150)
Primary pointer page: 241, Index root page: 242
Average record length: 26.11, total records: 115287
Average version length: 31.91, total versions: 70105, max versions: 9
Data pages: 1217, data page slots: 1217, average fill: 85%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 6
60 - 79% = 373
80 - 99% = 837

Index PDISTR_AGENT_ID (4)
Depth: 2, leaf buckets: 93, nodes: 115287
Average data length: 0.01, total dup: 115238, max dup: 23580
Fill distribution:
0 - 19% = 1
20 - 39% = 1
40 - 59% = 47
60 - 79% = 23
80 - 99% = 21

Index PDISTR_SNDOP_RCVOP_SNDID_ASC (2)
Depth: 2, leaf buckets: 119, nodes: 115287
Average data length: 0.90, total dup: 103601, max dup: 29
Fill distribution:
0 - 19% = 2
20 - 39% = 1
40 - 59% = 71
60 - 79% = 28
80 - 99% = 17

Index PDISTR_SNDOP_RCVOP_SNDID_DESC (3)
Depth: 2, leaf buckets: 122, nodes: 115287
Average data length: 0.90, total dup: 103601, max dup: 29
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 73
60 - 79% = 30
80 - 99% = 18

Index PDISTR_SND_ID (1)
Depth: 2, leaf buckets: 109, nodes: 115287
Average data length: 0.68, total dup: 103601, max dup: 29
Fill distribution:
0 - 19% = 1
20 - 39% = 2
40 - 59% = 51
60 - 79% = 36
80 - 99% = 19

PERF_ESTIMATED (160)
Primary pointer page: 289, Index root page: 290
Average record length: 26.64, total records: 14342
Average version length: 18.00, total versions: 2, max versions: 1
Data pages: 119, data page slots: 119, average fill: 64%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 118
80 - 99% = 0

Index PERF_EST_MINUTE_SINCE_START (0)
Depth: 2, leaf buckets: 8, nodes: 14342
Average data length: 0.01, total dup: 14203, max dup: 1360
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 1
80 - 99% = 7

PERF_ISQL_STAT (161)
Primary pointer page: 296, Index root page: 297
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PERF_ISQL_STAT_TRN (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

PERF_LOG (162)
Primary pointer page: 299, Index root page: 300
Average record length: 142.77, total records: 980736
Average version length: 153.00, total versions: 1, max versions: 1
Data pages: 22199, data page slots: 22199, average fill: 86%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 22198

Index PERF_LOG_DTS_BEG_DESC (0)
Depth: 3, leaf buckets: 1265, nodes: 980736
Average data length: 0.59, total dup: 526489, max dup: 34
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1067
60 - 79% = 141
80 - 99% = 57

Index PERF_LOG_GDSCODE (3)
Depth: 2, leaf buckets: 969, nodes: 980736
Average data length: 0.00, total dup: 980731, max dup: 938745
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 966
60 - 79% = 3
80 - 99% = 0

Index PERF_LOG_TRN_DESC (2)
Depth: 3, leaf buckets: 1256, nodes: 980736
Average data length: 0.43, total dup: 915254, max dup: 3994
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1123
60 - 79% = 80
80 - 99% = 53

Index PERF_LOG_UNIT (1)
Depth: 3, leaf buckets: 1105, nodes: 980736
Average data length: 0.32, total dup: 950190, max dup: 111685
Fill distribution:
0 - 19% = 2
20 - 39% = 0
40 - 59% = 813
60 - 79% = 149
80 - 99% = 141

PHRASES (145)
Primary pointer page: 212, Index root page: 213
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PHRASES_ID_DESC (3)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PHRASES_NAME (2)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PHRASES_UNQ (1)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PK_PHRASES (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

PSTORNED (151)
Primary pointer page: 248, Index root page: 249
Average record length: 52.23, total records: 80746
Average version length: 54.82, total versions: 3609, max versions: 1
Data pages: 938, data page slots: 938, average fill: 76%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 4
60 - 79% = 898
80 - 99% = 36

Index PSTORNED_RCV_ID (2)
Depth: 2, leaf buckets: 102, nodes: 80746
Average data length: 1.09, total dup: 67530, max dup: 144
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 100
60 - 79% = 1
80 - 99% = 1

Index PSTORNED_SND_ID (1)
Depth: 2, leaf buckets: 74, nodes: 80746
Average data length: 0.61, total dup: 73611, max dup: 72
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 37
60 - 79% = 31
80 - 99% = 6

RULES_FOR_PDISTR (152)
Primary pointer page: 250, Index root page: 251
Average record length: 18.00, total records: 4
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 1, data page slots: 1, average fill: 2%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index RULES_FOR_PDISTR_UNQ (0)
Depth: 1, leaf buckets: 1, nodes: 4
Average data length: 7.50, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

RULES_FOR_QDISTR (139)
Primary pointer page: 190, Index root page: 191
Average record length: 32.71, total records: 7
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 1, data page slots: 1, average fill: 4%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index RULES_FOR_QDISTR_RCVOP (1)
Depth: 1, leaf buckets: 1, nodes: 7
Average data length: 1.57, total dup: 2, max dup: 2
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index RULES_FOR_QDISTR_UNQ (0)
Depth: 1, leaf buckets: 1, nodes: 7
Average data length: 6.14, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

SEMAPHORES (156)
Primary pointer page: 263, Index root page: 264
Average record length: 34.25, total records: 4
Average version length: 9.00, total versions: 707, max versions: 409
Data pages: 3, data page slots: 3, average fill: 76%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 0
80 - 99% = 2

Index PK_SEMAPHORES (0)
Depth: 1, leaf buckets: 1, nodes: 4
Average data length: 8.50, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index SEMAPHORES_TASK_UNQ (1)
Depth: 1, leaf buckets: 1, nodes: 4
Average data length: 18.50, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

SETTINGS (137)
Primary pointer page: 183, Index root page: 184
Average record length: 98.32, total records: 114
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 2, data page slots: 2, average fill: 81%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 1
80 - 99% = 1

Index SETTINGS_MODE_CODE (0)
Depth: 1, leaf buckets: 1, nodes: 114
Average data length: 23.07, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TMP$AUTOGEN$RAND$CALLS (136)
Primary pointer page: 180, Index root page: 181
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TMP$AUTOGEN$SOURCE (135)
Primary pointer page: 178, Index root page: 179
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TMP$DEP_DOCS (129)
Primary pointer page: 159, Index root page: 160
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index TMP_DEP_DOCS_UNQ (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TMP$IDX_RECALC (132)
Primary pointer page: 169, Index root page: 170
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index TMP_IDX_RECALC_IDX_NAME_UNQ (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TMP$MON_LOG (133)
Primary pointer page: 174, Index root page: 175
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TMP$MON_LOG_TABLE_STATS (134)
Primary pointer page: 176, Index root page: 177
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TMP$PERF_LOG (163)
Primary pointer page: 301, Index root page: 302
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index TMP$PERF_LOG_UNIT_TRN_DTS_END (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TMP$PERF_MON (131)
Primary pointer page: 167, Index root page: 168
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TMP$RESULT_SET (130)
Primary pointer page: 162, Index root page: 163
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index TMP_RESULT_SET_DOC (1)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index TMP_RESULT_SET_WARE_DOC (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TMP$SHOPPING_CART (128)
Primary pointer page: 155, Index root page: 156
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index TMP_SHOPCART_UNQ (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

TRACE_STAT (276)
Primary pointer page: 513, Index root page: 95177
Average record length: 59.43, total records: 398
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 5, data page slots: 5, average fill: 74%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 5
80 - 99% = 0

WARES (144)
Primary pointer page: 210, Index root page: 211
Average record length: 78.00, total records: 400
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 6, data page slots: 6, average fill: 78%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 4
80 - 99% = 2

Index FK_WARES_WARE_GROUPS (3)
Depth: 1, leaf buckets: 1, nodes: 400
Average data length: 0.14, total dup: 392, max dup: 62
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PK_WARES (0)
Depth: 1, leaf buckets: 1, nodes: 400
Average data length: 7.22, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 0
80 - 99% = 0

Index WARES_ID_DESC (2)
Depth: 1, leaf buckets: 1, nodes: 400
Average data length: 7.22, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 0
80 - 99% = 0

Index WARES_NUMB_UNQ (1)
Depth: 2, leaf buckets: 2, nodes: 400
Average data length: 19.40, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 2
60 - 79% = 0
80 - 99% = 0

WARE_GROUPS (155)
Primary pointer page: 261, Index root page: 262
Average record length: 32.13, total records: 8
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 1, data page slots: 1, average fill: 5%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index PK_WARE_GROUPS (0)
Depth: 1, leaf buckets: 1, nodes: 8
Average data length: 7.25, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index WARE_GROUPS_NAME_UNQ (1)
Depth: 1, leaf buckets: 1, nodes: 8
Average data length: 15.88, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

XQD_1000_1200 (258)
Primary pointer page: 645, Index root page: 646
Average record length: 0.23, total records: 168810
Average version length: 69.50, total versions: 168250, max versions: 1
Data pages: 2267, data page slots: 2267, average fill: 94%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 1
80 - 99% = 2265

Index XQD_1000_1200_WARE_SOP_ROP_SND (0)
Depth: 2, leaf buckets: 190, nodes: 168810
Average data length: 1.30, total dup: 147790, max dup: 29
Fill distribution:
0 - 19% = 4
20 - 39% = 5
40 - 59% = 65
60 - 79% = 72
80 - 99% = 44

XQD_1000_3300 (260)
Primary pointer page: 653, Index root page: 654
Average record length: 46.17, total records: 170872
Average version length: 69.15, total versions: 57392, max versions: 1
Data pages: 2365, data page slots: 2398, average fill: 82%
Fill distribution:
0 - 19% = 19
20 - 39% = 28
40 - 59% = 27
60 - 79% = 930
80 - 99% = 1361

Index XQD_1000_3300_DOC (1)
Depth: 2, leaf buckets: 156, nodes: 170872
Average data length: 0.16, total dup: 167007, max dup: 205
Fill distribution:
0 - 19% = 1
20 - 39% = 19
40 - 59% = 75
60 - 79% = 14
80 - 99% = 47

Index XQD_1000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 197, nodes: 170872
Average data length: 1.31, total dup: 149693, max dup: 38
Fill distribution:
0 - 19% = 1
20 - 39% = 4
40 - 59% = 76
60 - 79% = 81
80 - 99% = 35

XQD_1200_2000 (259)
Primary pointer page: 648, Index root page: 649
Average record length: 0.15, total records: 203324
Average version length: 69.48, total versions: 202897, max versions: 1
Data pages: 2733, data page slots: 2733, average fill: 94%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 3
80 - 99% = 2729

Index XQD_1200_2000_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 212, nodes: 203324
Average data length: 0.98, total dup: 184456, max dup: 95
Fill distribution:
0 - 19% = 4
20 - 39% = 3
40 - 59% = 63
60 - 79% = 87
80 - 99% = 55

XQD_2000_3300 (261)
Primary pointer page: 657, Index root page: 658
Average record length: 16.13, total records: 908349
Average version length: 68.18, total versions: 697032, max versions: 1
Data pages: 12232, data page slots: 12232, average fill: 90%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 2623
80 - 99% = 9608

Index XQD_2000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 1180, nodes: 908349
Average data length: 1.15, total dup: 801475, max dup: 83
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 910
60 - 79% = 167
80 - 99% = 102

XQD_2100_3300 (262)
Primary pointer page: 661, Index root page: 662
Average record length: 5.64, total records: 168489
Average version length: 68.53, total versions: 154673, max versions: 1
Data pages: 3184, data page slots: 3257, average fill: 67%
Fill distribution:
0 - 19% = 188
20 - 39% = 262
40 - 59% = 366
60 - 79% = 1430
80 - 99% = 938

Index XQD_2100_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 253, nodes: 168489
Average data length: 1.45, total dup: 144067, max dup: 83
Fill distribution:
0 - 19% = 2
20 - 39% = 36
40 - 59% = 148
60 - 79% = 66
80 - 99% = 1

XQD_3300_3400 (263)
Primary pointer page: 664, Index root page: 665
Average record length: 39.11, total records: 665597
Average version length: 68.24, total versions: 286380, max versions: 1
Data pages: 9522, data page slots: 9536, average fill: 80%
Fill distribution:
0 - 19% = 109
20 - 39% = 225
40 - 59% = 405
60 - 79% = 3810
80 - 99% = 4973

Index XQD_3300_3400_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 912, nodes: 665597
Average data length: 1.28, total dup: 579131, max dup: 44
Fill distribution:
0 - 19% = 1
20 - 39% = 28
40 - 59% = 618
60 - 79% = 163
80 - 99% = 102

XQS_1000_1200 (264)
Primary pointer page: 667, Index root page: 668
Average record length: 78.84, total records: 885630
Average version length: 80.42, total versions: 17024, max versions: 1
Data pages: 13203, data page slots: 13203, average fill: 80%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 6681
80 - 99% = 6521

Index XQS_1000_1200_DOC_ID (0)
Depth: 3, leaf buckets: 981, nodes: 885630
Average data length: 0.16, total dup: 865574, max dup: 156
Fill distribution:
0 - 19% = 7
20 - 39% = 0
40 - 59% = 730
60 - 79% = 196
80 - 99% = 48

Index XQS_1000_1200_RCV_ID (2)
Depth: 3, leaf buckets: 701, nodes: 885630
Average data length: 0.74, total dup: 778016, max dup: 49
Fill distribution:
0 - 19% = 3
20 - 39% = 0
40 - 59% = 113
60 - 79% = 14
80 - 99% = 571

Index XQS_1000_1200_SND_ID (1)
Depth: 3, leaf buckets: 1126, nodes: 885630
Average data length: 0.76, total dup: 775310, max dup: 29
Fill distribution:
0 - 19% = 9
20 - 39% = 0
40 - 59% = 851
60 - 79% = 221
80 - 99% = 45

XQS_1000_3300 (266)
Primary pointer page: 678, Index root page: 679
Average record length: 72.64, total records: 597264
Average version length: 80.11, total versions: 56097, max versions: 1
Data pages: 9080, data page slots: 9089, average fill: 80%
Fill distribution:
0 - 19% = 43
20 - 39% = 107
40 - 59% = 142
60 - 79% = 5456
80 - 99% = 3332

Index XQS_1000_3300_DOC_ID (0)
Depth: 3, leaf buckets: 624, nodes: 597264
Average data length: 0.15, total dup: 584166, max dup: 290
Fill distribution:
0 - 19% = 0
20 - 39% = 4
40 - 59% = 397
60 - 79% = 173
80 - 99% = 50

Index XQS_1000_3300_RCV_ID (2)
Depth: 2, leaf buckets: 480, nodes: 597264
Average data length: 0.81, total dup: 519999, max dup: 14
Fill distribution:
0 - 19% = 2
20 - 39% = 9
40 - 59% = 72
60 - 79% = 2
80 - 99% = 395

Index XQS_1000_3300_SND_ID (1)
Depth: 3, leaf buckets: 755, nodes: 597264
Average data length: 0.74, total dup: 526422, max dup: 44
Fill distribution:
0 - 19% = 33
20 - 39% = 4
40 - 59% = 476
60 - 79% = 215
80 - 99% = 27

XQS_1200_2000 (265)
Primary pointer page: 673, Index root page: 674
Average record length: 78.15, total records: 908083
Average version length: 81.59, total versions: 25400, max versions: 1
Data pages: 13540, data page slots: 13540, average fill: 80%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 7337
80 - 99% = 6202

Index XQS_1200_2000_DOC_ID (0)
Depth: 3, leaf buckets: 952, nodes: 908083
Average data length: 0.05, total dup: 902550, max dup: 772
Fill distribution:
0 - 19% = 2
20 - 39% = 0
40 - 59% = 657
60 - 79% = 221
80 - 99% = 72

Index XQS_1200_2000_RCV_ID (2)
Depth: 3, leaf buckets: 674, nodes: 908083
Average data length: 0.71, total dup: 801089, max dup: 49
Fill distribution:
0 - 19% = 2
20 - 39% = 1
40 - 59% = 37
60 - 79% = 3
80 - 99% = 631

Index XQS_1200_2000_SND_ID (1)
Depth: 3, leaf buckets: 1094, nodes: 908083
Average data length: 0.72, total dup: 800529, max dup: 95
Fill distribution:
0 - 19% = 4
20 - 39% = 1
40 - 59% = 716
60 - 79% = 284
80 - 99% = 89

XQS_2000_3300 (267)
Primary pointer page: 683, Index root page: 684
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index XQS_2000_3300_DOC_ID (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index XQS_2000_3300_RCV_ID (2)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index XQS_2000_3300_SND_ID (1)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

XQS_2100_3300 (268)
Primary pointer page: 691, Index root page: 692
Average record length: 74.64, total records: 707657
Average version length: 80.16, total versions: 50107, max versions: 1
Data pages: 11106, data page slots: 11120, average fill: 77%
Fill distribution:
0 - 19% = 114
20 - 39% = 283
40 - 59% = 454
60 - 79% = 6490
80 - 99% = 3765

Index XQS_2100_3300_DOC_ID (0)
Depth: 3, leaf buckets: 652, nodes: 707657
Average data length: 0.05, total dup: 703422, max dup: 656
Fill distribution:
0 - 19% = 1
20 - 39% = 9
40 - 59% = 232
60 - 79% = 280
80 - 99% = 130

Index XQS_2100_3300_RCV_ID (2)
Depth: 3, leaf buckets: 668, nodes: 707657
Average data length: 0.82, total dup: 615162, max dup: 14
Fill distribution:
0 - 19% = 16
20 - 39% = 31
40 - 59% = 218
60 - 79% = 11
80 - 99% = 392

Index XQS_2100_3300_SND_ID (1)
Depth: 3, leaf buckets: 749, nodes: 707657
Average data length: 0.72, total dup: 624349, max dup: 59
Fill distribution:
0 - 19% = 0
20 - 39% = 7
40 - 59% = 235
60 - 79% = 363
80 - 99% = 144

XQS_3300_3400 (269)
Primary pointer page: 697, Index root page: 698
Average record length: 61.78, total records: 307726
Average version length: 68.28, total versions: 29393, max versions: 1
Data pages: 4141, data page slots: 4141, average fill: 79%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 3417
80 - 99% = 723

Index XQS_3300_3400_DOC_ID (0)
Depth: 2, leaf buckets: 278, nodes: 307726
Average data length: 0.21, total dup: 298281, max dup: 191
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 95
60 - 79% = 117
80 - 99% = 66

Index XQS_3300_3400_SND_ID (1)
Depth: 2, leaf buckets: 324, nodes: 307726
Average data length: 0.82, total dup: 267893, max dup: 29
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 123
60 - 79% = 139
80 - 99% = 62

ZDOC_DATA (228)
Primary pointer page: 564, Index root page: 565
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

ZDOC_LIST (227)
Primary pointer page: 560, Index root page: 561
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

ZINVNT_TURNOVER_LOG (229)
Primary pointer page: 567, Index root page: 568
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

ZPDISTR (232)
Primary pointer page: 573, Index root page: 574
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index ZPDISTR_ID (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

ZPSTORNED (233)
Primary pointer page: 575, Index root page: 576
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index ZPSTORNED_ID (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

ZQDISTR (230)
Primary pointer page: 569, Index root page: 570
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index ZQDISTR_ID (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index ZQDISTR_WARE_SNDOP_RCVOP (1)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

ZQSTORNED (231)
Primary pointer page: 571, Index root page: 572
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index ZQSTORNED_DOC_ID (1)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index ZQSTORNED_ID (0)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index ZQSTORNED_RCV_ID (3)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index ZQSTORNED_SND_ID (2)
Depth: 1, leaf buckets: 1, nodes: 0
Average data length: 0.00, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

ZTMP_DEP_DOCS (226)
Primary pointer page: 558, Index root page: 559
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

ZTMP_SHOPPING_CART (225)
Primary pointer page: 556, Index root page: 557
Average record length: 0.00, total records: 0
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 0, data page slots: 0, average fill: 0%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Z_USED_VIEWS (146)
Primary pointer page: 214, Index root page: 215
Average record length: 29.19, total records: 32
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 1, data page slots: 1, average fill: 18%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Index Z_USED_VIEWS_UNQ (0)
Depth: 1, leaf buckets: 1, nodes: 32
Average data length: 10.78, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 0

Done for 17950 ms, from 2:27:29.38 to 2:27:47.33.

Analyzing DB stat log: obtaining values of total records and versions

TABLE_NAME TOTAL_RECS TOTAL_VERS VERS_PERCENT
AGENTS 50 0 0.0000
BUSINESS_OPS 19 0 0.0000
DOC_DATA 417506 9266 2.2193
DOC_LIST 66028 4933 7.4710
DOC_STATES 4 0 0.0000
FB_ERRORS 758 0 0.0000
INVNT_SALDO 400 60077 15019.2500
INVNT_TURNOVER_LOG 106801 106460 99.6807
MONEY_SALDO 49 2926 5971.4285
MONEY_TURNOVER_LOG 4965 4942 99.5367
OPTYPES 9 0 0.0000
PDISTR 115287 70105 60.8091
PERF_ESTIMATED 14342 2 0.0139
PERF_LOG 980736 1 0.0001
PSTORNED 80746 3609 4.4695
RULES_FOR_PDISTR 4 0 0.0000
RULES_FOR_QDISTR 7 0 0.0000
SEMAPHORES 4 707 17675.0000
SETTINGS 114 0 0.0000
TRACE_STAT 398 0 0.0000
WARES 400 0 0.0000
WARE_GROUPS 8 0 0.0000
XQD_1000_1200 168810 168250 99.6682
XQD_1000_3300 170872 57392 33.5877
XQD_1200_2000 203324 202897 99.7899
XQD_2000_3300 908349 697032 76.7361
XQD_2100_3300 168489 154673 91.8000
XQD_3300_3400 665597 286380 43.0260
XQS_1000_1200 885630 17024 1.9222
XQS_1000_3300 597264 56097 9.3923
XQS_1200_2000 908083 25400 2.7971
XQS_2100_3300 707657 50107 7.0806
XQS_3300_3400 307726 29393 9.5516
Z_USED_VIEWS 32 0 0.0000
Done for 2580 ms, from 2:27:56.94 to 2:27:59.52.

Database validation

02:27:59.60 Validation started

02:27:59.60 Relation 140 (DOC_LIST)
02:27:59.60 process pointer page 0 of 1
02:27:59.61 Index 1 (PK_DOC_LIST)
02:27:59.61 Index 2 (DOC_LIST_ID_DESC)
02:27:59.63 Index 3 (FK_DOC_LIST_AGENTS)
02:27:59.63 Relation 140 (DOC_LIST) is ok

02:27:59.63 Relation 141 (DOC_DATA)
02:27:59.63 process pointer page 0 of 3
02:27:59.71 process pointer page 1 of 3
02:27:59.80 process pointer page 2 of 3
02:27:59.83 Index 2 (DOC_DATA_DOC_WARE_UNQ)
02:28:00.00 Index 3 (DOC_DATA_ID_DESC)
02:28:00.16 Index 4 (FK_DOC_DATA_DOC_LIST)
02:28:00.25 Relation 141 (DOC_DATA) is ok

02:28:00.25 Relation 147 (INVNT_SALDO)
02:28:00.25 process pointer page 0 of 1
02:28:00.31 Index 1 (PK_INVNT_SALDO)
02:28:00.31 Relation 147 (INVNT_SALDO) is ok

02:28:00.31 Relation 150 (PDISTR)
02:28:00.31 process pointer page 0 of 1
02:28:00.42 Index 2 (PDISTR_SND_ID)
02:28:00.45 Index 3 (PDISTR_SNDOP_RCVOP_SNDID_ASC)
02:28:00.49 Index 4 (PDISTR_SNDOP_RCVOP_SNDID_DESC)
02:28:00.52 Index 5 (PDISTR_AGENT_ID)
02:28:00.55 Relation 150 (PDISTR) is ok

02:28:00.55 Relation 151 (PSTORNED)
02:28:00.55 process pointer page 0 of 1
02:28:00.61 Index 2 (PSTORNED_SND_ID)
02:28:00.63 Index 3 (PSTORNED_RCV_ID)
02:28:00.64 Relation 151 (PSTORNED) is ok

02:28:00.64 Relation 156 (SEMAPHORES)
02:28:00.64 process pointer page 0 of 1
02:28:00.64 Index 1 (PK_SEMAPHORES)
02:28:00.64 Index 2 (SEMAPHORES_TASK_UNQ)
02:28:00.64 Relation 156 (SEMAPHORES) is ok

02:28:00.64 Relation 157 (INVNT_TURNOVER_LOG)
02:28:00.64 process pointer page 0 of 1
02:28:00.77 Index 1 (INVNT_TURNOVER_LOG_WARE_DD_ID)
02:28:00.83 Relation 157 (INVNT_TURNOVER_LOG) is ok

02:28:00.83 Relation 160 (PERF_ESTIMATED)
02:28:00.83 process pointer page 0 of 1
02:28:00.84 Index 1 (PERF_EST_MINUTE_SINCE_START)
02:28:00.84 Relation 160 (PERF_ESTIMATED) is ok

02:28:00.84 Relation 161 (PERF_ISQL_STAT)
02:28:00.84 process pointer page 0 of 1
02:28:00.84 Index 1 (PERF_ISQL_STAT_TRN)
02:28:00.84 Relation 161 (PERF_ISQL_STAT) is ok

02:28:00.84 Relation 162 (PERF_LOG)
02:28:00.84 process pointer page 0 of 12
02:28:00.92 process pointer page 1 of 12
02:28:01.00 process pointer page 2 of 12
02:28:01.03 process pointer page 3 of 12
02:28:01.05 process pointer page 4 of 12
02:28:01.08 process pointer page 5 of 12
02:28:01.16 process pointer page 6 of 12
02:28:01.23 process pointer page 7 of 12
02:28:01.31 process pointer page 8 of 12
02:28:01.39 process pointer page 9 of 12
02:28:01.45 process pointer page 10 of 12
02:28:01.53 process pointer page 11 of 12
02:28:01.56 Index 1 (PERF_LOG_DTS_BEG_DESC)
02:28:01.78 Index 2 (PERF_LOG_UNIT)
02:28:02.28 Index 3 (PERF_LOG_TRN_DESC)
02:28:02.54 Index 4 (PERF_LOG_GDSCODE)
02:28:02.69 Relation 162 (PERF_LOG) is ok

02:28:02.69 Relation 166 (V_STOPTEST)
02:28:02.69 Relation 166 (V_STOPTEST) is ok

02:28:02.69 Relation 183 (V_QDISTR_SOURCE)
02:28:02.69 Relation 183 (V_QDISTR_SOURCE) is ok

02:28:02.69 Relation 184 (V_QSTORNED_SOURCE)
02:28:02.69 Relation 184 (V_QSTORNED_SOURCE) is ok

02:28:02.69 Relation 258 (XQD_1000_1200)
02:28:02.69 process pointer page 0 of 2
02:28:02.86 process pointer page 1 of 2
02:28:02.89 Index 1 (XQD_1000_1200_WARE_SOP_ROP_SND)
02:28:02.93 Relation 258 (XQD_1000_1200) is ok

02:28:02.93 Relation 259 (XQD_1200_2000)
02:28:02.93 process pointer page 0 of 2
02:28:03.11 process pointer page 1 of 2
02:28:03.17 Index 1 (XQD_1200_2000_WARE_SOP_ROP_SND)
02:28:03.23 Relation 259 (XQD_1200_2000) is ok

02:28:03.23 Relation 260 (XQD_1000_3300)
02:28:03.23 process pointer page 0 of 2
02:28:03.36 process pointer page 1 of 2
02:28:03.37 Index 1 (XQD_1000_3300_WARE_SOP_ROP_SND)
02:28:03.42 Index 2 (XQD_1000_3300_DOC)
02:28:03.47 Relation 260 (XQD_1000_3300) is ok

02:28:03.47 Relation 261 (XQD_2000_3300)
02:28:03.47 process pointer page 0 of 7
02:28:03.64 process pointer page 1 of 7
02:28:03.79 process pointer page 2 of 7
02:28:03.96 process pointer page 3 of 7
02:28:04.12 process pointer page 4 of 7
02:28:04.26 process pointer page 5 of 7
02:28:04.37 process pointer page 6 of 7
02:28:04.40 Index 1 (XQD_2000_3300_WARE_SOP_ROP_SND)
02:28:04.70 Relation 261 (XQD_2000_3300) is ok

02:28:04.70 Relation 262 (XQD_2100_3300)
02:28:04.70 process pointer page 0 of 2
02:28:04.84 process pointer page 1 of 2
02:28:04.92 Index 1 (XQD_2100_3300_WARE_SOP_ROP_SND)
02:28:04.98 Relation 262 (XQD_2100_3300) is ok

02:28:04.98 Relation 263 (XQD_3300_3400)
02:28:04.98 process pointer page 0 of 5
02:28:05.12 process pointer page 1 of 5
02:28:05.26 process pointer page 2 of 5
02:28:05.37 process pointer page 3 of 5
02:28:05.48 process pointer page 4 of 5
02:28:05.52 Index 1 (XQD_3300_3400_WARE_SOP_ROP_SND)
02:28:05.73 Relation 263 (XQD_3300_3400) is ok

02:28:05.73 Relation 264 (XQS_1000_1200)
02:28:05.73 process pointer page 0 of 7
02:28:05.81 process pointer page 1 of 7
02:28:05.90 process pointer page 2 of 7
02:28:05.99 process pointer page 3 of 7
02:28:06.07 process pointer page 4 of 7
02:28:06.16 process pointer page 5 of 7
02:28:06.26 process pointer page 6 of 7
02:28:06.34 Index 1 (XQS_1000_1200_DOC_ID)
02:28:06.59 Index 2 (XQS_1000_1200_SND_ID)
02:28:06.85 Index 3 (XQS_1000_1200_RCV_ID)
02:28:07.07 Relation 264 (XQS_1000_1200) is ok

02:28:07.07 Relation 265 (XQS_1200_2000)
02:28:07.07 process pointer page 0 of 8
02:28:07.16 process pointer page 1 of 8
02:28:07.24 process pointer page 2 of 8
02:28:07.33 process pointer page 3 of 8
02:28:07.43 process pointer page 4 of 8
02:28:07.51 process pointer page 5 of 8
02:28:07.60 process pointer page 6 of 8
02:28:07.63 process pointer page 7 of 8
02:28:07.63 Index 1 (XQS_1200_2000_DOC_ID)
02:28:07.88 Index 2 (XQS_1200_2000_SND_ID)
02:28:08.15 Index 3 (XQS_1200_2000_RCV_ID)
02:28:08.35 Relation 265 (XQS_1200_2000) is ok

02:28:08.35 Relation 266 (XQS_1000_3300)
02:28:08.35 process pointer page 0 of 5
02:28:08.44 process pointer page 1 of 5
02:28:08.54 process pointer page 2 of 5
02:28:08.63 process pointer page 3 of 5
02:28:08.74 process pointer page 4 of 5
02:28:08.80 Index 1 (XQS_1000_3300_DOC_ID)
02:28:08.94 Index 2 (XQS_1000_3300_SND_ID)
02:28:09.11 Index 3 (XQS_1000_3300_RCV_ID)
02:28:09.25 Relation 266 (XQS_1000_3300) is ok

02:28:09.25 Relation 267 (XQS_2000_3300)
02:28:09.25 process pointer page 0 of 1
02:28:09.25 Index 1 (XQS_2000_3300_DOC_ID)
02:28:09.25 Index 2 (XQS_2000_3300_SND_ID)
02:28:09.25 Index 3 (XQS_2000_3300_RCV_ID)
02:28:09.25 Relation 267 (XQS_2000_3300) is ok

02:28:09.25 Relation 268 (XQS_2100_3300)
02:28:09.25 process pointer page 0 of 6
02:28:09.35 process pointer page 1 of 6
02:28:09.38 process pointer page 2 of 6
02:28:09.44 process pointer page 3 of 6
02:28:09.53 process pointer page 4 of 6
02:28:09.63 process pointer page 5 of 6
02:28:09.67 Index 1 (XQS_2100_3300_DOC_ID)
02:28:09.91 Index 2 (XQS_2100_3300_SND_ID)
02:28:10.16 Index 3 (XQS_2100_3300_RCV_ID)
02:28:10.33 Relation 268 (XQS_2100_3300) is ok

02:28:10.33 Relation 269 (XQS_3300_3400)
02:28:10.33 process pointer page 0 of 3
02:28:10.44 process pointer page 1 of 3
02:28:10.53 process pointer page 2 of 3
02:28:10.55 Index 1 (XQS_3300_3400_DOC_ID)
02:28:10.63 Index 2 (XQS_3300_3400_SND_ID)
02:28:10.69 Relation 269 (XQS_3300_3400) is ok

02:28:10.69 Relation 276 (TRACE_STAT)
02:28:10.69 process pointer page 0 of 1
02:28:10.69 Relation 276 (TRACE_STAT) is ok

02:28:10.69 Validation finished
Done for 11170 ms, from 2:27:59.53 to 2:28:10.70.

Differences between old and current firebird.log

2:28:11.33. Got:
STDOUT: ---------- E:\TEMP\LOGS.OLTP25\OLTP25_FB_LOG_WHEN_TEST_FINISHED.LOG: 5 (number of rows in extracted log)
Obtained firebird.log info:
Result of DIR command for firebird.log AFTER test finish:
2016-10-15 02:28 309 oltp25_fb_log_when_test_finished.log

End of gathering firebird.log AFTER test finish.

Comparison of old and new firebird.log (get messages that appeared during test):

+++ Start of comparison +++
result: files match. No new messages appeared in firebird.log during test ran.
+++ End of comparison +++

Final processing ISQL logs in e:\temp\logs.oltp25


Remove 401 logs of every ISQL session if there were no serious errors.

Remove logs of every ISQL session if there were no serious errors, pattern: e:\temp\logs.oltp25\oltp25_*.*:

ERRORS_CHECKING_RESULT
NO_SEVERE_ERRORS_FOUND
ISQL logs are removed because no severe errors occured during test.
2016-10-15 2:28:13.22 - end of report, text file: e:\temp\logs.oltp25\oltp25.report.txt, html: e:\temp\logs.oltp25\oltp25.report.html