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-13 2:26:44.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-13 02:26:14.8670 -1 <null> 127.0.0.1 92716 4272 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: 432.52 111179 77854 2016-10-12 23:26 2016-10-13 02:26
customer order: creation 26.98 27 4856 2016-10-12 23:26 2016-10-13 02:26
customer order: refuse 2.13 17 377 2016-10-12 23:26 2016-10-13 02:26
order to supplier: creation 6.81 2078 1226 2016-10-12 23:26 2016-10-13 02:26
order to supplier: removal 0.16 190347 27 2016-10-12 23:26 2016-10-13 02:26
invoice (draft): creation 6.93 1538 1240 2016-10-12 23:26 2016-10-13 02:26
invoice (draft): removal 1.08 50 185 2016-10-12 23:26 2016-10-13 02:26
invoice accept: apply 1.83 1629609 327 2016-10-12 23:26 2016-10-13 02:26
invoice accept: cancel 0.38 254639 66 2016-10-12 23:26 2016-10-13 02:26
customer reserve: creation 339.17 13509 61050 2016-10-12 23:26 2016-10-13 02:26
customer reserve: removal 10.15 14474 1816 2016-10-12 23:26 2016-10-13 02:26
realization accept: apply 11.88 32 2139 2016-10-12 23:26 2016-10-13 02:26
realization accept: cancel 3.78 22 680 2016-10-12 23:26 2016-10-13 02:26
payment from customer: creation 9.23 66 1661 2016-10-12 23:26 2016-10-13 02:26
payment from customer: removal 0.84 11 146 2016-10-12 23:26 2016-10-13 02:26
payment to supplier: creation 7.28 196 1296 2016-10-12 23:26 2016-10-13 02:26
payment to supplier: removal 0.54 11 93 2016-10-12 23:26 2016-10-13 02:26
service: total inventory turnovers 2.13 4742 379 2016-10-12 23:26 2016-10-13 02:26
service: total monetary turnovers 1.56 11 279 2016-10-12 23:26 2016-10-13 02:26
service: refresh index statistics 0.07 1026 11 2016-10-12 23:26 2016-10-13 02:26
Done for 8780 ms, from 2:27:03.49 to 2:27:12.27.

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 277 5268 4983 285 5.41 23:26:15 23:44:15
interval # 2, overall 2 249 4611 4475 136 2.95 23:44:16 00:02:16
interval # 3, overall 3 280 5219 5031 188 3.60 00:02:17 00:20:17
interval # 4, overall 4 302 5704 5437 267 4.68 00:20:18 00:38:18
interval # 5, overall 5 326 6234 5864 370 5.94 00:38:19 00:56:19
interval # 6, overall 6 289 5244 5194 50 0.95 00:56:20 01:14:20
interval # 7, overall 7 481 8870 8660 210 2.37 01:14:21 01:32:21
interval # 8, overall 8 666 12182 11987 195 1.60 01:32:22 01:50:22
interval # 9, overall 9 1051 19165 18925 240 1.25 01:50:23 02:08:23
interval # 10, overall 10 405 8077 7298 779 9.64 02:08:24 02:26:24
Done for 13460 ms, from 2:27:22.90 to 2:27:36.36.

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 2361.14 0.6429 1.3506 1137 97
WARM_TIME 2 1919.41 0.8309 1.1365 985 84
WARM_TIME 3 1568.49 0.9276 1.0693 697 75
WARM_TIME 4 1345.47 0.9351 1.0661 699 67
WARM_TIME 5 1234.03 0.9301 1.0737 936 54
WARM_TIME 6 1181.12 0.9349 1.0657 980 37
WARM_TIME 7 1151.91 0.9368 1.0618 1030 21
WARM_TIME 8 1092.81 0.9794 1.0209 395 7
WARM_TIME 9 1001.65 0.9902 1.0119 168 5
WARM_TIME 10 913.85 0.9984 1.0016 34 2
TEST_TIME 11 833.22 0.9988 1.0011 22 1
TEST_TIME 12 768.61 0.9949 1.0072 66 4
TEST_TIME 13 720.26 0.9922 1.0215 116 5
TEST_TIME 16 603.31 0.9999 1.0000 3 1
TEST_TIME 17 574.35 0.9905 1.0066 120 2
TEST_TIME 18 550.35 0.9922 1.0059 124 3
TEST_TIME 19 544.01 0.9641 1.0298 425 8
TEST_TIME 20 537.43 0.9904 1.0088 176 4
TEST_TIME 21 519.48 0.9941 1.0133 118 4
TEST_TIME 22 503.02 0.9990 1.0008 23 3
TEST_TIME 23 484.02 0.9949 1.0077 101 5
TEST_TIME 25 452.20 0.9930 1.0049 66 3
TEST_TIME 26 439.09 0.9952 1.0048 120 4
TEST_TIME 28 416.88 0.9898 1.0049 21 3
TEST_TIME 29 404.73 0.9995 1.0004 12 2
TEST_TIME 30 398.15 0.9883 1.0077 116 4
TEST_TIME 31 389.59 0.9966 1.0031 89 4
TEST_TIME 32 382.87 0.9944 1.0059 119 3
TEST_TIME 33 377.40 0.9896 1.0066 124 4
TEST_TIME 34 371.75 0.9919 1.0046 66 3
TEST_TIME 35 366.36 0.9902 1.0067 94 5
TEST_TIME 36 362.39 0.9896 1.0054 108 6
TEST_TIME 37 354.89 0.9990 1.0009 28 3
TEST_TIME 38 347.96 0.9994 1.0005 15 1
TEST_TIME 42 317.16 0.9993 1.0007 21 1
TEST_TIME 44 304.84 0.9990 1.0008 29 1
TEST_TIME 45 300.53 0.9989 1.0044 17 3
TEST_TIME 46 298.92 0.9901 1.0025 67 2
TEST_TIME 47 297.22 0.9920 1.0070 105 3
TEST_TIME 48 296.15 0.9897 1.0103 81 5
TEST_TIME 49 296.64 0.9882 1.0057 152 3
TEST_TIME 50 296.25 0.9906 1.0021 59 3
TEST_TIME 51 291.19 0.9996 1.0003 12 1
TEST_TIME 52 286.17 0.9983 1.0081 48 3
TEST_TIME 54 279.02 0.9998 1.0001 6 1
TEST_TIME 55 274.14 0.9995 1.0007 15 2
TEST_TIME 56 270.48 0.9962 1.0137 34 3
TEST_TIME 57 272.75 0.9877 1.0091 98 5
TEST_TIME 58 272.81 0.9915 1.0039 33 3
TEST_TIME 59 272.18 0.9970 1.0031 104 3
TEST_TIME 60 272.26 0.9969 1.0070 92 2
TEST_TIME 61 271.38 0.9938 1.0054 80 2
TEST_TIME 62 268.77 0.9989 1.0011 45 3
TEST_TIME 64 261.45 0.9989 1.0010 41 1
TEST_TIME 65 258.81 0.9957 1.0008 30 2
TEST_TIME 66 256.96 1.0000 1.0000 1 1
TEST_TIME 67 255.94 0.9959 1.0049 41 3
TEST_TIME 68 260.33 0.9756 1.0144 207 7
TEST_TIME 69 262.78 0.9904 1.0050 138 4
TEST_TIME 70 260.38 0.9998 1.0002 8 1
TEST_TIME 71 259.31 0.9990 1.0010 42 2
TEST_TIME 72 260.17 0.9878 1.0084 225 5
TEST_TIME 73 259.33 0.9978 1.0084 71 4
TEST_TIME 74 258.08 0.9996 1.0003 15 1
TEST_TIME 75 254.95 0.9998 1.0002 9 1
TEST_TIME 76 251.95 0.9994 1.0006 26 1
TEST_TIME 77 250.90 0.9918 1.0138 83 3
TEST_TIME 78 253.08 0.9922 1.0056 90 3
TEST_TIME 79 252.64 0.9999 1.0001 5 1
TEST_TIME 80 255.35 0.9849 1.0103 193 6
TEST_TIME 81 257.11 0.9916 1.0021 5 3
TEST_TIME 82 257.35 0.9890 1.0037 160 3
TEST_TIME 84 254.91 0.9982 1.0060 58 2
TEST_TIME 85 254.81 0.9946 1.0037 120 3
TEST_TIME 86 253.09 0.9988 1.0011 54 3
TEST_TIME 87 253.60 0.9876 1.0143 79 6
TEST_TIME 88 257.50 0.9876 1.0049 121 6
TEST_TIME 89 255.93 0.9998 1.0001 10 2
TEST_TIME 90 254.89 0.9942 1.0025 102 3
TEST_TIME 91 253.31 0.9998 1.0002 10 1
TEST_TIME 92 253.70 0.9954 1.0069 90 4
TEST_TIME 93 253.38 0.9974 1.0036 82 4
TEST_TIME 94 258.80 0.9803 1.0090 171 6
TEST_TIME 95 268.14 0.9636 1.0145 404 9
TEST_TIME 96 269.59 0.9985 1.0014 85 2
TEST_TIME 98 266.93 0.9907 1.0036 131 3
TEST_TIME 101 262.26 0.9998 1.0001 8 1
TEST_TIME 102 259.79 0.9998 1.0001 12 1
TEST_TIME 104 255.16 1.0000 1.0000 3 1
TEST_TIME 105 254.01 0.9950 1.0090 134 5
TEST_TIME 106 256.26 0.9989 1.0009 58 1
TEST_TIME 107 254.29 0.9993 1.0040 24 2
TEST_TIME 109 250.70 0.9997 1.0002 16 1
TEST_TIME 110 253.24 0.9844 1.0028 56 3
TEST_TIME 111 252.99 0.9948 1.0033 73 3
TEST_TIME 113 251.57 0.9998 1.0001 8 1
TEST_TIME 114 253.64 0.9916 1.0135 45 4
TEST_TIME 115 256.65 0.9998 1.0002 15 1
TEST_TIME 117 253.44 0.9956 1.0035 113 3
TEST_TIME 118 252.44 0.9990 1.0009 64 2
TEST_TIME 119 250.58 0.9999 1.0001 6 1
TEST_TIME 120 250.05 0.9956 1.0090 47 3
TEST_TIME 121 250.78 0.9978 1.0050 21 4
TEST_TIME 122 252.72 0.9969 1.0003 21 2
TEST_TIME 123 253.97 0.9940 1.0026 94 5
TEST_TIME 124 258.63 0.9857 1.0117 177 7
TEST_TIME 125 261.19 0.9938 1.0063 216 6
TEST_TIME 126 263.06 0.9944 1.0098 129 4
TEST_TIME 127 264.04 0.9981 1.0024 69 5
TEST_TIME 128 263.69 0.9959 1.0063 75 3
TEST_TIME 129 266.14 0.9960 1.0052 60 6
TEST_TIME 130 266.73 0.9953 1.0069 232 7
TEST_TIME 131 268.52 0.9973 1.0004 10 2
TEST_TIME 132 272.23 0.9793 1.0121 210 8
TEST_TIME 133 276.29 0.9897 1.0112 163 6
TEST_TIME 134 278.47 0.9958 1.0034 46 5
TEST_TIME 135 280.31 0.9951 1.0061 94 3
TEST_TIME 136 281.26 0.9958 1.0032 89 4
TEST_TIME 137 281.72 0.9965 1.0087 48 4
TEST_TIME 138 283.73 0.9951 1.0021 47 4
TEST_TIME 139 286.80 0.9860 1.0068 140 5
TEST_TIME 140 286.94 0.9996 1.0013 29 2
TEST_TIME 141 285.80 0.9982 1.0008 57 4
TEST_TIME 142 288.92 0.9909 1.0065 231 6
TEST_TIME 143 289.05 0.9990 1.0039 61 3
TEST_TIME 145 286.52 1.0000 1.0000 3 1
TEST_TIME 146 287.18 0.9909 1.0040 106 4
TEST_TIME 147 289.07 0.9906 1.0114 108 6
TEST_TIME 148 294.35 0.9866 1.0041 100 6
TEST_TIME 149 298.04 0.9850 1.0129 115 7
TEST_TIME 150 304.72 0.9852 1.0138 334 8
TEST_TIME 151 309.30 0.9922 1.0032 116 7
TEST_TIME 152 311.18 0.9906 1.0070 114 8
TEST_TIME 153 314.79 0.9966 1.0061 61 5
TEST_TIME 154 317.05 0.9964 1.0028 92 5
TEST_TIME 155 323.80 0.9756 1.0059 210 8
TEST_TIME 156 327.13 0.9906 1.0120 113 6
TEST_TIME 157 333.77 0.9855 1.0115 221 10
TEST_TIME 158 337.43 0.9942 1.0096 156 9
TEST_TIME 159 341.76 0.9906 1.0069 150 6
TEST_TIME 160 344.42 0.9929 1.0100 165 9
TEST_TIME 161 348.15 0.9930 1.0054 228 9
TEST_TIME 162 353.10 0.9852 1.0165 281 8
TEST_TIME 163 360.97 0.9883 1.0218 266 10
TEST_TIME 164 372.69 0.9836 1.0078 322 11
TEST_TIME 165 375.67 0.9938 1.0063 97 5
TEST_TIME 166 383.99 0.9836 1.0086 134 11
TEST_TIME 167 392.42 0.9811 1.0130 326 13
TEST_TIME 168 401.40 0.9845 1.0250 336 16
TEST_TIME 169 411.71 0.9934 1.0041 258 12
TEST_TIME 170 417.98 0.9851 1.0120 185 14
TEST_TIME 171 424.30 0.9911 1.0094 196 12
TEST_TIME 172 428.12 0.9946 1.0122 221 10
TEST_TIME 173 437.93 0.9838 1.0165 237 12
TEST_TIME 174 444.50 0.9957 1.0054 141 10
TEST_TIME 175 448.43 0.9933 1.0039 171 5
TEST_TIME 176 451.84 0.9907 1.0033 246 6
TEST_TIME 177 454.70 0.9914 1.0050 308 9
TEST_TIME 178 455.62 0.9973 1.0041 54 6
TEST_TIME 179 457.57 0.9943 1.0064 168 6
TEST_TIME 180 464.49 0.9915 1.0020 43 3
TEST_TIME 182 461.98 0.9988 1.0004 8 2
TEST_TIME 183 462.28 1.0000 1.0000 2 1
TEST_TIME 184 463.70 0.9942 1.0005 80 2
TEST_TIME 185 463.00 0.9993 1.0010 43 3
TEST_TIME 187 461.81 0.9955 1.0034 49 4
TEST_TIME 188 461.93 0.9978 1.0011 21 2
TEST_TIME 189 464.75 0.9916 1.0097 156 6
TEST_TIME 190 469.77 0.9936 1.0005 121 100
Done for 9490 ms, from 2:27:36.62 to 2:27:46.11.

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 2 97127 116100 25562 <null> 155200 <null> 1315 <null> 1334 <null> 95671 98272 147749 <null> 243249 <null> 190066 2050142 <null> 23:44:16 00:02:16
marks per second 2 18377 11592 1992 <null> 12365 <null> 200 <null> 167 <null> 10037 13939 5795 <null> 5912 <null> 894 8714 <null> 23:44:16 00:02:16
reads/fetches*100 2 4 4 8 <null> 5 <null> 14 <null> 12 <null> 5 5 6 <null> 3 <null> 23 0 <null> 23:44:16 00:02:16
writes/marks*100 2 13 14 9 <null> 9 <null> 1 <null> 2 <null> 8 7 8 <null> 3 <null> 43 0 <null> 23:44:16 00:02:16
Done for 4430 ms, from 2:27:46.28 to 2:27:50.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 53829 52160 1669 3.10 0 5647 15 1669 2016-10-12 23:26 2016-10-13 02:26
doc_list_biud 53882 52152 1730 3.21 0 1045723 1333 1730 2016-10-12 23:26 2016-10-13 02:26
sp_add_invoice_to_stock 1075 327 748 69.58 24711 8826541 1629609 748 2016-10-12 23:26 2016-10-13 02:26
sp_cancel_adding_invoice 131 66 65 49.61 16 2395111 254639 65 2016-10-12 23:26 2016-10-13 02:26
sp_cancel_client_order 408 377 31 7.59 0 94 17 31 2016-10-12 23:26 2016-10-13 02:26
sp_cancel_customer_reserve 3025 1816 1209 39.96 0 383530 14474 1209 2016-10-12 23:26 2016-10-13 02:26
sp_cancel_pay_from_customer 147 146 1 0.68 0 78 11 1 2016-10-12 23:26 2016-10-13 02:26
sp_cancel_pay_to_supplier 94 93 1 1.06 0 32 11 1 2016-10-12 23:26 2016-10-13 02:26
sp_cancel_supplier_invoice 240 185 55 22.91 0 249 50 55 2016-10-12 23:26 2016-10-13 02:26
sp_cancel_supplier_order 84 27 57 67.85 31 1631765 190347 57 2016-10-12 23:26 2016-10-13 02:26
sp_cancel_write_off 1147 680 467 40.71 0 125 22 467 2016-10-12 23:26 2016-10-13 02:26
sp_client_order 4856 4856 0 0.00 0 218 27 0 2016-10-12 23:26 2016-10-13 02:26
sp_customer_reserve 61050 61050 0 0.00 171 83479 13509 0 2016-10-12 23:26 2016-10-13 02:26
sp_fill_shopping_cart 7322 7322 0 0.00 0 36566 542 0 2016-10-12 23:26 2016-10-13 02:26
sp_fill_shopping_cart_clo_res 61050 61050 0 0.00 0 78 1 0 2016-10-12 23:26 2016-10-13 02:26
sp_get_clo_for_invoice 333 333 0 0.00 0 219 42 0 2016-10-12 23:26 2016-10-13 02:26
sp_kill_cost_storno 1450 986 464 32.00 0 94 2 464 2016-10-12 23:26 2016-10-13 02:26
sp_kill_qstorno_ret_qs2qd 7370 4524 2846 38.61 0 383499 5811 2846 2016-10-12 23:26 2016-10-13 02:26
sp_kill_qty_storno 7327 5618 1709 23.32 0 1045723 12375 1709 2016-10-12 23:26 2016-10-13 02:26
sp_lock_selected_doc 4595 3544 1051 22.87 0 16 0 1051 2016-10-12 23:26 2016-10-13 02:26
sp_make_cost_storno 5429 5429 0 0.00 0 936 64 0 2016-10-12 23:26 2016-10-13 02:26
sp_multiply_rows_for_pdistr 5429 5429 0 0.00 0 94 3 0 2016-10-12 23:26 2016-10-13 02:26
sp_multiply_rows_for_qdistr 4856 4856 0 0.00 0 141 9 0 2016-10-12 23:26 2016-10-13 02:26
sp_pay_from_customer 1661 1661 0 0.00 31 421 66 0 2016-10-12 23:26 2016-10-13 02:26
sp_pay_to_supplier 1296 1296 0 0.00 16 951 196 0 2016-10-12 23:26 2016-10-13 02:26
sp_payment_common 2957 2957 0 0.00 0 156 19 0 2016-10-12 23:26 2016-10-13 02:26
sp_qd_handle_on_cancel_clo 408 377 31 7.59 0 31 4 31 2016-10-12 23:26 2016-10-13 02:26
sp_qd_handle_on_invoice_upd_sts 424 394 30 7.07 0 1045723 109630 30 2016-10-12 23:26 2016-10-13 02:26
sp_qd_handle_on_reserve_upd_sts 2139 2139 0 0.00 0 63 5 0 2016-10-12 23:26 2016-10-13 02:26
sp_reserve_write_off 2225 2139 86 3.86 0 203 32 86 2016-10-12 23:26 2016-10-13 02:26
sp_supplier_invoice 1240 1240 0 0.00 15 32900 1538 0 2016-10-12 23:26 2016-10-13 02:26
sp_supplier_order 1226 1226 0 0.00 31 36801 2078 0 2016-10-12 23:26 2016-10-13 02:26
srv_find_qd_qs_mism 28986 27317 1669 5.75 0 5304 14 1669 2016-10-12 23:26 2016-10-13 02:26
srv_make_invnt_saldo 379 379 0 0.00 952 26723 4742 0 2016-10-12 23:26 2016-10-13 02:26
srv_make_money_saldo 279 279 0 0.00 0 62 11 0 2016-10-12 23:26 2016-10-13 02:26
srv_recalc_idx_stat 11 11 0 0.00 842 1357 1026 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-abend:sp_add_invoice_to_stock 748 748 0 0.00 0 47 2 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-abend:sp_cancel_adding_invoice 151 151 0 0.00 0 62 16 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-abend:sp_cancel_client_order 31 31 0 0.00 0 16 5 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-abend:sp_cancel_customer_reserve 6 6 0 0.00 0 16 5 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-abend:sp_cancel_pay_from_customer 1 1 0 0.00 15 15 15 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-abend:sp_cancel_pay_to_supplier 1 1 0 0.00 0 0 0 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-abend:sp_cancel_supplier_invoice 55 55 0 0.00 0 16 1 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-abend:sp_cancel_supplier_order 154 154 0 0.00 0 94 15 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-abend:sp_cancel_write_off 5 5 0 0.00 0 0 0 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-abend:sp_reserve_write_off 86 86 0 0.00 0 16 2 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_add_invoice_to_stock 417 417 0 0.00 0 1280 223 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_cancel_adding_invoice 50 50 0 0.00 0 374 79 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_cancel_client_order 377 377 0 0.00 0 62 3 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_cancel_customer_reserve 142 142 0 0.00 0 31 6 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_cancel_pay_from_customer 146 146 0 0.00 0 31 2 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_cancel_pay_to_supplier 93 93 0 0.00 0 16 2 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_cancel_supplier_invoice 34 34 0 0.00 0 32 6 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_cancel_supplier_order 27 27 0 0.00 0 1092 120 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_cancel_write_off 211 211 0 0.00 0 93 4 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_client_order 4856 4856 0 0.00 0 94 4 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_customer_reserve 227 227 0 0.00 0 47 5 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_pay_from_customer 1661 1661 0 0.00 0 62 3 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_pay_to_supplier 1296 1296 0 0.00 0 124 3 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_reserve_write_off 2139 2139 0 0.00 0 47 3 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_supplier_invoice 1241 1241 0 0.00 0 78 5 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:sp_supplier_order 1226 1226 0 0.00 0 78 5 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:srv_make_invnt_saldo 379 379 0 0.00 0 31 2 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:srv_make_money_saldo 279 279 0 0.00 0 16 1 0 2016-10-12 23:26 2016-10-13 02:26
t$perf-norm:srv_recalc_idx_stat 11 11 0 0.00 0 31 14 0 2016-10-12 23:26 2016-10-13 02:26
v_add_invoice_to_stock 2150 654 1496 69.58 0 16 1 1496 2016-10-12 23:26 2016-10-13 02:26
v_all_customers 7214 7214 0 0.00 0 16 0 0 2016-10-12 23:26 2016-10-13 02:26
v_all_suppliers 4932 4932 0 0.00 0 16 0 0 2016-10-12 23:26 2016-10-13 02:26
v_all_wares 9712 9712 0 0.00 0 16 0 0 2016-10-12 23:26 2016-10-13 02:26
v_cancel_adding_invoice 220 98 122 55.45 0 16 2 122 2016-10-12 23:26 2016-10-13 02:26
v_cancel_client_order 816 754 62 7.59 0 16 0 62 2016-10-12 23:26 2016-10-13 02:26
v_cancel_customer_prepayment 294 292 2 0.68 0 16 0 2 2016-10-12 23:26 2016-10-13 02:26
v_cancel_customer_reserve 294 282 12 4.08 0 16 0 12 2016-10-12 23:26 2016-10-13 02:26
v_cancel_payment_to_supplier 188 186 2 1.06 0 16 0 2 2016-10-12 23:26 2016-10-13 02:26
v_cancel_supplier_invoice 178 68 110 61.79 0 16 1 110 2016-10-12 23:26 2016-10-13 02:26
v_cancel_supplier_order 168 54 114 67.85 0 0 0 114 2016-10-12 23:26 2016-10-13 02:26
v_cancel_write_off 432 422 10 2.31 0 16 1 10 2016-10-12 23:26 2016-10-13 02:26
v_max_id_clo_ord 1226 1226 0 0.00 0 125 6 0 2016-10-12 23:26 2016-10-13 02:26
v_max_id_clo_res 227 227 0 0.00 0 16 1 0 2016-10-12 23:26 2016-10-13 02:26
v_max_id_ord_sup 1240 1240 0 0.00 0 47 5 0 2016-10-12 23:26 2016-10-13 02:26
v_max_non_paid_invoice 1296 1296 0 0.00 0 47 2 0 2016-10-12 23:26 2016-10-13 02:26
v_max_non_paid_realizn 1661 1661 0 0.00 0 32 4 0 2016-10-12 23:26 2016-10-13 02:26
v_min_id_clo_ord 1226 1226 0 0.00 0 1326 38 0 2016-10-12 23:26 2016-10-13 02:26
v_min_id_clo_res 227 227 0 0.00 0 16 1 0 2016-10-12 23:26 2016-10-13 02:26
v_min_id_ord_sup 1240 1240 0 0.00 0 1545 34 0 2016-10-12 23:26 2016-10-13 02:26
v_min_non_paid_invoice 1296 1296 0 0.00 0 156 15 0 2016-10-12 23:26 2016-10-13 02:26
v_min_non_paid_realizn 1661 1661 0 0.00 0 78 8 0 2016-10-12 23:26 2016-10-13 02:26
v_our_firm 2498 2498 0 0.00 0 16 0 0 2016-10-12 23:26 2016-10-13 02:26
v_reserve_write_off 4450 4278 172 3.86 0 16 0 172 2016-10-12 23:26 2016-10-13 02:26
x_lock_depdocs_on_canc_invoice 332 217 115 34.63 0 16 1 115 2016-10-12 23:26 2016-10-13 02:26
x_lock_depdocs_on_canc_sup_ord 84 27 57 67.85 0 16 1 57 2016-10-12 23:26 2016-10-13 02:26
x_make_qstorno_1000_1200 1226 1226 0 0.00 0 1264 208 0 2016-10-12 23:26 2016-10-13 02:26
x_make_qstorno_1200_2000 1241 1241 0 0.00 0 1466 184 0 2016-10-12 23:26 2016-10-13 02:26
x_make_qstorno_2100_3300 61050 61050 0 0.00 171 83479 13497 0 2016-10-12 23:26 2016-10-13 02:26
Done for 22770 ms, from 2:28:06.87 to 2:28:29.64.

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 6 sp_kill_cost_storno 335544336 2016-10-12 23:38 2016-10-13 00:54
deadlock 6 sp_qd_handle_on_invoice_upd_sts 335544336 2016-10-12 23:52 2016-10-13 01:42
lock_conflict 2 sp_kill_cost_storno 335544345 2016-10-13 00:51 2016-10-13 01:46
lock_conflict 900 sp_lock_selected_doc 335544345 2016-10-12 23:26 2016-10-13 02:25
lock_conflict 31 sp_qd_handle_on_cancel_clo 335544345 2016-10-12 23:34 2016-10-13 02:15
lock_conflict 24 sp_qd_handle_on_invoice_upd_sts 335544345 2016-10-12 23:28 2016-10-13 02:18
lock_conflict 61 x_lock_depdocs_on_canc_invoice 335544345 2016-10-12 23:29 2016-10-13 02:04
lock_conflict 26 x_lock_depdocs_on_canc_sup_ord 335544345 2016-10-12 23:29 2016-10-13 02:13
Done for 2420 ms, from 2:28:30.01 to 2:28:32.43.

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 93339
MON$OLDEST_ACTIVE 93340
MON$OLDEST_SNAPSHOT 93340
MON$NEXT_TRANSACTION 93340
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 97798
Page size 8192
ODS version 11.2
Oldest transaction 93341
Oldest active 93342
Oldest snapshot 93342
Next transaction 93343
Bumped transaction 1
Sequence number 0
Next attachment ID 4446
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.53, total records: 482479
Average version length: 46.28, total versions: 17596, max versions: 1
Data pages: 5160, data page slots: 5160, average fill: 73%
Fill distribution:
0 - 19% = 8
20 - 39% = 18
40 - 59% = 82
60 - 79% = 4975
80 - 99% = 77

