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 21:40:13.11.
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 21:39:43.3060 -1 <null> 127.0.0.1 93207 4242 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: 468.36 95669 84305 2016-10-15 18:39 2016-10-15 21:39
customer order: creation 27.31 30 4916 2016-10-15 18:39 2016-10-15 21:39
customer order: refuse 2.14 18 386 2016-10-15 18:39 2016-10-15 21:39
order to supplier: creation 7.35 2713 1323 2016-10-15 18:39 2016-10-15 21:39
order to supplier: removal 0.16 144306 28 2016-10-15 18:39 2016-10-15 21:39
invoice (draft): creation 7.13 1748 1283 2016-10-15 18:39 2016-10-15 21:39
invoice (draft): removal 1.14 60 205 2016-10-15 18:39 2016-10-15 21:39
invoice accept: apply 2.06 1571083 369 2016-10-15 18:39 2016-10-15 21:39
invoice accept: cancel 0.44 71693 71 2016-10-15 18:39 2016-10-15 21:39
customer reserve: creation 373.93 13428 67307 2016-10-15 18:39 2016-10-15 21:39
customer reserve: removal 9.34 6295 1682 2016-10-15 18:39 2016-10-15 21:39
realization accept: apply 12.46 30 2243 2016-10-15 18:39 2016-10-15 21:39
realization accept: cancel 2.97 23 535 2016-10-15 18:39 2016-10-15 21:39
payment from customer: creation 9.31 71 1675 2016-10-15 18:39 2016-10-15 21:39
payment from customer: removal 0.83 10 149 2016-10-15 18:39 2016-10-15 21:39
payment to supplier: creation 7.49 192 1349 2016-10-15 18:39 2016-10-15 21:39
payment to supplier: removal 0.44 10 79 2016-10-15 18:39 2016-10-15 21:39
service: total inventory turnovers 2.22 4904 400 2016-10-15 18:39 2016-10-15 21:39
service: total monetary turnovers 1.64 11 295 2016-10-15 18:39 2016-10-15 21:39
service: refresh index statistics 0.07 1090 10 2016-10-15 18:39 2016-10-15 21:39
Done for 8530 ms, from 21:40:32.10 to 21:40:40.63.

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 356 7259 6401 858 11.82 18:39:44 18:57:43
interval # 2, overall 2 215 4361 3873 488 11.19 18:57:44 19:15:43
interval # 3, overall 3 191 4100 3437 663 16.17 19:15:44 19:33:43
interval # 4, overall 4 231 5714 4151 1563 27.35 19:33:44 19:51:43
interval # 5, overall 5 320 5932 5766 166 2.80 19:51:44 20:09:43
interval # 6, overall 6 375 6911 6746 165 2.39 20:09:44 20:27:43
interval # 7, overall 7 489 9158 8793 365 3.99 20:27:44 20:45:43
interval # 8, overall 8 775 14405 13954 451 3.13 20:45:44 21:03:43
interval # 9, overall 9 986 18241 17750 491 2.69 21:03:44 21:21:43
interval # 10, overall 10 746 13846 13434 412 2.98 21:21:44 21:39:43
Done for 14260 ms, from 21:40:51.57 to 21:41:05.83.

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 1337.36 0.3484 1.6824 1709 95
WARM_TIME 2 1410.94 0.7976 1.2112 1155 84
WARM_TIME 3 1303.27 0.8744 1.1263 1002 74
WARM_TIME 4 1167.59 0.9433 1.0643 436 60
WARM_TIME 5 1058.73 0.9392 1.0797 476 57
WARM_TIME 6 1005.89 0.9472 1.0526 448 59
WARM_TIME 7 943.79 0.9617 1.0354 485 54
WARM_TIME 8 901.83 0.9483 1.0481 512 45
WARM_TIME 9 878.10 0.9569 1.0566 714 40
WARM_TIME 10 877.59 0.9521 1.0480 943 22
TEST_TIME 11 864.56 0.9672 1.0394 597 17
TEST_TIME 12 829.70 0.9929 1.0081 145 7
TEST_TIME 13 774.54 0.9970 1.0032 69 1
TEST_TIME 14 730.94 0.9872 1.0082 165 2
TEST_TIME 15 695.68 0.9888 1.0198 141 5
TEST_TIME 16 674.45 0.9890 1.0107 218 5
TEST_TIME 17 649.42 0.9880 1.0151 289 4
TEST_TIME 18 623.45 0.9988 1.0011 31 1
TEST_TIME 19 593.98 0.9955 1.0054 102 6
TEST_TIME 20 568.37 0.9982 1.0017 45 3
TEST_TIME 21 546.53 0.9921 1.0072 91 2
TEST_TIME 22 525.88 0.9992 1.0007 21 2
TEST_TIME 23 505.37 0.9961 1.0037 91 1
TEST_TIME 24 487.66 0.9999 1.0000 4 1
TEST_TIME 26 453.15 0.9946 1.0027 49 2
TEST_TIME 28 424.24 0.9987 1.0012 31 1
TEST_TIME 29 410.65 0.9988 1.0011 30 1
TEST_TIME 30 398.75 0.9967 1.0038 25 2
TEST_TIME 31 391.31 0.9992 1.0007 19 1
TEST_TIME 32 383.67 0.9979 1.0016 41 2
TEST_TIME 33 374.03 0.9964 1.0052 75 3
TEST_TIME 34 367.01 0.9992 1.0007 20 1
TEST_TIME 35 357.07 0.9992 1.0006 19 1
TEST_TIME 36 352.25 0.9914 1.0059 59 2
TEST_TIME 37 345.39 0.9983 1.0016 49 1
TEST_TIME 39 330.60 0.9974 1.0022 62 1
TEST_TIME 40 325.04 0.9940 1.0026 65 2
TEST_TIME 41 321.66 0.9899 1.0077 130 3
TEST_TIME 42 318.61 0.9932 1.0038 113 2
TEST_TIME 43 316.52 0.9928 1.0065 83 3
TEST_TIME 45 306.04 0.9999 1.0000 3 1
TEST_TIME 48 288.39 0.9999 1.0000 3 1
TEST_TIME 49 283.53 0.9986 1.0012 40 1
TEST_TIME 50 280.61 0.9915 1.0065 45 3
TEST_TIME 51 277.55 0.9978 1.0044 45 3
TEST_TIME 52 275.03 0.9942 1.0056 177 2
TEST_TIME 53 273.56 0.9985 1.0017 34 2
TEST_TIME 54 274.28 0.9807 1.0095 154 4
TEST_TIME 55 272.53 0.9976 1.0026 81 3
TEST_TIME 56 269.65 0.9952 1.0055 22 2
TEST_TIME 57 267.44 0.9961 1.0062 62 2
TEST_TIME 58 266.46 0.9935 1.0011 26 2
TEST_TIME 62 252.49 0.9977 1.0021 75 1
TEST_TIME 63 249.88 0.9976 1.0021 69 3
TEST_TIME 64 246.79 0.9989 1.0012 40 1
TEST_TIME 66 240.14 0.9992 1.0012 21 1
TEST_TIME 67 240.55 0.9952 1.0070 33 3
TEST_TIME 68 238.80 0.9995 1.0005 18 2
TEST_TIME 69 235.83 0.9995 1.0004 16 1
TEST_TIME 70 234.44 0.9988 1.0088 35 3
TEST_TIME 71 235.85 0.9951 1.0065 146 4
TEST_TIME 72 236.35 0.9905 1.0091 134 4
TEST_TIME 73 236.56 0.9945 1.0065 87 4
TEST_TIME 75 237.50 0.9849 1.0057 106 3
TEST_TIME 76 235.77 0.9998 1.0001 7 1
TEST_TIME 77 234.61 0.9940 1.0011 26 2
TEST_TIME 78 233.42 0.9940 1.0056 52 2
TEST_TIME 79 233.43 0.9998 1.0002 9 1
TEST_TIME 81 231.43 0.9909 1.0146 69 4
TEST_TIME 82 236.02 0.9828 1.0083 122 5
TEST_TIME 83 235.65 0.9978 1.0091 37 3
TEST_TIME 84 236.59 0.9932 1.0025 55 2
TEST_TIME 85 238.46 0.9901 1.0083 97 4
TEST_TIME 86 242.12 0.9815 1.0093 204 5
TEST_TIME 87 241.94 0.9985 1.0013 65 3
TEST_TIME 88 242.44 0.9879 1.0063 105 4
TEST_TIME 89 243.28 0.9983 1.0069 41 2
TEST_TIME 90 245.00 0.9937 1.0020 75 2
TEST_TIME 91 244.48 0.9932 1.0025 117 2
TEST_TIME 92 246.21 0.9847 1.0080 131 4
TEST_TIME 93 246.25 0.9970 1.0041 65 3
TEST_TIME 97 243.38 0.9776 1.0065 69 5
TEST_TIME 98 243.26 0.9967 1.0047 39 3
TEST_TIME 99 242.51 0.9976 1.0032 10 3
TEST_TIME 100 241.16 0.9993 1.0007 36 1
TEST_TIME 101 239.66 0.9970 1.0016 58 2
TEST_TIME 102 238.47 0.9989 1.0011 42 2
TEST_TIME 103 236.47 0.9998 1.0002 11 1
TEST_TIME 104 236.66 0.9934 1.0021 83 2
TEST_TIME 105 238.99 0.9830 1.0100 102 6
TEST_TIME 106 239.54 0.9982 1.0018 63 4
TEST_TIME 107 239.23 0.9979 1.0053 32 4
TEST_TIME 108 238.79 0.9997 1.0002 15 1
TEST_TIME 110 235.58 0.9999 1.0001 7 1
TEST_TIME 111 236.31 0.9881 1.0042 170 3
TEST_TIME 112 238.77 0.9872 1.0083 70 6
TEST_TIME 113 243.33 0.9878 1.0115 93 4
TEST_TIME 114 244.91 0.9962 1.0096 160 4
TEST_TIME 115 246.01 0.9964 1.0028 123 5
TEST_TIME 116 247.32 0.9897 1.0126 121 7
TEST_TIME 117 249.38 0.9957 1.0031 89 3
TEST_TIME 118 251.47 0.9886 1.0046 99 5
TEST_TIME 119 253.80 0.9871 1.0119 256 8
TEST_TIME 120 255.13 0.9983 1.0018 116 2
TEST_TIME 121 254.28 0.9972 1.0105 42 4
TEST_TIME 122 256.94 0.9919 1.0068 100 5
TEST_TIME 123 257.08 0.9981 1.0029 52 4
TEST_TIME 125 256.57 0.9919 1.0072 80 6
TEST_TIME 126 256.40 0.9999 1.0001 10 2
TEST_TIME 127 257.61 0.9961 1.0112 51 3
TEST_TIME 128 258.66 0.9993 1.0019 20 2
TEST_TIME 129 260.12 0.9886 1.0018 48 3
TEST_TIME 130 260.25 0.9990 1.0009 74 2
TEST_TIME 131 261.76 0.9886 1.0095 130 6
TEST_TIME 132 263.37 0.9957 1.0023 128 5
TEST_TIME 133 262.07 0.9997 1.0001 13 1
TEST_TIME 134 261.45 0.9951 1.0022 31 3
TEST_TIME 135 262.37 0.9954 1.0033 33 4
TEST_TIME 136 262.96 0.9938 1.0051 27 3
TEST_TIME 137 262.63 0.9998 1.0001 13 1
TEST_TIME 138 263.87 0.9948 1.0007 42 3
TEST_TIME 139 263.92 0.9971 1.0049 31 3
TEST_TIME 140 268.41 0.9873 1.0109 275 7
TEST_TIME 141 271.06 0.9939 1.0140 263 7
TEST_TIME 142 278.17 0.9812 1.0081 60 7
TEST_TIME 143 283.03 0.9906 1.0025 69 4
TEST_TIME 144 284.88 0.9958 1.0082 87 5
TEST_TIME 145 285.50 0.9998 1.0001 12 1
TEST_TIME 146 284.72 0.9960 1.0020 18 2
TEST_TIME 147 286.47 0.9892 1.0151 142 6
TEST_TIME 148 295.76 0.9766 1.0158 427 10
TEST_TIME 149 299.02 0.9980 1.0100 141 4
TEST_TIME 150 304.91 0.9909 1.0031 163 4
TEST_TIME 151 304.49 0.9979 1.0011 112 6
TEST_TIME 152 308.83 0.9837 1.0132 144 6
TEST_TIME 153 316.81 0.9876 1.0135 171 8
TEST_TIME 154 319.93 0.9970 1.0067 95 5
TEST_TIME 155 324.93 0.9848 1.0162 201 8
TEST_TIME 156 333.22 0.9888 1.0173 131 9
TEST_TIME 157 340.59 0.9889 1.0188 150 10
TEST_TIME 158 347.87 0.9912 1.0045 63 5
TEST_TIME 159 351.17 0.9888 1.0075 109 8
TEST_TIME 160 351.95 0.9990 1.0015 99 5
TEST_TIME 161 353.34 0.9952 1.0054 137 8
TEST_TIME 162 356.16 0.9913 1.0165 78 7
TEST_TIME 163 362.00 0.9958 1.0096 79 5
TEST_TIME 164 364.31 0.9971 1.0037 150 5
TEST_TIME 165 366.26 0.9923 1.0030 71 5
TEST_TIME 166 370.83 0.9909 1.0041 154 7
TEST_TIME 167 373.74 0.9904 1.0091 226 9
TEST_TIME 168 377.21 0.9939 1.0097 140 8
TEST_TIME 169 383.86 0.9864 1.0090 245 13
TEST_TIME 170 394.32 0.9795 1.0102 150 10
TEST_TIME 171 397.00 0.9975 1.0047 111 4
TEST_TIME 172 400.82 0.9901 1.0054 125 7
TEST_TIME 173 403.19 0.9974 1.0058 85 5
TEST_TIME 174 410.68 0.9818 1.0079 219 12
TEST_TIME 175 416.70 0.9877 1.0164 331 13
TEST_TIME 176 425.42 0.9899 1.0087 138 10
TEST_TIME 177 427.46 0.9983 1.0044 214 7
TEST_TIME 178 429.76 0.9934 1.0070 61 9
TEST_TIME 179 436.98 0.9876 1.0055 147 8
TEST_TIME 180 444.38 0.9833 1.0071 255 12
TEST_TIME 181 446.86 0.9960 1.0023 92 6
TEST_TIME 182 450.21 0.9894 1.0071 322 10
TEST_TIME 183 455.19 0.9942 1.0027 176 10
TEST_TIME 184 458.95 0.9927 1.0052 193 7
TEST_TIME 185 463.68 0.9943 1.0077 182 13
TEST_TIME 186 467.88 0.9933 1.0065 79 9
TEST_TIME 187 474.38 0.9874 1.0152 158 13
TEST_TIME 188 482.88 0.9921 1.0062 93 9
TEST_TIME 189 488.50 0.9894 1.0133 213 17
TEST_TIME 190 498.22 0.9883 1.0062 432 100
Done for 9500 ms, from 21:41:06.20 to 21:41:15.70.

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 4 92626 120857 <null> <null> 128828 <null> 944 <null> 890 <null> 116926 <null> 181293 <null> <null> <null> <null> <null> <null> 19:33:44 19:51:43
fetches per second 6 <null> <null> <null> <null> <null> <null> 1162 <null> <null> <null> <null> <null> <null> 189333 544201 <null> <null> <null> <null> 20:09:44 20:27:43
fetches per second 7 65738 <null> <null> <null> 29099 <null> 1554 <null> <null> <null> 161733 77354 156868 <null> <null> <null> <null> 1474435 <null> 20:27:44 20:45:43
fetches per second 9 79414 <null> <null> <null> 92036 <null> 2454 <null> <null> <null> <null> <null> <null> <null> <null> <null> 99471 <null> <null> 21:03:44 21:21:43
fetches per second 10 76327 136016 33670 <null> 69834 <null> 3064 1025 2915 <null> 141604 71366 188590 <null> 400541 130444 <null> <null> <null> 21:21:44 21:39:43
marks per second 4 17783 11857 <null> <null> 8528 <null> 135 <null> 107 <null> 14874 <null> 4581 <null> <null> <null> <null> <null> <null> 19:33:44 19:51:43
marks per second 6 <null> <null> <null> <null> <null> <null> 121 <null> <null> <null> <null> <null> <null> 18833 4913 <null> <null> <null> <null> 20:09:44 20:27:43
marks per second 7 12218 <null> <null> <null> 1505 <null> 108 <null> <null> <null> 10388 10921 3056 <null> <null> <null> <null> 2792 <null> 20:27:44 20:45:43
marks per second 9 13075 <null> <null> <null> 2948 <null> 114 <null> <null> <null> <null> <null> <null> <null> <null> <null> 392 <null> <null> 21:03:44 21:21:43
marks per second 10 12948 12353 1355 <null> 2997 <null> 87 153 275 <null> 20614 11800 3528 <null> 1440 13444 <null> <null> <null> 21:21:44 21:39:43
reads/fetches*100 4 4 3 <null> <null> 5 <null> 16 <null> 21 <null> 7 <null> 6 <null> <null> <null> <null> <null> <null> 19:33:44 19:51:43
reads/fetches*100 6 <null> <null> <null> <null> <null> <null> 17 <null> <null> <null> <null> <null> <null> 3 3 <null> <null> <null> <null> 20:09:44 20:27:43
reads/fetches*100 7 4 <null> <null> <null> 6 <null> 18 <null> <null> <null> 5 4 5 <null> <null> <null> <null> 0 <null> 20:27:44 20:45:43
reads/fetches*100 9 6 <null> <null> <null> 6 <null> 11 <null> <null> <null> <null> <null> <null> <null> <null> <null> 30 <null> <null> 21:03:44 21:21:43
reads/fetches*100 10 5 5 9 <null> 6 <null> 14 17 16 <null> 6 6 6 <null> 3 4 <null> <null> <null> 21:21:44 21:39:43
writes/marks*100 4 11 13 <null> <null> 8 <null> 0 <null> 3 <null> 7 <null> 9 <null> <null> <null> <null> <null> <null> 19:33:44 19:51:43
writes/marks*100 6 <null> <null> <null> <null> <null> <null> 1 <null> <null> <null> <null> <null> <null> 0 6 <null> <null> <null> <null> 20:09:44 20:27:43
writes/marks*100 7 11 <null> <null> <null> 11 <null> 1 <null> <null> <null> 7 7 7 <null> <null> <null> <null> 1 <null> 20:27:44 20:45:43
writes/marks*100 9 14 <null> <null> <null> 9 <null> 1 <null> <null> <null> <null> <null> <null> <null> <null> <null> 53 <null> <null> 21:03:44 21:21:43
writes/marks*100 10 13 13 8 <null> 10 <null> 3 2 4 <null> 7 6 7 <null> 7 0 <null> <null> <null> 21:21:44 21:39:43
Done for 6570 ms, from 21:41:15.95 to 21:41:22.52.

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 58603 54061 4542 7.75 0 3400 14 4542 2016-10-15 18:39 2016-10-15 21:39
doc_list_biud 58652 54044 4608 7.85 0 383763 597 4608 2016-10-15 18:39 2016-10-15 21:39
sp_add_invoice_to_stock 1128 369 759 67.28 1357 10113021 1571083 759 2016-10-15 18:39 2016-10-15 21:39
sp_cancel_adding_invoice 133 71 62 46.61 32 1172617 71693 62 2016-10-15 18:39 2016-10-15 21:39
sp_cancel_client_order 427 386 41 9.60 0 109 18 41 2016-10-15 18:39 2016-10-15 21:39
sp_cancel_customer_reserve 4787 1682 3105 64.86 0 103382 6295 3105 2016-10-15 18:39 2016-10-15 21:39
sp_cancel_pay_from_customer 152 149 3 1.97 0 47 10 3 2016-10-15 18:39 2016-10-15 21:39
sp_cancel_pay_to_supplier 79 79 0 0.00 0 47 10 0 2016-10-15 18:39 2016-10-15 21:39
sp_cancel_supplier_invoice 254 205 49 19.29 0 250 60 49 2016-10-15 18:39 2016-10-15 21:39
sp_cancel_supplier_order 101 28 73 72.27 47 2076249 144306 73 2016-10-15 18:39 2016-10-15 21:39
sp_cancel_write_off 1965 535 1430 72.77 0 187 23 1430 2016-10-15 18:39 2016-10-15 21:39
sp_client_order 4916 4916 0 0.00 0 281 30 0 2016-10-15 18:39 2016-10-15 21:39
sp_customer_reserve 67307 67307 0 0.00 31 145268 13428 0 2016-10-15 18:39 2016-10-15 21:39
sp_fill_shopping_cart 7522 7522 0 0.00 0 80995 694 0 2016-10-15 18:39 2016-10-15 21:39
sp_fill_shopping_cart_clo_res 67307 67307 0 0.00 0 32 1 0 2016-10-15 18:39 2016-10-15 21:39
sp_get_clo_for_invoice 383 383 0 0.00 0 234 45 0 2016-10-15 18:39 2016-10-15 21:39
sp_kill_cost_storno 2273 835 1438 63.26 0 47 1 1438 2016-10-15 18:39 2016-10-15 21:39
sp_kill_qstorno_ret_qs2qd 11754 4132 7622 64.84 0 103351 2564 7622 2016-10-15 18:39 2016-10-15 21:39
sp_kill_qty_storno 10118 5520 4598 45.44 0 383763 5847 4598 2016-10-15 18:39 2016-10-15 21:39
sp_lock_selected_doc 4777 3704 1073 22.46 0 16 0 1073 2016-10-15 18:39 2016-10-15 21:39
sp_make_cost_storno 5650 5650 0 0.00 0 1826 58 0 2016-10-15 18:39 2016-10-15 21:39
sp_multiply_rows_for_pdistr 5649 5649 0 0.00 0 172 4 0 2016-10-15 18:39 2016-10-15 21:39
sp_multiply_rows_for_qdistr 4916 4916 0 0.00 0 219 9 0 2016-10-15 18:39 2016-10-15 21:39
sp_pay_from_customer 1675 1675 0 0.00 31 374 71 0 2016-10-15 18:39 2016-10-15 21:39
sp_pay_to_supplier 1349 1349 0 0.00 47 1934 192 0 2016-10-15 18:39 2016-10-15 21:39
sp_payment_common 3024 3024 0 0.00 0 218 22 0 2016-10-15 18:39 2016-10-15 21:39
sp_qd_handle_on_cancel_clo 427 386 41 9.60 0 47 4 41 2016-10-15 18:39 2016-10-15 21:39
sp_qd_handle_on_invoice_upd_sts 476 441 35 7.35 0 383763 49075 35 2016-10-15 18:39 2016-10-15 21:39
sp_qd_handle_on_reserve_upd_sts 2243 2243 0 0.00 0 125 5 0 2016-10-15 18:39 2016-10-15 21:39
sp_reserve_write_off 2343 2243 100 4.26 0 234 30 100 2016-10-15 18:39 2016-10-15 21:39
sp_supplier_invoice 1283 1283 0 0.00 15 81135 1748 0 2016-10-15 18:39 2016-10-15 21:39
sp_supplier_order 1323 1323 0 0.00 31 46005 2713 0 2016-10-15 18:39 2016-10-15 21:39
srv_find_qd_qs_mism 32729 28194 4535 13.85 0 3385 13 4535 2016-10-15 18:39 2016-10-15 21:39
srv_make_invnt_saldo 400 400 0 0.00 1248 22948 4904 0 2016-10-15 18:39 2016-10-15 21:39
srv_make_money_saldo 295 295 0 0.00 0 63 11 0 2016-10-15 18:39 2016-10-15 21:39
srv_recalc_idx_stat 10 10 0 0.00 796 1592 1090 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-abend:sp_add_invoice_to_stock 759 759 0 0.00 0 47 3 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-abend:sp_cancel_adding_invoice 130 130 0 0.00 0 140 18 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-abend:sp_cancel_client_order 41 41 0 0.00 0 16 3 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-abend:sp_cancel_customer_reserve 3 3 0 0.00 0 15 5 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-abend:sp_cancel_pay_from_customer 3 3 0 0.00 0 0 0 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-abend:sp_cancel_supplier_invoice 41 41 0 0.00 0 32 3 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-abend:sp_cancel_supplier_order 497 497 0 0.00 0 94 15 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-abend:sp_cancel_write_off 8 8 0 0.00 0 16 2 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-abend:sp_reserve_write_off 100 100 0 0.00 0 31 2 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_add_invoice_to_stock 450 450 0 0.00 0 2106 233 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_cancel_adding_invoice 45 45 0 0.00 0 452 71 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_cancel_client_order 386 386 0 0.00 0 140 4 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_cancel_customer_reserve 167 167 0 0.00 0 47 6 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_cancel_pay_from_customer 149 149 0 0.00 0 31 2 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_cancel_pay_to_supplier 79 79 0 0.00 0 31 3 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_cancel_supplier_invoice 29 29 0 0.00 0 47 5 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_cancel_supplier_order 28 28 0 0.00 0 1420 130 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_cancel_write_off 213 213 0 0.00 0 109 3 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_client_order 4916 4916 0 0.00 0 94 4 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_customer_reserve 239 239 0 0.00 0 32 4 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_pay_from_customer 1676 1676 0 0.00 0 62 4 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_pay_to_supplier 1350 1350 0 0.00 0 109 4 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_reserve_write_off 2244 2244 0 0.00 0 63 4 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_supplier_invoice 1284 1284 0 0.00 0 78 5 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:sp_supplier_order 1326 1326 0 0.00 0 109 5 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:srv_make_invnt_saldo 400 400 0 0.00 0 31 1 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:srv_make_money_saldo 295 295 0 0.00 0 16 1 0 2016-10-15 18:39 2016-10-15 21:39
t$perf-norm:srv_recalc_idx_stat 10 10 0 0.00 0 16 4 0 2016-10-15 18:39 2016-10-15 21:39
v_add_invoice_to_stock 2256 738 1518 67.28 0 32 3 1518 2016-10-15 18:39 2016-10-15 21:39
v_all_customers 7376 7376 0 0.00 0 16 0 0 2016-10-15 18:39 2016-10-15 21:39
v_all_suppliers 5212 5212 0 0.00 0 16 0 0 2016-10-15 18:39 2016-10-15 21:39
v_all_wares 9832 9832 0 0.00 0 16 0 0 2016-10-15 18:39 2016-10-15 21:39
v_cancel_adding_invoice 178 88 90 50.56 0 31 2 90 2016-10-15 18:39 2016-10-15 21:39
v_cancel_client_order 854 772 82 9.60 0 31 0 82 2016-10-15 18:39 2016-10-15 21:39
v_cancel_customer_prepayment 304 298 6 1.97 0 16 0 6 2016-10-15 18:39 2016-10-15 21:39
v_cancel_customer_reserve 334 328 6 1.79 0 16 0 6 2016-10-15 18:39 2016-10-15 21:39
v_cancel_payment_to_supplier 158 158 0 0.00 0 16 0 0 2016-10-15 18:39 2016-10-15 21:39
v_cancel_supplier_invoice 140 58 82 58.57 0 47 5 82 2016-10-15 18:39 2016-10-15 21:39
v_cancel_supplier_order 202 56 146 72.27 0 16 0 146 2016-10-15 18:39 2016-10-15 21:39
v_cancel_write_off 442 426 16 3.61 0 32 1 16 2016-10-15 18:39 2016-10-15 21:39
v_max_id_clo_ord 1323 1323 0 0.00 0 141 8 0 2016-10-15 18:39 2016-10-15 21:39
v_max_id_clo_res 238 238 0 0.00 0 16 0 0 2016-10-15 18:39 2016-10-15 21:39
v_max_id_ord_sup 1283 1283 0 0.00 0 296 5 0 2016-10-15 18:39 2016-10-15 21:39
v_max_non_paid_invoice 1349 1349 0 0.00 0 63 2 0 2016-10-15 18:39 2016-10-15 21:39
v_max_non_paid_realizn 1675 1675 0 0.00 0 32 4 0 2016-10-15 18:39 2016-10-15 21:39
v_min_id_clo_ord 1323 1323 0 0.00 0 1123 53 0 2016-10-15 18:39 2016-10-15 21:39
v_min_id_clo_res 238 238 0 0.00 0 16 2 0 2016-10-15 18:39 2016-10-15 21:39
v_min_id_ord_sup 1283 1283 0 0.00 0 2512 42 0 2016-10-15 18:39 2016-10-15 21:39
v_min_non_paid_invoice 1349 1349 0 0.00 0 203 21 0 2016-10-15 18:39 2016-10-15 21:39
v_min_non_paid_realizn 1675 1675 0 0.00 0 93 9 0 2016-10-15 18:39 2016-10-15 21:39
v_our_firm 2456 2456 0 0.00 0 16 0 0 2016-10-15 18:39 2016-10-15 21:39
v_reserve_write_off 4686 4486 200 4.26 0 31 0 200 2016-10-15 18:39 2016-10-15 21:39
x_lock_depdocs_on_canc_invoice 436 247 189 43.34 0 31 1 189 2016-10-15 18:39 2016-10-15 21:39
x_lock_depdocs_on_canc_sup_ord 101 28 73 72.27 0 16 2 73 2016-10-15 18:39 2016-10-15 21:39
x_make_qstorno_1000_1200 1324 1324 0 0.00 0 2855 249 0 2016-10-15 18:39 2016-10-15 21:39
x_make_qstorno_1200_2000 1283 1283 0 0.00 0 2621 224 0 2016-10-15 18:39 2016-10-15 21:39
x_make_qstorno_2100_3300 67307 67307 0 0.00 31 145268 13419 0 2016-10-15 18:39 2016-10-15 21:39
Done for 25100 ms, from 21:41:38.21 to 21:42:03.31.

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 5 sp_kill_cost_storno 335544336 2016-10-15 18:45 2016-10-15 21:28
deadlock 4 sp_qd_handle_on_invoice_upd_sts 335544336 2016-10-15 19:39 2016-10-15 21:38
lock_conflict 3 sp_kill_cost_storno 335544345 2016-10-15 20:58 2016-10-15 21:04
lock_conflict 911 sp_lock_selected_doc 335544345 2016-10-15 18:39 2016-10-15 21:39
lock_conflict 41 sp_qd_handle_on_cancel_clo 335544345 2016-10-15 18:40 2016-10-15 21:38
lock_conflict 24 sp_qd_handle_on_invoice_upd_sts 335544345 2016-10-15 18:40 2016-10-15 21:38
lock_conflict 53 x_lock_depdocs_on_canc_invoice 335544345 2016-10-15 18:39 2016-10-15 21:39
lock_conflict 34 x_lock_depdocs_on_canc_sup_ord 335544345 2016-10-15 18:40 2016-10-15 21:32
Done for 2760 ms, from 21:42:03.97 to 21:42:06.73.

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 94032
MON$OLDEST_ACTIVE 94033
MON$OLDEST_SNAPSHOT 94033
MON$NEXT_TRANSACTION 94033
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 173782
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 98494
Page size 8192
ODS version 11.2
Oldest transaction 94034
Oldest active 94035
Oldest snapshot 94035
Next transaction 94036
Bumped transaction 1
Sequence number 0
Next attachment ID 4449
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: 44.83, total records: 482227
Average version length: 46.38, total versions: 14367, max versions: 1
Data pages: 5135, data page slots: 5135, average fill: 73%
Fill distribution:
0 - 19% = 0
20 - 39% = 5
40 - 59% = 48
60 - 79% = 5019
80 - 99% = 63

