Firebird 2.5 Performance Logs

Generated by E:\OLTP-EMUL\src\oltp_isql_run_worker.bat, ISQL session #1 of total launched 70. 2016-02-18 19:07:49.76.
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.26973 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
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-02-18 19:07:16.9560 -1 <null> 127.0.0.1 90337 4266 2

Current test settings

CATEGORY SETTING VAL
COMMON TRACED_UNITS ,,
COMMON ENABLE_MON_QUERY 0
COMMON HALT_TEST_ON_ERRORS ,CK,
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
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 179 minutes: 548.27 73733 98140 2016-02-18 16:07 2016-02-18 19:06
customer order: creation 28.77 89 5150 2016-02-18 16:07 2016-02-18 19:06
customer order: refuse 2.21 31 393 2016-02-18 16:07 2016-02-18 19:06
order to supplier: creation 7.06 2939 1264 2016-02-18 16:07 2016-02-18 19:06
order to supplier: removal 0.14 100523 23 2016-02-18 16:07 2016-02-18 19:06
invoice (draft): creation 7.25 2169 1297 2016-02-18 16:07 2016-02-18 19:06
invoice (draft): removal 1.39 139 241 2016-02-18 16:07 2016-02-18 19:06
invoice accept: apply 2.39 1236822 428 2016-02-18 16:07 2016-02-18 19:06
invoice accept: cancel 0.60 41081 99 2016-02-18 16:07 2016-02-18 19:06
customer reserve: creation 438.16 7328 78431 2016-02-18 16:07 2016-02-18 19:06
customer reserve: removal 19.60 2004 3410 2016-02-18 16:07 2016-02-18 19:06
realization accept: apply 13.33 51 2386 2016-02-18 16:07 2016-02-18 19:06
realization accept: cancel 4.81 37 857 2016-02-18 16:07 2016-02-18 19:06
payment from customer: creation 9.76 101 1747 2016-02-18 16:07 2016-02-18 19:06
payment from customer: removal 0.87 16 143 2016-02-18 16:07 2016-02-18 19:06
payment to supplier: creation 8.12 226 1453 2016-02-18 16:07 2016-02-18 19:06
payment to supplier: removal 0.61 18 106 2016-02-18 16:07 2016-02-18 19:06
service: total inventory turnovers 2.09 5967 374 2016-02-18 16:07 2016-02-18 19:06
service: total monetary turnovers 1.83 11 327 2016-02-18 16:07 2016-02-18 19:06
service: refresh index statistics 0.07 1383 11 2016-02-18 16:07 2016-02-18 19:06
Done for 5080 ms, from 19:08:03.26 to 19:08:08.34.

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 429 9344 7714 1630 17.44 16:07:17 16:25:11
interval # 2, overall 2 448 8679 8058 621 7.16 16:25:12 16:43:06
interval # 3, overall 3 436 8336 7853 483 5.79 16:43:07 17:01:01
interval # 4, overall 4 454 7851 7724 127 1.62 17:01:02 17:18:56
interval # 5, overall 5 511 9445 9202 243 2.57 17:18:57 17:36:51
interval # 6, overall 6 840 15488 15122 366 2.36 17:36:52 17:54:46
interval # 7, overall 7 1137 21061 20463 598 2.84 17:54:47 18:12:41
interval # 8, overall 8 803 16479 14448 2031 12.32 18:12:42 18:30:36
interval # 9, overall 9 347 6295 6240 55 0.87 18:30:37 18:48:31
interval # 10, overall 10 73 1341 1316 25 1.86 18:48:32 19:06:26
Done for 8670 ms, from 19:08:15.08 to 19:08:23.75.

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 1448.98 0.5210 1.7529 975 70
WARM_TIME 2 1562.16 0.8132 1.1868 963 68
WARM_TIME 3 1372.20 0.9009 1.1405 738 65
WARM_TIME 4 1301.12 0.9022 1.0850 678 61
WARM_TIME 5 1189.94 0.9492 1.0511 625 53
WARM_TIME 6 1089.76 0.9566 1.0444 621 42
WARM_TIME 7 1054.85 0.9249 1.0670 537 42
WARM_TIME 8 1008.44 0.9767 1.0293 324 35
WARM_TIME 9 959.32 0.9619 1.0411 417 37
WARM_TIME 10 925.78 0.9710 1.0254 466 31
TEST_TIME 11 894.26 0.9651 1.0401 637 28
TEST_TIME 12 873.72 0.9759 1.0262 351 12
TEST_TIME 13 840.05 0.9853 1.0103 101 7
TEST_TIME 14 796.63 0.9894 1.0070 162 8
TEST_TIME 15 760.40 0.9848 1.0266 272 8
TEST_TIME 16 736.47 0.9938 1.0130 112 7
TEST_TIME 17 711.43 0.9870 1.0105 162 4
TEST_TIME 18 682.23 0.9953 1.0027 66 2
TEST_TIME 19 652.59 0.9931 1.0040 69 3
TEST_TIME 20 626.17 0.9941 1.0043 104 3
TEST_TIME 21 602.47 0.9942 1.0044 69 2
TEST_TIME 22 579.61 0.9997 1.0002 9 2
TEST_TIME 23 555.38 0.9985 1.0015 44 1
TEST_TIME 24 535.80 0.9995 1.0005 14 1
TEST_TIME 25 518.83 0.9997 1.0003 10 1
TEST_TIME 26 506.62 0.9911 1.0041 36 2
TEST_TIME 27 490.32 0.9991 1.0008 23 1
TEST_TIME 28 481.51 0.9881 1.0031 78 2
TEST_TIME 29 471.63 0.9936 1.0062 182 2
TEST_TIME 30 463.90 0.9890 1.0033 56 2
TEST_TIME 31 458.96 0.9922 1.0038 81 3
TEST_TIME 34 422.75 0.9961 1.0095 56 3
TEST_TIME 35 416.62 0.9951 1.0049 109 4
TEST_TIME 36 408.68 0.9960 1.0087 88 4
TEST_TIME 37 410.42 0.9773 1.0136 63 6
TEST_TIME 38 419.71 0.9651 1.0120 193 6
TEST_TIME 39 430.02 0.9624 1.0112 264 5
TEST_TIME 40 431.43 0.9828 1.0177 149 7
TEST_TIME 41 432.35 0.9908 1.0084 163 5
TEST_TIME 42 428.33 0.9937 1.0093 147 6
TEST_TIME 43 431.31 0.9865 1.0015 48 2
TEST_TIME 44 428.74 0.9971 1.0101 90 2
TEST_TIME 45 429.22 0.9942 1.0045 32 2
TEST_TIME 46 425.81 0.9990 1.0003 11 2
TEST_TIME 47 421.45 0.9892 1.0069 218 5
TEST_TIME 48 422.24 0.9841 1.0208 134 6
TEST_TIME 49 425.95 0.9913 1.0071 162 5
TEST_TIME 50 425.44 0.9882 1.0049 110 3
TEST_TIME 51 422.00 0.9932 1.0059 230 4
TEST_TIME 52 419.75 0.9918 1.0092 125 5
TEST_TIME 53 416.13 0.9988 1.0011 50 3
TEST_TIME 54 411.70 0.9995 1.0004 24 1
TEST_TIME 56 402.33 0.9988 1.0009 38 2
TEST_TIME 57 400.32 0.9933 1.0069 43 2
TEST_TIME 58 405.28 0.9775 1.0070 187 3
TEST_TIME 60 402.82 0.9811 1.0183 132 5
TEST_TIME 61 408.10 0.9887 1.0129 140 4
TEST_TIME 63 400.97 0.9989 1.0021 3 2
TEST_TIME 64 400.57 0.9956 1.0009 44 3
TEST_TIME 65 395.11 0.9992 1.0006 30 2
TEST_TIME 66 391.30 0.9996 1.0003 18 1
TEST_TIME 67 390.25 0.9881 1.0027 70 3
TEST_TIME 68 394.96 0.9763 1.0090 125 5
TEST_TIME 69 400.53 0.9806 1.0074 151 4
TEST_TIME 70 401.33 0.9911 1.0036 60 2
TEST_TIME 71 399.80 0.9966 1.0028 36 2
TEST_TIME 72 400.44 0.9873 1.0054 257 5
TEST_TIME 73 400.45 0.9917 1.0085 69 3
TEST_TIME 74 403.90 0.9874 1.0094 121 5
TEST_TIME 76 402.33 0.9987 1.0032 81 3
TEST_TIME 77 399.31 0.9994 1.0005 39 1
TEST_TIME 79 393.66 0.9997 1.0002 15 1
TEST_TIME 80 392.18 0.9994 1.0006 27 2
TEST_TIME 81 388.05 0.9988 1.0043 44 2
TEST_TIME 82 387.87 0.9927 1.0081 48 3
TEST_TIME 83 386.88 0.9988 1.0011 87 3
TEST_TIME 84 382.96 0.9998 1.0001 10 1
TEST_TIME 86 382.08 0.9905 1.0025 26 2
TEST_TIME 87 381.65 0.9921 1.0120 168 4
TEST_TIME 88 382.75 0.9977 1.0011 73 2
TEST_TIME 89 384.90 0.9873 1.0106 75 5
TEST_TIME 90 386.76 0.9945 1.0136 205 6
TEST_TIME 91 387.95 0.9995 1.0005 37 1
TEST_TIME 92 397.04 0.9710 1.0089 178 7
TEST_TIME 93 397.07 0.9981 1.0049 58 4
TEST_TIME 94 399.09 0.9900 1.0038 53 4
TEST_TIME 95 396.98 0.9998 1.0001 12 1
TEST_TIME 96 394.79 0.9998 1.0001 12 1
TEST_TIME 97 395.20 0.9950 1.0013 6 3
TEST_TIME 98 395.40 0.9968 1.0173 83 6
TEST_TIME 100 396.27 0.9958 1.0015 86 4
TEST_TIME 101 399.16 0.9853 1.0059 106 4
TEST_TIME 102 400.79 0.9920 1.0036 70 5
TEST_TIME 103 401.45 0.9922 1.0038 41 5
TEST_TIME 104 403.72 0.9885 1.0061 154 5
TEST_TIME 105 406.80 0.9890 1.0159 124 7
TEST_TIME 106 418.28 0.9868 1.0114 148 6
TEST_TIME 107 425.18 0.9857 1.0152 250 7
TEST_TIME 108 432.04 0.9899 1.0084 157 7
TEST_TIME 109 434.69 0.9930 1.0065 197 8
TEST_TIME 110 438.11 0.9923 1.0076 63 3
TEST_TIME 111 444.00 0.9853 1.0055 236 7
TEST_TIME 112 444.29 0.9959 1.0051 88 3
TEST_TIME 113 445.28 0.9940 1.0037 47 4
TEST_TIME 114 447.25 0.9905 1.0078 169 5
TEST_TIME 115 453.09 0.9933 1.0019 74 3
TEST_TIME 116 456.19 0.9877 1.0165 143 9
TEST_TIME 117 462.47 0.9942 1.0100 110 6
TEST_TIME 118 470.58 0.9842 1.0092 213 7
TEST_TIME 119 477.15 0.9870 1.0045 136 6
TEST_TIME 120 482.19 0.9869 1.0151 81 7
TEST_TIME 121 490.99 0.9887 1.0104 95 8
TEST_TIME 122 502.83 0.9785 1.0185 271 11
TEST_TIME 123 510.75 0.9946 1.0095 282 11
TEST_TIME 124 514.93 0.9933 1.0147 263 11
TEST_TIME 125 521.44 0.9940 1.0032 79 6
TEST_TIME 126 529.24 0.9847 1.0149 153 12
TEST_TIME 127 538.88 0.9906 1.0049 387 11
TEST_TIME 128 541.79 0.9917 1.0019 107 7
TEST_TIME 129 545.12 0.9920 1.0106 116 7
TEST_TIME 130 551.37 0.9914 1.0038 118 10
TEST_TIME 131 551.74 0.9955 1.0034 96 7
TEST_TIME 132 555.51 0.9907 1.0124 181 8
TEST_TIME 133 563.56 0.9905 1.0127 224 13
TEST_TIME 134 570.04 0.9937 1.0076 109 8
TEST_TIME 135 576.80 0.9911 1.0131 119 9
TEST_TIME 136 582.98 0.9950 1.0052 247 8
TEST_TIME 137 587.64 0.9924 1.0051 191 9
TEST_TIME 138 592.55 0.9896 1.0082 160 11
TEST_TIME 139 596.47 0.9973 1.0026 159 7
TEST_TIME 140 595.46 0.9971 1.0043 88 7
TEST_TIME 141 599.76 0.9916 1.0113 211 7
TEST_TIME 142 603.06 0.9987 1.0067 184 5
TEST_TIME 143 604.11 0.9982 1.0003 64 4
TEST_TIME 144 600.51 0.9994 1.0019 16 3
TEST_TIME 145 603.23 0.9943 1.0059 51 5
TEST_TIME 146 603.77 0.9986 1.0022 88 5
TEST_TIME 147 605.81 0.9926 1.0078 69 4
TEST_TIME 148 609.90 0.9943 1.0018 37 3
TEST_TIME 149 608.54 0.9973 1.0030 64 4
TEST_TIME 150 609.10 0.9976 1.0030 34 6
TEST_TIME 151 609.66 0.9955 1.0020 97 3
TEST_TIME 152 609.71 0.9956 1.0078 59 4
TEST_TIME 153 616.18 0.9907 1.0036 78 6
TEST_TIME 154 616.91 0.9987 1.0021 22 3
TEST_TIME 155 619.94 0.9910 1.0070 164 6
TEST_TIME 156 624.76 0.9929 1.0119 129 7
TEST_TIME 157 629.29 0.9982 1.0060 76 5
TEST_TIME 158 631.30 0.9964 1.0083 129 5
TEST_TIME 159 635.92 0.9988 1.0007 89 6
TEST_TIME 160 637.51 0.9968 1.0014 39 3
TEST_TIME 161 637.05 0.9997 1.0008 29 2
TEST_TIME 162 634.42 0.9994 1.0029 68 3
TEST_TIME 163 632.72 0.9994 1.0006 86 2
TEST_TIME 164 631.68 0.9961 1.0051 58 3
TEST_TIME 165 631.28 0.9997 1.0009 15 2
TEST_TIME 166 628.63 0.9991 1.0019 72 3
TEST_TIME 167 626.25 0.9997 1.0002 18 2
TEST_TIME 169 619.48 0.9994 1.0003 66 2
TEST_TIME 170 616.38 0.9994 1.0014 33 3
TEST_TIME 171 615.46 0.9971 1.0046 135 3
TEST_TIME 172 615.37 0.9999 1.0000 8 1
TEST_TIME 174 609.14 0.9999 1.0000 17 1
TEST_TIME 175 606.65 0.9987 1.0011 33 3
TEST_TIME 176 607.96 0.9980 1.0010 52 3
TEST_TIME 177 605.80 0.9997 1.0002 18 2
TEST_TIME 179 600.19 0.9990 1.0027 20 2
TEST_TIME 180 598.56 0.9999 1.0001 19 1
TEST_TIME 181 596.16 0.9999 1.0000 14 1
TEST_TIME 184 586.79 0.9996 1.0004 93 1
TEST_TIME 187 578.26 0.9996 1.0011 41 2
TEST_TIME 188 577.27 0.9975 1.0011 106 4
TEST_TIME 189 575.27 0.9993 1.0002 22 2
TEST_TIME 190 572.42 1.0000 1.0000 140 70
Done for 9420 ms, from 19:08:24.07 to 19:08:33.49.

