Firebird 2.5 Performance Logs

Generated by E:\OLTP-EMUL\src\oltp_isql_run_worker.bat, ISQL session #1 of total launched 100. 2016-03-19 23:56:31.93.
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.6.26980 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.6 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

Test finish info

FINISH_STATE DTS_END FB_GDSCODE FB_MNEMONA STACK IP TRN_ID ATT_ID EXC_UNIT
NORMAL: TEST_TIME EXPIRED. 2016-03-19 23:56:13.4640 -1 <null> 127.0.0.1 84477 4204 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: 207.76 191924 37396 2016-03-19 20:56 2016-03-19 23:56
customer order: creation 19.71 589 3547 2016-03-19 20:56 2016-03-19 23:56
customer order: refuse 1.46 37 262 2016-03-19 20:56 2016-03-19 23:56
order to supplier: creation 5.14 6756 926 2016-03-19 20:56 2016-03-19 23:56
order to supplier: removal 0.06 153719 11 2016-03-19 20:56 2016-03-19 23:56
invoice (draft): creation 4.89 2600 881 2016-03-19 20:56 2016-03-19 23:56
invoice (draft): removal 0.58 189 103 2016-03-19 20:56 2016-03-19 23:56
invoice accept: apply 1.02 2858149 183 2016-03-19 20:56 2016-03-19 23:56
invoice accept: cancel 0.15 561318 27 2016-03-19 20:56 2016-03-19 23:56
customer reserve: creation 143.26 24293 25786 2016-03-19 20:56 2016-03-19 23:56
customer reserve: removal 3.91 31175 696 2016-03-19 20:56 2016-03-19 23:56
realization accept: apply 9.25 81 1665 2016-03-19 20:56 2016-03-19 23:56
realization accept: cancel 2.13 50 379 2016-03-19 20:56 2016-03-19 23:56
payment from customer: creation 6.58 187 1185 2016-03-19 20:56 2016-03-19 23:56
payment from customer: removal 0.61 27 109 2016-03-19 20:56 2016-03-19 23:56
payment to supplier: creation 5.83 574 1050 2016-03-19 20:56 2016-03-19 23:56
payment to supplier: removal 0.35 20 63 2016-03-19 20:56 2016-03-19 23:56
service: total inventory turnovers 1.54 5420 278 2016-03-19 20:56 2016-03-19 23:56
service: total monetary turnovers 1.32 10 235 2016-03-19 20:56 2016-03-19 23:56
service: refresh index statistics 0.06 1371 10 2016-03-19 20:56 2016-03-19 23:56
Done for 4850 ms, from 23:56:46.64 to 23:56:51.49.

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 416 8380 7493 887 10.58 20:56:14 21:14:13
interval # 2, overall 2 203 3831 3654 177 4.62 21:14:14 21:32:13
interval # 3, overall 3 167 3116 2999 117 3.75 21:32:14 21:50:13
interval # 4, overall 4 133 2489 2400 89 3.58 21:50:14 22:08:13
interval # 5, overall 5 162 2992 2909 83 2.77 22:08:14 22:26:13
interval # 6, overall 6 169 3194 3050 144 4.51 22:26:14 22:44:13
interval # 7, overall 7 185 3466 3329 137 3.95 22:44:14 23:02:13
interval # 8, overall 8 226 4257 4076 181 4.25 23:02:14 23:20:13
interval # 9, overall 9 227 4265 4078 187 4.38 23:20:14 23:38:13
interval # 10, overall 10 189 3521 3408 113 3.21 23:38:14 23:56:13
Done for 8090 ms, from 23:56:56.62 to 23:57:04.71.

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 1674.56 0.6885 1.3113 860 95
WARM_TIME 2 1484.44 0.7400 1.2300 709 85
WARM_TIME 3 1360.80 0.8948 1.2017 438 76
WARM_TIME 4 1294.45 0.9476 1.0649 384 62
WARM_TIME 5 1237.26 0.8914 1.0610 328 64
WARM_TIME 6 1168.66 0.9362 1.0710 356 65
WARM_TIME 7 1122.02 0.9563 1.0400 348 67
WARM_TIME 8 1074.30 0.9506 1.0254 431 67
WARM_TIME 9 1004.79 0.9745 1.0246 463 61
WARM_TIME 10 948.03 0.9775 1.0224 462 58
TEST_TIME 11 902.21 0.9773 1.0225 490 52
TEST_TIME 12 873.99 0.9676 1.0338 718 48
TEST_TIME 13 865.65 0.9636 1.0374 887 37
TEST_TIME 14 863.51 0.9658 1.0318 839 24
TEST_TIME 15 839.44 0.9907 1.0090 250 7
TEST_TIME 16 794.55 0.9993 1.0006 19 1
TEST_TIME 17 760.01 0.9993 1.0006 19 1
TEST_TIME 18 721.59 0.9991 1.0042 18 2
TEST_TIME 19 694.21 0.9904 1.0084 208 4
TEST_TIME 20 667.43 0.9965 1.0035 104 3
TEST_TIME 21 639.59 0.9986 1.0013 38 1
TEST_TIME 22 612.62 0.9979 1.0017 44 3
TEST_TIME 23 589.77 0.9954 1.0044 133 4
TEST_TIME 24 574.77 0.9877 1.0153 213 4
TEST_TIME 25 562.20 0.9966 1.0046 49 4
TEST_TIME 26 544.83 0.9968 1.0030 94 2
TEST_TIME 27 526.33 0.9999 1.0000 3 1
TEST_TIME 28 508.47 0.9982 1.0017 58 3
TEST_TIME 29 491.85 0.9999 1.0000 3 1
TEST_TIME 32 448.42 0.9996 1.0003 15 2
TEST_TIME 33 439.71 0.9893 1.0073 103 3
TEST_TIME 34 432.52 0.9940 1.0037 98 4
TEST_TIME 35 422.54 0.9981 1.0018 59 1
TEST_TIME 36 413.06 0.9964 1.0018 16 2
TEST_TIME 37 406.50 0.9996 1.0003 15 1
TEST_TIME 38 396.43 0.9988 1.0012 40 1
TEST_TIME 39 390.45 0.9944 1.0036 39 3
TEST_TIME 40 384.69 0.9998 1.0002 7 1
TEST_TIME 41 377.25 0.9951 1.0050 71 4
TEST_TIME 42 373.61 0.9907 1.0047 121 5
TEST_TIME 43 367.20 0.9985 1.0014 47 3
TEST_TIME 44 360.83 0.9960 1.0022 22 2
TEST_TIME 45 357.82 0.9882 1.0040 42 5
TEST_TIME 46 352.16 0.9994 1.0006 22 1
TEST_TIME 48 338.80 0.9999 1.0000 2 1
TEST_TIME 50 326.99 0.9996 1.0003 14 1
TEST_TIME 51 322.56 0.9991 1.0039 6 2
TEST_TIME 52 317.99 0.9988 1.0012 45 2
TEST_TIME 53 314.01 0.9990 1.0009 34 2
TEST_TIME 54 309.78 0.9958 1.0023 39 3
TEST_TIME 55 306.33 0.9952 1.0014 30 2
TEST_TIME 56 302.63 0.9999 1.0000 2 1
TEST_TIME 57 301.30 0.9998 1.0001 8 1
TEST_TIME 58 298.23 0.9931 1.0060 88 3
TEST_TIME 59 297.81 0.9913 1.0087 93 5
TEST_TIME 60 295.63 0.9993 1.0006 27 1
TEST_TIME 61 293.25 0.9973 1.0031 29 2
TEST_TIME 62 289.67 0.9991 1.0008 31 1
TEST_TIME 63 285.78 0.9984 1.0015 63 1
TEST_TIME 64 282.67 1.0000 1.0000 1 1
TEST_TIME 66 278.83 0.9857 1.0049 82 5
TEST_TIME 67 276.68 0.9990 1.0009 36 1
TEST_TIME 69 270.66 0.9964 1.0115 68 5
TEST_TIME 70 270.40 0.9980 1.0020 84 4
TEST_TIME 71 268.14 0.9991 1.0008 34 2
TEST_TIME 72 264.92 0.9989 1.0010 47 1
TEST_TIME 73 265.18 0.9864 1.0085 99 3
TEST_TIME 74 265.49 0.9999 1.0000 2 1
TEST_TIME 75 264.16 0.9998 1.0001 8 1
TEST_TIME 83 242.86 0.9898 1.0048 16 2
TEST_TIME 84 241.30 0.9993 1.0006 28 2
TEST_TIME 85 241.13 0.9999 1.0000 2 1
TEST_TIME 86 240.17 0.9999 1.0000 4 1
TEST_TIME 87 238.96 1.0000 1.0000 2 1
TEST_TIME 88 236.43 0.9993 1.0026 23 2
TEST_TIME 89 234.62 0.9991 1.0009 40 1
TEST_TIME 90 234.21 0.9915 1.0038 82 3
TEST_TIME 91 232.78 0.9989 1.0011 52 2
TEST_TIME 92 231.00 0.9979 1.0065 19 2
TEST_TIME 93 231.86 0.9921 1.0045 100 3
TEST_TIME 94 232.56 0.9989 1.0012 59 3
TEST_TIME 95 231.54 0.9950 1.0032 141 2
TEST_TIME 96 231.00 0.9996 1.0003 15 1
TEST_TIME 97 229.49 0.9997 1.0003 14 1
TEST_TIME 103 217.00 0.9999 1.0001 7 1
TEST_TIME 104 216.43 0.9948 1.0084 36 3
TEST_TIME 105 216.55 0.9982 1.0018 73 4
TEST_TIME 106 215.62 0.9967 1.0013 21 2
TEST_TIME 107 214.76 0.9959 1.0022 40 2
TEST_TIME 109 213.04 0.9999 1.0000 5 1
TEST_TIME 110 213.38 1.0000 1.0000 2 1
TEST_TIME 113 210.19 0.9971 1.0083 96 3
TEST_TIME 114 212.14 0.9903 1.0076 154 5
TEST_TIME 115 212.22 0.9985 1.0016 58 3
TEST_TIME 116 211.12 0.9982 1.0017 102 3
TEST_TIME 117 209.78 0.9996 1.0003 19 1
TEST_TIME 118 209.15 0.9948 1.0035 20 2
TEST_TIME 120 207.74 0.9987 1.0013 40 2
TEST_TIME 121 206.97 0.9968 1.0047 37 3
TEST_TIME 122 210.59 0.9794 1.0062 111 5
TEST_TIME 123 211.60 0.9933 1.0022 79 5
TEST_TIME 124 212.12 0.9917 1.0024 41 3
TEST_TIME 125 211.09 0.9993 1.0007 38 2
TEST_TIME 127 209.88 0.9998 1.0002 13 1
TEST_TIME 128 208.76 0.9998 1.0001 11 1
TEST_TIME 129 207.42 1.0000 1.0000 2 1
TEST_TIME 130 206.68 1.0000 1.0000 1 1
TEST_TIME 131 205.79 0.9999 1.0000 6 1
TEST_TIME 132 205.49 0.9998 1.0001 12 1
TEST_TIME 133 205.58 0.9997 1.0003 18 3
TEST_TIME 134 206.39 0.9890 1.0048 170 4
TEST_TIME 135 207.29 0.9931 1.0029 50 3
TEST_TIME 136 206.63 0.9987 1.0059 53 3
TEST_TIME 137 207.26 0.9955 1.0056 95 3
TEST_TIME 138 208.26 1.0000 1.0000 1 1
TEST_TIME 139 207.54 0.9962 1.0060 90 3
TEST_TIME 140 209.00 0.9919 1.0017 51 2
TEST_TIME 142 207.73 0.9993 1.0007 47 2
TEST_TIME 143 209.03 0.9876 1.0109 78 5
TEST_TIME 144 211.25 0.9980 1.0020 129 3
TEST_TIME 145 212.56 0.9933 1.0112 149 8
TEST_TIME 146 215.80 0.9892 1.0064 93 7
TEST_TIME 147 218.88 0.9856 1.0084 221 7
TEST_TIME 148 220.10 0.9961 1.0044 93 5
TEST_TIME 149 221.21 0.9927 1.0068 68 5
TEST_TIME 150 221.30 0.9997 1.0002 16 1
TEST_TIME 151 222.38 0.9948 1.0008 52 2
TEST_TIME 153 220.30 0.9994 1.0006 31 2
TEST_TIME 154 221.78 0.9875 1.0098 133 5
TEST_TIME 155 225.04 0.9888 1.0060 106 6
TEST_TIME 156 225.13 0.9992 1.0007 52 2
TEST_TIME 157 223.92 0.9997 1.0002 21 1
TEST_TIME 158 222.65 0.9995 1.0004 30 1
TEST_TIME 159 222.77 0.9936 1.0056 28 3
TEST_TIME 160 226.22 0.9890 1.0064 58 4
TEST_TIME 161 226.56 0.9987 1.0013 106 4
TEST_TIME 162 226.54 0.9980 1.0021 60 4
TEST_TIME 163 225.74 0.9996 1.0004 40 3
TEST_TIME 164 227.35 0.9875 1.0081 153 6
TEST_TIME 165 229.36 0.9933 1.0075 56 4
TEST_TIME 166 232.09 0.9953 1.0043 120 5
TEST_TIME 167 232.56 0.9963 1.0049 248 7
TEST_TIME 169 233.30 0.9928 1.0009 24 2
TEST_TIME 170 233.13 0.9996 1.0003 28 1
TEST_TIME 171 234.33 0.9922 1.0013 54 2
TEST_TIME 172 235.43 0.9909 1.0062 218 7
TEST_TIME 173 235.95 0.9982 1.0011 70 3
TEST_TIME 174 235.19 0.9985 1.0014 123 3
TEST_TIME 175 234.86 0.9971 1.0056 17 3
TEST_TIME 176 236.39 0.9934 1.0049 17 3
TEST_TIME 177 239.40 0.9868 1.0049 133 4
TEST_TIME 178 239.67 0.9982 1.0052 112 3
TEST_TIME 179 241.83 0.9961 1.0024 61 4
TEST_TIME 180 242.99 0.9921 1.0038 118 4
TEST_TIME 181 242.65 0.9997 1.0011 24 3
TEST_TIME 182 241.99 0.9983 1.0026 24 2
TEST_TIME 184 243.57 0.9894 1.0079 56 6
TEST_TIME 185 244.68 0.9998 1.0001 15 1
TEST_TIME 186 247.25 0.9844 1.0067 97 6
TEST_TIME 187 248.92 0.9989 1.0010 80 4
TEST_TIME 188 249.57 0.9955 1.0078 98 5
TEST_TIME 189 253.16 0.9928 1.0042 71 4
TEST_TIME 190 253.04 0.9994 1.0000 222 100
Done for 8100 ms, from 23:57:04.97 to 23:57:13.07.

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 6132 49277 5751 <null> 17920 <null> <null> <null> 1414 1059 19363 11747 29299 4205 71006 <null> 81136 446673 <null> 20:56:14 21:14:13
fetches per second 2 69483 <null> <null> <null> 52980 <null> 852 <null> <null> <null> <null> <null> <null> <null> <null> <null> 103034 <null> <null> 21:14:14 21:32:13
marks per second 1 1131 4388 571 <null> 1915 <null> <null> <null> 153 177 3107 1560 1206 476 3141 <null> 994 5007 <null> 20:56:14 21:14:13
marks per second 2 12516 <null> <null> <null> 3491 <null> 111 <null> <null> <null> <null> <null> <null> <null> <null> <null> 555 <null> <null> 21:14:14 21:32:13
reads/fetches*100 1 15 5 17 <null> 6 <null> <null> <null> 14 14 8 7 8 7 4 <null> 15 0 <null> 20:56:14 21:14:13
reads/fetches*100 2 3 <null> <null> <null> 6 <null> 16 <null> <null> <null> <null> <null> <null> <null> <null> <null> 25 <null> <null> 21:14:14 21:32:13
writes/marks*100 1 8 14 5 <null> 6 <null> <null> <null> 10 4 7 8 3 0 1 <null> 32 0 <null> 20:56:14 21:14:13
writes/marks*100 2 11 <null> <null> <null> 7 <null> 0 <null> <null> <null> <null> <null> <null> <null> <null> <null> 46 <null> <null> 21:14:14 21:32:13
Done for 4790 ms, from 23:57:13.23 to 23:57:18.02.

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 31294 30055 1239 3.95 0 10546 43 1239 2016-03-19 20:56 2016-03-19 23:56
doc_list_biud 31332 30054 1278 4.07 0 565386 1417 1278 2016-03-19 20:56 2016-03-19 23:56
sp_add_invoice_to_stock 756 183 573 75.79 6873 10565595 2858149 573 2016-03-19 20:56 2016-03-19 23:56
sp_cancel_adding_invoice 87 27 60 68.96 234 3541189 561318 60 2016-03-19 20:56 2016-03-19 23:56
sp_cancel_client_order 278 262 16 5.75 0 1032 37 16 2016-03-19 20:56 2016-03-19 23:56
sp_cancel_customer_reserve 1540 696 844 54.80 0 339770 31175 844 2016-03-19 20:56 2016-03-19 23:56
sp_cancel_pay_from_customer 113 109 4 3.53 0 436 27 4 2016-03-19 20:56 2016-03-19 23:56
sp_cancel_pay_to_supplier 63 63 0 0.00 0 172 20 0 2016-03-19 20:56 2016-03-19 23:56
sp_cancel_supplier_invoice 153 103 50 32.67 0 1186 189 50 2016-03-19 20:56 2016-03-19 23:56
sp_cancel_supplier_order 72 11 61 84.72 78 1591770 153719 61 2016-03-19 20:56 2016-03-19 23:56
sp_cancel_write_off 781 379 402 51.47 0 749 50 402 2016-03-19 20:56 2016-03-19 23:56
sp_client_order 3547 3547 0 0.00 0 26803 589 0 2016-03-19 20:56 2016-03-19 23:56
sp_customer_reserve 25786 25786 0 0.00 249 89501 24293 0 2016-03-19 20:56 2016-03-19 23:56
sp_fill_shopping_cart 5354 5354 0 0.00 0 72722 871 0 2016-03-19 20:56 2016-03-19 23:56
sp_fill_shopping_cart_clo_res 25786 25786 0 0.00 0 32 3 0 2016-03-19 20:56 2016-03-19 23:56
sp_get_clo_for_invoice 183 183 0 0.00 0 374 63 0 2016-03-19 20:56 2016-03-19 23:56
sp_kill_cost_storno 988 579 409 41.39 0 733 11 409 2016-03-19 20:56 2016-03-19 23:56
sp_kill_qstorno_ret_qs2qd 3953 1885 2068 52.31 0 339661 11524 2068 2016-03-19 20:56 2016-03-19 23:56
sp_kill_qty_storno 4593 3327 1266 27.56 0 565371 12790 1266 2016-03-19 20:56 2016-03-19 23:56
sp_lock_selected_doc 3474 2601 873 25.12 0 32 0 873 2016-03-19 20:56 2016-03-19 23:56
sp_make_cost_storno 4083 4083 0 0.00 0 10328 192 0 2016-03-19 20:56 2016-03-19 23:56
sp_multiply_rows_for_pdistr 4083 4083 0 0.00 0 827 21 0 2016-03-19 20:56 2016-03-19 23:56
sp_multiply_rows_for_qdistr 3547 3547 0 0.00 0 26418 538 0 2016-03-19 20:56 2016-03-19 23:56
sp_pay_from_customer 1185 1185 0 0.00 31 2574 187 0 2016-03-19 20:56 2016-03-19 23:56
sp_pay_to_supplier 1050 1050 0 0.00 16 10702 574 0 2016-03-19 20:56 2016-03-19 23:56
sp_payment_common 2235 2235 0 0.00 0 265 27 0 2016-03-19 20:56 2016-03-19 23:56
sp_qd_handle_on_cancel_clo 278 262 16 5.75 0 1013 19 16 2016-03-19 20:56 2016-03-19 23:56
sp_qd_handle_on_invoice_upd_sts 230 211 19 8.26 15 565371 98314 19 2016-03-19 20:56 2016-03-19 23:56
sp_qd_handle_on_reserve_upd_sts 1665 1665 0 0.00 0 265 14 0 2016-03-19 20:56 2016-03-19 23:56
sp_reserve_write_off 1770 1665 105 5.93 0 1701 81 105 2016-03-19 20:56 2016-03-19 23:56
sp_supplier_invoice 881 881 0 0.00 31 49469 2600 0 2016-03-19 20:56 2016-03-19 23:56
sp_supplier_order 926 926 0 0.00 31 112987 6756 0 2016-03-19 20:56 2016-03-19 23:56
srv_find_qd_qs_mism 16764 15520 1244 7.42 0 4930 25 1244 2016-03-19 20:56 2016-03-19 23:56
srv_make_invnt_saldo 278 278 0 0.00 1888 13931 5420 0 2016-03-19 20:56 2016-03-19 23:56
srv_make_money_saldo 235 235 0 0.00 0 47 10 0 2016-03-19 20:56 2016-03-19 23:56
srv_recalc_idx_stat 10 10 0 0.00 1030 1764 1371 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-abend:sp_add_invoice_to_stock 573 573 0 0.00 0 187 8 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-abend:sp_cancel_adding_invoice 82 82 0 0.00 0 265 27 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-abend:sp_cancel_client_order 16 16 0 0.00 0 62 10 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-abend:sp_cancel_customer_reserve 10 10 0 0.00 0 47 7 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-abend:sp_cancel_pay_from_customer 4 4 0 0.00 0 31 11 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-abend:sp_cancel_supplier_invoice 48 48 0 0.00 0 62 9 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-abend:sp_cancel_supplier_order 174 174 0 0.00 0 171 22 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-abend:sp_cancel_write_off 1 1 0 0.00 0 0 0 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-abend:sp_reserve_write_off 105 105 0 0.00 0 78 6 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_add_invoice_to_stock 215 215 0 0.00 0 1139 210 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_cancel_adding_invoice 25 25 0 0.00 15 780 128 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_cancel_client_order 262 262 0 0.00 0 109 8 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_cancel_customer_reserve 81 81 0 0.00 0 47 10 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_cancel_pay_from_customer 109 109 0 0.00 0 127 11 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_cancel_pay_to_supplier 63 63 0 0.00 0 78 7 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_cancel_supplier_invoice 34 34 0 0.00 0 78 11 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_cancel_supplier_order 11 11 0 0.00 15 281 79 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_cancel_write_off 169 169 0 0.00 0 219 14 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_client_order 3560 3560 0 0.00 0 249 14 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_customer_reserve 175 175 0 0.00 0 78 11 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_pay_from_customer 1185 1185 0 0.00 0 250 11 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_pay_to_supplier 1050 1050 0 0.00 0 156 9 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_reserve_write_off 1665 1665 0 0.00 0 265 12 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_supplier_invoice 881 881 0 0.00 0 187 13 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:sp_supplier_order 971 971 0 0.00 0 250 17 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:srv_make_invnt_saldo 278 278 0 0.00 0 32 3 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:srv_make_money_saldo 235 235 0 0.00 0 31 2 0 2016-03-19 20:56 2016-03-19 23:56
t$perf-norm:srv_recalc_idx_stat 10 10 0 0.00 0 16 11 0 2016-03-19 20:56 2016-03-19 23:56
v_add_invoice_to_stock 1512 366 1146 75.79 0 46 2 1146 2016-03-19 20:56 2016-03-19 23:56
v_all_customers 5374 5374 0 0.00 0 16 0 0 2016-03-19 20:56 2016-03-19 23:56
v_all_suppliers 3614 3614 0 0.00 0 31 0 0 2016-03-19 20:56 2016-03-19 23:56
v_all_wares 7094 7094 0 0.00 0 16 0 0 2016-03-19 20:56 2016-03-19 23:56
v_cancel_adding_invoice 158 48 110 69.62 0 46 8 110 2016-03-19 20:56 2016-03-19 23:56
v_cancel_client_order 556 524 32 5.75 0 16 0 32 2016-03-19 20:56 2016-03-19 23:56
v_cancel_customer_prepayment 226 218 8 3.53 0 16 1 8 2016-03-19 20:56 2016-03-19 23:56
v_cancel_customer_reserve 182 162 20 10.98 0 16 1 20 2016-03-19 20:56 2016-03-19 23:56
v_cancel_payment_to_supplier 126 126 0 0.00 0 16 0 0 2016-03-19 20:56 2016-03-19 23:56
v_cancel_supplier_invoice 164 68 96 58.53 0 62 3 96 2016-03-19 20:56 2016-03-19 23:56
v_cancel_supplier_order 144 22 122 84.72 0 16 1 122 2016-03-19 20:56 2016-03-19 23:56
v_cancel_write_off 340 338 2 0.58 0 32 2 2 2016-03-19 20:56 2016-03-19 23:56
v_max_id_clo_ord 927 927 0 0.00 0 967 34 0 2016-03-19 20:56 2016-03-19 23:56
v_max_id_clo_res 173 173 0 0.00 0 31 2 0 2016-03-19 20:56 2016-03-19 23:56
v_max_id_ord_sup 881 881 0 0.00 0 156 6 0 2016-03-19 20:56 2016-03-19 23:56
v_max_non_paid_invoice 1050 1050 0 0.00 0 140 6 0 2016-03-19 20:56 2016-03-19 23:56
v_max_non_paid_realizn 1185 1185 0 0.00 0 47 5 0 2016-03-19 20:56 2016-03-19 23:56
v_min_id_clo_ord 926 926 0 0.00 0 1560 97 0 2016-03-19 20:56 2016-03-19 23:56
v_min_id_clo_res 173 173 0 0.00 0 16 1 0 2016-03-19 20:56 2016-03-19 23:56
v_min_id_ord_sup 881 881 0 0.00 0 1295 56 0 2016-03-19 20:56 2016-03-19 23:56
v_min_non_paid_invoice 1050 1050 0 0.00 0 172 20 0 2016-03-19 20:56 2016-03-19 23:56
v_min_non_paid_realizn 1185 1185 0 0.00 0 109 12 0 2016-03-19 20:56 2016-03-19 23:56
v_our_firm 1720 1720 0 0.00 0 16 0 0 2016-03-19 20:56 2016-03-19 23:56
v_reserve_write_off 3540 3330 210 5.93 0 47 1 210 2016-03-19 20:56 2016-03-19 23:56
x_lock_depdocs_on_canc_invoice 250 96 154 61.60 0 31 1 154 2016-03-19 20:56 2016-03-19 23:56
x_lock_depdocs_on_canc_sup_ord 72 11 61 84.72 0 31 5 61 2016-03-19 20:56 2016-03-19 23:56
x_make_qstorno_1000_1200 936 936 0 0.00 13 106864 4401 0 2016-03-19 20:56 2016-03-19 23:56
x_make_qstorno_1200_2000 881 881 0 0.00 0 5133 484 0 2016-03-19 20:56 2016-03-19 23:56
x_make_qstorno_2100_3300 25786 25786 0 0.00 234 89484 24274 0 2016-03-19 20:56 2016-03-19 23:56
Done for 16550 ms, from 23:57:26.80 to 23:57:43.35.

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 9 sp_kill_cost_storno 335544336 2016-03-19 21:08 2016-03-19 23:39
deadlock 2 sp_qd_handle_on_invoice_upd_sts 335544336 2016-03-19 21:27 2016-03-19 23:40
lock_conflict 4 sp_kill_cost_storno 335544345 2016-03-19 21:00 2016-03-19 23:03
lock_conflict 738 sp_lock_selected_doc 335544345 2016-03-19 20:56 2016-03-19 23:56
lock_conflict 16 sp_qd_handle_on_cancel_clo 335544345 2016-03-19 20:57 2016-03-19 23:54
lock_conflict 15 sp_qd_handle_on_invoice_upd_sts 335544345 2016-03-19 20:56 2016-03-19 23:27
lock_conflict 74 x_lock_depdocs_on_canc_invoice 335544345 2016-03-19 20:56 2016-03-19 23:52
lock_conflict 18 x_lock_depdocs_on_canc_sup_ord 335544345 2016-03-19 20:57 2016-03-19 23:55
Done for 2540 ms, from 23:57:43.67 to 23:57:46.21.

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 86118
MON$OLDEST_ACTIVE 86119
MON$OLDEST_SNAPSHOT 86119
MON$NEXT_TRANSACTION 86119
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 153939
MON$STAT_ID 1
MON$BACKUP_STATE 0
ISQL Version: WI-V2.5.6.26980 Firebird 2.5
Server version:
Firebird/x86-64/Windows NT (access method), version "WI-V2.5.6.26980 Firebird 2.5"
Firebird/x86-64/Windows NT (remote server), version "WI-V2.5.6.26980 Firebird 2.5/tcp (WIN-2BE1UI8LGDL)/P12"
Firebird/x86-64/Windows NT (remote interface), version "WI-V2.5.6.26980 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 90576
Page size 8192
ODS version 11.2
Oldest transaction 86120
Oldest active 86121
Oldest snapshot 86121
Next transaction 86122
Bumped transaction 1
Sequence number 0
Next attachment ID 4425
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.62, total records: 440624
Average version length: 46.09, total versions: 14288, max versions: 1
Data pages: 4701, data page slots: 4701, average fill: 73%
Fill distribution:
0 - 19% = 2
20 - 39% = 11
40 - 59% = 67
60 - 79% = 4575
80 - 99% = 46