Index DOC_DATA_DOC_WARE_UNQ (1)
Depth: 3, leaf buckets: 1590, nodes: 482227
Average data length: 11.79, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 156
20 - 39% = 26
40 - 59% = 629
60 - 79% = 119
80 - 99% = 660

Index DOC_DATA_ID_DESC (2)
Depth: 3, leaf buckets: 1409, nodes: 482227
Average data length: 5.94, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 11
40 - 59% = 1397
60 - 79% = 0
80 - 99% = 1

Index FK_DOC_DATA_DOC_LIST (3)
Depth: 2, leaf buckets: 475, nodes: 482227
Average data length: 0.91, total dup: 413297, max dup: 49
Fill distribution:
0 - 19% = 2
20 - 39% = 13
40 - 59% = 217
60 - 79% = 14
80 - 99% = 229

DOC_LIST (140)
Primary pointer page: 195, Index root page: 196
Average record length: 58.26, total records: 83692
Average version length: 49.54, total versions: 9723, max versions: 7
Data pages: 1059, data page slots: 1059, average fill: 81%
Fill distribution:
0 - 19% = 3
20 - 39% = 15
40 - 59% = 38
60 - 79% = 252
80 - 99% = 751

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

Index FK_DOC_LIST_AGENTS (2)
Depth: 2, leaf buckets: 70, nodes: 83692
Average data length: 0.01, total dup: 83642, max dup: 18836
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 44
60 - 79% = 22
80 - 99% = 4