Performance from TRACE for ISQL instance #1:

TRACED_DATA ITRV_NO SP_CLIENT_ORDER SP_CANCEL_CLIENT_ORDER SP_SUPPLIER_ORDER SP_CANCEL_SUPPLIER_ORDER SP_SUPPLIER_INVOICE SP_CANCEL_SUPPLIER_INVOICE SP_ADD_INVOICE_TO_STOCK SP_CANCEL_ADDING_INVOICE SP_CUSTOMER_RESERVE SP_CANCEL_CUSTOMER_RESERVE SP_RESERVE_WRITE_OFF SP_CANCEL_WRITE_OFF SP_PAY_FROM_CUSTOMER SP_CANCEL_PAY_FROM_CUSTOMER SP_PAY_TO_SUPPLIER SP_CANCEL_PAY_TO_SUPPLIER SRV_MAKE_INVNT_SALDO SRV_MAKE_MONEY_SALDO SRV_RECALC_IDX_STAT ITRV_BEG ITRV_END
fetches per second 2 87856 129580 74389 <null> 42679 <null> 2655 <null> <null> <null> 101271 99168 155817 100500 342796 <null> 126296 <null> <null> 16:25:12 16:43:06
fetches per second 7 19945 62900 22136 <null> 31305 <null> 2394 <null> 2305 <null> 62646 37722 134434 21603 288029 111600 <null> 630052 <null> 17:54:47 18:12:41
fetches per second 8 42209 <null> <null> <null> 73927 <null> 1999 <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> 18:12:42 18:30:36
marks per second 2 15959 12413 6640 <null> 3230 <null> 281 <null> <null> <null> 13724 11581 8996 11400 9127 <null> 782 <null> <null> 16:25:12 16:43:06
marks per second 7 3693 6053 1236 <null> 1660 <null> 77 <null> 107 <null> 5667 4222 1975 2320 1750 10300 <null> 973 <null> 17:54:47 18:12:41
marks per second 8 8339 <null> <null> <null> 6846 <null> 49 <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> 18:12:42 18:30:36
reads/fetches*100 2 4 3 7 <null> 5 <null> 12 <null> <null> <null> 6 4 5 4 3 <null> 20 <null> <null> 16:25:12 16:43:06
reads/fetches*100 7 5 5 7 <null> 6 <null> 15 <null> 17 <null> 7 7 5 6 3 2 <null> 0 <null> 17:54:47 18:12:41
reads/fetches*100 8 4 <null> <null> <null> 5 <null> 15 <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> 18:12:42 18:30:36
writes/marks*100 2 13 13 7 <null> 7 <null> 1 <null> <null> <null> 7 10 4 0 3 <null> 40 <null> <null> 16:25:12 16:43:06
writes/marks*100 7 11 16 7 <null> 6 <null> 6 <null> 14 <null> 8 12 8 0 6 0 <null> 0 <null> 17:54:47 18:12:41
writes/marks*100 8 13 <null> <null> <null> 9 <null> 3 <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> <null> 18:12:42 18:30:36
Done for 4120 ms, from 19:08:34.22 to 19:08:38.34.

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 68399 63283 5116 7.47 0 6084 18 5116 2016-02-18 16:07 2016-02-18 19:07
doc_list_biud 68482 63282 5200 7.59 0 272422 335 5200 2016-02-18 16:07 2016-02-18 19:07
sp_add_invoice_to_stock 1161 428 733 63.13 4415 7288611 1236822 733 2016-02-18 16:07 2016-02-18 19:07
sp_cancel_adding_invoice 155 99 56 36.12 94 557049 41081 56 2016-02-18 16:07 2016-02-18 19:07
sp_cancel_client_order 429 393 36 8.39 0 296 31 36 2016-02-18 16:07 2016-02-18 19:07
sp_cancel_customer_reserve 6932 3410 3522 50.80 0 56133 2004 3522 2016-02-18 16:07 2016-02-18 19:07
sp_cancel_pay_from_customer 143 143 0 0.00 0 141 16 0 2016-02-18 16:07 2016-02-18 19:07
sp_cancel_pay_to_supplier 106 106 0 0.00 0 141 18 0 2016-02-18 16:07 2016-02-18 19:07
sp_cancel_supplier_invoice 307 241 66 21.49 0 1107 139 66 2016-02-18 16:07 2016-02-18 19:07
sp_cancel_supplier_order 96 23 73 76.04 62 909231 100523 73 2016-02-18 16:07 2016-02-18 19:07
sp_cancel_write_off 2460 857 1603 65.16 0 297 37 1603 2016-02-18 16:07 2016-02-18 19:07
sp_client_order 5150 5150 0 0.00 0 6131 89 0 2016-02-18 16:07 2016-02-18 19:07
sp_customer_reserve 78431 78431 0 0.00 6 48834 7328 0 2016-02-18 16:07 2016-02-18 19:07
sp_fill_shopping_cart 7711 7711 0 0.00 0 45750 588 0 2016-02-18 16:07 2016-02-18 19:07
sp_fill_shopping_cart_clo_res 78431 78431 0 0.00 0 375 2 0 2016-02-18 16:07 2016-02-18 19:07
sp_get_clo_for_invoice 429 429 0 0.00 0 717 83 0 2016-02-18 16:07 2016-02-18 19:07
sp_kill_cost_storno 2810 1205 1605 57.11 0 203 4 1605 2016-02-18 16:07 2016-02-18 19:07
sp_kill_qstorno_ret_qs2qd 16557 7941 8616 52.03 0 56071 863 8616 2016-02-18 16:07 2016-02-18 19:07
sp_kill_qty_storno 13017 7837 5180 39.79 0 272421 2698 5180 2016-02-18 16:07 2016-02-18 19:07
sp_lock_selected_doc 5037 3999 1038 20.60 0 16 0 1038 2016-02-18 16:07 2016-02-18 19:07
sp_make_cost_storno 6015 6015 0 0.00 0 1622 72 0 2016-02-18 16:07 2016-02-18 19:07
sp_multiply_rows_for_pdistr 6015 6015 0 0.00 0 531 8 0 2016-02-18 16:07 2016-02-18 19:07
sp_multiply_rows_for_qdistr 5150 5150 0 0.00 0 4852 50 0 2016-02-18 16:07 2016-02-18 19:07
sp_pay_from_customer 1747 1747 0 0.00 31 936 101 0 2016-02-18 16:07 2016-02-18 19:07
sp_pay_to_supplier 1453 1453 0 0.00 31 1622 226 0 2016-02-18 16:07 2016-02-18 19:07
sp_payment_common 3200 3200 0 0.00 0 593 27 0 2016-02-18 16:07 2016-02-18 19:07
sp_qd_handle_on_cancel_clo 429 393 36 8.39 0 265 12 36 2016-02-18 16:07 2016-02-18 19:07
sp_qd_handle_on_invoice_upd_sts 564 527 37 6.56 15 272421 26958 37 2016-02-18 16:07 2016-02-18 19:07
sp_qd_handle_on_reserve_upd_sts 2386 2386 0 0.00 0 234 12 0 2016-02-18 16:07 2016-02-18 19:07
sp_reserve_write_off 2476 2386 90 3.63 0 780 51 90 2016-02-18 16:07 2016-02-18 19:07
sp_supplier_invoice 1297 1297 0 0.00 16 45968 2169 0 2016-02-18 16:07 2016-02-18 19:07
sp_supplier_order 1264 1264 0 0.00 31 36604 2939 0 2016-02-18 16:07 2016-02-18 19:07
srv_find_qd_qs_mism 38997 33857 5140 13.18 0 6084 19 5140 2016-02-18 16:07 2016-02-18 19:07
srv_make_invnt_saldo 374 374 0 0.00 1794 26652 5967 0 2016-02-18 16:07 2016-02-18 19:07
srv_make_money_saldo 327 327 0 0.00 0 93 11 0 2016-02-18 16:07 2016-02-18 19:07
srv_recalc_idx_stat 11 11 0 0.00 1139 1653 1383 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-abend:sp_add_invoice_to_stock 733 733 0 0.00 0 124 6 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-abend:sp_cancel_adding_invoice 139 139 0 0.00 0 406 56 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-abend:sp_cancel_client_order 36 36 0 0.00 0 32 9 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-abend:sp_cancel_customer_reserve 5 5 0 0.00 0 16 3 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-abend:sp_cancel_supplier_invoice 58 58 0 0.00 0 62 10 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-abend:sp_cancel_supplier_order 572 572 0 0.00 0 484 50 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-abend:sp_cancel_write_off 5 5 0 0.00 0 16 3 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-abend:sp_reserve_write_off 90 90 0 0.00 0 47 4 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_add_invoice_to_stock 468 468 0 0.00 15 2184 271 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_cancel_adding_invoice 60 60 0 0.00 15 936 142 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_cancel_client_order 393 393 0 0.00 0 250 10 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_cancel_customer_reserve 160 160 0 0.00 0 109 12 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_cancel_pay_from_customer 143 143 0 0.00 0 109 6 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_cancel_pay_to_supplier 106 106 0 0.00 0 156 6 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_cancel_supplier_invoice 46 46 0 0.00 0 47 7 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_cancel_supplier_order 23 23 0 0.00 0 2933 370 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_cancel_write_off 254 254 0 0.00 0 125 10 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_client_order 5155 5155 0 0.00 0 359 12 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_customer_reserve 236 236 0 0.00 0 203 12 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_pay_from_customer 1747 1747 0 0.00 0 188 9 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_pay_to_supplier 1453 1453 0 0.00 0 156 7 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_reserve_write_off 2386 2386 0 0.00 0 249 10 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_supplier_invoice 1299 1299 0 0.00 0 247 12 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:sp_supplier_order 1282 1282 0 0.00 0 375 14 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:srv_make_invnt_saldo 375 375 0 0.00 0 32 2 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:srv_make_money_saldo 327 327 0 0.00 0 93 2 0 2016-02-18 16:07 2016-02-18 19:07
t$perf-norm:srv_recalc_idx_stat 11 11 0 0.00 0 16 9 0 2016-02-18 16:07 2016-02-18 19:07
v_add_invoice_to_stock 2322 856 1466 63.13 0 78 2 1466 2016-02-18 16:07 2016-02-18 19:07
v_all_customers 7706 7706 0 0.00 0 16 0 0 2016-02-18 16:07 2016-02-18 19:07
v_all_suppliers 5122 5122 0 0.00 0 46 0 0 2016-02-18 16:07 2016-02-18 19:07
v_all_wares 10300 10300 0 0.00 0 16 0 0 2016-02-18 16:07 2016-02-18 19:07
v_cancel_adding_invoice 196 120 76 38.77 0 47 6 76 2016-02-18 16:07 2016-02-18 19:07
v_cancel_client_order 858 786 72 8.39 0 16 0 72 2016-02-18 16:07 2016-02-18 19:07
v_cancel_customer_prepayment 286 286 0 0.00 0 16 0 0 2016-02-18 16:07 2016-02-18 19:07
v_cancel_customer_reserve 330 320 10 3.03 0 16 1 10 2016-02-18 16:07 2016-02-18 19:07
v_cancel_payment_to_supplier 212 212 0 0.00 0 16 1 0 2016-02-18 16:07 2016-02-18 19:07
v_cancel_supplier_invoice 208 92 116 55.76 0 16 2 116 2016-02-18 16:07 2016-02-18 19:07
v_cancel_supplier_order 192 46 146 76.04 0 16 0 146 2016-02-18 16:07 2016-02-18 19:07
v_cancel_write_off 518 508 10 1.93 0 47 2 10 2016-02-18 16:07 2016-02-18 19:07
v_max_id_clo_ord 1264 1264 0 0.00 0 577 13 0 2016-02-18 16:07 2016-02-18 19:07
v_max_id_clo_res 235 235 0 0.00 0 31 2 0 2016-02-18 16:07 2016-02-18 19:07
v_max_id_ord_sup 1297 1297 0 0.00 0 281 7 0 2016-02-18 16:07 2016-02-18 19:07
v_max_non_paid_invoice 1453 1453 0 0.00 0 109 3 0 2016-02-18 16:07 2016-02-18 19:07
v_max_non_paid_realizn 1747 1747 0 0.00 0 31 5 0 2016-02-18 16:07 2016-02-18 19:07
v_min_id_clo_ord 1264 1264 0 0.00 0 1342 43 0 2016-02-18 16:07 2016-02-18 19:07
v_min_id_clo_res 235 235 0 0.00 0 31 2 0 2016-02-18 16:07 2016-02-18 19:07
v_min_id_ord_sup 1297 1297 0 0.00 0 1045 39 0 2016-02-18 16:07 2016-02-18 19:07
v_min_non_paid_invoice 1453 1453 0 0.00 0 546 27 0 2016-02-18 16:07 2016-02-18 19:07
v_min_non_paid_realizn 1747 1747 0 0.00 0 93 11 0 2016-02-18 16:07 2016-02-18 19:07
v_our_firm 2594 2594 0 0.00 0 16 0 0 2016-02-18 16:07 2016-02-18 19:07
v_reserve_write_off 4952 4772 180 3.63 0 62 1 180 2016-02-18 16:07 2016-02-18 19:07
x_lock_depdocs_on_canc_invoice 546 294 252 46.15 0 56 3 252 2016-02-18 16:07 2016-02-18 19:07
x_lock_depdocs_on_canc_sup_ord 96 23 73 76.04 0 31 6 73 2016-02-18 16:07 2016-02-18 19:07
x_make_qstorno_1000_1200 1267 1267 0 0.00 0 28477 1043 0 2016-02-18 16:07 2016-02-18 19:07
x_make_qstorno_1200_2000 1298 1298 0 0.00 0 12751 562 0 2016-02-18 16:07 2016-02-18 19:07
x_make_qstorno_2100_3300 78431 78431 0 0.00 5 48787 7318 0 2016-02-18 16:07 2016-02-18 19:07
Done for 18300 ms, from 19:08:49.40 to 19:09:07.70.

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 7 sp_kill_cost_storno 335544336 2016-02-18 16:10 2016-02-18 18:20
deadlock 6 sp_qd_handle_on_invoice_upd_sts 335544336 2016-02-18 17:13 2016-02-18 18:17
lock_conflict 891 sp_lock_selected_doc 335544345 2016-02-18 16:07 2016-02-18 19:06
lock_conflict 36 sp_qd_handle_on_cancel_clo 335544345 2016-02-18 16:07 2016-02-18 18:35
lock_conflict 23 sp_qd_handle_on_invoice_upd_sts 335544345 2016-02-18 16:07 2016-02-18 18:30
lock_conflict 52 x_lock_depdocs_on_canc_invoice 335544345 2016-02-18 16:07 2016-02-18 18:48
lock_conflict 26 x_lock_depdocs_on_canc_sup_ord 335544345 2016-02-18 16:08 2016-02-18 19:01
Done for 2570 ms, from 19:09:08.12 to 19:09:10.69.

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 93236
MON$OLDEST_ACTIVE 93237
MON$OLDEST_SNAPSHOT 93237
MON$NEXT_TRANSACTION 93237
MON$PAGE_BUFFERS 384
MON$SQL_DIALECT 3
MON$SHUTDOWN_MODE 0
MON$SWEEP_INTERVAL 0
MON$READ_ONLY 0
MON$FORCED_WRITES 1
MON$RESERVE_SPACE 1
MON$CREATION_DATE 2015-12-11 10:02:47.3010
MON$PAGES 173782
MON$STAT_ID 1
MON$BACKUP_STATE 0
ISQL Version: WI-V2.5.6.26973 Firebird 2.5
Server version:
Firebird/x86-64/Windows NT (access method), version "WI-V2.5.6.26973 Firebird 2.5"
Firebird/x86-64/Windows NT (remote server), version "WI-V2.5.6.26973 Firebird 2.5/tcp (WIN-2BE1UI8LGDL)/P12"
Firebird/x86-64/Windows NT (remote interface), version "WI-V2.5.6.26973 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 97668
Page size 8192
ODS version 11.2
Oldest transaction 93238
Oldest active 93239
Oldest snapshot 93239
Next transaction 93240
Bumped transaction 1
Sequence number 0
Next attachment ID 4396
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: 43.96, total records: 487170
Average version length: 46.27, total versions: 23628, max versions: 1
Data pages: 5210, data page slots: 5212, average fill: 73%
Fill distribution:
0 - 19% = 19
20 - 39% = 17
40 - 59% = 90
60 - 79% = 4967
80 - 99% = 117