Index DOC_DATA_DOC_WARE_UNQ (1)
Depth: 3, leaf buckets: 1389, nodes: 440624
Average data length: 11.65, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 130
20 - 39% = 32
40 - 59% = 492
60 - 79% = 88
80 - 99% = 647

Index DOC_DATA_ID_DESC (2)
Depth: 3, leaf buckets: 1296, nodes: 440624
Average data length: 5.99, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 25
40 - 59% = 1271
60 - 79% = 0
80 - 99% = 0

Index FK_DOC_DATA_DOC_LIST (3)
Depth: 2, leaf buckets: 405, nodes: 440624
Average data length: 0.84, total dup: 382445, max dup: 49
Fill distribution:
0 - 19% = 0
20 - 39% = 19
40 - 59% = 150
60 - 79% = 7
80 - 99% = 229

DOC_LIST (140)
Primary pointer page: 195, Index root page: 196
Average record length: 58.93, total records: 70990
Average version length: 52.16, total versions: 5425, max versions: 5
Data pages: 918, data page slots: 918, average fill: 77%
Fill distribution:
0 - 19% = 3
20 - 39% = 26
40 - 59% = 43
60 - 79% = 388
80 - 99% = 458

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

Index FK_DOC_LIST_AGENTS (2)
Depth: 2, leaf buckets: 63, nodes: 70990
Average data length: 0.01, total dup: 70940, max dup: 17130
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 48
60 - 79% = 12
80 - 99% = 3