Index PK_DOC_LIST (0)
Depth: 2, leaf buckets: 174, nodes: 83692
Average data length: 6.25, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 5
20 - 39% = 11
40 - 59% = 87
60 - 79% = 0
80 - 99% = 71

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: 63.29, total records: 400
Average version length: 13.19, total versions: 94868, max versions: 284
Data pages: 371, data page slots: 371, average fill: 96%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 1
80 - 99% = 370

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.75, total records: 192994
Average version length: 68.69, total versions: 190890, max versions: 1
Data pages: 2709, data page slots: 2709, average fill: 90%
Fill distribution:
0 - 19% = 3
20 - 39% = 23
40 - 59% = 51
60 - 79% = 157
80 - 99% = 2475

Index INVNT_TURNOVER_LOG_WARE_DD_ID (0)
Depth: 3, leaf buckets: 449, nodes: 192994
Average data length: 8.12, total dup: 24281, max dup: 7
Fill distribution:
0 - 19% = 0
20 - 39% = 2
40 - 59% = 99
60 - 79% = 211
80 - 99% = 137

MONEY_SALDO (143)
Primary pointer page: 208, Index root page: 209
Average record length: 14.69, total records: 49
Average version length: 9.00, total versions: 4657, max versions: 397
Data pages: 17, data page slots: 17, average fill: 89%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 0
80 - 99% = 16

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.23, total records: 9204
Average version length: 44.06, total versions: 9158, max versions: 1
Data pages: 95, data page slots: 95, average fill: 93%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 0
80 - 99% = 94

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

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: 22.74, total records: 154423
Average version length: 33.32, total versions: 107325, max versions: 9
Data pages: 1621, data page slots: 1621, average fill: 87%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 4
60 - 79% = 260
80 - 99% = 1356