Index DOC_DATA_DOC_WARE_UNQ (1)
Depth: 3, leaf buckets: 1638, nodes: 487170
Average data length: 11.86, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 164
20 - 39% = 37
40 - 59% = 710
60 - 79% = 74
80 - 99% = 653

Index DOC_DATA_ID_DESC (2)
Depth: 3, leaf buckets: 1424, nodes: 487170
Average data length: 5.95, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 0
20 - 39% = 27
40 - 59% = 1395
60 - 79% = 2
80 - 99% = 0

Index FK_DOC_DATA_DOC_LIST (3)
Depth: 2, leaf buckets: 491, nodes: 487170
Average data length: 0.94, total dup: 415155, max dup: 49
Fill distribution:
0 - 19% = 8
20 - 39% = 19
40 - 59% = 224
60 - 79% = 10
80 - 99% = 230

DOC_LIST (140)
Primary pointer page: 195, Index root page: 196
Average record length: 57.49, total records: 85855
Average version length: 45.92, total versions: 10830, max versions: 5
Data pages: 1092, data page slots: 1092, average fill: 80%
Fill distribution:
0 - 19% = 13
20 - 39% = 14
40 - 59% = 36
60 - 79% = 355
80 - 99% = 674

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

Index FK_DOC_LIST_AGENTS (2)
Depth: 2, leaf buckets: 71, nodes: 85855
Average data length: 0.01, total dup: 85805, max dup: 18475
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 40
60 - 79% = 23
80 - 99% = 7

