Firebird 2.5 Performance Logs

Generated by E:\OLTP-EMUL\src\oltp_isql_run_worker.bat, ISQL session #1 of total launched 100. 2016-09-27 2:26:35.72.
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.7.27025 Firebird 2.5
Server implementation: Firebird/x86-64/Windows NT
FB_ARCHITECTURE DB_NAME FORCED_WRITES SWEEP_INT PAGE_BUFFERS PAGE_SIZE
Classic 2.5.7 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-09-27 02:26:15.2140 -1 <null> 127.0.0.1 86632 4217 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: 275.99 170260 49679 2016-09-26 23:26 2016-09-27 02:26
customer order: creation 24.69 1099 4445 2016-09-26 23:26 2016-09-27 02:26
customer order: refuse 1.94 49 349 2016-09-26 23:26 2016-09-27 02:26
order to supplier: creation 6.42 9901 1156 2016-09-26 23:26 2016-09-27 02:26
order to supplier: removal 0.11 322314 18 2016-09-26 23:26 2016-09-27 02:26
invoice (draft): creation 6.66 3236 1199 2016-09-26 23:26 2016-09-27 02:26
invoice (draft): removal 0.76 107 130 2016-09-26 23:26 2016-09-27 02:26
invoice accept: apply 1.43 2549195 255 2016-09-26 23:26 2016-09-27 02:26
invoice accept: cancel 0.28 301648 50 2016-09-26 23:26 2016-09-27 02:26
customer reserve: creation 190.97 20060 34375 2016-09-26 23:26 2016-09-27 02:26
customer reserve: removal 7.43 18432 1337 2016-09-26 23:26 2016-09-27 02:26
realization accept: apply 11.64 61 2096 2016-09-26 23:26 2016-09-27 02:26
realization accept: cancel 3.16 55 569 2016-09-26 23:26 2016-09-27 02:26
payment from customer: creation 8.56 121 1540 2016-09-26 23:26 2016-09-27 02:26
payment from customer: removal 0.72 23 126 2016-09-26 23:26 2016-09-27 02:26
payment to supplier: creation 7.26 258 1306 2016-09-26 23:26 2016-09-27 02:26
payment to supplier: removal 0.47 19 85 2016-09-26 23:26 2016-09-27 02:26
service: total inventory turnovers 1.87 6089 334 2016-09-26 23:26 2016-09-27 02:26
service: total monetary turnovers 1.67 13 299 2016-09-26 23:26 2016-09-27 02:26
service: refresh index statistics 0.06 2254 10 2016-09-26 23:26 2016-09-27 02:26
Done for 4760 ms, from 2:26:51.21 to 2:26:55.97.

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 554 10669 9972 697 6.53 23:26:16 23:44:15
interval # 2, overall 2 245 4513 4403 110 2.44 23:44:16 00:02:15
interval # 3, overall 3 201 3681 3611 70 1.90 00:02:16 00:20:15
interval # 4, overall 4 212 3925 3812 113 2.88 00:20:16 00:38:15
interval # 5, overall 5 208 3853 3736 117 3.04 00:38:16 00:56:15
interval # 6, overall 6 222 4110 3995 115 2.80 00:56:16 01:14:15
interval # 7, overall 7 236 4355 4254 101 2.32 01:14:16 01:32:15
interval # 8, overall 8 370 6963 6660 303 4.35 01:32:16 01:50:15
interval # 9, overall 9 345 6384 6215 169 2.65 01:50:16 02:08:15
interval # 10, overall 10 168 3315 3021 294 8.87 02:08:16 02:26:15
Done for 6440 ms, from 2:27:00.17 to 2:27:06.61.

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 1998.48 0.6574 1.3315 838 95
WARM_TIME 2 1877.58 0.7088 1.2231 704 84
WARM_TIME 3 1687.39 0.9075 1.0750 470 74
WARM_TIME 4 1436.56 0.9541 1.0412 421 66
WARM_TIME 5 1261.39 0.9487 1.0621 286 55
WARM_TIME 6 1191.74 0.9369 1.0610 291 64
WARM_TIME 7 1153.54 0.9397 1.0595 345 56
WARM_TIME 8 1123.22 0.9522 1.0591 360 59
WARM_TIME 9 1110.79 0.9520 1.0454 408 61
WARM_TIME 10 1069.78 0.9771 1.0324 435 61
TEST_TIME 11 1020.40 0.9841 1.0159 396 63
TEST_TIME 12 976.75 0.9730 1.0298 530 54
TEST_TIME 13 947.83 0.9797 1.0190 498 51
TEST_TIME 14 924.85 0.9698 1.0307 814 47
TEST_TIME 15 914.00 0.9735 1.0264 808 35
TEST_TIME 16 903.88 0.9730 1.0255 808 26
TEST_TIME 17 884.17 0.9998 1.0002 7 1
TEST_TIME 18 842.26 0.9958 1.0041 114 4
TEST_TIME 19 803.21 0.9976 1.0023 77 2
TEST_TIME 20 768.21 0.9956 1.0047 62 4
TEST_TIME 21 741.99 0.9940 1.0067 168 5
TEST_TIME 22 715.56 0.9965 1.0020 69 3
TEST_TIME 23 689.35 0.9950 1.0047 105 3
TEST_TIME 24 667.05 0.9962 1.0022 67 2
TEST_TIME 25 653.31 0.9825 1.0073 201 6
TEST_TIME 26 634.24 0.9977 1.0033 54 3
TEST_TIME 27 618.46 0.9963 1.0061 91 3
TEST_TIME 28 600.30 0.9996 1.0004 16 1
TEST_TIME 29 583.40 0.9960 1.0039 102 4
TEST_TIME 30 569.55 0.9941 1.0040 89 5
TEST_TIME 31 554.30 0.9984 1.0015 61 1
TEST_TIME 32 538.41 0.9989 1.0010 39 1
TEST_TIME 33 524.41 0.9986 1.0014 49 1
TEST_TIME 34 514.74 0.9902 1.0051 108 3
TEST_TIME 35 510.09 0.9939 1.0040 127 4
TEST_TIME 36 505.56 0.9904 1.0078 104 4
TEST_TIME 37 497.38 0.9995 1.0004 20 1
TEST_TIME 38 486.18 0.9966 1.0080 52 3
TEST_TIME 39 479.93 0.9982 1.0069 18 3
TEST_TIME 41 464.84 0.9993 1.0003 15 2
TEST_TIME 42 455.58 0.9964 1.0089 92 4
TEST_TIME 43 451.25 0.9950 1.0052 191 4
TEST_TIME 44 445.08 0.9960 1.0014 36 2
TEST_TIME 45 438.90 0.9989 1.0010 46 1
TEST_TIME 55 362.73 0.9956 1.0006 22 2
TEST_TIME 56 356.68 0.9995 1.0004 24 2
TEST_TIME 57 350.68 0.9997 1.0002 14 1
TEST_TIME 59 341.58 0.9998 1.0001 8 1
TEST_TIME 60 336.71 0.9977 1.0053 56 2
TEST_TIME 61 335.34 0.9929 1.0060 165 4
TEST_TIME 62 334.21 0.9932 1.0049 195 3
TEST_TIME 63 332.32 0.9996 1.0003 15 2
TEST_TIME 64 327.44 0.9994 1.0004 27 2
TEST_TIME 65 323.56 0.9969 1.0018 11 2
TEST_TIME 67 315.06 0.9995 1.0004 23 1
TEST_TIME 68 312.62 0.9934 1.0023 38 2
TEST_TIME 69 309.00 0.9993 1.0006 35 2
TEST_TIME 70 307.21 1.0000 1.0000 2 1
TEST_TIME 71 304.34 0.9996 1.0004 20 1
TEST_TIME 72 306.27 0.9877 1.0118 57 5
TEST_TIME 73 308.51 0.9907 1.0058 179 5
TEST_TIME 74 306.17 0.9998 1.0001 10 1
TEST_TIME 75 302.24 0.9997 1.0003 16 2
TEST_TIME 76 300.17 1.0000 1.0000 2 1
TEST_TIME 77 296.45 0.9998 1.0001 6 1
TEST_TIME 78 297.17 0.9906 1.0028 74 3
TEST_TIME 79 297.65 0.9885 1.0074 103 6
TEST_TIME 80 296.21 0.9997 1.0003 14 1
TEST_TIME 82 293.15 0.9959 1.0056 55 4
TEST_TIME 83 295.29 0.9863 1.0095 120 5
TEST_TIME 84 295.16 0.9980 1.0019 116 2
TEST_TIME 85 292.83 0.9981 1.0020 101 2
TEST_TIME 86 291.16 0.9960 1.0010 17 2
TEST_TIME 87 289.23 0.9962 1.0014 75 2
TEST_TIME 88 286.90 0.9981 1.0055 45 4
TEST_TIME 89 286.01 0.9973 1.0071 84 4
TEST_TIME 90 285.05 0.9993 1.0006 36 2
TEST_TIME 91 282.46 0.9990 1.0009 56 1
TEST_TIME 92 281.03 0.9997 1.0002 14 1
TEST_TIME 93 278.54 0.9984 1.0010 14 3
TEST_TIME 94 276.00 0.9999 1.0001 6 1
TEST_TIME 95 274.21 0.9960 1.0032 34 2
TEST_TIME 96 273.87 0.9947 1.0036 71 3
TEST_TIME 98 272.24 0.9895 1.0020 96 2
TEST_TIME 99 270.10 0.9998 1.0001 7 1
TEST_TIME 100 269.69 0.9996 1.0003 23 1
TEST_TIME 101 269.23 0.9952 1.0024 48 3
TEST_TIME 102 267.41 0.9994 1.0005 35 2
TEST_TIME 103 270.17 0.9808 1.0050 175 7
TEST_TIME 104 271.15 0.9918 1.0089 159 6
TEST_TIME 105 271.82 0.9969 1.0048 57 2
TEST_TIME 106 270.75 0.9992 1.0008 47 1
TEST_TIME 107 268.75 0.9988 1.0009 46 3
TEST_TIME 108 271.00 0.9911 1.0067 76 4
TEST_TIME 109 273.13 0.9897 1.0051 93 4
TEST_TIME 110 274.73 0.9966 1.0078 96 5
TEST_TIME 111 274.69 0.9989 1.0011 71 3
TEST_TIME 112 272.79 0.9991 1.0088 36 2
TEST_TIME 113 273.84 0.9991 1.0008 52 2
TEST_TIME 114 272.20 0.9983 1.0016 92 3
TEST_TIME 115 271.96 0.9940 1.0035 64 3
TEST_TIME 116 271.54 0.9964 1.0016 39 2
TEST_TIME 117 270.58 0.9994 1.0005 40 3
TEST_TIME 118 271.47 0.9888 1.0074 76 4
TEST_TIME 119 271.76 0.9979 1.0010 67 4
TEST_TIME 120 269.80 1.0000 1.0000 2 1
TEST_TIME 122 267.43 0.9975 1.0071 28 3
TEST_TIME 123 267.78 0.9977 1.0031 34 2
TEST_TIME 124 267.90 0.9946 1.0040 78 3
TEST_TIME 125 267.27 0.9983 1.0013 66 3
TEST_TIME 126 268.38 0.9893 1.0061 69 3
TEST_TIME 127 268.52 0.9977 1.0042 55 4
TEST_TIME 128 267.79 0.9991 1.0008 72 4
TEST_TIME 129 266.29 0.9987 1.0034 87 4
TEST_TIME 130 265.23 0.9997 1.0003 23 2
TEST_TIME 131 264.99 0.9935 1.0053 62 5
TEST_TIME 132 264.47 0.9997 1.0002 22 3
TEST_TIME 133 262.56 1.0000 1.0000 1 1
TEST_TIME 134 262.54 0.9948 1.0040 36 3
TEST_TIME 135 261.76 0.9999 1.0001 8 1
TEST_TIME 136 262.82 0.9888 1.0049 54 3
TEST_TIME 137 263.18 0.9998 1.0002 16 1
TEST_TIME 138 263.27 0.9992 1.0019 7 2
TEST_TIME 139 264.51 0.9981 1.0013 100 3
TEST_TIME 140 271.88 0.9672 1.0100 222 8
TEST_TIME 141 272.72 0.9998 1.0001 13 2
TEST_TIME 142 272.53 0.9938 1.0032 122 4
TEST_TIME 143 272.25 0.9972 1.0047 73 5
TEST_TIME 144 274.76 0.9886 1.0095 311 6
TEST_TIME 145 277.24 0.9936 1.0109 77 5
TEST_TIME 146 278.96 0.9978 1.0022 94 4
TEST_TIME 147 280.23 0.9909 1.0183 208 6
TEST_TIME 148 284.01 0.9980 1.0028 63 4
TEST_TIME 149 284.02 0.9966 1.0020 65 4
TEST_TIME 150 284.61 0.9933 1.0051 143 6
TEST_TIME 151 284.23 0.9998 1.0001 15 2
TEST_TIME 152 283.74 0.9987 1.0013 118 2
TEST_TIME 153 284.63 0.9916 1.0056 99 5
TEST_TIME 154 284.91 0.9981 1.0036 76 7
TEST_TIME 155 284.51 0.9985 1.0013 73 5
TEST_TIME 156 283.45 0.9986 1.0013 120 5
TEST_TIME 157 284.08 0.9928 1.0058 68 6
TEST_TIME 158 284.98 0.9963 1.0036 110 5
TEST_TIME 159 286.84 0.9938 1.0140 111 7
TEST_TIME 160 290.40 0.9953 1.0028 56 8
TEST_TIME 161 290.26 0.9971 1.0034 159 9
TEST_TIME 162 291.23 0.9938 1.0126 97 11
TEST_TIME 163 295.72 0.9971 1.0020 44 6
TEST_TIME 164 296.27 0.9941 1.0081 113 7
TEST_TIME 165 297.37 0.9983 1.0039 44 5
TEST_TIME 166 297.16 0.9986 1.0012 55 3
TEST_TIME 167 298.78 0.9898 1.0095 78 6
TEST_TIME 168 303.16 0.9890 1.0036 111 6
TEST_TIME 169 302.55 0.9997 1.0001 8 2
TEST_TIME 170 305.87 0.9940 1.0023 97 4
TEST_TIME 171 304.83 0.9999 1.0002 7 3
TEST_TIME 172 306.81 0.9932 1.0006 14 2
TEST_TIME 173 306.19 0.9982 1.0019 25 3
TEST_TIME 174 307.34 0.9958 1.0039 148 8
TEST_TIME 175 309.16 0.9923 1.0080 136 7
TEST_TIME 176 310.10 0.9992 1.0018 74 3
TEST_TIME 177 310.39 0.9952 1.0033 112 4
TEST_TIME 178 312.35 0.9967 1.0008 29 4
TEST_TIME 179 315.14 0.9864 1.0105 148 10
TEST_TIME 180 317.24 0.9982 1.0033 179 9
TEST_TIME 181 320.46 0.9877 1.0112 70 7
TEST_TIME 182 322.31 0.9999 1.0001 13 3
TEST_TIME 183 320.76 0.9999 1.0000 4 1
TEST_TIME 184 320.88 0.9942 1.0010 43 3
TEST_TIME 185 320.17 0.9978 1.0010 33 3
TEST_TIME 186 319.85 0.9989 1.0018 106 4
TEST_TIME 187 321.13 0.9924 1.0031 108 6
TEST_TIME 188 321.40 0.9969 1.0114 24 6
TEST_TIME 189 323.83 0.9985 1.0008 97 3
TEST_TIME 190 322.64 0.9992 1.0001 133 100
Done for 11310 ms, from 2:27:06.86 to 2:27:18.17.