Index PDISTR_AGENT_ID (4)
Depth: 2, leaf buckets: 142, nodes: 154423
Average data length: 0.01, total dup: 154374, max dup: 36424
Fill distribution:
0 - 19% = 1
20 - 39% = 4
40 - 59% = 96
60 - 79% = 23
80 - 99% = 18

Index PDISTR_SNDOP_RCVOP_SNDID_ASC (2)
Depth: 2, leaf buckets: 166, nodes: 154423
Average data length: 0.88, total dup: 139235, max dup: 43
Fill distribution:
0 - 19% = 0
20 - 39% = 2
40 - 59% = 99
60 - 79% = 46
80 - 99% = 19

Index PDISTR_SNDOP_RCVOP_SNDID_DESC (3)
Depth: 2, leaf buckets: 178, nodes: 154423
Average data length: 0.88, total dup: 139235, max dup: 43
Fill distribution:
0 - 19% = 0
20 - 39% = 3
40 - 59% = 117
60 - 79% = 40
80 - 99% = 18

Index PDISTR_SND_ID (1)
Depth: 2, leaf buckets: 150, nodes: 154423
Average data length: 0.65, total dup: 139235, max dup: 43
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 62
60 - 79% = 68
80 - 99% = 19

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

Index PERF_EST_MINUTE_SINCE_START (0)
Depth: 2, leaf buckets: 19, nodes: 24682
Average data length: 0.01, total dup: 24508, max dup: 1708
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 11
60 - 79% = 5
80 - 99% = 3

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: 144.59, total records: 1319148
Average version length: 153.00, total versions: 1, max versions: 1
Data pages: 30163, data page slots: 30163, average fill: 87%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 0
80 - 99% = 30162