Index DOC_DATA_DOC_WARE_UNQ (1)
Depth: 3, leaf buckets: 1595, nodes: 482479
Average data length: 11.76, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 158
20 - 39% = 37
40 - 59% = 630
60 - 79% = 114
80 - 99% = 656

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

Index FK_DOC_DATA_DOC_LIST (3)
Depth: 2, leaf buckets: 475, nodes: 482479
Average data length: 0.89, total dup: 414464, max dup: 49
Fill distribution:
0 - 19% = 4
20 - 39% = 20
40 - 59% = 204
60 - 79% = 17
80 - 99% = 230

DOC_LIST (140)
Primary pointer page: 195, Index root page: 196
Average record length: 58.06, total records: 82428
Average version length: 49.80, total versions: 10055, max versions: 5
Data pages: 1052, data page slots: 1052, average fill: 80%
Fill distribution:
0 - 19% = 7
20 - 39% = 27
40 - 59% = 37
60 - 79% = 220
80 - 99% = 761

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

Index FK_DOC_LIST_AGENTS (2)
Depth: 2, leaf buckets: 69, nodes: 82428
Average data length: 0.01, total dup: 82378, max dup: 18643
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 41
60 - 79% = 22
80 - 99% = 6

Index PK_DOC_LIST (0)
Depth: 2, leaf buckets: 166, nodes: 82428
Average data length: 6.26, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 2
20 - 39% = 6
40 - 59% = 86
60 - 79% = 1
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.67, total records: 400
Average version length: 13.31, total versions: 96676, max versions: 282
Data pages: 380, data page slots: 380, average fill: 96%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 379

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.24, total records: 197626
Average version length: 68.68, total versions: 196948, max versions: 1
Data pages: 2783, data page slots: 2783, average fill: 89%
Fill distribution:
0 - 19% = 12
20 - 39% = 31
40 - 59% = 48
60 - 79% = 160
80 - 99% = 2532