Index PK_DOC_LIST (0)
Depth: 2, leaf buckets: 143, nodes: 70990
Average data length: 6.28, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 6
20 - 39% = 19
40 - 59% = 47
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: 61.92, total records: 400
Average version length: 13.33, total versions: 70313, max versions: 208
Data pages: 277, data page slots: 277, average fill: 96%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 277

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.07, total records: 124006
Average version length: 68.50, total versions: 123881, max versions: 1
Data pages: 1807, data page slots: 1816, average fill: 86%
Fill distribution:
0 - 19% = 14
20 - 39% = 15
40 - 59% = 37
60 - 79% = 219
80 - 99% = 1522

Index INVNT_TURNOVER_LOG_WARE_DD_ID (0)
Depth: 3, leaf buckets: 348, nodes: 124006
Average data length: 8.59, total dup: 10757, max dup: 4
Fill distribution:
0 - 19% = 2
20 - 39% = 1
40 - 59% = 199
60 - 79% = 95
80 - 99% = 51

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

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.30, total records: 5847
Average version length: 44.09, total versions: 5807, max versions: 1
Data pages: 62, data page slots: 62, average fill: 90%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 2
60 - 79% = 2
80 - 99% = 58

Index MONEY_TURNOVER_LOG_AGENT_OPTYPE (0)
Depth: 2, leaf buckets: 4, nodes: 5847
Average data length: 0.16, total dup: 5755, max dup: 1307
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
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.94, total records: 121179
Average version length: 31.40, total versions: 82049, max versions: 10
Data pages: 1298, data page slots: 1298, average fill: 86%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 4
60 - 79% = 317
80 - 99% = 976