Index PK_DOC_LIST (0)
Depth: 2, leaf buckets: 185, nodes: 85855
Average data length: 6.26, total dup: 0, max dup: 0
Fill distribution:
0 - 19% = 12
20 - 39% = 10
40 - 59% = 90
60 - 79% = 1
80 - 99% = 72

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.57, total records: 400
Average version length: 13.94, total versions: 96165, max versions: 281
Data pages: 385, data page slots: 385, average fill: 96%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 0
80 - 99% = 384

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.02, total records: 143420
Average version length: 68.83, total versions: 143392, max versions: 1
Data pages: 2661, data page slots: 2668, average fill: 70%
Fill distribution:
0 - 19% = 300
20 - 39% = 404
40 - 59% = 127
60 - 79% = 246
80 - 99% = 1584

Index INVNT_TURNOVER_LOG_WARE_DD_ID (0)
Depth: 3, leaf buckets: 408, nodes: 143420
Average data length: 8.11, total dup: 18141, max dup: 5
Fill distribution:
0 - 19% = 0
20 - 39% = 13
40 - 59% = 179
60 - 79% = 214
80 - 99% = 2

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

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

MONEY_TURNOVER_LOG (142)
Primary pointer page: 206, Index root page: 207
Average record length: 0.01, total records: 6398
Average version length: 44.29, total versions: 6397, max versions: 1
Data pages: 77, data page slots: 79, average fill: 81%
Fill distribution:
0 - 19% = 9
20 - 39% = 2
40 - 59% = 0
60 - 79% = 3
80 - 99% = 63