Index INVNT_TURNOVER_LOG_WARE_DD_ID (0)
Depth: 3, leaf buckets: 477, nodes: 197626
Average data length: 8.17, total dup: 23929, max dup: 5
Fill distribution:
0 - 19% = 1
20 - 39% = 1
40 - 59% = 145
60 - 79% = 192
80 - 99% = 138

MONEY_SALDO (143)
Primary pointer page: 208, Index root page: 209
Average record length: 14.55, total records: 49
Average version length: 9.00, total versions: 4619, max versions: 373
Data pages: 17, data page slots: 17, average fill: 88%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
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.17, total records: 9315
Average version length: 44.08, total versions: 9282, max versions: 1
Data pages: 96, data page slots: 96, average fill: 93%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 1
80 - 99% = 94

Index MONEY_TURNOVER_LOG_AGENT_OPTYPE (0)
Depth: 2, leaf buckets: 7, nodes: 9315
Average data length: 0.11, total dup: 9220, max dup: 2032
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 3
60 - 79% = 4
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: 21.81, total records: 153312
Average version length: 31.63, total versions: 122061, max versions: 11
Data pages: 1635, data page slots: 1635, average fill: 89%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 4
60 - 79% = 214
80 - 99% = 1417

Index PDISTR_AGENT_ID (4)
Depth: 2, leaf buckets: 137, nodes: 153312
Average data length: 0.01, total dup: 153263, max dup: 35588
Fill distribution:
0 - 19% = 2
20 - 39% = 0
40 - 59% = 93
60 - 79% = 14
80 - 99% = 28