Index PDISTR_AGENT_ID (4)
Depth: 2, leaf buckets: 99, nodes: 121179
Average data length: 0.01, total dup: 121130, max dup: 26140
Fill distribution:
0 - 19% = 0
20 - 39% = 2
40 - 59% = 54
60 - 79% = 24
80 - 99% = 19

Index PDISTR_SNDOP_RCVOP_SNDID_ASC (2)
Depth: 2, leaf buckets: 129, nodes: 121179
Average data length: 0.92, total dup: 108756, max dup: 32
Fill distribution:
0 - 19% = 3
20 - 39% = 4
40 - 59% = 74
60 - 79% = 27
80 - 99% = 21

Index PDISTR_SNDOP_RCVOP_SNDID_DESC (3)
Depth: 2, leaf buckets: 134, nodes: 121179
Average data length: 0.92, total dup: 108756, max dup: 32
Fill distribution:
0 - 19% = 0
20 - 39% = 3
40 - 59% = 83
60 - 79% = 32
80 - 99% = 16

Index PDISTR_SND_ID (1)
Depth: 2, leaf buckets: 118, nodes: 121179
Average data length: 0.68, total dup: 108756, max dup: 32
Fill distribution:
0 - 19% = 1
20 - 39% = 3
40 - 59% = 57
60 - 79% = 37
80 - 99% = 20

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

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

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

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

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