Performance from TRACE for ISQL instance #1:

TRACED_DATA ITRV_NO SP_CLIENT_ORDER SP_CANCEL_CLIENT_ORDER SP_SUPPLIER_ORDER SP_CANCEL_SUPPLIER_ORDER SP_SUPPLIER_INVOICE SP_CANCEL_SUPPLIER_INVOICE SP_ADD_INVOICE_TO_STOCK SP_CANCEL_ADDING_INVOICE SP_CUSTOMER_RESERVE SP_CANCEL_CUSTOMER_RESERVE SP_RESERVE_WRITE_OFF SP_CANCEL_WRITE_OFF SP_PAY_FROM_CUSTOMER SP_CANCEL_PAY_FROM_CUSTOMER SP_PAY_TO_SUPPLIER SP_CANCEL_PAY_TO_SUPPLIER SRV_MAKE_INVNT_SALDO SRV_MAKE_MONEY_SALDO SRV_RECALC_IDX_STAT ITRV_BEG ITRV_END
fetches per second 1 4205 3584 1052 <null> 17756 34555 <null> <null> <null> <null> 21196 <null> 51653 4551 88013 <null> 67594 381429 <null> 23:26:16 23:44:15
fetches per second 2 30838 <null> 32766 <null> 17761 <null> 622 <null> <null> <null> 36258 <null> <null> <null> 151006 <null> <null> <null> <null> 23:44:16 00:02:15
marks per second 1 853 309 126 <null> 1773 5993 <null> <null> <null> <null> 3303 <null> 2143 604 1375 <null> 873 3131 <null> 23:26:16 23:44:15
marks per second 2 5642 <null> 2647 <null> 1147 <null> 55 <null> <null> <null> 4059 <null> <null> <null> 1461 <null> <null> <null> <null> 23:44:16 00:02:15
reads/fetches*100 1 17 8 24 <null> 7 4 <null> <null> <null> <null> 8 <null> 6 8 3 <null> 14 1 <null> 23:26:16 23:44:15
reads/fetches*100 2 5 <null> 9 <null> 6 <null> 18 <null> <null> <null> 6 <null> <null> <null> 3 <null> <null> <null> <null> 23:44:16 00:02:15
writes/marks*100 1 7 14 2 <null> 7 1 <null> <null> <null> <null> 6 <null> 3 0 2 <null> 31 0 <null> 23:26:16 23:44:15
writes/marks*100 2 12 <null> 7 <null> 7 <null> 1 <null> <null> <null> 7 <null> <null> <null> 5 <null> <null> <null> <null> 23:44:16 00:02:15
Done for 4760 ms, from 2:27:18.36 to 2:27:23.12.

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 40105 39026 1079 2.69 0 5928 28 1079 2016-09-26 23:26 2016-09-27 02:26
doc_list_biud 40150 39026 1124 2.79 0 656527 1311 1124 2016-09-26 23:26 2016-09-27 02:26
sp_add_invoice_to_stock 963 255 708 73.52 9126 9898358 2549195 708 2016-09-26 23:26 2016-09-27 02:26
sp_cancel_adding_invoice 94 50 44 46.80 156 3286614 301648 44 2016-09-26 23:26 2016-09-27 02:26
sp_cancel_client_order 374 349 25 6.68 0 1014 49 25 2016-09-26 23:26 2016-09-27 02:26
sp_cancel_customer_reserve 2144 1337 807 37.63 0 406458 18432 807 2016-09-26 23:26 2016-09-27 02:26
sp_cancel_pay_from_customer 130 126 4 3.07 0 188 23 4 2016-09-26 23:26 2016-09-27 02:26
sp_cancel_pay_to_supplier 85 85 0 0.00 0 63 19 0 2016-09-26 23:26 2016-09-27 02:26
sp_cancel_supplier_invoice 174 130 44 25.28 0 765 107 44 2016-09-26 23:26 2016-09-27 02:26
sp_cancel_supplier_order 85 18 67 78.82 47 3416703 322314 67 2016-09-26 23:26 2016-09-27 02:26
sp_cancel_write_off 855 569 286 33.45 0 1139 55 286 2016-09-26 23:26 2016-09-27 02:26
sp_client_order 4445 4445 0 0.00 0 28720 1099 0 2016-09-26 23:26 2016-09-27 02:26
sp_customer_reserve 34375 34375 0 0.00 141 85270 20060 0 2016-09-26 23:26 2016-09-27 02:26
sp_fill_shopping_cart 6800 6800 0 0.00 0 112835 1057 0 2016-09-26 23:26 2016-09-27 02:26
sp_fill_shopping_cart_clo_res 34375 34375 0 0.00 0 125 3 0 2016-09-26 23:26 2016-09-27 02:26
sp_get_clo_for_invoice 255 255 0 0.00 0 375 82 0 2016-09-26 23:26 2016-09-27 02:26
sp_kill_cost_storno 1116 830 286 25.62 0 172 6 286 2016-09-26 23:26 2016-09-27 02:26
sp_kill_qstorno_ret_qs2qd 5253 3391 1862 35.44 0 406397 7278 1862 2016-09-26 23:26 2016-09-27 02:26
sp_kill_qty_storno 5911 4804 1107 18.72 0 656527 10642 1107 2016-09-26 23:26 2016-09-27 02:26
sp_lock_selected_doc 4340 3330 1010 23.27 0 16 0 1010 2016-09-26 23:26 2016-09-27 02:26
sp_make_cost_storno 5197 5197 0 0.00 0 1466 82 0 2016-09-26 23:26 2016-09-27 02:26
sp_multiply_rows_for_pdistr 5197 5197 0 0.00 0 218 9 0 2016-09-26 23:26 2016-09-27 02:26
sp_multiply_rows_for_qdistr 4445 4445 0 0.00 0 27909 1033 0 2016-09-26 23:26 2016-09-27 02:26
sp_pay_from_customer 1540 1540 0 0.00 31 639 121 0 2016-09-26 23:26 2016-09-27 02:26
sp_pay_to_supplier 1306 1306 0 0.00 31 1638 258 0 2016-09-26 23:26 2016-09-27 02:26
sp_payment_common 2846 2846 0 0.00 0 328 34 0 2016-09-26 23:26 2016-09-27 02:26
sp_qd_handle_on_cancel_clo 374 349 25 6.68 0 999 28 25 2016-09-26 23:26 2016-09-27 02:26
sp_qd_handle_on_invoice_upd_sts 321 305 16 4.98 15 656527 86317 16 2016-09-26 23:26 2016-09-27 02:26
sp_qd_handle_on_reserve_upd_sts 2096 2096 0 0.00 0 1248 17 0 2016-09-26 23:26 2016-09-27 02:26
sp_reserve_write_off 2200 2096 104 4.72 0 1373 61 104 2016-09-26 23:26 2016-09-27 02:26
sp_supplier_invoice 1199 1199 0 0.00 31 113179 3236 0 2016-09-26 23:26 2016-09-27 02:26
sp_supplier_order 1156 1156 0 0.00 31 122242 9901 0 2016-09-26 23:26 2016-09-27 02:26
srv_find_qd_qs_mism 21501 20423 1078 5.01 0 5787 28 1078 2016-09-26 23:26 2016-09-27 02:26
srv_make_invnt_saldo 334 334 0 0.00 1107 19999 6089 0 2016-09-26 23:26 2016-09-27 02:26
srv_make_money_saldo 299 299 0 0.00 0 63 13 0 2016-09-26 23:26 2016-09-27 02:26
srv_recalc_idx_stat 10 10 0 0.00 1030 4368 2254 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-abend:sp_add_invoice_to_stock 708 708 0 0.00 0 172 8 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-abend:sp_cancel_adding_invoice 109 109 0 0.00 0 156 37 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-abend:sp_cancel_client_order 25 25 0 0.00 0 16 8 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-abend:sp_cancel_customer_reserve 12 12 0 0.00 0 16 9 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-abend:sp_cancel_pay_from_customer 4 4 0 0.00 0 78 35 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-abend:sp_cancel_supplier_invoice 44 44 0 0.00 0 62 9 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-abend:sp_cancel_supplier_order 116 116 0 0.00 0 125 27 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-abend:sp_cancel_write_off 4 4 0 0.00 0 0 0 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-abend:sp_reserve_write_off 104 104 0 0.00 0 78 7 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_add_invoice_to_stock 277 277 0 0.00 15 1919 231 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_cancel_adding_invoice 39 39 0 0.00 15 812 117 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_cancel_client_order 349 349 0 0.00 0 172 11 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_cancel_customer_reserve 142 142 0 0.00 0 78 14 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_cancel_pay_from_customer 126 126 0 0.00 0 140 12 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_cancel_pay_to_supplier 85 85 0 0.00 0 125 8 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_cancel_supplier_invoice 23 23 0 0.00 0 31 8 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_cancel_supplier_order 18 18 0 0.00 15 1732 189 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_cancel_write_off 197 197 0 0.00 0 156 13 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_client_order 4466 4466 0 0.00 0 281 16 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_customer_reserve 206 206 0 0.00 0 141 12 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_pay_from_customer 1540 1540 0 0.00 0 140 10 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_pay_to_supplier 1306 1306 0 0.00 0 202 10 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_reserve_write_off 2096 2096 0 0.00 0 219 13 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_supplier_invoice 1204 1204 0 0.00 0 218 16 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:sp_supplier_order 1203 1203 0 0.00 0 219 17 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:srv_make_invnt_saldo 334 334 0 0.00 0 47 4 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:srv_make_money_saldo 299 299 0 0.00 0 63 3 0 2016-09-26 23:26 2016-09-27 02:26
t$perf-norm:srv_recalc_idx_stat 10 10 0 0.00 0 62 23 0 2016-09-26 23:26 2016-09-27 02:26
v_add_invoice_to_stock 1926 510 1416 73.52 0 31 3 1416 2016-09-26 23:26 2016-09-27 02:26
v_all_customers 6672 6672 0 0.00 0 16 0 0 2016-09-26 23:26 2016-09-27 02:26
v_all_suppliers 4710 4710 0 0.00 0 16 0 0 2016-09-26 23:26 2016-09-27 02:26
v_all_wares 8890 8890 0 0.00 0 16 0 0 2016-09-26 23:26 2016-09-27 02:26
v_cancel_adding_invoice 162 78 84 51.85 0 63 11 84 2016-09-26 23:26 2016-09-27 02:26
v_cancel_client_order 748 698 50 6.68 0 16 0 50 2016-09-26 23:26 2016-09-27 02:26
v_cancel_customer_prepayment 260 252 8 3.07 0 31 1 8 2016-09-26 23:26 2016-09-27 02:26
v_cancel_customer_reserve 308 284 24 7.79 0 16 1 24 2016-09-26 23:26 2016-09-27 02:26
v_cancel_payment_to_supplier 170 170 0 0.00 0 16 1 0 2016-09-26 23:26 2016-09-27 02:26
v_cancel_supplier_invoice 134 46 88 65.67 0 16 2 88 2016-09-26 23:26 2016-09-27 02:26
v_cancel_supplier_order 170 36 134 78.82 0 16 1 134 2016-09-26 23:26 2016-09-27 02:26
v_cancel_write_off 402 394 8 1.99 0 31 3 8 2016-09-26 23:26 2016-09-27 02:26
v_max_id_clo_ord 1156 1156 0 0.00 0 1124 74 0 2016-09-26 23:26 2016-09-27 02:26
v_max_id_clo_res 205 205 0 0.00 0 16 2 0 2016-09-26 23:26 2016-09-27 02:26
v_max_id_ord_sup 1199 1199 0 0.00 0 437 10 0 2016-09-26 23:26 2016-09-27 02:26
v_max_non_paid_invoice 1306 1306 0 0.00 0 47 4 0 2016-09-26 23:26 2016-09-27 02:26
v_max_non_paid_realizn 1540 1540 0 0.00 0 32 6 0 2016-09-26 23:26 2016-09-27 02:26
v_min_id_clo_ord 1156 1156 0 0.00 0 1607 133 0 2016-09-26 23:26 2016-09-27 02:26
v_min_id_clo_res 205 205 0 0.00 0 16 2 0 2016-09-26 23:26 2016-09-27 02:26
v_min_id_ord_sup 1199 1199 0 0.00 0 40451 99 0 2016-09-26 23:26 2016-09-27 02:26
v_min_non_paid_invoice 1306 1306 0 0.00 0 312 34 0 2016-09-26 23:26 2016-09-27 02:26
v_min_non_paid_realizn 1540 1540 0 0.00 0 109 13 0 2016-09-26 23:26 2016-09-27 02:26
v_our_firm 2218 2218 0 0.00 0 16 0 0 2016-09-26 23:26 2016-09-27 02:26
v_reserve_write_off 4400 4192 208 4.72 0 31 1 208 2016-09-26 23:26 2016-09-27 02:26
x_lock_depdocs_on_canc_invoice 284 157 127 44.71 0 46 3 127 2016-09-26 23:26 2016-09-27 02:26
x_lock_depdocs_on_canc_sup_ord 85 18 67 78.82 0 16 6 67 2016-09-26 23:26 2016-09-27 02:26
x_make_qstorno_1000_1200 1162 1162 0 0.00 0 101759 6755 0 2016-09-26 23:26 2016-09-27 02:26
x_make_qstorno_1200_2000 1203 1203 0 0.00 15 51137 630 0 2016-09-26 23:26 2016-09-27 02:26
x_make_qstorno_2100_3300 34375 34375 0 0.00 141 85270 20039 0 2016-09-26 23:26 2016-09-27 02:26
Done for 16200 ms, from 2:27:30.71 to 2:27:46.91.

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 4 sp_kill_cost_storno 335544336 2016-09-26 23:27 2016-09-27 02:11
deadlock 2 sp_qd_handle_on_invoice_upd_sts 335544336 2016-09-26 23:45 2016-09-27 00:24
lock_conflict 4 sp_kill_cost_storno 335544345 2016-09-26 23:32 2016-09-27 02:11
lock_conflict 872 sp_lock_selected_doc 335544345 2016-09-26 23:26 2016-09-27 02:26
lock_conflict 25 sp_qd_handle_on_cancel_clo 335544345 2016-09-26 23:27 2016-09-27 02:22
lock_conflict 14 sp_qd_handle_on_invoice_upd_sts 335544345 2016-09-26 23:26 2016-09-27 02:20
lock_conflict 68 x_lock_depdocs_on_canc_invoice 335544345 2016-09-26 23:27 2016-09-27 02:11
lock_conflict 22 x_lock_depdocs_on_canc_sup_ord 335544345 2016-09-26 23:28 2016-09-27 02:23
Done for 2090 ms, from 2:27:47.27 to 2:27:49.36.

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 89157
MON$OLDEST_ACTIVE 89158
MON$OLDEST_SNAPSHOT 89158
MON$NEXT_TRANSACTION 89158
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 163560
MON$STAT_ID 1
MON$BACKUP_STATE 0
ISQL Version: WI-V2.5.7.27025 Firebird 2.5
Server version:
Firebird/x86-64/Windows NT (access method), version "WI-V2.5.7.27025 Firebird 2.5"
Firebird/x86-64/Windows NT (remote server), version "WI-V2.5.7.27025 Firebird 2.5/tcp (WIN-2BE1UI8LGDL)/P12"
Firebird/x86-64/Windows NT (remote interface), version "WI-V2.5.7.27025 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 93627
Page size 8192
ODS version 11.2
Oldest transaction 89159
Oldest active 89160
Oldest snapshot 89160
Next transaction 89161
Bumped transaction 1
Sequence number 0
Next attachment ID 4431
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.27, total records: 458184
Average version length: 46.07, total versions: 17853, max versions: 1
Data pages: 4893, data page slots: 4893, average fill: 73%
Fill distribution:
0 - 19% = 5
20 - 39% = 18
40 - 59% = 68
60 - 79% = 4726
80 - 99% = 76