Index PDISTR_SNDOP_RCVOP_SNDID_ASC (2)
Depth: 2, leaf buckets: 167, nodes: 153312
Average data length: 0.90, total dup: 137879, max dup: 34
Fill distribution:
0 - 19% = 0
20 - 39% = 3
40 - 59% = 102
60 - 79% = 42
80 - 99% = 20

Index PDISTR_SNDOP_RCVOP_SNDID_DESC (3)
Depth: 2, leaf buckets: 178, nodes: 153312
Average data length: 0.90, total dup: 137879, max dup: 34
Fill distribution:
0 - 19% = 1
20 - 39% = 2
40 - 59% = 118
60 - 79% = 37
80 - 99% = 20

Index PDISTR_SND_ID (1)
Depth: 2, leaf buckets: 147, nodes: 153312
Average data length: 0.67, total dup: 137879, max dup: 34
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 65
60 - 79% = 57
80 - 99% = 25

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

Index PERF_EST_MINUTE_SINCE_START (0)
Depth: 2, leaf buckets: 17, nodes: 24356
Average data length: 0.01, total dup: 24186, max dup: 1154
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 4
60 - 79% = 8
80 - 99% = 5

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

Index PERF_LOG_DTS_BEG_DESC (0)
Depth: 3, leaf buckets: 1568, nodes: 1271139
Average data length: 0.58, total dup: 727845, max dup: 34
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1150
60 - 79% = 283
80 - 99% = 135

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