Index PERF_LOG_DTS_BEG_DESC (0)
Depth: 3, leaf buckets: 1356, nodes: 1058672
Average data length: 0.62, total dup: 558523, max dup: 29
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1104
60 - 79% = 181
80 - 99% = 71

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

Index PERF_LOG_TRN_DESC (2)
Depth: 3, leaf buckets: 1344, nodes: 1058672
Average data length: 0.42, total dup: 989921, max dup: 2807
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1181
60 - 79% = 99
80 - 99% = 64

Index PERF_LOG_UNIT (1)
Depth: 3, leaf buckets: 1234, nodes: 1058672
Average data length: 0.53, total dup: 1001084, max dup: 120282
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 891
60 - 79% = 177
80 - 99% = 166

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.25, total records: 84780
Average version length: 54.63, total versions: 5392, max versions: 1
Data pages: 986, data page slots: 986, average fill: 77%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 3
60 - 79% = 936
80 - 99% = 47

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

Index PSTORNED_SND_ID (1)
Depth: 2, leaf buckets: 77, nodes: 84780
Average data length: 0.63, total dup: 77046, max dup: 91
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 35
60 - 79% = 32
80 - 99% = 10

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

TRACE_STAT (276)
Primary pointer page: 513, Index root page: 95177
Average record length: 59.54, total records: 223
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 3, data page slots: 3, average fill: 70%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
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.14, total records: 197755
Average version length: 69.58, total versions: 197376, max versions: 1
Data pages: 2685, data page slots: 2685, average fill: 93%
Fill distribution:
0 - 19% = 28
20 - 39% = 1
40 - 59% = 1
60 - 79% = 2
80 - 99% = 2653

Index XQD_1000_1200_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 238, nodes: 197755
Average data length: 1.27, total dup: 173244, max dup: 29
Fill distribution:
0 - 19% = 11
20 - 39% = 8
40 - 59% = 89
60 - 79% = 86
80 - 99% = 44

XQD_1000_3300 (260)
Primary pointer page: 653, Index root page: 654
Average record length: 51.36, total records: 211110
Average version length: 69.17, total versions: 55198, max versions: 1
Data pages: 3042, data page slots: 3067, average fill: 78%
Fill distribution:
0 - 19% = 36
20 - 39% = 77
40 - 59% = 143
60 - 79% = 1426
80 - 99% = 1360