Index DOC_DATA_DOC_WARE_UNQ (1)
Depth: 3, leaf buckets: 1501, nodes: 458184
Average data length: 11.71, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 156
20 - 39% = 36
40 - 59% = 593
60 - 79% = 68
80 - 99% = 648

Index DOC_DATA_ID_DESC (2)
Depth: 3, leaf buckets: 1344, nodes: 458184
Average data length: 5.97, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 14
40 - 59% = 1328
60 - 79% = 2
80 - 99% = 0

Index FK_DOC_DATA_DOC_LIST (3)
Depth: 2, leaf buckets: 437, nodes: 458184
Average data length: 0.87, total dup: 395741, max dup: 49
Fill distribution:
0 - 19% = 4
20 - 39% = 19
40 - 59% = 176
60 - 79% = 9
80 - 99% = 229

DOC_LIST (140)
Primary pointer page: 195, Index root page: 196
Average record length: 58.58, total records: 75534
Average version length: 50.51, total versions: 6841, max versions: 5
Data pages: 973, data page slots: 973, average fill: 78%
Fill distribution:
0 - 19% = 7
20 - 39% = 23
40 - 59% = 39
60 - 79% = 373
80 - 99% = 531

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

Index FK_DOC_LIST_AGENTS (2)
Depth: 2, leaf buckets: 65, nodes: 75534
Average data length: 0.01, total dup: 75484, max dup: 17802
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 48
60 - 79% = 13
80 - 99% = 4