Index PERF_LOG_TRN_DESC (2)
Depth: 3, leaf buckets: 1574, nodes: 1271139
Average data length: 0.38, total dup: 1195786, max dup: 2259
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1353
60 - 79% = 116
80 - 99% = 105

Index PERF_LOG_UNIT (1)
Depth: 3, leaf buckets: 1416, nodes: 1271139
Average data length: 0.30, total dup: 1234737, max dup: 143605
Fill distribution:
0 - 19% = 2
20 - 39% = 0
40 - 59% = 1030
60 - 79% = 184
80 - 99% = 200

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: 51.33, total records: 89029
Average version length: 54.69, total versions: 5584, max versions: 1
Data pages: 1038, data page slots: 1038, average fill: 76%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 6
60 - 79% = 977
80 - 99% = 54

Index PSTORNED_RCV_ID (2)
Depth: 2, leaf buckets: 113, nodes: 89029
Average data length: 1.14, total dup: 73726, max dup: 242
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 109
60 - 79% = 3
80 - 99% = 0

Index PSTORNED_SND_ID (1)
Depth: 2, leaf buckets: 82, nodes: 89029
Average data length: 0.61, total dup: 81249, max dup: 93
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 36
60 - 79% = 37
80 - 99% = 8

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: 1020, max versions: 572
Data pages: 4, data page slots: 4, average fill: 82%
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.64, total records: 160
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 2, data page slots: 2, average fill: 75%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 2
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.03, total records: 320850
Average version length: 69.76, total versions: 320725, max versions: 1
Data pages: 4304, data page slots: 4304, average fill: 95%
Fill distribution:
0 - 19% = 2
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 4302