Index MONEY_TURNOVER_LOG_AGENT_OPTYPE (0)
Depth: 2, leaf buckets: 6, nodes: 6398
Average data length: 0.15, total dup: 6311, max dup: 1378
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 4
60 - 79% = 0
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: 26.20, total records: 127466
Average version length: 34.13, total versions: 71037, max versions: 7
Data pages: 1523, data page slots: 1523, average fill: 75%
Fill distribution:
0 - 19% = 26
20 - 39% = 116
40 - 59% = 120
60 - 79% = 528
80 - 99% = 733

Index PDISTR_AGENT_ID (4)
Depth: 2, leaf buckets: 117, nodes: 127466
Average data length: 0.01, total dup: 127417, max dup: 27201
Fill distribution:
0 - 19% = 1
20 - 39% = 9
40 - 59% = 71
60 - 79% = 16
80 - 99% = 20

Index PDISTR_SNDOP_RCVOP_SNDID_ASC (2)
Depth: 2, leaf buckets: 138, nodes: 127466
Average data length: 0.90, total dup: 114563, max dup: 32
Fill distribution:
0 - 19% = 0
20 - 39% = 3
40 - 59% = 83
60 - 79% = 29
80 - 99% = 23

Index PDISTR_SNDOP_RCVOP_SNDID_DESC (3)
Depth: 2, leaf buckets: 155, nodes: 127466
Average data length: 0.91, total dup: 114563, max dup: 32
Fill distribution:
0 - 19% = 8
20 - 39% = 3
40 - 59% = 94
60 - 79% = 35
80 - 99% = 15

Index PDISTR_SND_ID (1)
Depth: 2, leaf buckets: 131, nodes: 127466
Average data length: 0.68, total dup: 114563, max dup: 32
Fill distribution:
0 - 19% = 0
20 - 39% = 9
40 - 59% = 61
60 - 79% = 40
80 - 99% = 21

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

Index PERF_EST_MINUTE_SINCE_START (0)
Depth: 2, leaf buckets: 17, nodes: 24075
Average data length: 0.01, total dup: 23900, max dup: 974
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 2
60 - 79% = 11
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: 145.21, total records: 1392536
Average version length: 153.00, total versions: 1, max versions: 1
Data pages: 31950, data page slots: 31950, average fill: 87%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 31949

Index PERF_LOG_DTS_BEG_DESC (0)
Depth: 3, leaf buckets: 1696, nodes: 1392536
Average data length: 0.60, total dup: 786599, max dup: 40
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 1205
60 - 79% = 321
80 - 99% = 169

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

Index PERF_LOG_TRN_DESC (2)
Depth: 3, leaf buckets: 1707, nodes: 1392536
Average data length: 0.35, total dup: 1317467, max dup: 4612
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1449
60 - 79% = 147
80 - 99% = 111

Index PERF_LOG_UNIT (1)
Depth: 3, leaf buckets: 1566, nodes: 1392536
Average data length: 0.40, total dup: 1338015, max dup: 148333
Fill distribution:
0 - 19% = 3
20 - 39% = 0
40 - 59% = 1086
60 - 79% = 252
80 - 99% = 225

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.98, total records: 87339
Average version length: 54.92, total versions: 6016, max versions: 1
Data pages: 1019, data page slots: 1019, average fill: 77%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 7
60 - 79% = 945
80 - 99% = 66

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

Index PSTORNED_SND_ID (1)
Depth: 2, leaf buckets: 79, nodes: 87339
Average data length: 0.60, total dup: 79781, max dup: 96
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 37
60 - 79% = 34
80 - 99% = 8

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

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

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

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

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

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

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

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

SETTINGS (137)
Primary pointer page: 183, Index root page: 184
Average record length: 97.51, total records: 114
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 2, data page slots: 2, average fill: 80%
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.41, total records: 290
Average version length: 0.00, total versions: 0, max versions: 0
Data pages: 4, data page slots: 4, average fill: 68%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 3
80 - 99% = 0

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

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

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

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

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

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

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

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

XQD_1000_1200 (258)
Primary pointer page: 645, Index root page: 646
Average record length: 0.27, total records: 202314
Average version length: 70.01, total versions: 201524, max versions: 1
Data pages: 2718, data page slots: 3472, average fill: 95%
Fill distribution:
0 - 19% = 0
20 - 39% = 2
40 - 59% = 0
60 - 79% = 3
80 - 99% = 2713