Index PK_DOC_LIST (0)
Depth: 2, leaf buckets: 153, nodes: 75534
Average data length: 6.27, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 3
20 - 39% = 17
40 - 59% = 62
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: 62.97, total records: 400
Average version length: 13.64, total versions: 78692, max versions: 230
Data pages: 313, data page slots: 313, average fill: 96%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 1
80 - 99% = 312

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.29, total records: 132381
Average version length: 68.32, total versions: 131842, max versions: 1
Data pages: 1917, data page slots: 1917, average fill: 87%
Fill distribution:
0 - 19% = 10
20 - 39% = 15
40 - 59% = 56
60 - 79% = 212
80 - 99% = 1624

Index INVNT_TURNOVER_LOG_WARE_DD_ID (0)
Depth: 3, leaf buckets: 376, nodes: 132381
Average data length: 8.50, total dup: 11934, max dup: 5
Fill distribution:
0 - 19% = 2
20 - 39% = 2
40 - 59% = 218
60 - 79% = 124
80 - 99% = 30

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

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.19, total records: 6555
Average version length: 44.06, total versions: 6527, max versions: 1
Data pages: 74, data page slots: 74, average fill: 85%
Fill distribution:
0 - 19% = 2
20 - 39% = 2
40 - 59% = 6
60 - 79% = 2
80 - 99% = 62