Index XQD_1000_1200_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 373, nodes: 320850
Average data length: 1.24, total dup: 281255, max dup: 29
Fill distribution:
0 - 19% = 9
20 - 39% = 0
40 - 59% = 139
60 - 79% = 145
80 - 99% = 80

XQD_1000_3300 (260)
Primary pointer page: 653, Index root page: 654
Average record length: 48.88, total records: 307163
Average version length: 69.19, total versions: 92054, max versions: 1
Data pages: 4159, data page slots: 4239, average fill: 83%
Fill distribution:
0 - 19% = 9
20 - 39% = 9
40 - 59% = 10
60 - 79% = 1842
80 - 99% = 2289

Index XQD_1000_3300_DOC (1)
Depth: 2, leaf buckets: 268, nodes: 307163
Average data length: 0.15, total dup: 300503, max dup: 216
Fill distribution:
0 - 19% = 2
20 - 39% = 20
40 - 59% = 120
60 - 79% = 18
80 - 99% = 108

Index XQD_1000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 364, nodes: 307163
Average data length: 1.24, total dup: 269573, max dup: 44
Fill distribution:
0 - 19% = 2
20 - 39% = 2
40 - 59% = 156
60 - 79% = 142
80 - 99% = 62

XQD_1200_2000 (259)
Primary pointer page: 648, Index root page: 649
Average record length: 0.84, total records: 374188
Average version length: 69.73, total versions: 369689, max versions: 1
Data pages: 5029, data page slots: 5029, average fill: 94%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 42
80 - 99% = 4987

Index XQD_1200_2000_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 412, nodes: 374188
Average data length: 1.00, total dup: 337391, max dup: 170
Fill distribution:
0 - 19% = 10
20 - 39% = 0
40 - 59% = 130
60 - 79% = 184
80 - 99% = 88

XQD_2000_3300 (261)
Primary pointer page: 657, Index root page: 658
Average record length: 19.60, total records: 1071783
Average version length: 68.31, total versions: 770402, max versions: 1
Data pages: 14440, data page slots: 14440, average fill: 89%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 3720
80 - 99% = 10719

Index XQD_2000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 1374, nodes: 1071783
Average data length: 1.14, total dup: 947068, max dup: 89
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1081
60 - 79% = 121
80 - 99% = 172

XQD_2100_3300 (262)
Primary pointer page: 661, Index root page: 662
Average record length: 3.36, total records: 261199
Average version length: 68.76, total versions: 248509, max versions: 1
Data pages: 4053, data page slots: 4197, average fill: 81%
Fill distribution:
0 - 19% = 45
20 - 39% = 116
40 - 59% = 187
60 - 79% = 753
80 - 99% = 2952

Index XQD_2100_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 349, nodes: 261199
Average data length: 1.38, total dup: 225071, max dup: 89
Fill distribution:
0 - 19% = 4
20 - 39% = 18
40 - 59% = 184
60 - 79% = 115
80 - 99% = 28

XQD_3300_3400 (263)
Primary pointer page: 664, Index root page: 665
Average record length: 36.12, total records: 763309
Average version length: 68.33, total versions: 363306, max versions: 1
Data pages: 10751, data page slots: 10776, average fill: 82%
Fill distribution:
0 - 19% = 138
20 - 39% = 243
40 - 59% = 245
60 - 79% = 3475
80 - 99% = 6650

Index XQD_3300_3400_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 1075, nodes: 763309
Average data length: 1.32, total dup: 660601, max dup: 44
Fill distribution:
0 - 19% = 1
20 - 39% = 26
40 - 59% = 850
60 - 79% = 110
80 - 99% = 88

XQS_1000_1200 (264)
Primary pointer page: 667, Index root page: 668
Average record length: 78.35, total records: 1022651
Average version length: 80.80, total versions: 30362, max versions: 1
Data pages: 15275, data page slots: 15275, average fill: 81%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 6310
80 - 99% = 8964

Index XQS_1000_1200_DOC_ID (0)
Depth: 3, leaf buckets: 1129, nodes: 1022651
Average data length: 0.16, total dup: 999535, max dup: 159
Fill distribution:
0 - 19% = 8
20 - 39% = 0
40 - 59% = 829
60 - 79% = 232
80 - 99% = 60

Index XQS_1000_1200_RCV_ID (2)
Depth: 3, leaf buckets: 796, nodes: 1022651
Average data length: 0.72, total dup: 898356, max dup: 49
Fill distribution:
0 - 19% = 1
20 - 39% = 1
40 - 59% = 109
60 - 79% = 11
80 - 99% = 674

Index XQS_1000_1200_SND_ID (1)
Depth: 3, leaf buckets: 1285, nodes: 1022651
Average data length: 0.74, total dup: 895753, max dup: 29
Fill distribution:
0 - 19% = 10
20 - 39% = 0
40 - 59% = 933
60 - 79% = 286
80 - 99% = 56

XQS_1000_3300 (266)
Primary pointer page: 678, Index root page: 679
Average record length: 68.63, total records: 624416
Average version length: 80.14, total versions: 90591, max versions: 1
Data pages: 9546, data page slots: 9559, average fill: 80%
Fill distribution:
0 - 19% = 72
20 - 39% = 134
40 - 59% = 137
60 - 79% = 4840
80 - 99% = 4363

Index XQS_1000_3300_DOC_ID (0)
Depth: 3, leaf buckets: 636, nodes: 624416
Average data length: 0.15, total dup: 610854, max dup: 298
Fill distribution:
0 - 19% = 0
20 - 39% = 5
40 - 59% = 350
60 - 79% = 226
80 - 99% = 55

Index XQS_1000_3300_RCV_ID (2)
Depth: 3, leaf buckets: 514, nodes: 624416
Average data length: 0.83, total dup: 541537, max dup: 14
Fill distribution:
0 - 19% = 8
20 - 39% = 19
40 - 59% = 70
60 - 79% = 2
80 - 99% = 415

Index XQS_1000_3300_SND_ID (1)
Depth: 3, leaf buckets: 759, nodes: 624416
Average data length: 0.72, total dup: 552378, max dup: 59
Fill distribution:
0 - 19% = 33
20 - 39% = 8
40 - 59% = 400
60 - 79% = 277
80 - 99% = 41

XQS_1200_2000 (265)
Primary pointer page: 673, Index root page: 674
Average record length: 76.68, total records: 1066551
Average version length: 81.74, total versions: 53970, max versions: 1
Data pages: 15933, data page slots: 15933, average fill: 81%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 7311
80 - 99% = 8622

Index XQS_1200_2000_DOC_ID (0)
Depth: 3, leaf buckets: 1105, nodes: 1066551
Average data length: 0.05, total dup: 1060208, max dup: 943
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 725
60 - 79% = 287
80 - 99% = 92

Index XQS_1200_2000_RCV_ID (2)
Depth: 3, leaf buckets: 789, nodes: 1066551
Average data length: 0.69, total dup: 941836, max dup: 49
Fill distribution:
0 - 19% = 2
20 - 39% = 0
40 - 59% = 44
60 - 79% = 3
80 - 99% = 740