Index PERF_LOG_DTS_BEG_DESC (0)
Depth: 3, leaf buckets: 1616, nodes: 1319148
Average data length: 0.58, total dup: 762188, max dup: 28
Fill distribution:
0 - 19% = 3
20 - 39% = 0
40 - 59% = 1186
60 - 79% = 279
80 - 99% = 148

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

Index PERF_LOG_TRN_DESC (2)
Depth: 3, leaf buckets: 1624, nodes: 1319148
Average data length: 0.37, total dup: 1243478, max dup: 4205
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1372
60 - 79% = 143
80 - 99% = 109

Index PERF_LOG_UNIT (1)
Depth: 3, leaf buckets: 1467, nodes: 1319148
Average data length: 0.30, total dup: 1282059, max dup: 145940
Fill distribution:
0 - 19% = 3
20 - 39% = 0
40 - 59% = 1054
60 - 79% = 206
80 - 99% = 204

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.38, total records: 86455
Average version length: 54.91, total versions: 3720, max versions: 1
Data pages: 1006, data page slots: 1006, average fill: 76%
Fill distribution:
0 - 19% = 1
20 - 39% = 1
40 - 59% = 0
60 - 79% = 975
80 - 99% = 29

Index PSTORNED_RCV_ID (2)
Depth: 2, leaf buckets: 112, nodes: 86455
Average data length: 1.16, total dup: 71302, max dup: 144
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 109
60 - 79% = 0
80 - 99% = 2

Index PSTORNED_SND_ID (1)
Depth: 2, leaf buckets: 79, nodes: 86455
Average data length: 0.58, total dup: 79203, max dup: 76
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 38
60 - 79% = 34
80 - 99% = 7

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: 1051, max versions: 587
Data pages: 4, data page slots: 4, average fill: 84%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 0
80 - 99% = 3

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.84, total records: 229
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 3, data page slots: 3, average fill: 72%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 3
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.09, total records: 316058
Average version length: 69.76, total versions: 315660, max versions: 1
Data pages: 4234, data page slots: 4234, average fill: 95%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 0
80 - 99% = 4233