Index XQD_1000_1200_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 310, nodes: 202314
Average data length: 1.22, total dup: 177338, max dup: 29
Fill distribution:
0 - 19% = 8
20 - 39% = 86
40 - 59% = 132
60 - 79% = 78
80 - 99% = 6

XQD_1000_3300 (260)
Primary pointer page: 653, Index root page: 654
Average record length: 52.50, total records: 270378
Average version length: 69.26, total versions: 66918, max versions: 1
Data pages: 3708, data page slots: 3759, average fill: 82%
Fill distribution:
0 - 19% = 10
20 - 39% = 13
40 - 59% = 32
60 - 79% = 1267
80 - 99% = 2386

Index XQD_1000_3300_DOC (1)
Depth: 2, leaf buckets: 265, nodes: 270378
Average data length: 0.16, total dup: 264043, max dup: 178
Fill distribution:
0 - 19% = 4
20 - 39% = 22
40 - 59% = 151
60 - 79% = 13
80 - 99% = 75

Index XQD_1000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 325, nodes: 270378
Average data length: 1.24, total dup: 237194, max dup: 41
Fill distribution:
0 - 19% = 1
20 - 39% = 1
40 - 59% = 159
60 - 79% = 107
80 - 99% = 57

XQD_1200_2000 (259)
Primary pointer page: 648, Index root page: 649
Average record length: 0.27, total records: 252767
Average version length: 69.91, total versions: 251788, max versions: 1
Data pages: 3402, data page slots: 4291, average fill: 94%
Fill distribution:
0 - 19% = 1
20 - 39% = 0
40 - 59% = 0
60 - 79% = 0
80 - 99% = 3401

Index XQD_1200_2000_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 349, nodes: 252767
Average data length: 1.04, total dup: 226773, max dup: 103
Fill distribution:
0 - 19% = 7
20 - 39% = 32
40 - 59% = 223
60 - 79% = 58
80 - 99% = 29

XQD_2000_3300 (261)
Primary pointer page: 657, Index root page: 658
Average record length: 18.43, total records: 1087202
Average version length: 68.35, total versions: 799760, max versions: 1
Data pages: 14648, data page slots: 14648, average fill: 89%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 0
60 - 79% = 3509
80 - 99% = 11139

Index XQD_2000_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 1392, nodes: 1087202
Average data length: 1.11, total dup: 963486, max dup: 119
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1105
60 - 79% = 116
80 - 99% = 171

XQD_2100_3300 (262)
Primary pointer page: 661, Index root page: 662
Average record length: 12.27, total records: 144757
Average version length: 69.39, total versions: 119078, max versions: 1
Data pages: 2462, data page slots: 4243, average fill: 74%
Fill distribution:
0 - 19% = 292
20 - 39% = 170
40 - 59% = 121
60 - 79% = 157
80 - 99% = 1722

Index XQD_2100_3300_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 284, nodes: 144757
Average data length: 1.53, total dup: 123539, max dup: 199
Fill distribution:
0 - 19% = 0
20 - 39% = 136
40 - 59% = 133
60 - 79% = 14
80 - 99% = 1

XQD_3300_3400 (263)
Primary pointer page: 664, Index root page: 665
Average record length: 38.31, total records: 762160
Average version length: 68.47, total versions: 338689, max versions: 1
Data pages: 10704, data page slots: 10827, average fill: 82%
Fill distribution:
0 - 19% = 109
20 - 39% = 201
40 - 59% = 345
60 - 79% = 3016
80 - 99% = 7033

Index XQD_3300_3400_WARE_SOP_ROP_SND (0)
Depth: 3, leaf buckets: 1122, nodes: 762160
Average data length: 1.34, total dup: 657908, max dup: 44
Fill distribution:
0 - 19% = 0
20 - 39% = 40
40 - 59% = 890
60 - 79% = 120
80 - 99% = 72

XQS_1000_1200 (264)
Primary pointer page: 667, Index root page: 668
Average record length: 78.29, total records: 1020725
Average version length: 80.85, total versions: 30579, max versions: 1
Data pages: 15242, data page slots: 15242, average fill: 81%
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 1
60 - 79% = 6559
80 - 99% = 8682

Index XQS_1000_1200_DOC_ID (0)
Depth: 3, leaf buckets: 1124, nodes: 1020725
Average data length: 0.16, total dup: 997703, max dup: 154
Fill distribution:
0 - 19% = 13
20 - 39% = 1
40 - 59% = 804
60 - 79% = 241
80 - 99% = 65

Index XQS_1000_1200_RCV_ID (2)
Depth: 3, leaf buckets: 807, nodes: 1020725
Average data length: 0.72, total dup: 898091, max dup: 49
Fill distribution:
0 - 19% = 4
20 - 39% = 1
40 - 59% = 123
60 - 79% = 23
80 - 99% = 656

Index XQS_1000_1200_SND_ID (1)
Depth: 3, leaf buckets: 1273, nodes: 1020725
Average data length: 0.75, total dup: 894132, max dup: 44
Fill distribution:
0 - 19% = 9
20 - 39% = 0
40 - 59% = 909
60 - 79% = 285
80 - 99% = 70

XQS_1000_3300 (266)
Primary pointer page: 678, Index root page: 679
Average record length: 65.93, total records: 661707
Average version length: 80.34, total versions: 118686, max versions: 1
Data pages: 10063, data page slots: 10164, average fill: 81%
Fill distribution:
0 - 19% = 53
20 - 39% = 96
40 - 59% = 185
60 - 79% = 4424
80 - 99% = 5305

Index XQS_1000_3300_DOC_ID (0)
Depth: 3, leaf buckets: 668, nodes: 661707
Average data length: 0.15, total dup: 647545, max dup: 306
Fill distribution:
0 - 19% = 1
20 - 39% = 3
40 - 59% = 344
60 - 79% = 258
80 - 99% = 62

Index XQS_1000_3300_RCV_ID (2)
Depth: 3, leaf buckets: 568, nodes: 661707
Average data length: 0.84, total dup: 572752, max dup: 14
Fill distribution:
0 - 19% = 7
20 - 39% = 25
40 - 59% = 116
60 - 79% = 4
80 - 99% = 416

Index XQS_1000_3300_SND_ID (1)
Depth: 3, leaf buckets: 789, nodes: 661707
Average data length: 0.70, total dup: 586640, max dup: 55
Fill distribution:
0 - 19% = 29
20 - 39% = 3
40 - 59% = 406
60 - 79% = 298
80 - 99% = 53

XQS_1200_2000 (265)
Primary pointer page: 673, Index root page: 674
Average record length: 75.93, total records: 1078376
Average version length: 81.50, total versions: 64391, max versions: 1
Data pages: 16107, data page slots: 16107, average fill: 81%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 7302
80 - 99% = 8804

Index XQS_1200_2000_DOC_ID (0)
Depth: 3, leaf buckets: 1110, nodes: 1078376
Average data length: 0.05, total dup: 1072131, max dup: 1055
Fill distribution:
0 - 19% = 5
20 - 39% = 1
40 - 59% = 714
60 - 79% = 276
80 - 99% = 114

Index XQS_1200_2000_RCV_ID (2)
Depth: 3, leaf buckets: 804, nodes: 1078376
Average data length: 0.68, total dup: 954409, max dup: 49
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 65
60 - 79% = 2
80 - 99% = 737

Index XQS_1200_2000_SND_ID (1)
Depth: 3, leaf buckets: 1261, nodes: 1078376
Average data length: 0.68, total dup: 955847, max dup: 108
Fill distribution:
0 - 19% = 4
20 - 39% = 1
40 - 59% = 769
60 - 79% = 349
80 - 99% = 138

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: 68.59, total records: 823399
Average version length: 80.51, total versions: 122535, max versions: 1
Data pages: 12711, data page slots: 12883, average fill: 79%
Fill distribution:
0 - 19% = 161
20 - 39% = 173
40 - 59% = 349
60 - 79% = 4772
80 - 99% = 7256

