locked
Full client restore not working for WHS 2011, I get Completed failed, HR=0x8007001F RRS feed

  • Question

  • So, I'm trying to restore my Windows 7 64-bit computer using the Client Restore CD.

    My original drive was 500gb, with 3 partitions.  The 100MB partition, OS (c) ~300GB, and a scratch volume (d), the rest of the space.  I did full backups, but excluded the D drive (didn't want to backup what was on it).  Drive crashed.  Got identical replacement drive.

    The closest I got was using the advanced partition thing, creating the partitions, but didn't format that partitions.  Then the restore actually started, got a few percent done, and then crashed.

    Here's most of the log:

    [09/20/2012 06:26:37  2ec] Calling m_Restore.RequestVolumes
    [09/20/2012 06:26:38  3a0] PASS :RequestCompleteHandler::Completed succeeded (at func: Request completed, d:\wssg_src\colorado_qfe\backup\pc\client\homeservermanager\homeservermanager.cpp (26)
    [09/20/2012 06:26:38  3a0] Calling m_Restore.RetrieveVolumes
    [09/20/2012 06:26:38  3a0] m_Restore.RetrieveVolumes returned 2 volumes
    [09/20/2012 06:26:38  2ec] PASS :GetStartDateTimeString succeeded (at func: GetDateTimeFormat, d:\wssg_src\colorado_qfe\backup\pc\bmr\restorewizard\backupsetutils.h (40)
    [09/20/2012 06:26:38  2ec] Adding volume, status=Successful, size=0.10 GB, description= System Reserved (0.10 GB)
    [09/20/2012 06:26:38  2ec] Adding volume, status=Successful, size=214.75 GB, description=C:\ Volume 2 (214.75 GB)
    [09/20/2012 06:27:20  2ec] Calling m_Restore.RequestVolumes
    [09/20/2012 06:27:20  3a0] PASS :RequestCompleteHandler::Completed succeeded (at func: Request completed, d:\wssg_src\colorado_qfe\backup\pc\client\homeservermanager\homeservermanager.cpp (26)
    [09/20/2012 06:27:20  3a0] Calling m_Restore.RetrieveVolumes
    [09/20/2012 06:27:20  3a0] m_Restore.RetrieveVolumes returned 2 volumes
    [09/20/2012 06:27:30  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:27:31  41c]   Volume: \\?\Volume{986a04c7-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:31  41c]   Volume: \\?\Volume{986a04c8-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:31  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:31  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:27:31  41c] WARN: Volumes changed, new number of volumes=2.
    [09/20/2012 06:27:35  3a0] Found 4 disk entries
    [09/20/2012 06:27:35  3a0]   Disk: \\?\PhysicalDrive0
    [09/20/2012 06:27:35  3a0]   Disk: \\?\PhysicalDrive0
    [09/20/2012 06:27:35  3a0]   Disk: \\?\PhysicalDrive0
    [09/20/2012 06:27:35  3a0]   Disk: \\?\PhysicalDrive1
    [09/20/2012 06:27:35  2ec] RestoreHelper::CanDoAutoPartition started
    [09/20/2012 06:27:35  2ec] Found disk:   \\?\PhysicalDrive0
    [09/20/2012 06:27:35  2ec]    Size:      500104691712
    [09/20/2012 06:27:35  2ec]    mediaType: 0xc
    [09/20/2012 06:27:35  2ec] Found disk:   \\?\PhysicalDrive1
    [09/20/2012 06:27:35  2ec]    Size:      257949696
    [09/20/2012 06:27:35  2ec]    mediaType: 0xb
    [09/20/2012 06:27:35  2ec] No existing partition for volume  (Offset=105906176, Size=230581862400), must flatten disk
    [09/20/2012 06:27:35  2ec] Disk Size=500104691712, Total Source Volume Size=230686720000, CanReuse=0
    [09/20/2012 06:27:35  2ec] Last Volume Size=230581862400, Last Volume Min Size=132245996032, LastVolumeOffset=105906176
    [09/20/2012 06:27:35  2ec] endOfLastVolume=230688817152, modifiedLastVolumeSize=499997736960
    [09/20/2012 06:27:41  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:27:42  41c]   Volume: \\?\Volume{986a04c7-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:42  41c]   Volume: \\?\Volume{986a04c8-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:42  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:42  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:27:44  2ec] Adding None
    [09/20/2012 06:27:44  2ec] Can't lock volume C:
    [09/20/2012 06:27:44  2ec] Adding  System Reserved (100 MB)
    [09/20/2012 06:27:44  2ec] Adding C:\ Volume 2 (219900 MB)
    [09/20/2012 06:27:44  2ec] Adding None
    [09/20/2012 06:27:44  2ec] Can't lock volume D:
    [09/20/2012 06:27:44  2ec] Adding  System Reserved (100 MB)
    [09/20/2012 06:27:44  2ec] Adding C:\ Volume 2 (219900 MB)
    [09/20/2012 06:27:52  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:27:54  41c]   Volume: \\?\Volume{986a04c7-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:54  41c]   Volume: \\?\Volume{986a04c8-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:54  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:54  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:27:58  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:27:59  41c]   Volume: \\?\Volume{986a04c7-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:59  41c]   Volume: \\?\Volume{986a04c8-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:59  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:27:59  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:28:03  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:28:03  41c]   Volume: \\?\Volume{986a04c7-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:28:03  41c]   Volume: \\?\Volume{986a04c8-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:28:03  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:28:03  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:28:07  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:28:07  41c]   Volume: \\?\Volume{986a04c7-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:28:07  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:28:07  41c] PhysicalVolumeInfo::GetAll returned 2 volumes, filtered to 1 volumes
    [09/20/2012 06:28:07  41c] WARN: Volumes changed, new number of volumes=1.
    [09/20/2012 06:28:07  2ec] Selection for C: is C:\.
    [09/20/2012 06:28:07  2ec] Selection for D: is 'None'.
    [09/20/2012 06:28:07  2ec] Adding None
    [09/20/2012 06:28:07  2ec] Can't lock volume C:

    Removed some repetitive errors contained below

    [09/20/2012 06:30:28  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:30:28  41c] WARN: Volumes changed, new number of volumes=2.
    [09/20/2012 06:30:28  2ec] Selection for {986a04e1-032e-11e2-a1b1-001cc090160a} is 'None'.
    [09/20/2012 06:30:28  2ec] Adding None
    [09/20/2012 06:30:28  2ec] Can't lock volume C:
    [09/20/2012 06:30:28  2ec] Adding  System Reserved (100 MB)
    [09/20/2012 06:30:28  2ec] Adding C:\ Volume 2 (219900 MB)
    [09/20/2012 06:30:28  2ec] Adding None
    [09/20/2012 06:30:28  2ec] Can't lock volume {986a04e1-032e-11e2-a1b1-001cc090160a}
    [09/20/2012 06:30:28  2ec] Previous selection for this combo was 'None'
    [09/20/2012 06:30:28  2ec] Adding  System Reserved (100 MB)
    [09/20/2012 06:30:28  2ec] Adding C:\ Volume 2 (219900 MB)
    [09/20/2012 06:30:32  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:30:32  41c]   Volume: \\?\Volume{986a04e1-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:32  41c]   Volume: \\?\Volume{986a04e4-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:32  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:30:32  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:30:36  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:30:37  41c]   Volume: \\?\Volume{986a04e1-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:37  41c]   Volume: \\?\Volume{986a04e4-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:37  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:30:37  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:30:40  2ec] Selection for combo 1 changed, updating images
    [09/20/2012 06:30:41  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:30:41  41c]   Volume: \\?\Volume{986a04e1-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:41  41c]   Volume: \\?\Volume{986a04e4-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:41  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:30:41  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:30:45  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:30:45  41c]   Volume: \\?\Volume{986a04e1-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:45  41c]   Volume: \\?\Volume{986a04e4-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:45  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:30:45  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:30:49  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:30:50  41c]   Volume: \\?\Volume{986a04e1-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:50  41c]   Volume: \\?\Volume{986a04e4-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:50  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:30:50  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:30:54  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:30:54  41c]   Volume: \\?\Volume{986a04e1-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:54  41c]   Volume: \\?\Volume{986a04e4-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:54  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:30:54  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:30:55  2ec] Selection for C: is C:\.
    [09/20/2012 06:30:55  2ec] Selection for {986a04e1-032e-11e2-a1b1-001cc090160a} is 'None'.
    [09/20/2012 06:30:56  2ec] PASS :GetStartDateTimeString succeeded (at func: GetDateTimeFormat, d:\wssg_src\colorado_qfe\backup\pc\bmr\restorewizard\backupsetutils.h (40)
    [09/20/2012 06:30:58  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:30:58  41c]   Volume: \\?\Volume{986a04e1-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:58  41c]   Volume: \\?\Volume{986a04e4-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:30:58  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:30:58  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:31:01  2ec] PASS :GetStartDateTimeString succeeded (at func: GetDateTimeFormat, d:\wssg_src\colorado_qfe\backup\pc\bmr\restorewizard\backupsetutils.h (40)
    [09/20/2012 06:31:01  41c] Calling PhysicalVolumeInfo::GetAll
    [09/20/2012 06:31:02  41c]   Volume: \\?\Volume{986a04e1-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:31:02  41c]   Volume: \\?\Volume{986a04e4-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:31:02  41c]   Volume: \\?\Volume{986a04d2-032e-11e2-a1b1-806e6f6e6963}\
    [09/20/2012 06:31:02  41c] PhysicalVolumeInfo::GetAll returned 3 volumes, filtered to 2 volumes
    [09/20/2012 06:31:02  2ec] Calling m_Restore.Restore
    [09/20/2012 06:31:02  2ec] ReconnectableStream: Created ClientReconnectableStream for {947F52EF-1581-47C9-9537-9A503EEE4BA9}, sizes 16384,10485760,10485760,16384
    [09/20/2012 06:31:02  2ec] Restore - we are in reconnectable state
    [09/20/2012 06:31:02  2ec] Restore started.
    [09/20/2012 06:31:02  60c] Recreating 2 volumes
    [09/20/2012 06:31:02  60c] Volume has 1 extents
    [09/20/2012 06:31:02  60c] Searching for disk: \\.\PhysicalDrive0
    [09/20/2012 06:31:03  60c] Found existing volume
    [09/20/2012 06:31:03  60c] Enter FormatVolume
    [09/20/2012 06:31:03  60c] Formatting Volume 1
    [09/20/2012 06:31:08  60c] Done formatting volume
    [09/20/2012 06:31:08  60c]  Volume<GUID>: \\?\Volume{986a04e4-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:31:08  60c] Volume has 1 extents
    [09/20/2012 06:31:08  60c] Searching for disk: \\.\PhysicalDrive0
    [09/20/2012 06:31:08  60c] Found existing volume
    [09/20/2012 06:31:08  60c] Enter FormatVolume
    [09/20/2012 06:31:08  60c] Formatting Volume 2
    [09/20/2012 06:31:08  60c] Done formatting volume
    [09/20/2012 06:31:08  60c]  Volume<GUID>: \\?\Volume{986a04e1-032e-11e2-a1b1-001cc090160a}\
    [09/20/2012 06:31:08  60c] T_Restore staring the restore of 2 volumes
    [09/20/2012 06:31:08  60c] RestoreHelper::T_Restore: restoring source volume: C:\
    [09/20/2012 06:31:08  60c] RestoreHelper::T_Restore: sending SendBeginRestoreReq
    [09/20/2012 06:31:08  60c] RestoreHelper::T_Restore: entering the server queue
    [09/20/2012 06:31:10  60c] RestoreHelper::T_Restore: we're out of the server queue
    [09/20/2012 06:32:43  60c] RestoreHelper::T_Restore: sending RecvBeginRestoreResp
    [09/20/2012 06:32:43  60c] IoCtl::LockVolume GetOverlappedResult failed with 5
    [09/20/2012 06:32:43  60c] FSCTL_LOCK_VOLUME failed with 5, retrying in 1 second
    [09/20/2012 06:32:44  60c] IoCtl::LockVolume GetOverlappedResult failed with 5
    [09/20/2012 06:32:44  60c] FSCTL_LOCK_VOLUME failed with 5, retrying in 1 second
    [09/20/2012 06:32:45  60c] IoCtl::LockVolume GetOverlappedResult failed with 5
    [09/20/2012 06:32:45  60c] FSCTL_LOCK_VOLUME failed with 5, retrying in 1 second
    [09/20/2012 06:32:47  60c] Volume::Initialize:  IoCtl::LockVolume failed with 5
    [09/20/2012 06:32:47  60c] RestoreHelper::T_Restore: restore complete with return value 31
    [09/20/2012 06:32:47  60c] ERROR: CDialog_Restore::Completed failed, HR=0x8007001F (at func: CDialog_Restore::Completed, d:\wssg_src\colorado_qfe\backup\pc\bmr\restorewizard\dialog_restore.cpp (88)

    Thursday, September 20, 2012 7:55 PM

Answers

  • I recently ran into what sounds like the same thing. My logs had the same FSCTL and IoCtl errors.
    In searching, my issue was apparently related to using the onboard Intel RAID.

    If you are only using a single disk, try changing the SATA controller mode in your BIOS from RAID or AHCI to IDE.

    Then try to restore it again. I had better luck deleting all partitions and letting the restore handle them.
    If that succeeds, you need to change the BIOS setting back or it will likely fail to boot.

    Hope that helps.

    -timotl

    • Marked as answer by Sean Zhu - Thursday, September 27, 2012 7:12 AM
    Friday, September 21, 2012 2:33 PM

All replies

  • I recently ran into what sounds like the same thing. My logs had the same FSCTL and IoCtl errors.
    In searching, my issue was apparently related to using the onboard Intel RAID.

    If you are only using a single disk, try changing the SATA controller mode in your BIOS from RAID or AHCI to IDE.

    Then try to restore it again. I had better luck deleting all partitions and letting the restore handle them.
    If that succeeds, you need to change the BIOS setting back or it will likely fail to boot.

    Hope that helps.

    -timotl

    • Marked as answer by Sean Zhu - Thursday, September 27, 2012 7:12 AM
    Friday, September 21, 2012 2:33 PM
  • Just wanted to say thank you very much!  That is what the problem was. It restored perfectly, switched it back to RAID afterwards, started up just like normal!

    Not sure who I should be mad at for that... I'll direct my anger at Intel! :)

    Thursday, September 27, 2012 1:54 PM