Index XQD_1000_1200_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 379, nodes: 316058
Average data length: 1.25, total dup: 276673, max dup: 29
Fill distribution:
0 - 19% = 12
20 - 39% = 4
40 - 59% = 141
60 - 79% = 147
80 - 99% = 75

XQD_1000_3300 (260)
Primary pointer page: 653, Index root page: 654
Average record length: 49.91, total records: 286998
Average version length: 69.25, total versions: 81840, max versions: 1
Data pages: 3910, data page slots: 3918, average fill: 82%
Fill distribution:
0 - 19% = 10
20 - 39% = 27
40 - 59% = 37
60 - 79% = 1876
80 - 99% = 1960

Index XQD_1000_3300_DOC (1)
Depth: 2, leaf buckets: 256, nodes: 286998
Average data length: 0.15, total dup: 280667, max dup: 249
Fill distribution:
0 - 19% = 2
20 - 39% = 13
40 - 59% = 135
60 - 79% = 11
80 - 99% = 95

Index XQD_1000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 337, nodes: 286998
Average data length: 1.25, total dup: 251557, max dup: 42
Fill distribution:
0 - 19% = 2
20 - 39% = 1
40 - 59% = 146
60 - 79% = 130
80 - 99% = 58

XQD_1200_2000 (259)
Primary pointer page: 648, Index root page: 649
Average record length: 0.46, total records: 374720
Average version length: 69.72, total versions: 372259, max versions: 1
Data pages: 5031, data page slots: 5031, average fill: 94%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 11
80 - 99% = 5019

Index XQD_1200_2000_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 402, nodes: 374720
Average data length: 0.98, total dup: 338542, max dup: 131
Fill distribution:
0 - 19% = 4
20 - 39% = 0
40 - 59% = 114
60 - 79% = 194
80 - 99% = 90

XQD_2000_3300 (261)
Primary pointer page: 657, Index root page: 658
Average record length: 18.85, total records: 1074300
Average version length: 68.32, total versions: 783789, max versions: 1
Data pages: 14474, data page slots: 14474, average fill: 89%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 3608
80 - 99% = 10865

Index XQD_2000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 1376, nodes: 1074300
Average data length: 1.14, total dup: 949217, max dup: 93
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1077
60 - 79% = 128
80 - 99% = 171

XQD_2100_3300 (262)
Primary pointer page: 661, Index root page: 662
Average record length: 4.79, total records: 237026
Average version length: 68.82, total versions: 220611, max versions: 1
Data pages: 4073, data page slots: 4081, average fill: 74%
Fill distribution:
0 - 19% = 82
20 - 39% = 222
40 - 59% = 292
60 - 79% = 1882
80 - 99% = 1595

Index XQD_2100_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 308, nodes: 237026
Average data length: 1.36, total dup: 204774, max dup: 98
Fill distribution:
0 - 19% = 1
20 - 39% = 8
40 - 59% = 153
60 - 79% = 119
80 - 99% = 27

XQD_3300_3400 (263)
Primary pointer page: 664, Index root page: 665
Average record length: 39.12, total records: 707468
Average version length: 68.33, total versions: 305621, max versions: 1
Data pages: 9830, data page slots: 9852, average fill: 82%
Fill distribution:
0 - 19% = 52
20 - 39% = 80
40 - 59% = 207
60 - 79% = 3632
80 - 99% = 5859

Index XQD_3300_3400_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 986, nodes: 707468
Average data length: 1.33, total dup: 611526, max dup: 44
Fill distribution:
0 - 19% = 0
20 - 39% = 24
40 - 59% = 720
60 - 79% = 109
80 - 99% = 133

XQS_1000_1200 (264)
Primary pointer page: 667, Index root page: 668
Average record length: 78.43, total records: 1021324
Average version length: 80.89, total versions: 29122, max versions: 1
Data pages: 15254, data page slots: 15254, average fill: 81%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 6516
80 - 99% = 8737

Index XQS_1000_1200_DOC_ID (0)
Depth: 3, leaf buckets: 1141, nodes: 1021324
Average data length: 0.16, total dup: 998196, max dup: 171
Fill distribution:
0 - 19% = 9
20 - 39% = 0
40 - 59% = 866
60 - 79% = 218
80 - 99% = 48

Index XQS_1000_1200_RCV_ID (2)
Depth: 3, leaf buckets: 807, nodes: 1021324
Average data length: 0.73, total dup: 896814, max dup: 49
Fill distribution:
0 - 19% = 5
20 - 39% = 0
40 - 59% = 125
60 - 79% = 13
80 - 99% = 664

Index XQS_1000_1200_SND_ID (1)
Depth: 3, leaf buckets: 1297, nodes: 1021324
Average data length: 0.75, total dup: 894050, max dup: 29
Fill distribution:
0 - 19% = 13
20 - 39% = 0
40 - 59% = 967
60 - 79% = 266
80 - 99% = 51

XQS_1000_3300 (266)
Primary pointer page: 678, Index root page: 679
Average record length: 71.35, total records: 611261
Average version length: 80.25, total versions: 67734, max versions: 1
Data pages: 9252, data page slots: 9280, average fill: 80%
Fill distribution:
0 - 19% = 40
20 - 39% = 39
40 - 59% = 114
60 - 79% = 5358
80 - 99% = 3701

Index XQS_1000_3300_DOC_ID (0)
Depth: 3, leaf buckets: 639, nodes: 611261
Average data length: 0.16, total dup: 597459, max dup: 301
Fill distribution:
0 - 19% = 1
20 - 39% = 2
40 - 59% = 411
60 - 79% = 175
80 - 99% = 50

Index XQS_1000_3300_RCV_ID (2)
Depth: 2, leaf buckets: 486, nodes: 611261
Average data length: 0.83, total dup: 529904, max dup: 14
Fill distribution:
0 - 19% = 5
20 - 39% = 3
40 - 59% = 58
60 - 79% = 4
80 - 99% = 416

Index XQS_1000_3300_SND_ID (1)
Depth: 3, leaf buckets: 766, nodes: 611261
Average data length: 0.74, total dup: 538242, max dup: 47
Fill distribution:
0 - 19% = 33
20 - 39% = 5
40 - 59% = 463
60 - 79% = 227
80 - 99% = 38

XQS_1200_2000 (265)
Primary pointer page: 673, Index root page: 674
Average record length: 76.59, total records: 1069903
Average version length: 81.89, total versions: 55261, max versions: 1
Data pages: 15983, data page slots: 15983, average fill: 81%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 7408
80 - 99% = 8575

Index XQS_1200_2000_DOC_ID (0)
Depth: 3, leaf buckets: 1108, nodes: 1069903
Average data length: 0.05, total dup: 1063504, max dup: 975
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 752
60 - 79% = 250
80 - 99% = 106

Index XQS_1200_2000_RCV_ID (2)
Depth: 3, leaf buckets: 792, nodes: 1069903
Average data length: 0.69, total dup: 944683, max dup: 49
Fill distribution:
0 - 19% = 1
20 - 39% = 1
40 - 59% = 46
60 - 79% = 3
80 - 99% = 741

Index XQS_1200_2000_SND_ID (1)
Depth: 3, leaf buckets: 1260, nodes: 1069903
Average data length: 0.70, total dup: 945634, max dup: 131
Fill distribution:
0 - 19% = 5
20 - 39% = 1
40 - 59% = 780
60 - 79% = 342
80 - 99% = 132

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: 73.98, total records: 770047
Average version length: 80.44, total versions: 62331, max versions: 1
Data pages: 11776, data page slots: 11822, average fill: 79%
Fill distribution:
0 - 19% = 67
20 - 39% = 98
40 - 59% = 197
60 - 79% = 6175
80 - 99% = 5239