Index MONEY_TURNOVER_LOG_AGENT_OPTYPE (0)
Depth: 2, leaf buckets: 5, nodes: 6555
Average data length: 0.15, total dup: 6462, max dup: 1464
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 2
60 - 79% = 2
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: 24.86, total records: 126920
Average version length: 32.29, total versions: 83298, max versions: 10
Data pages: 1356, data page slots: 1356, average fill: 85%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 4
60 - 79% = 356
80 - 99% = 996

Index PDISTR_AGENT_ID (4)
Depth: 2, leaf buckets: 105, nodes: 126920
Average data length: 0.01, total dup: 126871, max dup: 28266
Fill distribution:
0 - 19% = 0
20 - 39% = 4
40 - 59% = 58
60 - 79% = 17
80 - 99% = 26

Index PDISTR_SNDOP_RCVOP_SNDID_ASC (2)
Depth: 2, leaf buckets: 134, nodes: 126920
Average data length: 0.91, total dup: 113989, max dup: 34
Fill distribution:
0 - 19% = 0
20 - 39% = 2
40 - 59% = 81
60 - 79% = 30
80 - 99% = 21

Index PDISTR_SNDOP_RCVOP_SNDID_DESC (3)
Depth: 2, leaf buckets: 143, nodes: 126920
Average data length: 0.91, total dup: 113989, max dup: 34
Fill distribution:
0 - 19% = 0
20 - 39% = 5
40 - 59% = 90
60 - 79% = 33
80 - 99% = 15

Index PDISTR_SND_ID (1)
Depth: 2, leaf buckets: 125, nodes: 126920
Average data length: 0.68, total dup: 113989, max dup: 34
Fill distribution:
0 - 19% = 0
20 - 39% = 4
40 - 59% = 63
60 - 79% = 37
80 - 99% = 21

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

Index PERF_EST_MINUTE_SINCE_START (0)
Depth: 2, leaf buckets: 14, nodes: 20554
Average data length: 0.01, total dup: 20378, max dup: 1140
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 3
60 - 79% = 7
80 - 99% = 4

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

Index PERF_LOG_DTS_BEG_DESC (0)
Depth: 3, leaf buckets: 1441, nodes: 1141704
Average data length: 0.61, total dup: 622202, max dup: 32
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1127
60 - 79% = 208
80 - 99% = 106

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

Index PERF_LOG_TRN_DESC (2)
Depth: 3, leaf buckets: 1425, nodes: 1141704
Average data length: 0.40, total dup: 1070120, max dup: 5762
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 1212
60 - 79% = 122
80 - 99% = 90