Index XQD_1000_3300_DOC (1)
Depth: 2, leaf buckets: 184, nodes: 211110
Average data length: 0.16, total dup: 206345, max dup: 241
Fill distribution:
0 - 19% = 1
20 - 39% = 12
40 - 59% = 88
60 - 79% = 12
80 - 99% = 71

Index XQD_1000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 249, nodes: 211110
Average data length: 1.28, total dup: 184978, max dup: 44
Fill distribution:
0 - 19% = 1
20 - 39% = 3
40 - 59% = 99
60 - 79% = 107
80 - 99% = 39

XQD_1200_2000 (259)
Primary pointer page: 648, Index root page: 649
Average record length: 0.12, total records: 242196
Average version length: 69.51, total versions: 241771, max versions: 1
Data pages: 3252, data page slots: 3252, average fill: 94%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 0
80 - 99% = 3251

Index XQD_1200_2000_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 255, nodes: 242196
Average data length: 0.98, total dup: 219344, max dup: 143
Fill distribution:
0 - 19% = 1
20 - 39% = 5
40 - 59% = 70
60 - 79% = 119
80 - 99% = 60

XQD_2000_3300 (261)
Primary pointer page: 657, Index root page: 658
Average record length: 17.17, total records: 967338
Average version length: 68.20, total versions: 728145, max versions: 1
Data pages: 13029, data page slots: 13029, average fill: 89%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 2984
80 - 99% = 10044

Index XQD_2000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 1235, nodes: 967338
Average data length: 1.14, total dup: 854511, max dup: 67
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 923
60 - 79% = 179
80 - 99% = 133

XQD_2100_3300 (262)
Primary pointer page: 661, Index root page: 662
Average record length: 3.60, total records: 129164
Average version length: 68.90, total versions: 122417, max versions: 1
Data pages: 3688, data page slots: 3727, average fill: 47%
Fill distribution:
0 - 19% = 259
20 - 39% = 1581
40 - 59% = 742
60 - 79% = 699
80 - 99% = 407

Index XQD_2100_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 257, nodes: 129164
Average data length: 1.62, total dup: 108754, max dup: 67
Fill distribution:
0 - 19% = 1
20 - 39% = 122
40 - 59% = 116
60 - 79% = 17
80 - 99% = 1

XQD_3300_3400 (263)
Primary pointer page: 664, Index root page: 665
Average record length: 38.77, total records: 719220
Average version length: 68.30, total versions: 313567, max versions: 1
Data pages: 10128, data page slots: 10188, average fill: 81%
Fill distribution:
0 - 19% = 98
20 - 39% = 173
40 - 59% = 341
60 - 79% = 3939
80 - 99% = 5577

Index XQD_3300_3400_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 997, nodes: 719220
Average data length: 1.30, total dup: 624224, max dup: 35
Fill distribution:
0 - 19% = 2
20 - 39% = 14
40 - 59% = 747
60 - 79% = 127
80 - 99% = 107

XQS_1000_1200 (264)
Primary pointer page: 667, Index root page: 668
Average record length: 78.75, total records: 928016
Average version length: 80.52, total versions: 19934, max versions: 1
Data pages: 13841, data page slots: 13841, average fill: 80%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 6653
80 - 99% = 7187

Index XQS_1000_1200_DOC_ID (0)
Depth: 3, leaf buckets: 1033, nodes: 928016
Average data length: 0.16, total dup: 906992, max dup: 148
Fill distribution:
0 - 19% = 6
20 - 39% = 0
40 - 59% = 780
60 - 79% = 208
80 - 99% = 39

Index XQS_1000_1200_RCV_ID (2)
Depth: 3, leaf buckets: 744, nodes: 928016
Average data length: 0.73, total dup: 815983, max dup: 49
Fill distribution:
0 - 19% = 5
20 - 39% = 0
40 - 59% = 132
60 - 79% = 18
80 - 99% = 589

Index XQS_1000_1200_SND_ID (1)
Depth: 3, leaf buckets: 1175, nodes: 928016
Average data length: 0.75, total dup: 812648, max dup: 29
Fill distribution:
0 - 19% = 9
20 - 39% = 0
40 - 59% = 866
60 - 79% = 252
80 - 99% = 48

XQS_1000_3300 (266)
Primary pointer page: 678, Index root page: 679
Average record length: 69.30, total records: 616213
Average version length: 80.09, total versions: 83890, max versions: 1
Data pages: 9362, data page slots: 9374, average fill: 80%
Fill distribution:
0 - 19% = 53
20 - 39% = 101
40 - 59% = 121
60 - 79% = 4908
80 - 99% = 4179

Index XQS_1000_3300_DOC_ID (0)
Depth: 3, leaf buckets: 629, nodes: 616213
Average data length: 0.15, total dup: 603124, max dup: 269
Fill distribution:
0 - 19% = 1
20 - 39% = 7
40 - 59% = 340
60 - 79% = 223
80 - 99% = 58

Index XQS_1000_3300_RCV_ID (2)
Depth: 3, leaf buckets: 493, nodes: 616213
Average data length: 0.82, total dup: 535859, max dup: 14
Fill distribution:
0 - 19% = 5
20 - 39% = 5
40 - 59% = 65
60 - 79% = 7
80 - 99% = 411

Index XQS_1000_3300_SND_ID (1)
Depth: 3, leaf buckets: 752, nodes: 616213
Average data length: 0.71, total dup: 545657, max dup: 44
Fill distribution:
0 - 19% = 32
20 - 39% = 6
40 - 59% = 414
60 - 79% = 252
80 - 99% = 48

XQS_1200_2000 (265)
Primary pointer page: 673, Index root page: 674
Average record length: 77.04, total records: 965730
Average version length: 80.92, total versions: 41541, max versions: 1
Data pages: 14405, data page slots: 14405, average fill: 81%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 7314
80 - 99% = 7090

Index XQS_1200_2000_DOC_ID (0)
Depth: 3, leaf buckets: 1011, nodes: 965730
Average data length: 0.05, total dup: 959916, max dup: 1167
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 700
60 - 79% = 231
80 - 99% = 79

Index XQS_1200_2000_RCV_ID (2)
Depth: 3, leaf buckets: 722, nodes: 965730
Average data length: 0.70, total dup: 852891, max dup: 49
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 58
60 - 79% = 4
80 - 99% = 660

Index XQS_1200_2000_SND_ID (1)
Depth: 3, leaf buckets: 1161, nodes: 965730
Average data length: 0.70, total dup: 853764, max dup: 143
Fill distribution:
0 - 19% = 4
20 - 39% = 1
40 - 59% = 771
60 - 79% = 297
80 - 99% = 88

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: 72.35, total records: 753837
Average version length: 80.05, total versions: 75588, max versions: 1
Data pages: 11645, data page slots: 11674, average fill: 79%
Fill distribution:
0 - 19% = 101
20 - 39% = 193
40 - 59% = 314
60 - 79% = 5617
80 - 99% = 5420

Index XQS_2100_3300_DOC_ID (0)
Depth: 3, leaf buckets: 689, nodes: 753837
Average data length: 0.05, total dup: 749511, max dup: 752
Fill distribution:
0 - 19% = 2
20 - 39% = 12
40 - 59% = 237
60 - 79% = 301
80 - 99% = 137