Index XQS_2100_3300_DOC_ID (0)
Depth: 3, leaf buckets: 711, nodes: 770047
Average data length: 0.05, total dup: 765530, max dup: 634
Fill distribution:
0 - 19% = 3
20 - 39% = 8
40 - 59% = 252
60 - 79% = 311
80 - 99% = 137

Index XQS_2100_3300_RCV_ID (2)
Depth: 3, leaf buckets: 722, nodes: 770047
Average data length: 0.86, total dup: 664904, max dup: 14
Fill distribution:
0 - 19% = 10
20 - 39% = 17
40 - 59% = 254
60 - 79% = 6
80 - 99% = 435

Index XQS_2100_3300_SND_ID (1)
Depth: 3, leaf buckets: 806, nodes: 770047
Average data length: 0.71, total dup: 680025, max dup: 65
Fill distribution:
0 - 19% = 0
20 - 39% = 8
40 - 59% = 268
60 - 79% = 357
80 - 99% = 173

XQS_3300_3400 (269)
Primary pointer page: 697, Index root page: 698
Average record length: 61.53, total records: 339951
Average version length: 68.43, total versions: 34082, max versions: 1
Data pages: 4576, data page slots: 4576, average fill: 79%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 3731
80 - 99% = 845

Index XQS_3300_3400_DOC_ID (0)
Depth: 2, leaf buckets: 305, nodes: 339951
Average data length: 0.22, total dup: 329237, max dup: 213
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 107
60 - 79% = 112
80 - 99% = 86

Index XQS_3300_3400_SND_ID (1)
Depth: 2, leaf buckets: 361, nodes: 339951
Average data length: 0.82, total dup: 295929, max dup: 44
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 146
60 - 79% = 147
80 - 99% = 68

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 5600 ms, from 21:42:07.18 to 21:42:12.78.

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 482227 14367 2.9793
DOC_LIST 83692 9723 11.6175
DOC_STATES 4 0 0.0000
FB_ERRORS 758 0 0.0000
INVNT_SALDO 400 94868 23717.0000
INVNT_TURNOVER_LOG 192994 190890 98.9098
MONEY_SALDO 49 4657 9504.0816
MONEY_TURNOVER_LOG 9204 9158 99.5002
OPTYPES 9 0 0.0000
PDISTR 154423 107325 69.5006
PERF_ESTIMATED 24682 2 0.0081
PERF_LOG 1319148 1 0.0000
PSTORNED 86455 3720 4.3028
RULES_FOR_PDISTR 4 0 0.0000
RULES_FOR_QDISTR 7 0 0.0000
SEMAPHORES 4 1051 26275.0000
SETTINGS 114 0 0.0000
TRACE_STAT 229 0 0.0000
WARES 400 0 0.0000
WARE_GROUPS 8 0 0.0000
XQD_1000_1200 316058 315660 99.8740
XQD_1000_3300 286998 81840 28.5158
XQD_1200_2000 374720 372259 99.3432
XQD_2000_3300 1074300 783789 72.9581
XQD_2100_3300 237026 220611 93.0745
XQD_3300_3400 707468 305621 43.1992
XQS_1000_1200 1021324 29122 2.8513
XQS_1000_3300 611261 67734 11.0810
XQS_1200_2000 1069903 55261 5.1650
XQS_2100_3300 770047 62331 8.0944
XQS_3300_3400 339951 34082 10.0255
Z_USED_VIEWS 32 0 0.0000
Done for 3910 ms, from 21:42:24.36 to 21:42:28.27.

Database validation

21:42:28.41 Validation started

21:42:28.41 Relation 140 (DOC_LIST)
21:42:28.41 process pointer page 0 of 1
21:42:28.48 Index 1 (PK_DOC_LIST)
21:42:28.51 Index 2 (DOC_LIST_ID_DESC)
21:42:28.54 Index 3 (FK_DOC_LIST_AGENTS)
21:42:28.55 Relation 140 (DOC_LIST) is ok

21:42:28.55 Relation 141 (DOC_DATA)
21:42:28.55 process pointer page 0 of 3
21:42:28.66 process pointer page 1 of 3
21:42:28.76 process pointer page 2 of 3
21:42:28.77 Index 2 (DOC_DATA_DOC_WARE_UNQ)
21:42:28.96 Index 3 (DOC_DATA_ID_DESC)
21:42:29.13 Index 4 (FK_DOC_DATA_DOC_LIST)
21:42:29.26 Relation 141 (DOC_DATA) is ok

21:42:29.26 Relation 147 (INVNT_SALDO)
21:42:29.26 process pointer page 0 of 1
21:42:29.35 Index 1 (PK_INVNT_SALDO)
21:42:29.36 Relation 147 (INVNT_SALDO) is ok

21:42:29.36 Relation 150 (PDISTR)
21:42:29.36 process pointer page 0 of 1
21:42:29.54 Index 2 (PDISTR_SND_ID)
21:42:29.58 Index 3 (PDISTR_SNDOP_RCVOP_SNDID_ASC)
21:42:29.61 Index 4 (PDISTR_SNDOP_RCVOP_SNDID_DESC)
21:42:29.66 Index 5 (PDISTR_AGENT_ID)
21:42:29.71 Relation 150 (PDISTR) is ok

21:42:29.71 Relation 151 (PSTORNED)
21:42:29.71 process pointer page 0 of 1
21:42:29.75 Index 2 (PSTORNED_SND_ID)
21:42:29.77 Index 3 (PSTORNED_RCV_ID)
21:42:29.80 Relation 151 (PSTORNED) is ok

21:42:29.80 Relation 156 (SEMAPHORES)
21:42:29.80 process pointer page 0 of 1
21:42:29.80 Index 1 (PK_SEMAPHORES)
21:42:29.80 Index 2 (SEMAPHORES_TASK_UNQ)
21:42:29.80 Relation 156 (SEMAPHORES) is ok

21:42:29.80 Relation 157 (INVNT_TURNOVER_LOG)
21:42:29.80 process pointer page 0 of 2
21:42:29.96 process pointer page 1 of 2
21:42:30.04 Index 1 (INVNT_TURNOVER_LOG_WARE_DD_ID)
21:42:30.16 Relation 157 (INVNT_TURNOVER_LOG) is ok

21:42:30.16 Relation 160 (PERF_ESTIMATED)
21:42:30.16 process pointer page 0 of 1
21:42:30.16 Index 1 (PERF_EST_MINUTE_SINCE_START)
21:42:30.18 Relation 160 (PERF_ESTIMATED) is ok

21:42:30.18 Relation 161 (PERF_ISQL_STAT)
21:42:30.18 process pointer page 0 of 1
21:42:30.18 Index 1 (PERF_ISQL_STAT_TRN)
21:42:30.18 Relation 161 (PERF_ISQL_STAT) is ok

21:42:30.18 Relation 162 (PERF_LOG)
21:42:30.18 process pointer page 0 of 16
21:42:30.22 process pointer page 1 of 16
21:42:30.25 process pointer page 2 of 16
21:42:30.33 process pointer page 3 of 16
21:42:30.41 process pointer page 4 of 16
21:42:30.49 process pointer page 5 of 16
21:42:30.57 process pointer page 6 of 16
21:42:30.64 process pointer page 7 of 16
21:42:30.71 process pointer page 8 of 16
21:42:30.78 process pointer page 9 of 16
21:42:30.86 process pointer page 10 of 16
21:42:30.94 process pointer page 11 of 16
21:42:31.00 process pointer page 12 of 16
21:42:31.08 process pointer page 13 of 16
21:42:31.14 process pointer page 14 of 16
21:42:31.22 process pointer page 15 of 16
21:42:31.27 Index 1 (PERF_LOG_DTS_BEG_DESC)
21:42:31.69 Index 2 (PERF_LOG_UNIT)
21:42:32.38 Index 3 (PERF_LOG_TRN_DESC)
21:42:32.75 Index 4 (PERF_LOG_GDSCODE)
21:42:32.94 Relation 162 (PERF_LOG) is ok