Index PERF_LOG_UNIT (1)
Depth: 3, leaf buckets: 1259, nodes: 1141704
Average data length: 0.28, total dup: 1111623, max dup: 130090
Fill distribution:
0 - 19% = 1
20 - 39% = 1
40 - 59% = 893
60 - 79% = 178
80 - 99% = 186

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: 50.76, total records: 85264
Average version length: 54.61, total versions: 6210, max versions: 1
Data pages: 995, data page slots: 995, average fill: 77%
Fill distribution:
0 - 19% = 0
20 - 39% = 4
40 - 59% = 5
60 - 79% = 914
80 - 99% = 72

Index PSTORNED_RCV_ID (2)
Depth: 2, leaf buckets: 108, nodes: 85264
Average data length: 1.14, total dup: 70620, max dup: 144
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 104
60 - 79% = 4
80 - 99% = 0

Index PSTORNED_SND_ID (1)
Depth: 2, leaf buckets: 81, nodes: 85264
Average data length: 0.62, total dup: 77625, max dup: 93
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 42
60 - 79% = 34
80 - 99% = 5

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: 883, max versions: 459
Data pages: 4, data page slots: 4, average fill: 71%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
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.23, total records: 200
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 3, data page slots: 3, average fill: 62%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
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.23, total records: 224489
Average version length: 69.32, total versions: 223746, max versions: 1
Data pages: 3018, data page slots: 3018, average fill: 94%
Fill distribution:
0 - 19% = 6
20 - 39% = 1
40 - 59% = 0
60 - 79% = 3
80 - 99% = 3008

Index XQD_1000_1200_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 261, nodes: 224489
Average data length: 1.24, total dup: 196744, max dup: 29
Fill distribution:
0 - 19% = 13
20 - 39% = 18
40 - 59% = 65
60 - 79% = 95
80 - 99% = 70

XQD_1000_3300 (260)
Primary pointer page: 653, Index root page: 654
Average record length: 53.73, total records: 221709
Average version length: 69.18, total versions: 49822, max versions: 1
Data pages: 2996, data page slots: 2996, average fill: 82%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 1352
80 - 99% = 1643

Index XQD_1000_3300_DOC (1)
Depth: 2, leaf buckets: 201, nodes: 221709
Average data length: 0.16, total dup: 216664, max dup: 149
Fill distribution:
0 - 19% = 1
20 - 39% = 16
40 - 59% = 99
60 - 79% = 23
80 - 99% = 62

Index XQD_1000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 245, nodes: 221709
Average data length: 1.26, total dup: 194280, max dup: 29
Fill distribution:
0 - 19% = 2
20 - 39% = 0
40 - 59% = 79
60 - 79% = 94
80 - 99% = 70

XQD_1200_2000 (259)
Primary pointer page: 648, Index root page: 649
Average record length: 0.39, total records: 265716
Average version length: 69.29, total versions: 264254, max versions: 1
Data pages: 3567, data page slots: 3567, average fill: 94%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 4
80 - 99% = 3562

Index XQD_1200_2000_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 288, nodes: 265716
Average data length: 0.95, total dup: 240881, max dup: 99
Fill distribution:
0 - 19% = 6
20 - 39% = 8
40 - 59% = 94
60 - 79% = 111
80 - 99% = 69

XQD_2000_3300 (261)
Primary pointer page: 657, Index root page: 658
Average record length: 18.36, total records: 1005331
Average version length: 68.22, total versions: 738677, max versions: 1
Data pages: 13542, data page slots: 13542, average fill: 89%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 3325
80 - 99% = 10216

Index XQD_2000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 1274, nodes: 1005331
Average data length: 1.14, total dup: 887692, max dup: 97
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 955
60 - 79% = 158
80 - 99% = 161

XQD_2100_3300 (262)
Primary pointer page: 661, Index root page: 662
Average record length: 7.14, total records: 114237
Average version length: 69.07, total versions: 102442, max versions: 1
Data pages: 3820, data page slots: 4000, average fill: 40%
Fill distribution:
0 - 19% = 1850
20 - 39% = 378
40 - 59% = 228
60 - 79% = 558
80 - 99% = 806

Index XQD_2100_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 226, nodes: 114237
Average data length: 1.63, total dup: 96392, max dup: 97
Fill distribution:
0 - 19% = 0
20 - 39% = 103
40 - 59% = 104
60 - 79% = 18
80 - 99% = 1

XQD_3300_3400 (263)
Primary pointer page: 664, Index root page: 665
Average record length: 37.28, total records: 751235
Average version length: 68.35, total versions: 343723, max versions: 1
Data pages: 10534, data page slots: 10599, average fill: 82%
Fill distribution:
0 - 19% = 97
20 - 39% = 165
40 - 59% = 334
60 - 79% = 3707
80 - 99% = 6231

Index XQD_3300_3400_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 1059, nodes: 751235
Average data length: 1.31, total dup: 651269, max dup: 41
Fill distribution:
0 - 19% = 0
20 - 39% = 22
40 - 59% = 833
60 - 79% = 118
80 - 99% = 86

XQS_1000_1200 (264)
Primary pointer page: 667, Index root page: 668
Average record length: 78.41, total records: 967385
Average version length: 80.48, total versions: 23940, max versions: 1
Data pages: 14419, data page slots: 14419, average fill: 80%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 6653
80 - 99% = 7765

Index XQS_1000_1200_DOC_ID (0)
Depth: 3, leaf buckets: 1073, nodes: 967385
Average data length: 0.16, total dup: 945476, max dup: 138
Fill distribution:
0 - 19% = 10
20 - 39% = 0
40 - 59% = 773
60 - 79% = 244
80 - 99% = 46

Index XQS_1000_1200_RCV_ID (2)
Depth: 3, leaf buckets: 776, nodes: 967385
Average data length: 0.72, total dup: 851901, max dup: 49
Fill distribution:
0 - 19% = 3
20 - 39% = 0
40 - 59% = 148
60 - 79% = 16
80 - 99% = 609

Index XQS_1000_1200_SND_ID (1)
Depth: 3, leaf buckets: 1212, nodes: 967385
Average data length: 0.75, total dup: 847268, max dup: 37
Fill distribution:
0 - 19% = 9
20 - 39% = 0
40 - 59% = 872
60 - 79% = 279
80 - 99% = 52

XQS_1000_3300 (266)
Primary pointer page: 678, Index root page: 679
Average record length: 67.55, total records: 643220
Average version length: 80.16, total versions: 101753, max versions: 1
Data pages: 9787, data page slots: 9830, average fill: 80%
Fill distribution:
0 - 19% = 64
20 - 39% = 81
40 - 59% = 156
60 - 79% = 4805
80 - 99% = 4681

Index XQS_1000_3300_DOC_ID (0)
Depth: 3, leaf buckets: 648, nodes: 643220
Average data length: 0.15, total dup: 629651, max dup: 299
Fill distribution:
0 - 19% = 1
20 - 39% = 7
40 - 59% = 336
60 - 79% = 231
80 - 99% = 73

Index XQS_1000_3300_RCV_ID (2)
Depth: 3, leaf buckets: 520, nodes: 643220
Average data length: 0.82, total dup: 558567, max dup: 14
Fill distribution:
0 - 19% = 6
20 - 39% = 18
40 - 59% = 57
60 - 79% = 8
80 - 99% = 431