Index XQS_1200_2000_SND_ID (1)
Depth: 3, leaf buckets: 1253, nodes: 1066551
Average data length: 0.70, total dup: 942414, max dup: 170
Fill distribution:
0 - 19% = 5
20 - 39% = 1
40 - 59% = 761
60 - 79% = 364
80 - 99% = 122

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: 71.85, total records: 782864
Average version length: 80.27, total versions: 84354, max versions: 1
Data pages: 12146, data page slots: 12182, average fill: 78%
Fill distribution:
0 - 19% = 161
20 - 39% = 257
40 - 59% = 218
60 - 79% = 5559
80 - 99% = 5951

Index XQS_2100_3300_DOC_ID (0)
Depth: 3, leaf buckets: 727, nodes: 782864
Average data length: 0.05, total dup: 778390, max dup: 646
Fill distribution:
0 - 19% = 2
20 - 39% = 11
40 - 59% = 275
60 - 79% = 293
80 - 99% = 146

Index XQS_2100_3300_RCV_ID (2)
Depth: 3, leaf buckets: 740, nodes: 782864
Average data length: 0.86, total dup: 676409, max dup: 14
Fill distribution:
0 - 19% = 16
20 - 39% = 29
40 - 59% = 247
60 - 79% = 3
80 - 99% = 445

Index XQS_2100_3300_SND_ID (1)
Depth: 3, leaf buckets: 831, nodes: 782864
Average data length: 0.70, total dup: 693861, max dup: 81
Fill distribution:
0 - 19% = 0
20 - 39% = 15
40 - 59% = 275
60 - 79% = 374
80 - 99% = 167

XQS_3300_3400 (269)
Primary pointer page: 697, Index root page: 698
Average record length: 59.38, total records: 343953
Average version length: 68.30, total versions: 45446, max versions: 1
Data pages: 4631, data page slots: 4631, average fill: 80%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 3526
80 - 99% = 1104

Index XQS_3300_3400_DOC_ID (0)
Depth: 2, leaf buckets: 310, nodes: 343953
Average data length: 0.22, total dup: 333286, max dup: 242
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 109
60 - 79% = 126
80 - 99% = 75

Index XQS_3300_3400_SND_ID (1)
Depth: 2, leaf buckets: 360, nodes: 343953
Average data length: 0.81, total dup: 299689, max dup: 44
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 135
60 - 79% = 145
80 - 99% = 80

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 7970 ms, from 2:28:32.87 to 2:28:40.84.

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 482479 17596 3.6469
DOC_LIST 82428 10055 12.1985
DOC_STATES 4 0 0.0000
FB_ERRORS 758 0 0.0000
INVNT_SALDO 400 96676 24169.0000
INVNT_TURNOVER_LOG 197626 196948 99.6569
MONEY_SALDO 49 4619 9426.5306
MONEY_TURNOVER_LOG 9315 9282 99.6457
OPTYPES 9 0 0.0000
PDISTR 153312 122061 79.6160
PERF_ESTIMATED 24356 2 0.0082
PERF_LOG 1271139 1 0.0000
PSTORNED 89029 5584 6.2721
RULES_FOR_PDISTR 4 0 0.0000
RULES_FOR_QDISTR 7 0 0.0000
SEMAPHORES 4 1020 25500.0000
SETTINGS 114 0 0.0000
TRACE_STAT 160 0 0.0000
WARES 400 0 0.0000
WARE_GROUPS 8 0 0.0000
XQD_1000_1200 320850 320725 99.9610
XQD_1000_3300 307163 92054 29.9691
XQD_1200_2000 374188 369689 98.7976
XQD_2000_3300 1071783 770402 71.8804
XQD_2100_3300 261199 248509 95.1416
XQD_3300_3400 763309 363306 47.5961
XQS_1000_1200 1022651 30362 2.9689
XQS_1000_3300 624416 90591 14.5081
XQS_1200_2000 1066551 53970 5.0602
XQS_2100_3300 782864 84354 10.7750
XQS_3300_3400 343953 45446 13.2128
Z_USED_VIEWS 32 0 0.0000
Done for 2570 ms, from 2:28:50.31 to 2:28:52.88.

Database validation

02:28:53.04 Validation started

02:28:53.04 Relation 140 (DOC_LIST)
02:28:53.04 process pointer page 0 of 1
02:28:53.10 Index 1 (PK_DOC_LIST)
02:28:53.12 Index 2 (DOC_LIST_ID_DESC)
02:28:53.15 Index 3 (FK_DOC_LIST_AGENTS)
02:28:53.18 Relation 140 (DOC_LIST) is ok

02:28:53.18 Relation 141 (DOC_DATA)
02:28:53.18 process pointer page 0 of 3
02:28:53.27 process pointer page 1 of 3
02:28:53.36 process pointer page 2 of 3
02:28:53.44 Index 2 (DOC_DATA_DOC_WARE_UNQ)
02:28:53.65 Index 3 (DOC_DATA_ID_DESC)
02:28:53.82 Index 4 (FK_DOC_DATA_DOC_LIST)
02:28:53.94 Relation 141 (DOC_DATA) is ok

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

02:28:54.05 Relation 150 (PDISTR)
02:28:54.05 process pointer page 0 of 1
02:28:54.24 Index 2 (PDISTR_SND_ID)
02:28:54.29 Index 3 (PDISTR_SNDOP_RCVOP_SNDID_ASC)
02:28:54.32 Index 4 (PDISTR_SNDOP_RCVOP_SNDID_DESC)
02:28:54.36 Index 5 (PDISTR_AGENT_ID)
02:28:54.41 Relation 150 (PDISTR) is ok

02:28:54.41 Relation 151 (PSTORNED)
02:28:54.41 process pointer page 0 of 1
02:28:54.47 Index 2 (PSTORNED_SND_ID)
02:28:54.49 Index 3 (PSTORNED_RCV_ID)
02:28:54.50 Relation 151 (PSTORNED) is ok

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

02:28:54.50 Relation 157 (INVNT_TURNOVER_LOG)
02:28:54.50 process pointer page 0 of 2
02:28:54.68 process pointer page 1 of 2
02:28:54.74 Index 1 (INVNT_TURNOVER_LOG_WARE_DD_ID)
02:28:54.88 Relation 157 (INVNT_TURNOVER_LOG) is ok

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

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

02:28:54.89 Relation 162 (PERF_LOG)
02:28:54.89 process pointer page 0 of 16
02:28:54.97 process pointer page 1 of 16
02:28:55.05 process pointer page 2 of 16
02:28:55.11 process pointer page 3 of 16
02:28:55.19 process pointer page 4 of 16
02:28:55.27 process pointer page 5 of 16
02:28:55.35 process pointer page 6 of 16
02:28:55.42 process pointer page 7 of 16
02:28:55.50 process pointer page 8 of 16
02:28:55.58 process pointer page 9 of 16
02:28:55.64 process pointer page 10 of 16
02:28:55.72 process pointer page 11 of 16
02:28:55.80 process pointer page 12 of 16
02:28:55.86 process pointer page 13 of 16
02:28:55.94 process pointer page 14 of 16
02:28:56.00 process pointer page 15 of 16
02:28:56.02 Index 1 (PERF_LOG_DTS_BEG_DESC)
02:28:56.42 Index 2 (PERF_LOG_UNIT)
02:28:57.03 Index 3 (PERF_LOG_TRN_DESC)
02:28:57.39 Index 4 (PERF_LOG_GDSCODE)
02:28:57.70 Relation 162 (PERF_LOG) is ok

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

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

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