21:42:32.94 Relation 166 (V_STOPTEST)
21:42:32.94 Relation 166 (V_STOPTEST) is ok

21:42:32.94 Relation 183 (V_QDISTR_SOURCE)
21:42:32.94 Relation 183 (V_QDISTR_SOURCE) is ok

21:42:32.94 Relation 184 (V_QSTORNED_SOURCE)
21:42:32.94 Relation 184 (V_QSTORNED_SOURCE) is ok

21:42:32.94 Relation 258 (XQD_1000_1200)
21:42:32.94 process pointer page 0 of 3
21:42:33.09 process pointer page 1 of 3
21:42:33.25 process pointer page 2 of 3
21:42:33.28 Index 1 (XQD_1000_1200_WARE_SOP_ROP_SND)
21:42:33.39 Relation 258 (XQD_1000_1200) is ok

21:42:33.39 Relation 259 (XQD_1200_2000)
21:42:33.39 process pointer page 0 of 3
21:42:33.56 process pointer page 1 of 3
21:42:33.72 process pointer page 2 of 3
21:42:33.83 Index 1 (XQD_1200_2000_WARE_SOP_ROP_SND)
21:42:33.95 Relation 259 (XQD_1200_2000) is ok

21:42:33.95 Relation 260 (XQD_1000_3300)
21:42:33.95 process pointer page 0 of 3
21:42:34.08 process pointer page 1 of 3
21:42:34.17 process pointer page 2 of 3
21:42:34.17 Index 1 (XQD_1000_3300_WARE_SOP_ROP_SND)
21:42:34.26 Index 2 (XQD_1000_3300_DOC)
21:42:34.33 Relation 260 (XQD_1000_3300) is ok

21:42:34.33 Relation 261 (XQD_2000_3300)
21:42:34.33 process pointer page 0 of 8
21:42:34.43 process pointer page 1 of 8
21:42:34.59 process pointer page 2 of 8
21:42:34.75 process pointer page 3 of 8
21:42:34.92 process pointer page 4 of 8
21:42:35.07 process pointer page 5 of 8
21:42:35.18 process pointer page 6 of 8
21:42:35.29 process pointer page 7 of 8
21:42:35.35 Index 1 (XQD_2000_3300_WARE_SOP_ROP_SND)
21:42:35.70 Relation 261 (XQD_2000_3300) is ok

21:42:35.70 Relation 262 (XQD_2100_3300)
21:42:35.70 process pointer page 0 of 3
21:42:35.84 process pointer page 1 of 3
21:42:35.98 process pointer page 2 of 3
21:42:35.99 Index 1 (XQD_2100_3300_WARE_SOP_ROP_SND)
21:42:36.06 Relation 262 (XQD_2100_3300) is ok

21:42:36.06 Relation 263 (XQD_3300_3400)
21:42:36.06 process pointer page 0 of 6
21:42:36.15 process pointer page 1 of 6
21:42:36.29 process pointer page 2 of 6
21:42:36.40 process pointer page 3 of 6
21:42:36.52 process pointer page 4 of 6
21:42:36.62 process pointer page 5 of 6
21:42:36.63 Index 1 (XQD_3300_3400_WARE_SOP_ROP_SND)
21:42:36.87 Relation 263 (XQD_3300_3400) is ok

21:42:36.87 Relation 264 (XQS_1000_1200)
21:42:36.87 process pointer page 0 of 8
21:42:36.96 process pointer page 1 of 8
21:42:37.06 process pointer page 2 of 8
21:42:37.13 process pointer page 3 of 8
21:42:37.23 process pointer page 4 of 8
21:42:37.32 process pointer page 5 of 8
21:42:37.41 process pointer page 6 of 8
21:42:37.51 process pointer page 7 of 8
21:42:37.59 Index 1 (XQS_1000_1200_DOC_ID)
21:42:37.88 Index 2 (XQS_1000_1200_SND_ID)
21:42:38.19 Index 3 (XQS_1000_1200_RCV_ID)
21:42:38.35 Relation 264 (XQS_1000_1200) is ok

21:42:38.35 Relation 265 (XQS_1200_2000)
21:42:38.37 process pointer page 0 of 9
21:42:38.41 process pointer page 1 of 9
21:42:38.44 process pointer page 2 of 9
21:42:38.52 process pointer page 3 of 9
21:42:38.62 process pointer page 4 of 9
21:42:38.69 process pointer page 5 of 9
21:42:38.79 process pointer page 6 of 9
21:42:38.83 process pointer page 7 of 9
21:42:38.86 process pointer page 8 of 9
21:42:38.88 Index 1 (XQS_1200_2000_DOC_ID)
21:42:39.11 Index 2 (XQS_1200_2000_SND_ID)
21:42:39.40 Index 3 (XQS_1200_2000_RCV_ID)
21:42:39.64 Relation 265 (XQS_1200_2000) is ok

21:42:39.64 Relation 266 (XQS_1000_3300)
21:42:39.64 process pointer page 0 of 5
21:42:39.74 process pointer page 1 of 5
21:42:39.83 process pointer page 2 of 5
21:42:39.94 process pointer page 3 of 5
21:42:40.03 process pointer page 4 of 5
21:42:40.11 Index 1 (XQS_1000_3300_DOC_ID)
21:42:40.27 Index 2 (XQS_1000_3300_SND_ID)
21:42:40.46 Index 3 (XQS_1000_3300_RCV_ID)
21:42:40.60 Relation 266 (XQS_1000_3300) is ok

21:42:40.60 Relation 267 (XQS_2000_3300)
21:42:40.60 process pointer page 0 of 1
21:42:40.60 Index 1 (XQS_2000_3300_DOC_ID)
21:42:40.60 Index 2 (XQS_2000_3300_SND_ID)
21:42:40.60 Index 3 (XQS_2000_3300_RCV_ID)
21:42:40.60 Relation 267 (XQS_2000_3300) is ok

21:42:40.60 Relation 268 (XQS_2100_3300)
21:42:40.60 process pointer page 0 of 7
21:42:40.69 process pointer page 1 of 7
21:42:40.78 process pointer page 2 of 7
21:42:40.88 process pointer page 3 of 7
21:42:40.97 process pointer page 4 of 7
21:42:41.06 process pointer page 5 of 7
21:42:41.16 process pointer page 6 of 7
21:42:41.17 Index 1 (XQS_2100_3300_DOC_ID)
21:42:41.42 Index 2 (XQS_2100_3300_SND_ID)
21:42:41.69 Index 3 (XQS_2100_3300_RCV_ID)
21:42:41.88 Relation 268 (XQS_2100_3300) is ok

21:42:41.88 Relation 269 (XQS_3300_3400)
21:42:41.88 process pointer page 0 of 3
21:42:41.97 process pointer page 1 of 3
21:42:42.08 process pointer page 2 of 3
21:42:42.11 Index 1 (XQS_3300_3400_DOC_ID)
21:42:42.20 Index 2 (XQS_3300_3400_SND_ID)
21:42:42.28 Relation 269 (XQS_3300_3400) is ok

21:42:42.28 Relation 276 (TRACE_STAT)
21:42:42.28 process pointer page 0 of 1
21:42:42.28 Relation 276 (TRACE_STAT) is ok

21:42:42.28 Validation finished
Done for 14000 ms, from 21:42:28.30 to 21:42:42.30.

Differences between old and current firebird.log

21:42:42.98. 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 21:42 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 21:42:45.18 - end of report, text file: e:\temp\logs.oltp25\oltp25.report.txt, html: e:\temp\logs.oltp25\oltp25.report.html