[15.01.2010 14:00:00]
[15.01.2010 14:21:44  ec4] Sleeping between passes 3600992,4392ms
[15.01.2010 14:21:44  ec4] MigrationDisabled = 0
[15.01.2010 14:21:44  ec4] Audit Grace Period is set to: 1440
[15.01.2010 14:21:45  ec4] ***Starting Primary Pass
[15.01.2010 14:21:45  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 14:21:45  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 14:21:45  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new
[15.01.2010 14:21:45  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 14:21:45  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 14:21:45  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 14:21:45  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 14:21:45  ec4] ErrorNeedsReporting never seen the error 170 on file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new before, suppressing for now
[15.01.2010 14:21:45  ec4] Info for D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.FileRecordHash.4096.new
[15.01.2010 14:21:45  ec4]     State = Migrated	 NumberOfShadows = 1
[15.01.2010 14:21:45  ec4]     Shadow(0)	 Volume(59b0f9a8-0643-41d2-8137-6bc8b9873d94)	State(Unsanitary)
[15.01.2010 14:21:45  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 14:21:45  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 14:21:45  ec4] OpenedFile::Lock: Error 300 from SendToFilter(BatchOplock) on D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new
[15.01.2010 14:21:45  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 14:21:45  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 14:21:45  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 14:21:45  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 14:21:45  ec4] ErrorNeedsReporting never seen the error 170 on file D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new before, suppressing for now
[15.01.2010 14:21:45  ec4] Info for D:\folders\{00008086-058D-4C89-AB57-A7F909A47AB4}\TECRAS4BRO.C.VolumeCluster.4096.new
[15.01.2010 14:21:45  ec4]     State = Migrated	 NumberOfShadows = 1
[15.01.2010 14:21:45  ec4]     Shadow(0)	 Volume(59b0f9a8-0643-41d2-8137-6bc8b9873d94)	State(Unsanitary)
[15.01.2010 14:22:35  ec4] Primary Pass: (duplication) 50077,7952ms
[15.01.2010 14:22:35  ec4] ***Starting Secondary Pass
[15.01.2010 14:22:35  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 = 1224818 MB
Volume - Id = 58b176be-6863-4541-99c6-1dadbdcd7383 Size = 1397 GB Free = 1219674 MB
[15.01.2010 14:22:35  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 14:22:35  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 14:22:35  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 14:22:35  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 14:22:35  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 14:22:35  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 14:22:58  ec4] Secondary Pass: (balancing) 23905,1944ms
[15.01.2010 14:22:58  ec4] Full Primary and Secondary Pass 74001,2359ms