Index XQS_2100_3300_DOC_ID (0)
Depth: 3, leaf buckets: 764, nodes: 823399
Average data length: 0.05, total dup: 818842, max dup: 733
Fill distribution:
0 - 19% = 0
20 - 39% = 12
40 - 59% = 277
60 - 79% = 325
80 - 99% = 150

Index XQS_2100_3300_RCV_ID (2)
Depth: 3, leaf buckets: 808, nodes: 823399
Average data length: 0.87, total dup: 709655, max dup: 14
Fill distribution:
0 - 19% = 20
20 - 39% = 49
40 - 59% = 294
60 - 79% = 1
80 - 99% = 444

Index XQS_2100_3300_SND_ID (1)
Depth: 3, leaf buckets: 879, nodes: 823399
Average data length: 0.67, total dup: 732495, max dup: 119
Fill distribution:
0 - 19% = 2
20 - 39% = 8
40 - 59% = 328
60 - 79% = 372
80 - 99% = 169

XQS_3300_3400 (269)
Primary pointer page: 697, Index root page: 698
Average record length: 56.40, total records: 336284
Average version length: 68.40, total versions: 58891, max versions: 1
Data pages: 4527, data page slots: 4527, average fill: 80%
Fill distribution:
0 - 19% = 0
20 - 39% = 1
40 - 59% = 0
60 - 79% = 3136
80 - 99% = 1390

Index XQS_3300_3400_DOC_ID (0)
Depth: 2, leaf buckets: 300, nodes: 336284
Average data length: 0.22, total dup: 325541, max dup: 211
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 96
60 - 79% = 124
80 - 99% = 80

Index XQS_3300_3400_SND_ID (1)
Depth: 2, leaf buckets: 355, nodes: 336284
Average data length: 0.83, total dup: 292379, max dup: 44
Fill distribution:
0 - 19% = 0
20 - 39% = 0
40 - 59% = 139
60 - 79% = 145
80 - 99% = 71

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 3290 ms, from 19:09:11.66 to 19:09:14.95.

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 487170 23628 4.8500
DOC_LIST 85855 10830 12.6142
DOC_STATES 4 0 0.0000
FB_ERRORS 758 0 0.0000
INVNT_SALDO 400 96165 24041.2500
INVNT_TURNOVER_LOG 143420 143392 99.9804
MONEY_SALDO 49 4762 9718.3673
MONEY_TURNOVER_LOG 6398 6397 99.9843
OPTYPES 9 0 0.0000
PDISTR 127466 71037 55.7301
PERF_ESTIMATED 24075 2 0.0083
PERF_LOG 1392536 1 0.0000
PSTORNED 87339 6016 6.8881
RULES_FOR_PDISTR 4 0 0.0000
RULES_FOR_QDISTR 7 0 0.0000
SEMAPHORES 4 1029 25725.0000
SETTINGS 114 0 0.0000
TRACE_STAT 290 0 0.0000
WARES 400 0 0.0000
WARE_GROUPS 8 0 0.0000
XQD_1000_1200 202314 201524 99.6095
XQD_1000_3300 270378 66918 24.7497
XQD_1200_2000 252767 251788 99.6126
XQD_2000_3300 1087202 799760 73.5613
XQD_2100_3300 144757 119078 82.2606
XQD_3300_3400 762160 338689 44.4380
XQS_1000_1200 1020725 30579 2.9958
XQS_1000_3300 661707 118686 17.9363
XQS_1200_2000 1078376 64391 5.9711
XQS_2100_3300 823399 122535 14.8816
XQS_3300_3400 336284 58891 17.5122
Z_USED_VIEWS 32 0 0.0000
Done for 2110 ms, from 19:09:20.07 to 19:09:22.18.

Database validation

19:09:22.50 Validation started

19:09:22.50 Relation 140 (DOC_LIST)
19:09:22.50 process pointer page 0 of 1
19:09:22.53 Index 1 (PK_DOC_LIST)
19:09:22.53 Index 2 (DOC_LIST_ID_DESC)
19:09:22.55 Index 3 (FK_DOC_LIST_AGENTS)
19:09:22.55 Relation 140 (DOC_LIST) is ok

19:09:22.55 Relation 141 (DOC_DATA)
19:09:22.55 process pointer page 0 of 3
19:09:22.63 process pointer page 1 of 3
19:09:22.74 process pointer page 2 of 3
19:09:22.80 Index 2 (DOC_DATA_DOC_WARE_UNQ)
19:09:22.88 Index 3 (DOC_DATA_ID_DESC)
19:09:22.96 Index 4 (FK_DOC_DATA_DOC_LIST)
19:09:23.00 Relation 141 (DOC_DATA) is ok

19:09:23.00 Relation 147 (INVNT_SALDO)
19:09:23.00 process pointer page 0 of 1
19:09:23.05 Index 1 (PK_INVNT_SALDO)
19:09:23.05 Relation 147 (INVNT_SALDO) is ok

19:09:23.05 Relation 150 (PDISTR)
19:09:23.05 process pointer page 0 of 1
19:09:23.10 Index 2 (PDISTR_SND_ID)
19:09:23.11 Index 3 (PDISTR_SNDOP_RCVOP_SNDID_ASC)
19:09:23.11 Index 4 (PDISTR_SNDOP_RCVOP_SNDID_DESC)
19:09:23.13 Index 5 (PDISTR_AGENT_ID)
19:09:23.14 Relation 150 (PDISTR) is ok

19:09:23.14 Relation 151 (PSTORNED)
19:09:23.14 process pointer page 0 of 1
19:09:23.16 Index 2 (PSTORNED_SND_ID)
19:09:23.16 Index 3 (PSTORNED_RCV_ID)
19:09:23.17 Relation 151 (PSTORNED) is ok

19:09:23.17 Relation 156 (SEMAPHORES)
19:09:23.17 process pointer page 0 of 1
19:09:23.17 Index 1 (PK_SEMAPHORES)
19:09:23.17 Index 2 (SEMAPHORES_TASK_UNQ)
19:09:23.17 Relation 156 (SEMAPHORES) is ok

19:09:23.17 Relation 157 (INVNT_TURNOVER_LOG)
19:09:23.17 process pointer page 0 of 2
19:09:23.22 process pointer page 1 of 2
19:09:23.24 Index 1 (INVNT_TURNOVER_LOG_WARE_DD_ID)
19:09:23.27 Relation 157 (INVNT_TURNOVER_LOG) is ok

19:09:23.27 Relation 160 (PERF_ESTIMATED)
19:09:23.27 process pointer page 0 of 1
19:09:23.27 Index 1 (PERF_EST_MINUTE_SINCE_START)
19:09:23.27 Relation 160 (PERF_ESTIMATED) is ok

19:09:23.27 Relation 161 (PERF_ISQL_STAT)
19:09:23.27 process pointer page 0 of 1
19:09:23.27 Index 1 (PERF_ISQL_STAT_TRN)
19:09:23.27 Relation 161 (PERF_ISQL_STAT) is ok

19:09:23.27 Relation 162 (PERF_LOG)
19:09:23.27 process pointer page 0 of 17
19:09:23.30 process pointer page 1 of 17
19:09:23.31 process pointer page 2 of 17
19:09:23.35 process pointer page 3 of 17
19:09:23.36 process pointer page 4 of 17
19:09:23.39 process pointer page 5 of 17
19:09:23.41 process pointer page 6 of 17
19:09:23.44 process pointer page 7 of 17
19:09:23.45 process pointer page 8 of 17
19:09:23.47 process pointer page 9 of 17
19:09:23.50 process pointer page 10 of 17
19:09:23.52 process pointer page 11 of 17
19:09:23.55 process pointer page 12 of 17
19:09:23.56 process pointer page 13 of 17
19:09:23.60 process pointer page 14 of 17
19:09:23.61 process pointer page 15 of 17
19:09:23.64 process pointer page 16 of 17
19:09:23.66 Index 1 (PERF_LOG_DTS_BEG_DESC)
19:09:23.81 Index 2 (PERF_LOG_UNIT)
19:09:24.31 Index 3 (PERF_LOG_TRN_DESC)
19:09:24.58 Index 4 (PERF_LOG_GDSCODE)
19:09:24.70 Relation 162 (PERF_LOG) is ok