Index XQS_1000_3300_SND_ID (1)
Depth: 3, leaf buckets: 770, nodes: 643220
Average data length: 0.70, total dup: 570894, max dup: 56
Fill distribution:
0 - 19% = 33
20 - 39% = 6
40 - 59% = 402
60 - 79% = 272
80 - 99% = 57

XQS_1200_2000 (265)
Primary pointer page: 673, Index root page: 674
Average record length: 77.06, total records: 1002753
Average version length: 80.80, total versions: 41940, max versions: 1
Data pages: 14948, data page slots: 14948, average fill: 81%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 7305
80 - 99% = 7642

Index XQS_1200_2000_DOC_ID (0)
Depth: 3, leaf buckets: 1045, nodes: 1002753
Average data length: 0.05, total dup: 996742, max dup: 907
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 709
60 - 79% = 250
80 - 99% = 85

Index XQS_1200_2000_RCV_ID (2)
Depth: 3, leaf buckets: 753, nodes: 1002753
Average data length: 0.70, total dup: 885105, max dup: 49
Fill distribution:
0 - 19% = 3
20 - 39% = 1
40 - 59% = 58
60 - 79% = 5
80 - 99% = 686

Index XQS_1200_2000_SND_ID (1)
Depth: 3, leaf buckets: 1191, nodes: 1002753
Average data length: 0.70, total dup: 887411, max dup: 99
Fill distribution:
0 - 19% = 4
20 - 39% = 1
40 - 59% = 760
60 - 79% = 315
80 - 99% = 111

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: 70.27, total records: 780826
Average version length: 80.19, total versions: 98462, max versions: 1
Data pages: 12041, data page slots: 12126, average fill: 79%
Fill distribution:
0 - 19% = 123
20 - 39% = 177
40 - 59% = 300
60 - 79% = 5331
80 - 99% = 6110

Index XQS_2100_3300_DOC_ID (0)
Depth: 3, leaf buckets: 728, nodes: 780826
Average data length: 0.05, total dup: 776432, max dup: 777
Fill distribution:
0 - 19% = 2
20 - 39% = 8
40 - 59% = 286
60 - 79% = 300
80 - 99% = 132

Index XQS_2100_3300_RCV_ID (2)
Depth: 3, leaf buckets: 736, nodes: 780826
Average data length: 0.84, total dup: 676142, max dup: 14
Fill distribution:
0 - 19% = 25
20 - 39% = 37
40 - 59% = 213
60 - 79% = 8
80 - 99% = 453

Index XQS_2100_3300_SND_ID (1)
Depth: 3, leaf buckets: 826, nodes: 780826
Average data length: 0.68, total dup: 694118, max dup: 78
Fill distribution:
0 - 19% = 1
20 - 39% = 8
40 - 59% = 292
60 - 79% = 370
80 - 99% = 155

XQS_3300_3400 (269)
Primary pointer page: 697, Index root page: 698
Average record length: 57.27, total records: 328178
Average version length: 68.23, total versions: 53326, max versions: 1
Data pages: 4417, data page slots: 4417, average fill: 80%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 3116
80 - 99% = 1301

Index XQS_3300_3400_DOC_ID (0)
Depth: 2, leaf buckets: 294, nodes: 328178
Average data length: 0.22, total dup: 317938, max dup: 275
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 99
60 - 79% = 122
80 - 99% = 73

Index XQS_3300_3400_SND_ID (1)
Depth: 2, leaf buckets: 345, nodes: 328178
Average data length: 0.82, total dup: 285566, max dup: 44
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 127
60 - 79% = 153
80 - 99% = 65

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 18110 ms, from 2:27:49.90 to 2:28:08.01.

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 458184 17853 3.8964
DOC_LIST 75534 6841 9.0568
DOC_STATES 4 0 0.0000
FB_ERRORS 758 0 0.0000
INVNT_SALDO 400 78692 19673.0000
INVNT_TURNOVER_LOG 132381 131842 99.5928
MONEY_SALDO 49 4087 8340.8163
MONEY_TURNOVER_LOG 6555 6527 99.5728
OPTYPES 9 0 0.0000
PDISTR 126920 83298 65.6303
PERF_ESTIMATED 20554 2 0.0097
PERF_LOG 1141704 1 0.0000
PSTORNED 85264 6210 7.2832
RULES_FOR_PDISTR 4 0 0.0000
RULES_FOR_QDISTR 7 0 0.0000
SEMAPHORES 4 883 22075.0000
SETTINGS 114 0 0.0000
TRACE_STAT 200 0 0.0000
WARES 400 0 0.0000
WARE_GROUPS 8 0 0.0000
XQD_1000_1200 224489 223746 99.6690
XQD_1000_3300 221709 49822 22.4717
XQD_1200_2000 265716 264254 99.4497
XQD_2000_3300 1005331 738677 73.4759
XQD_2100_3300 114237 102442 89.6749
XQD_3300_3400 751235 343723 45.7543
XQS_1000_1200 967385 23940 2.4747
XQS_1000_3300 643220 101753 15.8193
XQS_1200_2000 1002753 41940 4.1824
XQS_2100_3300 780826 98462 12.6099
XQS_3300_3400 328178 53326 16.2491
Z_USED_VIEWS 32 0 0.0000
Done for 2840 ms, from 2:28:17.61 to 2:28:20.45.

Database validation

02:28:20.57 Validation started

02:28:20.57 Relation 140 (DOC_LIST)
02:28:20.57 process pointer page 0 of 1
02:28:20.62 Index 1 (PK_DOC_LIST)
02:28:20.65 Index 2 (DOC_LIST_ID_DESC)
02:28:20.67 Index 3 (FK_DOC_LIST_AGENTS)
02:28:20.70 Relation 140 (DOC_LIST) is ok

02:28:20.70 Relation 141 (DOC_DATA)
02:28:20.70 process pointer page 0 of 3
02:28:20.79 process pointer page 1 of 3
02:28:20.90 process pointer page 2 of 3
02:28:20.95 Index 2 (DOC_DATA_DOC_WARE_UNQ)
02:28:21.13 Index 3 (DOC_DATA_ID_DESC)
02:28:21.30 Index 4 (FK_DOC_DATA_DOC_LIST)
02:28:21.37 Relation 141 (DOC_DATA) is ok

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

02:28:21.41 Relation 150 (PDISTR)
02:28:21.41 process pointer page 0 of 1
02:28:21.55 Index 2 (PDISTR_SND_ID)
02:28:21.60 Index 3 (PDISTR_SNDOP_RCVOP_SNDID_ASC)
02:28:21.63 Index 4 (PDISTR_SNDOP_RCVOP_SNDID_DESC)
02:28:21.66 Index 5 (PDISTR_AGENT_ID)
02:28:21.69 Relation 150 (PDISTR) is ok

02:28:21.69 Relation 151 (PSTORNED)
02:28:21.69 process pointer page 0 of 1
02:28:21.76 Index 2 (PSTORNED_SND_ID)
02:28:21.77 Index 3 (PSTORNED_RCV_ID)
02:28:21.79 Relation 151 (PSTORNED) is ok

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

02:28:21.79 Relation 157 (INVNT_TURNOVER_LOG)
02:28:21.79 process pointer page 0 of 1
02:28:21.91 Index 1 (INVNT_TURNOVER_LOG_WARE_DD_ID)
02:28:21.94 Relation 157 (INVNT_TURNOVER_LOG) is ok

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

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