02:28:57.70 Relation 258 (XQD_1000_1200)
02:28:57.70 process pointer page 0 of 3
02:28:57.86 process pointer page 1 of 3
02:28:58.03 process pointer page 2 of 3
02:28:58.08 Index 1 (XQD_1000_1200_WARE_SOP_ROP_SND)
02:28:58.17 Relation 258 (XQD_1000_1200) is ok

02:28:58.17 Relation 259 (XQD_1200_2000)
02:28:58.17 process pointer page 0 of 3
02:28:58.34 process pointer page 1 of 3
02:28:58.51 process pointer page 2 of 3
02:28:58.61 Index 1 (XQD_1200_2000_WARE_SOP_ROP_SND)
02:28:58.73 Relation 259 (XQD_1200_2000) is ok

02:28:58.73 Relation 260 (XQD_1000_3300)
02:28:58.73 process pointer page 0 of 3
02:28:58.86 process pointer page 1 of 3
02:28:58.97 process pointer page 2 of 3
02:28:58.98 Index 1 (XQD_1000_3300_WARE_SOP_ROP_SND)
02:28:59.07 Index 2 (XQD_1000_3300_DOC)
02:28:59.14 Relation 260 (XQD_1000_3300) is ok

02:28:59.14 Relation 261 (XQD_2000_3300)
02:28:59.14 process pointer page 0 of 8
02:28:59.31 process pointer page 1 of 8
02:28:59.48 process pointer page 2 of 8
02:28:59.64 process pointer page 3 of 8
02:28:59.81 process pointer page 4 of 8
02:28:59.96 process pointer page 5 of 8
02:29:00.07 process pointer page 6 of 8
02:29:00.18 process pointer page 7 of 8
02:29:00.23 Index 1 (XQD_2000_3300_WARE_SOP_ROP_SND)
02:29:00.57 Relation 261 (XQD_2000_3300) is ok

02:29:00.57 Relation 262 (XQD_2100_3300)
02:29:00.57 process pointer page 0 of 3
02:29:00.73 process pointer page 1 of 3
02:29:00.88 process pointer page 2 of 3
02:29:00.90 Index 1 (XQD_2100_3300_WARE_SOP_ROP_SND)
02:29:01.01 Relation 262 (XQD_2100_3300) is ok

02:29:01.01 Relation 263 (XQD_3300_3400)
02:29:01.01 process pointer page 0 of 6
02:29:01.15 process pointer page 1 of 6
02:29:01.31 process pointer page 2 of 6
02:29:01.43 process pointer page 3 of 6
02:29:01.48 process pointer page 4 of 6
02:29:01.52 process pointer page 5 of 6
02:29:01.54 Index 1 (XQD_3300_3400_WARE_SOP_ROP_SND)
02:29:01.63 Relation 263 (XQD_3300_3400) is ok

02:29:01.63 Relation 264 (XQS_1000_1200)
02:29:01.63 process pointer page 0 of 8
02:29:01.66 process pointer page 1 of 8
02:29:01.70 process pointer page 2 of 8
02:29:01.73 process pointer page 3 of 8
02:29:01.74 process pointer page 4 of 8
02:29:01.79 process pointer page 5 of 8
02:29:01.85 process pointer page 6 of 8
02:29:01.95 process pointer page 7 of 8
02:29:02.02 Index 1 (XQS_1000_1200_DOC_ID)
02:29:02.32 Index 2 (XQS_1000_1200_SND_ID)
02:29:02.63 Index 3 (XQS_1000_1200_RCV_ID)
02:29:02.88 Relation 264 (XQS_1000_1200) is ok

02:29:02.88 Relation 265 (XQS_1200_2000)
02:29:02.88 process pointer page 0 of 9
02:29:02.97 process pointer page 1 of 9
02:29:03.05 process pointer page 2 of 9
02:29:03.15 process pointer page 3 of 9
02:29:03.24 process pointer page 4 of 9
02:29:03.32 process pointer page 5 of 9
02:29:03.41 process pointer page 6 of 9
02:29:03.52 process pointer page 7 of 9
02:29:03.61 process pointer page 8 of 9
02:29:03.63 Index 1 (XQS_1200_2000_DOC_ID)
02:29:03.93 Index 2 (XQS_1200_2000_SND_ID)
02:29:04.24 Index 3 (XQS_1200_2000_RCV_ID)
02:29:04.49 Relation 265 (XQS_1200_2000) is ok

02:29:04.49 Relation 266 (XQS_1000_3300)
02:29:04.49 process pointer page 0 of 5
02:29:04.58 process pointer page 1 of 5
02:29:04.69 process pointer page 2 of 5
02:29:04.78 process pointer page 3 of 5
02:29:04.88 process pointer page 4 of 5
02:29:04.97 Index 1 (XQS_1000_3300_DOC_ID)
02:29:05.14 Index 2 (XQS_1000_3300_SND_ID)
02:29:05.33 Index 3 (XQS_1000_3300_RCV_ID)
02:29:05.44 Relation 266 (XQS_1000_3300) is ok

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

02:29:05.44 Relation 268 (XQS_2100_3300)
02:29:05.44 process pointer page 0 of 7
02:29:05.47 process pointer page 1 of 7
02:29:05.50 process pointer page 2 of 7
02:29:05.55 process pointer page 3 of 7
02:29:05.64 process pointer page 4 of 7
02:29:05.74 process pointer page 5 of 7
02:29:05.83 process pointer page 6 of 7
02:29:05.86 Index 1 (XQS_2100_3300_DOC_ID)
02:29:06.11 Index 2 (XQS_2100_3300_SND_ID)
02:29:06.39 Index 3 (XQS_2100_3300_RCV_ID)
02:29:06.58 Relation 268 (XQS_2100_3300) is ok

02:29:06.58 Relation 269 (XQS_3300_3400)
02:29:06.59 process pointer page 0 of 3
02:29:06.69 process pointer page 1 of 3
02:29:06.80 process pointer page 2 of 3
02:29:06.83 Index 1 (XQS_3300_3400_DOC_ID)
02:29:06.92 Index 2 (XQS_3300_3400_SND_ID)
02:29:07.00 Relation 269 (XQS_3300_3400) is ok

02:29:07.00 Relation 276 (TRACE_STAT)
02:29:07.00 process pointer page 0 of 1
02:29:07.00 Relation 276 (TRACE_STAT) is ok

02:29:07.00 Validation finished
Done for 14110 ms, from 2:28:52.91 to 2:29:07.02.

Differences between old and current firebird.log

2:29:07.58. 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-13 02:29 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-13 2:29:09.64 - end of report, text file: e:\temp\logs.oltp25\oltp25.report.txt, html: e:\temp\logs.oltp25\oltp25.report.html