Index XQS_2100_3300_RCV_ID (2)
Depth: 3, leaf buckets: 705, nodes: 753837
Average data length: 0.84, total dup: 653622, max dup: 14
Fill distribution:
0 - 19% = 12
20 - 39% = 19
40 - 59% = 239
60 - 79% = 16
80 - 99% = 419

Index XQS_2100_3300_SND_ID (1)
Depth: 3, leaf buckets: 785, nodes: 753837
Average data length: 0.70, total dup: 668123, max dup: 80
Fill distribution:
0 - 19% = 0
20 - 39% = 7
40 - 59% = 246
60 - 79% = 364
80 - 99% = 168

XQS_3300_3400 (269)
Primary pointer page: 697, Index root page: 698
Average record length: 59.13, total records: 315076
Average version length: 68.19, total versions: 42480, max versions: 1
Data pages: 4240, data page slots: 4240, average fill: 80%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 3194
80 - 99% = 1046

Index XQS_3300_3400_DOC_ID (0)
Depth: 2, leaf buckets: 286, nodes: 315076
Average data length: 0.22, total dup: 305264, max dup: 251
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 114
60 - 79% = 106
80 - 99% = 66

Index XQS_3300_3400_SND_ID (1)
Depth: 2, leaf buckets: 330, nodes: 315076
Average data length: 0.82, total dup: 274171, max dup: 44
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 130
60 - 79% = 134
80 - 99% = 66

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 5840 ms, from 23:57:46.72 to 23:57:52.56.

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 440624 14288 3.2426
DOC_LIST 70990 5425 7.6419
DOC_STATES 4 0 0.0000
FB_ERRORS 758 0 0.0000
INVNT_SALDO 400 70313 17578.2500
INVNT_TURNOVER_LOG 124006 123881 99.8991
MONEY_SALDO 49 3635 7418.3673
MONEY_TURNOVER_LOG 5847 5807 99.3158
OPTYPES 9 0 0.0000
PDISTR 121179 82049 67.7089
PERF_ESTIMATED 17777 2 0.0112
PERF_LOG 1058672 1 0.0000
PSTORNED 84780 5392 6.3599
RULES_FOR_PDISTR 4 0 0.0000
RULES_FOR_QDISTR 7 0 0.0000
SEMAPHORES 4 789 19725.0000
SETTINGS 114 0 0.0000
TRACE_STAT 223 0 0.0000
WARES 400 0 0.0000
WARE_GROUPS 8 0 0.0000
XQD_1000_1200 197755 197376 99.8083
XQD_1000_3300 211110 55198 26.1465
XQD_1200_2000 242196 241771 99.8245
XQD_2000_3300 967338 728145 75.2730
XQD_2100_3300 129164 122417 94.7764
XQD_3300_3400 719220 313567 43.5982
XQS_1000_1200 928016 19934 2.1480
XQS_1000_3300 616213 83890 13.6137
XQS_1200_2000 965730 41541 4.3015
XQS_2100_3300 753837 75588 10.0271
XQS_3300_3400 315076 42480 13.4824
Z_USED_VIEWS 32 0 0.0000
Done for 2360 ms, from 23:58:03.01 to 23:58:05.37.

Database validation

23:58:05.44 Validation started

23:58:05.44 Relation 140 (DOC_LIST)
23:58:05.44 process pointer page 0 of 1
23:58:05.46 Index 1 (PK_DOC_LIST)
23:58:05.48 Index 2 (DOC_LIST_ID_DESC)
23:58:05.48 Index 3 (FK_DOC_LIST_AGENTS)
23:58:05.49 Relation 140 (DOC_LIST) is ok

23:58:05.49 Relation 141 (DOC_DATA)
23:58:05.49 process pointer page 0 of 3
23:58:05.58 process pointer page 1 of 3
23:58:05.71 process pointer page 2 of 3
23:58:05.76 Index 2 (DOC_DATA_DOC_WARE_UNQ)
23:58:05.93 Index 3 (DOC_DATA_ID_DESC)
23:58:06.08 Index 4 (FK_DOC_DATA_DOC_LIST)
23:58:06.19 Relation 141 (DOC_DATA) is ok

23:58:06.19 Relation 147 (INVNT_SALDO)
23:58:06.19 process pointer page 0 of 1
23:58:06.29 Index 1 (PK_INVNT_SALDO)
23:58:06.29 Relation 147 (INVNT_SALDO) is ok

23:58:06.29 Relation 150 (PDISTR)
23:58:06.29 process pointer page 0 of 1
23:58:06.43 Index 2 (PDISTR_SND_ID)
23:58:06.46 Index 3 (PDISTR_SNDOP_RCVOP_SNDID_ASC)
23:58:06.50 Index 4 (PDISTR_SNDOP_RCVOP_SNDID_DESC)
23:58:06.54 Index 5 (PDISTR_AGENT_ID)
23:58:06.57 Relation 150 (PDISTR) is ok

23:58:06.57 Relation 151 (PSTORNED)
23:58:06.57 process pointer page 0 of 1
23:58:06.63 Index 2 (PSTORNED_SND_ID)
23:58:06.65 Index 3 (PSTORNED_RCV_ID)
23:58:06.66 Relation 151 (PSTORNED) is ok

23:58:06.66 Relation 156 (SEMAPHORES)
23:58:06.66 process pointer page 0 of 1
23:58:06.66 Index 1 (PK_SEMAPHORES)
23:58:06.66 Index 2 (SEMAPHORES_TASK_UNQ)
23:58:06.66 Relation 156 (SEMAPHORES) is ok

23:58:06.66 Relation 157 (INVNT_TURNOVER_LOG)
23:58:06.66 process pointer page 0 of 1
23:58:06.85 Index 1 (INVNT_TURNOVER_LOG_WARE_DD_ID)
23:58:06.91 Relation 157 (INVNT_TURNOVER_LOG) is ok

23:58:06.91 Relation 160 (PERF_ESTIMATED)
23:58:06.91 process pointer page 0 of 1
23:58:06.93 Index 1 (PERF_EST_MINUTE_SINCE_START)
23:58:06.93 Relation 160 (PERF_ESTIMATED) is ok

23:58:06.93 Relation 161 (PERF_ISQL_STAT)
23:58:06.93 process pointer page 0 of 1
23:58:06.93 Index 1 (PERF_ISQL_STAT_TRN)
23:58:06.93 Relation 161 (PERF_ISQL_STAT) is ok

23:58:06.93 Relation 162 (PERF_LOG)
23:58:06.93 process pointer page 0 of 13
23:58:07.02 process pointer page 1 of 13
23:58:07.10 process pointer page 2 of 13
23:58:07.19 process pointer page 3 of 13
23:58:07.27 process pointer page 4 of 13
23:58:07.36 process pointer page 5 of 13
23:58:07.44 process pointer page 6 of 13
23:58:07.53 process pointer page 7 of 13
23:58:07.63 process pointer page 8 of 13
23:58:07.71 process pointer page 9 of 13
23:58:07.78 process pointer page 10 of 13
23:58:07.85 process pointer page 11 of 13
23:58:07.92 process pointer page 12 of 13
23:58:07.97 Index 1 (PERF_LOG_DTS_BEG_DESC)
23:58:08.30 Index 2 (PERF_LOG_UNIT)
23:58:08.84 Index 3 (PERF_LOG_TRN_DESC)
23:58:09.11 Index 4 (PERF_LOG_GDSCODE)
23:58:09.23 Relation 162 (PERF_LOG) is ok