02:28:21.96 Relation 162 (PERF_LOG)
02:28:21.96 process pointer page 0 of 14
02:28:21.98 process pointer page 1 of 14
02:28:22.01 process pointer page 2 of 14
02:28:22.05 process pointer page 3 of 14
02:28:22.13 process pointer page 4 of 14
02:28:22.21 process pointer page 5 of 14
02:28:22.27 process pointer page 6 of 14
02:28:22.30 process pointer page 7 of 14
02:28:22.33 process pointer page 8 of 14
02:28:22.37 process pointer page 9 of 14
02:28:22.40 process pointer page 10 of 14
02:28:22.43 process pointer page 11 of 14
02:28:22.44 process pointer page 12 of 14
02:28:22.47 process pointer page 13 of 14
02:28:22.47 Index 1 (PERF_LOG_DTS_BEG_DESC)
02:28:22.58 Index 2 (PERF_LOG_UNIT)
02:28:22.77 Index 3 (PERF_LOG_TRN_DESC)
02:28:22.88 Index 4 (PERF_LOG_GDSCODE)
02:28:22.94 Relation 162 (PERF_LOG) is ok

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

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

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

02:28:22.94 Relation 258 (XQD_1000_1200)
02:28:22.94 process pointer page 0 of 2
02:28:22.99 process pointer page 1 of 2
02:28:23.04 Index 1 (XQD_1000_1200_WARE_SOP_ROP_SND)
02:28:23.05 Relation 258 (XQD_1000_1200) is ok

02:28:23.05 Relation 259 (XQD_1200_2000)
02:28:23.05 process pointer page 0 of 2
02:28:23.15 process pointer page 1 of 2
02:28:23.22 Index 1 (XQD_1200_2000_WARE_SOP_ROP_SND)
02:28:23.25 Relation 259 (XQD_1200_2000) is ok

02:28:23.25 Relation 260 (XQD_1000_3300)
02:28:23.25 process pointer page 0 of 2
02:28:23.33 process pointer page 1 of 2
02:28:23.38 Index 1 (XQD_1000_3300_WARE_SOP_ROP_SND)
02:28:23.44 Index 2 (XQD_1000_3300_DOC)
02:28:23.50 Relation 260 (XQD_1000_3300) is ok

02:28:23.50 Relation 261 (XQD_2000_3300)
02:28:23.50 process pointer page 0 of 8
02:28:23.68 process pointer page 1 of 8
02:28:23.80 process pointer page 2 of 8
02:28:23.88 process pointer page 3 of 8
02:28:23.96 process pointer page 4 of 8
02:28:24.11 process pointer page 5 of 8
02:28:24.22 process pointer page 6 of 8
02:28:24.30 process pointer page 7 of 8
02:28:24.30 Index 1 (XQD_2000_3300_WARE_SOP_ROP_SND)
02:28:24.47 Relation 261 (XQD_2000_3300) is ok

02:28:24.47 Relation 262 (XQD_2100_3300)
02:28:24.47 process pointer page 0 of 3
02:28:24.53 process pointer page 1 of 3
02:28:24.66 process pointer page 2 of 3
02:28:24.66 Index 1 (XQD_2100_3300_WARE_SOP_ROP_SND)
02:28:24.71 Relation 262 (XQD_2100_3300) is ok

02:28:24.71 Relation 263 (XQD_3300_3400)
02:28:24.71 process pointer page 0 of 6
02:28:24.88 process pointer page 1 of 6
02:28:25.02 process pointer page 2 of 6
02:28:25.14 process pointer page 3 of 6
02:28:25.27 process pointer page 4 of 6
02:28:25.35 process pointer page 5 of 6
02:28:25.36 Index 1 (XQD_3300_3400_WARE_SOP_ROP_SND)
02:28:25.47 Relation 263 (XQD_3300_3400) is ok

02:28:25.47 Relation 264 (XQS_1000_1200)
02:28:25.47 process pointer page 0 of 8
02:28:25.50 process pointer page 1 of 8
02:28:25.53 process pointer page 2 of 8
02:28:25.56 process pointer page 3 of 8
02:28:25.59 process pointer page 4 of 8
02:28:25.63 process pointer page 5 of 8
02:28:25.66 process pointer page 6 of 8
02:28:25.69 process pointer page 7 of 8
02:28:25.70 Index 1 (XQS_1000_1200_DOC_ID)
02:28:25.80 Index 2 (XQS_1000_1200_SND_ID)
02:28:25.89 Index 3 (XQS_1000_1200_RCV_ID)
02:28:25.95 Relation 264 (XQS_1000_1200) is ok

02:28:25.95 Relation 265 (XQS_1200_2000)
02:28:25.95 process pointer page 0 of 8
02:28:25.97 process pointer page 1 of 8
02:28:26.00 process pointer page 2 of 8
02:28:26.02 process pointer page 3 of 8
02:28:26.05 process pointer page 4 of 8
02:28:26.06 process pointer page 5 of 8
02:28:26.09 process pointer page 6 of 8
02:28:26.11 process pointer page 7 of 8
02:28:26.14 Index 1 (XQS_1200_2000_DOC_ID)
02:28:26.20 Index 2 (XQS_1200_2000_SND_ID)
02:28:26.30 Index 3 (XQS_1200_2000_RCV_ID)
02:28:26.37 Relation 265 (XQS_1200_2000) is ok

02:28:26.37 Relation 266 (XQS_1000_3300)
02:28:26.37 process pointer page 0 of 6
02:28:26.42 process pointer page 1 of 6
02:28:26.45 process pointer page 2 of 6
02:28:26.48 process pointer page 3 of 6
02:28:26.52 process pointer page 4 of 6
02:28:26.58 process pointer page 5 of 6
02:28:26.59 Index 1 (XQS_1000_3300_DOC_ID)
02:28:26.75 Index 2 (XQS_1000_3300_SND_ID)
02:28:26.94 Index 3 (XQS_1000_3300_RCV_ID)
02:28:27.09 Relation 266 (XQS_1000_3300) is ok

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

02:28:27.09 Relation 268 (XQS_2100_3300)
02:28:27.09 process pointer page 0 of 7
02:28:27.19 process pointer page 1 of 7
02:28:27.30 process pointer page 2 of 7
02:28:27.39 process pointer page 3 of 7
02:28:27.48 process pointer page 4 of 7
02:28:27.53 process pointer page 5 of 7
02:28:27.56 process pointer page 6 of 7
02:28:27.58 Index 1 (XQS_2100_3300_DOC_ID)
02:28:27.65 Index 2 (XQS_2100_3300_SND_ID)
02:28:27.75 Index 3 (XQS_2100_3300_RCV_ID)
02:28:27.93 Relation 268 (XQS_2100_3300) is ok

02:28:27.93 Relation 269 (XQS_3300_3400)
02:28:27.93 process pointer page 0 of 3
02:28:28.04 process pointer page 1 of 3
02:28:28.11 process pointer page 2 of 3
02:28:28.12 Index 1 (XQS_3300_3400_DOC_ID)
02:28:28.15 Index 2 (XQS_3300_3400_SND_ID)
02:28:28.18 Relation 269 (XQS_3300_3400) is ok

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

02:28:28.18 Validation finished
Done for 7720 ms, from 2:28:20.46 to 2:28:28.18.

Differences between old and current firebird.log

2:28:28.68. 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-09-27 02:28 309 oltp25_fb_log_when_test_finished.log

End of gathering firebird.log AFTER test finish.

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

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

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


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

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

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