[15.01.2010 15:00:00]
[15.01.2010 15:22:59  ec4] Sleeping between passes 3600992,5294ms
[15.01.2010 15:22:59  ec4] MigrationDisabled = 0
[15.01.2010 15:22:59  ec4] Audit Grace Period is set to: 1440
[15.01.2010 15:22:59  ec4] ***Starting Primary Pass
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},1b00000000002b last write date mismatch, 15.01.2010 14:12:26 vs 15.01.2010 14:12:26
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},1b00000000002b end of file mismatch, 0 vs 242400
[15.01.2010 15:23:00  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new
[15.01.2010 15:23:00  ec4] Unable to fully process file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new error (most likely the file is in use - which is okay) - err=170
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},1b00000000002b last write date mismatch, 15.01.2010 14:12:26 vs 15.01.2010 14:12:26
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},1b00000000002b end of file mismatch, 0 vs 242400
[15.01.2010 15:23:00  ec4] ERROR WITH: D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new because shadow 0 is in state Unsanitary
[15.01.2010 15:23:00  ec4]    ...the file is 61,25minutes old,  the grace period for files is 1440 minutes - not reporting the file
[15.01.2010 15:23:00  ec4] Info for D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new
[15.01.2010 15:23:00  ec4]     State = Migrated	 NumberOfShadows = 1
[15.01.2010 15:23:00  ec4]     Shadow(0)	 Volume(59b0f9a8-0643-41d2-8137-6bc8b9873d94)	State(Unsanitary)
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},100000000002aa last write date mismatch, 15.01.2010 14:12:42 vs 15.01.2010 14:12:42
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},100000000002aa end of file mismatch, 0 vs 41200
[15.01.2010 15:23:00  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new
[15.01.2010 15:23:00  ec4] Unable to fully process file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new error (most likely the file is in use - which is okay) - err=170
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},100000000002aa last write date mismatch, 15.01.2010 14:12:42 vs 15.01.2010 14:12:42
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},100000000002aa end of file mismatch, 0 vs 41200
[15.01.2010 15:23:00  ec4] ERROR WITH: D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new because shadow 0 is in state Unsanitary
[15.01.2010 15:23:00  ec4]    ...the file is 61,2497395833333minutes old,  the grace period for files is 1440 minutes - not reporting the file
[15.01.2010 15:23:00  ec4] Info for D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new
[15.01.2010 15:23:00  ec4]     State = Migrated	 NumberOfShadows = 1
[15.01.2010 15:23:00  ec4]     Shadow(0)	 Volume(59b0f9a8-0643-41d2-8137-6bc8b9873d94)	State(Unsanitary)
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest1.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},120000000002af end of file mismatch, 0 vs 11e7000
[15.01.2010 15:23:00  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest1.4096.new
[15.01.2010 15:23:00  ec4] Unable to fully process file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest1.4096.new error (most likely the file is in use - which is okay) - err=170
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest1.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},120000000002af end of file mismatch, 0 vs 11e7000
[15.01.2010 15:23:00  ec4] ERROR WITH: D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest1.4096.new because shadow 0 is in state Unsanitary
[15.01.2010 15:23:00  ec4] ErrorNeedsReporting never seen the error 170 on file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest1.4096.new before, suppressing for now
[15.01.2010 15:23:00  ec4] Info for D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest1.4096.new
[15.01.2010 15:23:00  ec4]     State = Migrated	 NumberOfShadows = 1
[15.01.2010 15:23:00  ec4]     Shadow(0)	 Volume(59b0f9a8-0643-41d2-8137-6bc8b9873d94)	State(Unsanitary)
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest2.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},130000000002ac end of file mismatch, 0 vs 1241000
[15.01.2010 15:23:00  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest2.4096.new
[15.01.2010 15:23:00  ec4] Unable to fully process file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest2.4096.new error (most likely the file is in use - which is okay) - err=170
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest2.4096.new shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},130000000002ac end of file mismatch, 0 vs 1241000
[15.01.2010 15:23:00  ec4] ERROR WITH: D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest2.4096.new because shadow 0 is in state Unsanitary
[15.01.2010 15:23:00  ec4] ErrorNeedsReporting never seen the error 170 on file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest2.4096.new before, suppressing for now
[15.01.2010 15:23:00  ec4] Info for D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest2.4096.new
[15.01.2010 15:23:00  ec4]     State = Migrated	 NumberOfShadows = 1
[15.01.2010 15:23:00  ec4]     Shadow(0)	 Volume(59b0f9a8-0643-41d2-8137-6bc8b9873d94)	State(Unsanitary)
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{6D8F81B3-BED8-4891-A22E-191527FEFEB5}.tmp shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},120000000002ad end of file mismatch, 0 vs 2a20a00
[15.01.2010 15:23:00  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{6D8F81B3-BED8-4891-A22E-191527FEFEB5}.tmp
[15.01.2010 15:23:00  ec4] Unable to fully process file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{6D8F81B3-BED8-4891-A22E-191527FEFEB5}.tmp error (most likely the file is in use - which is okay) - err=170
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{6D8F81B3-BED8-4891-A22E-191527FEFEB5}.tmp shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},120000000002ad end of file mismatch, 0 vs 2a20a00
[15.01.2010 15:23:00  ec4] ERROR WITH: D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{6D8F81B3-BED8-4891-A22E-191527FEFEB5}.tmp because shadow 0 is in state Unsanitary
[15.01.2010 15:23:00  ec4] ErrorNeedsReporting never seen the error 170 on file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{6D8F81B3-BED8-4891-A22E-191527FEFEB5}.tmp before, suppressing for now
[15.01.2010 15:23:00  ec4] Info for D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{6D8F81B3-BED8-4891-A22E-191527FEFEB5}.tmp
[15.01.2010 15:23:00  ec4]     State = Migrated	 NumberOfShadows = 1
[15.01.2010 15:23:00  ec4]     Shadow(0)	 Volume(59b0f9a8-0643-41d2-8137-6bc8b9873d94)	State(Unsanitary)
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{F91AF3BC-F34B-4410-A07A-2FEA4AEB1C1F}.tmp shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},120000000002ae end of file mismatch, 5f6fd600 vs 5f716200
[15.01.2010 15:23:00  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{F91AF3BC-F34B-4410-A07A-2FEA4AEB1C1F}.tmp
[15.01.2010 15:23:00  ec4] Unable to fully process file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{F91AF3BC-F34B-4410-A07A-2FEA4AEB1C1F}.tmp error (most likely the file is in use - which is okay) - err=170
[15.01.2010 15:23:00  ec4] GetShadowState: \folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{F91AF3BC-F34B-4410-A07A-2FEA4AEB1C1F}.tmp shadow {59B0F9A8-0643-41D2-8137-6BC8B9873D94},120000000002ae end of file mismatch, 5f6fd600 vs 5f716200
[15.01.2010 15:23:00  ec4] ERROR WITH: D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{F91AF3BC-F34B-4410-A07A-2FEA4AEB1C1F}.tmp because shadow 0 is in state Unsanitary
[15.01.2010 15:23:00  ec4] ErrorNeedsReporting never seen the error 170 on file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{F91AF3BC-F34B-4410-A07A-2FEA4AEB1C1F}.tmp before, suppressing for now
[15.01.2010 15:23:00  ec4] Info for D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{F91AF3BC-F34B-4410-A07A-2FEA4AEB1C1F}.tmp
[15.01.2010 15:23:00  ec4]     State = Migrated	 NumberOfShadows = 1
[15.01.2010 15:23:00  ec4]     Shadow(0)	 Volume(59b0f9a8-0643-41d2-8137-6bc8b9873d94)	State(Unsanitary)
[15.01.2010 15:23:59  ec4] Primary Pass: (duplication) 59068,9ms
[15.01.2010 15:23:59  ec4] ***Starting Secondary Pass
[15.01.2010 15:23:59  ec4] Volume information - 
Volume - Id = 52d5aaf8-f6ac-4097-961d-3157ea9b38da Size = 911 GB Free = 111418 MB
Volume - Id = 59b0f9a8-0643-41d2-8137-6bc8b9873d94 Size = 1397 GB Free = 1223211 MB
Volume - Id = 58b176be-6863-4541-99c6-1dadbdcd7383 Size = 1397 GB Free = 1219674 MB
[15.01.2010 15:23:59  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\Data.4096.9.dat
[15.01.2010 15:23:59  ec4] nonfatal - DEFileFromShadow(c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\Data.4096.9.dat,52d5aaf8-f6ac-4097-961d-3157ea9b38da) threw - err=170
[15.01.2010 15:23:59  ec4] Nonfatal exception in Secondary Pass on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\Data.4096.9.dat: error =170
[15.01.2010 15:23:59  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\Index.4096.dat
[15.01.2010 15:23:59  ec4] nonfatal - DEFileFromShadow(c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\Index.4096.dat,52d5aaf8-f6ac-4097-961d-3157ea9b38da) threw - err=170
[15.01.2010 15:23:59  ec4] Nonfatal exception in Secondary Pass on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\Index.4096.dat: error =170
[15.01.2010 15:23:59  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new
[15.01.2010 15:23:59  ec4] nonfatal - DEFileFromShadow(c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new,52d5aaf8-f6ac-4097-961d-3157ea9b38da) threw - err=170
[15.01.2010 15:23:59  ec4] Nonfatal exception in Secondary Pass on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new: error =170
[15.01.2010 15:23:59  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new
[15.01.2010 15:23:59  ec4] nonfatal - DEFileFromShadow(c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new,52d5aaf8-f6ac-4097-961d-3157ea9b38da) threw - err=170
[15.01.2010 15:23:59  ec4] Nonfatal exception in Secondary Pass on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new: error =170
[15.01.2010 15:23:59  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest1.4096.new
[15.01.2010 15:23:59  ec4] nonfatal - DEFileFromShadow(c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest1.4096.new,52d5aaf8-f6ac-4097-961d-3157ea9b38da) threw - err=170
[15.01.2010 15:23:59  ec4] Nonfatal exception in Secondary Pass on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest1.4096.new: error =170
[15.01.2010 15:23:59  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest2.4096.new
[15.01.2010 15:23:59  ec4] nonfatal - DEFileFromShadow(c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest2.4096.new,52d5aaf8-f6ac-4097-961d-3157ea9b38da) threw - err=170
[15.01.2010 15:23:59  ec4] Nonfatal exception in Secondary Pass on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeClusterLatest2.4096.new: error =170
[15.01.2010 15:23:59  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{6D8F81B3-BED8-4891-A22E-191527FEFEB5}.tmp
[15.01.2010 15:23:59  ec4] nonfatal - DEFileFromShadow(c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{6D8F81B3-BED8-4891-A22E-191527FEFEB5}.tmp,52d5aaf8-f6ac-4097-961d-3157ea9b38da) threw - err=170
[15.01.2010 15:23:59  ec4] Nonfatal exception in Secondary Pass on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{6D8F81B3-BED8-4891-A22E-191527FEFEB5}.tmp: error =170
[15.01.2010 15:23:59  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{F91AF3BC-F34B-4410-A07A-2FEA4AEB1C1F}.tmp
[15.01.2010 15:23:59  ec4] nonfatal - DEFileFromShadow(c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{F91AF3BC-F34B-4410-A07A-2FEA4AEB1C1F}.tmp,52d5aaf8-f6ac-4097-961d-3157ea9b38da) threw - err=170
[15.01.2010 15:23:59  ec4] Nonfatal exception in Secondary Pass on c:\fs\I\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\{F91AF3BC-F34B-4410-A07A-2FEA4AEB1C1F}.tmp: error =170
[15.01.2010 15:24:06  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on c:\fs\G\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\GlobalCluster.4096.dat
[15.01.2010 15:24:06  ec4] nonfatal - DEFileFromShadow(c:\fs\G\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\GlobalCluster.4096.dat,52d5aaf8-f6ac-4097-961d-3157ea9b38da) threw - err=170
[15.01.2010 15:24:06  ec4] Nonfatal exception in Secondary Pass on c:\fs\G\DE\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\GlobalCluster.4096.dat: error =170
[15.01.2010 15:24:27  ec4] Secondary Pass: (balancing) 28401,4833ms
[15.01.2010 15:24:27  ec4] Full Primary and Secondary Pass 87473,4884ms