19:09:24.70 Relation 166 (V_STOPTEST)
19:09:24.70 Relation 166 (V_STOPTEST) is ok

19:09:24.70 Relation 183 (V_QDISTR_SOURCE)
19:09:24.70 Relation 183 (V_QDISTR_SOURCE) is ok

19:09:24.70 Relation 184 (V_QSTORNED_SOURCE)
19:09:24.70 Relation 184 (V_QSTORNED_SOURCE) is ok

19:09:24.70 Relation 258 (XQD_1000_1200)
19:09:24.70 process pointer page 0 of 2
19:09:24.73 process pointer page 1 of 2
19:09:24.78 Index 1 (XQD_1000_1200_WARE_SOP_ROP_SND)
19:09:24.81 Relation 258 (XQD_1000_1200) is ok

19:09:24.81 Relation 259 (XQD_1200_2000)
19:09:24.81 process pointer page 0 of 3
19:09:24.84 process pointer page 1 of 3
19:09:24.92 process pointer page 2 of 3
19:09:24.97 Index 1 (XQD_1200_2000_WARE_SOP_ROP_SND)
19:09:25.05 Relation 259 (XQD_1200_2000) is ok

19:09:25.05 Relation 260 (XQD_1000_3300)
19:09:25.05 process pointer page 0 of 3
19:09:25.17 process pointer page 1 of 3
19:09:25.26 process pointer page 2 of 3
19:09:25.26 Index 1 (XQD_1000_3300_WARE_SOP_ROP_SND)
19:09:25.33 Index 2 (XQD_1000_3300_DOC)
19:09:25.36 Relation 260 (XQD_1000_3300) is ok

19:09:25.36 Relation 261 (XQD_2000_3300)
19:09:25.36 process pointer page 0 of 8
19:09:25.51 process pointer page 1 of 8
19:09:25.59 process pointer page 2 of 8
19:09:25.65 process pointer page 3 of 8
19:09:25.75 process pointer page 4 of 8
19:09:25.84 process pointer page 5 of 8
19:09:25.92 process pointer page 6 of 8
19:09:25.97 process pointer page 7 of 8
19:09:26.00 Index 1 (XQD_2000_3300_WARE_SOP_ROP_SND)
19:09:26.12 Relation 261 (XQD_2000_3300) is ok

19:09:26.12 Relation 262 (XQD_2100_3300)
19:09:26.12 process pointer page 0 of 3
19:09:26.14 process pointer page 1 of 3
19:09:26.17 process pointer page 2 of 3
19:09:26.18 Index 1 (XQD_2100_3300_WARE_SOP_ROP_SND)
19:09:26.20 Relation 262 (XQD_2100_3300) is ok

19:09:26.20 Relation 263 (XQD_3300_3400)
19:09:26.20 process pointer page 0 of 6
19:09:26.26 process pointer page 1 of 6
19:09:26.39 process pointer page 2 of 6
19:09:26.53 process pointer page 3 of 6
19:09:26.60 process pointer page 4 of 6
19:09:26.65 process pointer page 5 of 6
19:09:26.67 Index 1 (XQD_3300_3400_WARE_SOP_ROP_SND)
19:09:26.95 Relation 263 (XQD_3300_3400) is ok

19:09:26.95 Relation 264 (XQS_1000_1200)
19:09:26.95 process pointer page 0 of 8
19:09:27.04 process pointer page 1 of 8
19:09:27.13 process pointer page 2 of 8
19:09:27.17 process pointer page 3 of 8
19:09:27.24 process pointer page 4 of 8
19:09:27.34 process pointer page 5 of 8
19:09:27.43 process pointer page 6 of 8
19:09:27.53 process pointer page 7 of 8
19:09:27.61 Index 1 (XQS_1000_1200_DOC_ID)
19:09:27.74 Index 2 (XQS_1000_1200_SND_ID)
19:09:27.85 Index 3 (XQS_1000_1200_RCV_ID)
19:09:27.92 Relation 264 (XQS_1000_1200) is ok

19:09:27.92 Relation 265 (XQS_1200_2000)
19:09:27.92 process pointer page 0 of 9
19:09:27.95 process pointer page 1 of 9
19:09:27.98 process pointer page 2 of 9
19:09:28.02 process pointer page 3 of 9
19:09:28.10 process pointer page 4 of 9
19:09:28.19 process pointer page 5 of 9
19:09:28.26 process pointer page 6 of 9
19:09:28.30 process pointer page 7 of 9
19:09:28.34 process pointer page 8 of 9
19:09:28.36 Index 1 (XQS_1200_2000_DOC_ID)
19:09:28.46 Index 2 (XQS_1200_2000_SND_ID)
19:09:28.56 Index 3 (XQS_1200_2000_RCV_ID)
19:09:28.62 Relation 265 (XQS_1200_2000) is ok

19:09:28.62 Relation 266 (XQS_1000_3300)
19:09:28.62 process pointer page 0 of 6
19:09:28.65 process pointer page 1 of 6
19:09:28.68 process pointer page 2 of 6
19:09:28.71 process pointer page 3 of 6
19:09:28.73 process pointer page 4 of 6
19:09:28.76 process pointer page 5 of 6
19:09:28.78 Index 1 (XQS_1000_3300_DOC_ID)
19:09:28.82 Index 2 (XQS_1000_3300_SND_ID)
19:09:28.87 Index 3 (XQS_1000_3300_RCV_ID)
19:09:28.92 Relation 266 (XQS_1000_3300) is ok

19:09:28.93 Relation 267 (XQS_2000_3300)
19:09:28.93 process pointer page 0 of 1
19:09:28.93 Index 1 (XQS_2000_3300_DOC_ID)
19:09:28.93 Index 2 (XQS_2000_3300_SND_ID)
19:09:28.93 Index 3 (XQS_2000_3300_RCV_ID)
19:09:28.93 Relation 267 (XQS_2000_3300) is ok

19:09:28.93 Relation 268 (XQS_2100_3300)
19:09:28.93 process pointer page 0 of 7
19:09:28.95 process pointer page 1 of 7
19:09:29.00 process pointer page 2 of 7
19:09:29.03 process pointer page 3 of 7
19:09:29.07 process pointer page 4 of 7
19:09:29.10 process pointer page 5 of 7
19:09:29.18 process pointer page 6 of 7
19:09:29.20 Index 1 (XQS_2100_3300_DOC_ID)
19:09:29.43 Index 2 (XQS_2100_3300_SND_ID)
19:09:29.54 Index 3 (XQS_2100_3300_RCV_ID)
19:09:29.68 Relation 268 (XQS_2100_3300) is ok

19:09:29.68 Relation 269 (XQS_3300_3400)
19:09:29.68 process pointer page 0 of 3
19:09:29.71 process pointer page 1 of 3
19:09:29.77 process pointer page 2 of 3
19:09:29.79 Index 1 (XQS_3300_3400_DOC_ID)
19:09:29.82 Index 2 (XQS_3300_3400_SND_ID)
19:09:29.84 Relation 269 (XQS_3300_3400) is ok

19:09:29.84 Relation 276 (TRACE_STAT)
19:09:29.84 process pointer page 0 of 1
19:09:29.84 Relation 276 (TRACE_STAT) is ok

19:09:29.84 Validation finished
Done for 7660 ms, from 19:09:22.19 to 19:09:29.85.

Differences between old and current firebird.log

19:09:30.24. 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-02-18 19:09 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 212 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-02-18 19:09:31.77 - end of report, text file: e:\temp\logs.oltp25\oltp25.report.txt, html: e:\temp\logs.oltp25\oltp25.report.html