23:58:09.23 Relation 166 (V_STOPTEST)
23:58:09.23 Relation 166 (V_STOPTEST) is ok

23:58:09.23 Relation 183 (V_QDISTR_SOURCE)
23:58:09.23 Relation 183 (V_QDISTR_SOURCE) is ok

23:58:09.23 Relation 184 (V_QSTORNED_SOURCE)
23:58:09.23 Relation 184 (V_QSTORNED_SOURCE) is ok

23:58:09.23 Relation 258 (XQD_1000_1200)
23:58:09.23 process pointer page 0 of 2
23:58:09.44 process pointer page 1 of 2
23:58:09.52 Index 1 (XQD_1000_1200_WARE_SOP_ROP_SND)
23:58:09.58 Relation 258 (XQD_1000_1200) is ok

23:58:09.58 Relation 259 (XQD_1200_2000)
23:58:09.58 process pointer page 0 of 2
23:58:09.78 process pointer page 1 of 2
23:58:09.92 Index 1 (XQD_1200_2000_WARE_SOP_ROP_SND)
23:58:09.98 Relation 259 (XQD_1200_2000) is ok

23:58:09.98 Relation 260 (XQD_1000_3300)
23:58:09.98 process pointer page 0 of 2
23:58:10.12 process pointer page 1 of 2
23:58:10.19 Index 1 (XQD_1000_3300_WARE_SOP_ROP_SND)
23:58:10.25 Index 2 (XQD_1000_3300_DOC)
23:58:10.30 Relation 260 (XQD_1000_3300) is ok

23:58:10.30 Relation 261 (XQD_2000_3300)
23:58:10.30 process pointer page 0 of 7
23:58:10.50 process pointer page 1 of 7
23:58:10.70 process pointer page 2 of 7
23:58:10.89 process pointer page 3 of 7
23:58:11.09 process pointer page 4 of 7
23:58:11.26 process pointer page 5 of 7
23:58:11.40 process pointer page 6 of 7
23:58:11.48 Index 1 (XQD_2000_3300_WARE_SOP_ROP_SND)
23:58:11.79 Relation 261 (XQD_2000_3300) is ok

23:58:11.79 Relation 262 (XQD_2100_3300)
23:58:11.79 process pointer page 0 of 2
23:58:11.90 process pointer page 1 of 2
23:58:12.03 Index 1 (XQD_2100_3300_WARE_SOP_ROP_SND)
23:58:12.09 Relation 262 (XQD_2100_3300) is ok

23:58:12.09 Relation 263 (XQD_3300_3400)
23:58:12.09 process pointer page 0 of 6
23:58:12.26 process pointer page 1 of 6
23:58:12.43 process pointer page 2 of 6
23:58:12.59 process pointer page 3 of 6
23:58:12.71 process pointer page 4 of 6
23:58:12.82 process pointer page 5 of 6
23:58:12.85 Index 1 (XQD_3300_3400_WARE_SOP_ROP_SND)
23:58:13.09 Relation 263 (XQD_3300_3400) is ok

23:58:13.09 Relation 264 (XQS_1000_1200)
23:58:13.09 process pointer page 0 of 8
23:58:13.20 process pointer page 1 of 8
23:58:13.31 process pointer page 2 of 8
23:58:13.42 process pointer page 3 of 8
23:58:13.52 process pointer page 4 of 8
23:58:13.63 process pointer page 5 of 8
23:58:13.74 process pointer page 6 of 8
23:58:13.85 process pointer page 7 of 8
23:58:13.87 Index 1 (XQS_1000_1200_DOC_ID)
23:58:14.13 Index 2 (XQS_1000_1200_SND_ID)
23:58:14.41 Index 3 (XQS_1000_1200_RCV_ID)
23:58:14.62 Relation 264 (XQS_1000_1200) is ok

23:58:14.62 Relation 265 (XQS_1200_2000)
23:58:14.62 process pointer page 0 of 8
23:58:14.73 process pointer page 1 of 8
23:58:14.84 process pointer page 2 of 8
23:58:14.94 process pointer page 3 of 8
23:58:14.99 process pointer page 4 of 8
23:58:15.04 process pointer page 5 of 8
23:58:15.13 process pointer page 6 of 8
23:58:15.24 process pointer page 7 of 8
23:58:15.30 Index 1 (XQS_1200_2000_DOC_ID)
23:58:15.55 Index 2 (XQS_1200_2000_SND_ID)
23:58:15.83 Index 3 (XQS_1200_2000_RCV_ID)
23:58:16.05 Relation 265 (XQS_1200_2000) is ok

23:58:16.05 Relation 266 (XQS_1000_3300)
23:58:16.05 process pointer page 0 of 5
23:58:16.16 process pointer page 1 of 5
23:58:16.29 process pointer page 2 of 5
23:58:16.33 process pointer page 3 of 5
23:58:16.43 process pointer page 4 of 5
23:58:16.52 Index 1 (XQS_1000_3300_DOC_ID)
23:58:16.68 Index 2 (XQS_1000_3300_SND_ID)
23:58:16.85 Index 3 (XQS_1000_3300_RCV_ID)
23:58:16.99 Relation 266 (XQS_1000_3300) is ok

23:58:16.99 Relation 267 (XQS_2000_3300)
23:58:16.99 process pointer page 0 of 1
23:58:16.99 Index 1 (XQS_2000_3300_DOC_ID)
23:58:16.99 Index 2 (XQS_2000_3300_SND_ID)
23:58:16.99 Index 3 (XQS_2000_3300_RCV_ID)
23:58:16.99 Relation 267 (XQS_2000_3300) is ok

23:58:16.99 Relation 268 (XQS_2100_3300)
23:58:16.99 process pointer page 0 of 7
23:58:17.11 process pointer page 1 of 7
23:58:17.24 process pointer page 2 of 7
23:58:17.35 process pointer page 3 of 7
23:58:17.46 process pointer page 4 of 7
23:58:17.57 process pointer page 5 of 7
23:58:17.67 process pointer page 6 of 7
23:58:17.67 Index 1 (XQS_2100_3300_DOC_ID)
23:58:17.92 Index 2 (XQS_2100_3300_SND_ID)
23:58:18.17 Index 3 (XQS_2100_3300_RCV_ID)
23:58:18.36 Relation 268 (XQS_2100_3300) is ok

23:58:18.36 Relation 269 (XQS_3300_3400)
23:58:18.36 process pointer page 0 of 3
23:58:18.49 process pointer page 1 of 3
23:58:18.61 process pointer page 2 of 3
23:58:18.64 Index 1 (XQS_3300_3400_DOC_ID)
23:58:18.70 Index 2 (XQS_3300_3400_SND_ID)
23:58:18.74 Relation 269 (XQS_3300_3400) is ok

23:58:18.74 Relation 276 (TRACE_STAT)
23:58:18.74 process pointer page 0 of 1
23:58:18.74 Relation 276 (TRACE_STAT) is ok

23:58:18.74 Validation finished
Done for 13360 ms, from 23:58:05.38 to 23:58:18.74.

Differences between old and current firebird.log

23:58:19.09. 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-03-19 23:58 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 302 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-03-19 23:58:20.95 - end of report, text file: e:\temp\logs.oltp25\oltp25.report.txt, html: e:\temp\logs.oltp25\oltp25.report.html