DX4000 - Recovery

WD Legacy Products Sentinel Products
1

Okay here is the short version of the story.

I have an 8TB DX 4000 that we use in our office. I have all of our files backed up so we are good in that respect.

Two weeks ago I noticed that the light for drive 3 was out and the LCD was showing “storage degraded.” I contacted WD and they said I should do a recovery and sent me the ISO file. I did the recovery and it went up through the point where I had to name the NAS and provide a password. Once this was done the NAS rebooted and got hung up at loading the “OS system please wait.” The light for drive 3 went off with the reboot. You could see the renamed NAS on the network, but couldn’t login. The other 3 drives are working fine (at least according to the lights).

After a week on the phone with WD and several recovery attempts with the same results the level 2 tech says you have a bad hard drive (#3) and sends out a replacement hard drive. No kidding!

With the replacement hard drive in hand and high hopes I install the new drive. Then I do a storage recovery. This seems to work as I got a “storage ok” message followed by a message that says “storage status success.” I assume the latter message means it is complete and call WD to confirm, but couldn’t get a straight answer and didn’t see anything in the user manual about the “storage status success” message so I assume this process is complete at this point.

Then I went on to the recovery. This worked up to the same point as before and has now been at the “loading the OS Please wait” for 7 days. I did check to load updates as I had previously been selecting not to. I have also removed the USB from the back of the machine. I can see the NAS on the network, but can’t login. Drive 3 is showing as working and there aren’t any error messages on the LCD.

At this point should I continue to wait? I feel like after 7 days it isn’t going to come back to life. I would like to get my data off of the machine if possible, but I do have backups so all isn’t lost if I can’t.

By the way the NAS was working fine with the 3rd drive failed before we tried the recovery. It is a 4 drive system setup as Raid 5. I can see the renamed NAS on the network, but can’t login.

Does anybody have any other tricks or suggestions to get into this NAS? Did I not wait long enough during the storage recovery?

Any help would be much appreciated.

Thanks,

Jeff

2

If you still have the thumb drive from recreate storage there is a log fil in it that will shed some light on what it did. As in if it used all 4 drives for the raid 5.

you need to verify the md5 hash of your downloaded iso.
I would select do not check for updates
So you ran the setup wizard again and when you say you can’t login it says bad password?

3

Gramps,

I did confirm the md5 hash and it was good.

I used the same thumb drive for both the storage recovery and then the OS recovery. Would it still have the log file on it from the storage recovery? I do see a ServerRecovery.log file.

I did run the setup wizard and got the screen that says “Your server is setup, but there are some alerts.” After this the NAS rebooted and has been stuck loading the os for 7 days. I can see the NAS, but can’t log in.

I can always go back to Step 2 if you think that will help and try to do the storage recovery and then the OS recovery again. When I did the storage recovery the first time it was fairly quick.

Thanks for your help!

Jeff

4

[04/04/2017 16:58:01 634] Starting ServerRecovery.
[04/04/2017 16:58:01 634] AddFirewallException
[04/04/2017 16:58:02 634] Listener CListener created
[04/04/2017 16:58:02 634] Listener UDP listener created
[04/04/2017 16:58:02 634] Listener UDP listener created
[04/04/2017 16:58:02 634] Listener TCP listener created
[04/04/2017 16:58:02 634] ServerRecovery started and is listening for request.
[04/04/2017 16:59:08 668] Listener TCPListener::TcpEventCallbackStatic
[04/04/2017 16:59:08 668] Listener TcpEventCallback
[04/04/2017 16:59:08 668] Listener TCPListener::TcpConditionCallback
[04/04/2017 16:59:08 668] Listener TcpConditionCallback from 192.168.10.14
[04/04/2017 16:59:08 668] NumberUnauthenticatedSessions is called.
[04/04/2017 16:59:08 668] StartSession
[04/04/2017 16:59:08 668] Received connection.
[04/04/2017 16:59:10 7f0] Entering HandshakeThread
[04/04/2017 16:59:10 7f0] Exiting HandshakeThread
[04/04/2017 16:59:10 668] Processing query system disk request.
[04/04/2017 16:59:10 668] GetSystemFirmwareType returned 2
[04/04/2017 16:59:10 668] pVdsDisk->GetPack failed with hr = 0x80042417
[04/04/2017 16:59:10 668] Ignore. One case is uninitialized disk.
[04/04/2017 16:59:10 668] GetPhysicalDriveNumber (\?\PhysicalDrive0)
[04/04/2017 16:59:10 668] End processing query system disk request.
[04/04/2017 16:59:10 668] Processing disk validation for reset request.
[04/04/2017 16:59:10 668] CoCreateInstance CLSID_DOMDocument60 succeeded.
[04/04/2017 16:59:10 668] GetValue - Unable to find element [ImageFile].
[04/04/2017 16:59:10 668] GetValue - Unable to find element [ImageFile].
[04/04/2017 16:59:10 668] pVdsDisk->GetPack failed with hr = 0x80042417
[04/04/2017 16:59:10 668] Ignore. One case is uninitialized disk.
[04/04/2017 16:59:10 668] QueryPartitions - QueryPartitions failed with hr = 0x80042417
[04/04/2017 16:59:10 668] ValidateDisk - DiskValidationResults = 1
[04/04/2017 16:59:10 668] End processing disk validation for reset request.
[04/04/2017 16:59:10 668] Processing factory reset request.
[04/04/2017 16:59:10 668] End processing factory reset request.
[04/04/2017 16:59:10 7f8] Entering BeginResetThread
[04/04/2017 16:59:10 7f8] Executing Reset
[04/04/2017 16:59:10 7f8] DiskImaging::Reset, bCleanDisk = 1
[04/04/2017 16:59:10 7f8] pVdsDisk->GetPack failed with hr = 0x80042417
[04/04/2017 16:59:10 7f8] Ignore. One case is uninitialized disk.
[04/04/2017 16:59:10 7f8] Executing CreatePartition (0x6400000, 0x100000, style=2)
[04/04/2017 16:59:10 7f8] Calling CreatePartitionEx (0x6400000, 0x100000, style=2)
[04/04/2017 16:59:13 7f8] Executing CreatePartition (0x8000000, 0x6500000, style=2)
[04/04/2017 16:59:13 7f8] Calling CreatePartitionEx (0x8000000, 0x6500000, style=2)
[04/04/2017 16:59:13 7f8] Executing CreatePartition (0xf00000000, 0xe500000, style=2)
[04/04/2017 16:59:13 7f8] Calling CreatePartitionEx (0xf00000000, 0xe500000, style=2)
[04/04/2017 16:59:21 7f8] Executing CreatePartition (0x1fdffe00000, 0xffffffffffffffff, style=2)
[04/04/2017 16:59:21 7f8] Calling CreatePartitionEx (0x1fdffe00000, 0xf0e500000, style=2)
[04/04/2017 16:59:28 7f8] Executing CreatePartition (0x1fdffe00000, 0xffffffffffffffff, style=2)
[04/04/2017 16:59:28 7f8] Calling CreatePartitionEx (0x1fdffe00000, 0x20d0e300000, style=2)
[04/04/2017 16:59:35 7f8] Executing CreatePartition (0xffffffffffffffff, 0xffffffffffffffff, style=2)
[04/04/2017 16:59:35 7f8] Calling CreatePartitionEx (0x16a34afbe00, 0x40b0e100000, style=2)
[04/04/2017 16:59:41 7f8] Found extent with VDS_DISK_EXTENT_TYPE = 4 that is not a volume.
[04/04/2017 16:59:41 7f8] Found extent with VDS_DISK_EXTENT_TYPE = 5 that is not a volume.
[04/04/2017 16:59:42 7f8] WimApply (C:\sys.wim, 1, a:, 2, *)
[04/04/2017 16:59:42 7f8] Calling WIMCreateFile
[04/04/2017 16:59:42 7f8] Calling WIMRegisterMessageCallback
[04/04/2017 16:59:42 7f8] Calling WIMGetAttributes
[04/04/2017 16:59:42 7f8] Calling WIMSetTemporaryPath
[04/04/2017 16:59:42 7f8] Calling WIMLoadImage
[04/04/2017 16:59:42 7f8] BuildReferenceList - Extension found is [.wim]. Skip building file references
[04/04/2017 16:59:42 7f8] Calling WIMApplyImage
[04/04/2017 16:59:42 7f8] Progress 0
[04/04/2017 16:59:42 584] Progress 0
[04/04/2017 16:59:42 584] Progress 1
[04/04/2017 16:59:42 584] Progress 1
[04/04/2017 16:59:42 584] Progress 2
[04/04/2017 16:59:42 584] Progress 2
[04/04/2017 16:59:42 584] Progress 2
[04/04/2017 16:59:42 584] Progress 3
[04/04/2017 16:59:42 584] Progress 3
[04/04/2017 16:59:42 584] Progress 3
[04/04/2017 16:59:42 584] Progress 3
[04/04/2017 16:59:42 584] Progress 4
[04/04/2017 16:59:42 584] Progress 4
[04/04/2017 16:59:42 584] Progress 4
[04/04/2017 16:59:42 584] Progress 4
[04/04/2017 16:59:42 584] Progress 5
[04/04/2017 16:59:42 584] Progress 5
[04/04/2017 16:59:42 584] Progress 5
[04/04/2017 16:59:42 584] Progress 5
[04/04/2017 16:59:42 584] Progress 6
[04/04/2017 16:59:42 584] Progress 6
[04/04/2017 16:59:42 584] Progress 6
[04/04/2017 16:59:42 584] Progress 6
[04/04/2017 16:59:42 584] Progress 7
[04/04/2017 16:59:42 584] Progress 8
[04/04/2017 16:59:42 584] Progress 9
[04/04/2017 16:59:42 584] Progress 10
[04/04/2017 16:59:42 584] Progress 10
[04/04/2017 16:59:42 584] Progress 13
[04/04/2017 16:59:42 584] Progress 14
[04/04/2017 16:59:42 584] Progress 16
[04/04/2017 16:59:42 584] Progress 18
[04/04/2017 16:59:42 584] Progress 19
[04/04/2017 16:59:42 584] Progress 20
[04/04/2017 16:59:42 584] Progress 22
[04/04/2017 16:59:42 584] Progress 24
[04/04/2017 16:59:42 584] Progress 24
[04/04/2017 16:59:43 584] Progress 25
[04/04/2017 16:59:43 7f8] Progress 25
[04/04/2017 16:59:43 7f8] Cleanup
[04/04/2017 16:59:43 7f8] WimApply (C:\os.swm, 1, G:, 2, )
[04/04/2017 16:59:43 7f8] Calling WIMCreateFile
[04/04/2017 16:59:43 7f8] Calling WIMRegisterMessageCallback
[04/04/2017 16:59:43 7f8] Calling WIMGetAttributes
[04/04/2017 16:59:43 7f8] Calling WIMSetTemporaryPath
[04/04/2017 16:59:43 7f8] Calling WIMLoadImage
[04/04/2017 16:59:45 7f8] Search pattern for reference file: [C:\os.swm]
[04/04/2017 16:59:45 7f8] File [C:\OS.SWM] is skipped.
[04/04/2017 16:59:45 7f8] File [C:\OS2.SWM] added to ref list
[04/04/2017 16:59:45 7f8] File [C:\OS3.SWM] added to ref list
[04/04/2017 16:59:45 7f8] Calling WimSetReferenceFile [C:\OS2.SWM]
[04/04/2017 16:59:45 7f8] Calling WimSetReferenceFile [C:\OS3.SWM]
[04/04/2017 16:59:45 7f8] Calling WIMApplyImage
[04/04/2017 17:00:32 7f8] Progress 50
[04/04/2017 17:00:32 5c8] Progress 50
[04/04/2017 17:00:41 5c8] Progress 50
[04/04/2017 17:00:58 5c8] Progress 50
[04/04/2017 17:01:14 5c8] Progress 51
[04/04/2017 17:01:32 5c8] Progress 51
[04/04/2017 17:01:49 5c8] Progress 51
[04/04/2017 17:02:07 5c8] Progress 51
[04/04/2017 17:02:19 5c8] Progress 52
[04/04/2017 17:02:29 5cc] Progress 52
[04/04/2017 17:02:33 5c8] Progress 52
[04/04/2017 17:02:39 5cc] Progress 52
[04/04/2017 17:02:44 5c8] Progress 53
[04/04/2017 17:02:55 5cc] Progress 53
[04/04/2017 17:02:58 5cc] Progress 53
[04/04/2017 17:03:02 5c8] Progress 53
[04/04/2017 17:03:08 5c8] Progress 54
[04/04/2017 17:03:14 5c8] Progress 54
[04/04/2017 17:03:20 5c8] Progress 54
[04/04/2017 17:03:26 5c8] Progress 54
[04/04/2017 17:03:33 5c8] Progress 55
[04/04/2017 17:03:38 5cc] Progress 55
[04/04/2017 17:03:44 5c8] Progress 55
[04/04/2017 17:03:49 5cc] Progress 55
[04/04/2017 17:03:54 5c8] Progress 56
[04/04/2017 17:03:59 5c8] Progress 56
[04/04/2017 17:04:05 5cc] Progress 56
[04/04/2017 17:04:09 5cc] Progress 56
[04/04/2017 17:04:15 5cc] Progress 57
[04/04/2017 17:04:20 5cc] Progress 57
[04/04/2017 17:04:27 5c8] Progress 57
[04/04/2017 17:04:34 5cc] Progress 57
[04/04/2017 17:04:44 5c8] Progress 58
[04/04/2017 17:04:51 5c8] Progress 58
[04/04/2017 17:04:58 5c8] Progress 58
[04/04/2017 17:05:06 5c8] Progress 58
[04/04/2017 17:05:17 5c8] Progress 59
[04/04/2017 17:05:24 5c8] Progress 59
[04/04/2017 17:05:34 5c8] Progress 59
[04/04/2017 17:05:56 5c8] Progress 59
[04/04/2017 17:06:26 5c8] Progress 60
[04/04/2017 17:07:09 5c8] Progress 60
[04/04/2017 17:07:42 5c8] Progress 60
[04/04/2017 17:08:00 5cc] Progress 60
[04/04/2017 17:08:25 5c8] Progress 61
[04/04/2017 17:08:46 5c8] Progress 61
[04/04/2017 17:08:56 5cc] Progress 61
[04/04/2017 17:08:59 5cc] Progress 61
[04/04/2017 17:09:03 5cc] Progress 62
[04/04/2017 17:09:06 5cc] Progress 62
[04/04/2017 17:09:13 5c8] Progress 62
[04/04/2017 17:09:18 5cc] Progress 62
[04/04/2017 17:09:22 5c8] Progress 63
[04/04/2017 17:09:30 5c8] Progress 63
[04/04/2017 17:09:44 5c8] Progress 63
[04/04/2017 17:09:48 5cc] Progress 63
[04/04/2017 17:09:53 5cc] Progress 64
[04/04/2017 17:09:58 5c8] Progress 64
[04/04/2017 17:10:03 5c8] Progress 64
[04/04/2017 17:10:19 5cc] Progress 64
[04/04/2017 17:10:44 5c8] Progress 65
[04/04/2017 17:11:02 5c8] Progress 65
[04/04/2017 17:11:23 5cc] Progress 65
[04/04/2017 17:11:35 5c8] Progress 65
[04/04/2017 17:11:44 5c8] Progress 66
[04/04/2017 17:11:50 5c8] Progress 66
[04/04/2017 17:11:53 5c8] Progress 66
[04/04/2017 17:11:58 5c8] Progress 66
[04/04/2017 17:12:03 5cc] Progress 67
[04/04/2017 17:12:06 5c8] Progress 67
[04/04/2017 17:12:10 5cc] Progress 67
[04/04/2017 17:12:26 5c8] Progress 67
[04/04/2017 17:12:39 5c8] Progress 68
[04/04/2017 17:12:49 5c8] Progress 68
[04/04/2017 17:13:01 5c8] Progress 68
[04/04/2017 17:13:09 5c8] Progress 68
[04/04/2017 17:13:16 5c8] Progress 69
[04/04/2017 17:13:22 5c8] Progress 69
[04/04/2017 17:13:28 5c8] Progress 69
[04/04/2017 17:13:34 5c8] Progress 69
[04/04/2017 17:13:42 5c8] Progress 70
[04/04/2017 17:13:51 5c8] Progress 70
[04/04/2017 17:13:58 5c8] Progress 70
[04/04/2017 17:14:05 5c8] Progress 70
[04/04/2017 17:14:12 5c8] Progress 71
[04/04/2017 17:14:20 5c8] Progress 71
[04/04/2017 17:14:27 5c8] Progress 71
[04/04/2017 17:14:33 5c8] Progress 71
[04/04/2017 17:15:05 5c8] Progress 72
[04/04/2017 17:15:40 5c8] Progress 72
[04/04/2017 17:15:48 5c8] Progress 72
[04/04/2017 17:15:55 5c8] Progress 72
[04/04/2017 17:16:02 5c8] Progress 73
[04/04/2017 17:16:08 5c8] Progress 73
[04/04/2017 17:16:15 5c8] Progress 73
[04/04/2017 17:16:22 5c8] Progress 73
[04/04/2017 17:16:29 5c8] Progress 74
[04/04/2017 17:16:37 5c8] Progress 74
[04/04/2017 17:16:44 5c8] Progress 74
[04/04/2017 17:16:53 5c8] Progress 74
[04/04/2017 17:17:26 7f8] Progress 75
[04/04/2017 17:17:26 7f8] Cleanup
[04/04/2017 17:17:27 7f8] Execution [BCDEdit.exe /store a:\EFI\Microsoft\Boot\Bcd /set {bootmgr} device partition=a:]
[04/04/2017 17:17:28 7f8] Execution [BCDEdit.exe /store a:\EFI\Microsoft\Boot\Bcd /set {memdiag} device partition=a:]
[04/04/2017 17:17:28 7f8] Execution [BCDEdit.exe /store a:\EFI\Microsoft\Boot\Bcd /set {default} device partition=G:]
[04/04/2017 17:17:28 7f8] Execution [BCDEdit.exe /store a:\EFI\Microsoft\Boot\Bcd /set {default} osdevice partition=G:]
[04/04/2017 17:17:28 7f8] Execution [BCDEdit.exe /store a:\EFI\Microsoft\Boot\Bcd /set {FWbootmgr} displayorder {bootmgr} /addfirst]
[04/04/2017 17:17:28 7f8] Reset completed with hr = 0x0
[04/04/2017 17:17:28 7f8] Exiting BeginResetThread
[04/04/2017 17:17:30 668] Processing reboot request.
[04/04/2017 17:17:30 668] Stop listening for any requests.
[04/04/2017 17:17:30 668] End processing reboot request.
[04/04/2017 17:17:30 668] Calling ~CSession.
[04/04/2017 17:17:30 668] SessionComplete: Number of active connections: 0.
[04/04/2017 17:17:30 668] StartSession - close socket
[04/04/2017 17:17:30 668] End StartSession
[04/04/2017 17:17:30 634] Stopping listener
[04/04/2017 17:17:30 634] Listener CListener destroyed
[04/04/2017 17:17:30 634] Listener UDP listener destroyed
[04/04/2017 17:17:30 634] Listener UDP listener destroyed
[04/04/2017 17:17:30 634] Listener TCPListener destroyed
[04/04/2017 17:17:30 634] Program exit with hr = 0x0
[04/04/2017 17:17:30 634] START ~CSessionMgr
[04/04/2017 17:17:30 634] END ~CSessionMgr
[04/05/2017 16:41:18 564] Starting ServerRecovery.
[04/05/2017 16:41:18 564] AddFirewallException
[04/05/2017 16:41:18 564] Listener CListener created
[04/05/2017 16:41:18 564] Listener UDP listener created
[04/05/2017 16:41:18 564] Listener UDP listener created
[04/05/2017 16:41:18 564] Listener TCP listener created
[04/05/2017 16:41:18 564] ServerRecovery started and is listening for request.
[04/05/2017 16:42:55 63c] Listener TCPListener::TcpEventCallbackStatic
[04/05/2017 16:42:55 63c] Listener TcpEventCallback
[04/05/2017 16:42:55 63c] Listener TCPListener::TcpConditionCallback
[04/05/2017 16:42:55 63c] Listener TcpConditionCallback from 192.168.10.14
[04/05/2017 16:42:55 63c] NumberUnauthenticatedSessions is called.
[04/05/2017 16:42:55 63c] StartSession
[04/05/2017 16:42:55 63c] Received connection.
[04/05/2017 16:42:56 4d8] Entering HandshakeThread
[04/05/2017 16:42:56 4d8] Exiting HandshakeThread
[04/05/2017 16:42:56 63c] Processing query backup machines request.
[04/05/2017 16:42:56 63c] No backup catalog is found in [\?\Volume{49bb91fc-73f9-46cf-a0fc-1629742ea83c}]
[04/05/2017 16:42:56 63c] No backup catalog is found in [\?\Volume{2ab947d5-5adb-428c-8e88-399714d5217d}]
[04/05/2017 16:42:56 63c] End processing query backup machines request.
[04/05/2017 16:43:36 63c] Processing query backup machines request.
[04/05/2017 16:43:36 63c] No backup catalog is found in [\?\Volume{49bb91fc-73f9-46cf-a0fc-1629742ea83c}]
[04/05/2017 16:43:36 63c] No backup catalog is found in [\?\Volume{2ab947d5-5adb-428c-8e88-399714d5217d}]
[04/05/2017 16:43:36 63c] End processing query backup machines request.
[04/05/2017 16:43:36 63c] Processing query backup machines request.
[04/05/2017 16:43:36 63c] No backup catalog is found in [\?\Volume{49bb91fc-73f9-46cf-a0fc-1629742ea83c}]
[04/05/2017 16:43:36 63c] No backup catalog is found in [\?\Volume{2ab947d5-5adb-428c-8e88-399714d5217d}]
[04/05/2017 16:43:36 63c] End processing query backup machines request.
[04/05/2017 16:43:37 63c] Processing query backup machines request.
[04/05/2017 16:43:37 63c] No backup catalog is found in [\?\Volume{49bb91fc-73f9-46cf-a0fc-1629742ea83c}]
[04/05/2017 16:43:37 63c] No backup catalog is found in [\?\Volume{2ab947d5-5adb-428c-8e88-399714d5217d}]
[04/05/2017 16:43:37 63c] End processing query backup machines request.
[04/05/2017 16:43:38 63c] RestoreProtocol::Disconnect is called.
[04/05/2017 16:43:38 63c] RestoreProtocol::Disconnect - calling socket shutdown.
[04/05/2017 16:43:38 63c] MC_Closed
[04/05/2017 16:43:38 63c] Calling ~CSession.
[04/05/2017 16:43:38 63c] SessionComplete: Number of active connections: 0.
[04/05/2017 16:43:38 63c] StartSession - close socket
[04/05/2017 16:43:38 63c] End StartSession
[04/05/2017 16:44:19 63c] Listener TCPListener::TcpEventCallbackStatic
[04/05/2017 16:44:19 63c] Listener TcpEventCallback
[04/05/2017 16:44:19 63c] Listener TCPListener::TcpConditionCallback
[04/05/2017 16:44:19 63c] Listener TcpConditionCallback from 192.168.10.14
[04/05/2017 16:44:19 63c] NumberUnauthenticatedSessions is called.
[04/05/2017 16:44:19 63c] StartSession
[04/05/2017 16:44:19 63c] Received connection.
[04/05/2017 16:44:19 6e8] Entering HandshakeThread
[04/05/2017 16:44:19 6e8] Exiting HandshakeThread
[04/05/2017 16:44:19 63c] Processing query backup machines request.
[04/05/2017 16:44:19 63c] No backup catalog is found in [\?\Volume{49bb91fc-73f9-46cf-a0fc-1629742ea83c}]
[04/05/2017 16:44:19 63c] No backup catalog is found in [\?\Volume{2ab947d5-5adb-428c-8e88-399714d5217d}]
[04/05/2017 16:44:19 63c] No backup catalog is found in [\?\Volume{dde3d259-1a5f-11e7-81f3-0090a9b8bc31}]
[04/05/2017 16:44:19 63c] End processing query backup machines request.
[04/05/2017 16:44:22 63c] Processing query backup machines request.
[04/05/2017 16:44:22 63c] No backup catalog is found in [\?\Volume{49bb91fc-73f9-46cf-a0fc-1629742ea83c}]
[04/05/2017 16:44:22 63c] No backup catalog is found in [\?\Volume{2ab947d5-5adb-428c-8e88-399714d5217d}]
[04/05/2017 16:44:22 63c] No backup catalog is found in [\?\Volume{dde3d259-1a5f-11e7-81f3-0090a9b8bc31}]
[04/05/2017 16:44:22 63c] End processing query backup machines request.
[04/05/2017 16:44:24 63c] Processing query backup machines request.
[04/05/2017 16:44:24 63c] No backup catalog is found in [\?\Volume{49bb91fc-73f9-46cf-a0fc-1629742ea83c}]
[04/05/2017 16:44:24 63c] No backup catalog is found in [\?\Volume{2ab947d5-5adb-428c-8e88-399714d5217d}]
[04/05/2017 16:44:24 63c] No backup catalog is found in [\?\Volume{dde3d259-1a5f-11e7-81f3-0090a9b8bc31}]
[04/05/2017 16:44:24 63c] End processing query backup machines request.
[04/05/2017 16:44:25 63c] Processing query backup machines request.
[04/05/2017 16:44:25 63c] No backup catalog is found in [\?\Volume{49bb91fc-73f9-46cf-a0fc-1629742ea83c}]
[04/05/2017 16:44:25 63c] No backup catalog is found in [\?\Volume{2ab947d5-5adb-428c-8e88-399714d5217d}]
[04/05/2017 16:44:25 63c] No backup catalog is found in [\?\Volume{dde3d259-1a5f-11e7-81f3-0090a9b8bc31}]
[04/05/2017 16:44:25 63c] End processing query backup machines request.
[04/05/2017 16:44:30 63c] RestoreProtocol::Disconnect is called.
[04/05/2017 16:44:30 63c] RestoreProtocol::Disconnect - calling socket shutdown.
[04/05/2017 16:44:30 63c] MC_Closed
[04/05/2017 16:44:30 63c] Calling ~CSession.
[04/05/2017 16:44:30 63c] SessionComplete: Number of active connections: 0.
[04/05/2017 16:44:30 63c] StartSession - close socket
[04/05/2017 16:44:30 63c] End StartSession
[04/05/2017 16:45:06 63c] Listener TCPListener::TcpEventCallbackStatic
[04/05/2017 16:45:06 63c] Listener TcpEventCallback
[04/05/2017 16:45:06 63c] Listener TCPListener::TcpConditionCallback
[04/05/2017 16:45:06 63c] Listener TcpConditionCallback from 192.168.10.14
[04/05/2017 16:45:06 63c] NumberUnauthenticatedSessions is called.
[04/05/2017 16:45:06 63c] StartSession
[04/05/2017 16:45:06 63c] Received connection.
[04/05/2017 16:45:06 3a4] Entering HandshakeThread
[04/05/2017 16:45:06 3a4] Exiting HandshakeThread
[04/05/2017 16:45:06 63c] Processing query backup machines request.
[04/05/2017 16:45:06 63c] No backup catalog is found in [\?\Volume{49bb91fc-73f9-46cf-a0fc-1629742ea83c}]
[04/05/2017 16:45:06 63c] No backup catalog is found in [\?\Volume{2ab947d5-5adb-428c-8e88-399714d5217d}]
[04/05/2017 16:45:06 63c] End processing query backup machines request.
[04/05/2017 16:45:06 63c] Processing query backups request.
[04/05/2017 16:45:06 63c] No backup is found on this catalog in [\?\Volume{49bb91fc-73f9-46cf-a0fc-1629742ea83c}].
[04/05/2017 16:45:06 63c] No backup is found on this catalog in [\?\Volume{2ab947d5-5adb-428c-8e88-399714d5217d}].
[04/05/2017 16:45:08 63c] End processing query backups request.
[04/05/2017 16:51:16 63c] Processing query system disk request.
[04/05/2017 16:51:16 63c] Found extent with VDS_DISK_EXTENT_TYPE = 4 that is not a volume.
[04/05/2017 16:51:16 63c] Found extent with VDS_DISK_EXTENT_TYPE = 5 that is not a volume.
[04/05/2017 16:51:16 63c] End processing query system disk request.
[04/05/2017 16:51:16 63c] Processing disk validation for reset request.
[04/05/2017 16:51:16 63c] CoCreateInstance CLSID_DOMDocument60 succeeded.
[04/05/2017 16:51:16 63c] GetValue - Unable to find element [ImageFile].
[04/05/2017 16:51:16 63c] GetValue - Unable to find element [ImageFile].
[04/05/2017 16:51:16 63c] Found extent with VDS_DISK_EXTENT_TYPE = 4 that is not a volume.
[04/05/2017 16:51:16 63c] Found extent with VDS_DISK_EXTENT_TYPE = 5 that is not a volume.
[04/05/2017 16:51:16 63c] ValidateDiskGeometry - setting bMatch to TRUE.
[04/05/2017 16:51:16 63c] ValidateDisk - DiskValidationResults = 0
[04/05/2017 16:51:16 63c] End processing disk validation for reset request.
[04/05/2017 16:51:16 63c] Processing factory reset request.
[04/05/2017 16:51:16 63c] End processing factory reset request.
[04/05/2017 16:51:16 574] Entering BeginResetThread
[04/05/2017 16:51:16 574] Executing Reset
[04/05/2017 16:51:16 574] DiskImaging::Reset, bCleanDisk = 0
[04/05/2017 16:51:16 574] Found extent with VDS_DISK_EXTENT_TYPE = 4 that is not a volume.
[04/05/2017 16:51:16 574] Found extent with VDS_DISK_EXTENT_TYPE = 5 that is not a volume.
[04/05/2017 16:51:17 574] WimApply (E:\sys.wim, 1, a:, 2, *)
[04/05/2017 16:51:17 574] Calling WIMCreateFile
[04/05/2017 16:51:17 574] Calling WIMRegisterMessageCallback
[04/05/2017 16:51:17 574] Calling WIMGetAttributes
[04/05/2017 16:51:17 574] Calling WIMSetTemporaryPath
[04/05/2017 16:51:17 574] Calling WIMLoadImage
[04/05/2017 16:51:17 574] BuildReferenceList - Extension found is [.wim]. Skip building file references
[04/05/2017 16:51:17 574] Calling WIMApplyImage
[04/05/2017 16:51:17 574] Progress 0
[04/05/2017 16:51:17 56c] Progress 0
[04/05/2017 16:51:17 56c] Progress 1
[04/05/2017 16:51:17 56c] Progress 1
[04/05/2017 16:51:17 56c] Progress 2
[04/05/2017 16:51:17 56c] Progress 2
[04/05/2017 16:51:17 56c] Progress 2
[04/05/2017 16:51:17 56c] Progress 3
[04/05/2017 16:51:17 56c] Progress 3
[04/05/2017 16:51:17 56c] Progress 3
[04/05/2017 16:51:17 56c] Progress 3
[04/05/2017 16:51:17 56c] Progress 4
[04/05/2017 16:51:17 56c] Progress 4
[04/05/2017 16:51:17 56c] Progress 4
[04/05/2017 16:51:17 56c] Progress 4
[04/05/2017 16:51:17 56c] Progress 5
[04/05/2017 16:51:17 56c] Progress 5
[04/05/2017 16:51:17 56c] Progress 5
[04/05/2017 16:51:17 56c] Progress 5
[04/05/2017 16:51:17 56c] Progress 6
[04/05/2017 16:51:17 56c] Progress 6
[04/05/2017 16:51:17 56c] Progress 6
[04/05/2017 16:51:17 56c] Progress 6
[04/05/2017 16:51:17 56c] Progress 7
[04/05/2017 16:51:17 56c] Progress 8
[04/05/2017 16:51:17 56c] Progress 9
[04/05/2017 16:51:17 56c] Progress 10
[04/05/2017 16:51:17 56c] Progress 10
[04/05/2017 16:51:17 56c] Progress 13
[04/05/2017 16:51:17 56c] Progress 14
[04/05/2017 16:51:17 56c] Progress 16
[04/05/2017 16:51:18 56c] Progress 18
[04/05/2017 16:51:18 56c] Progress 19
[04/05/2017 16:51:18 56c] Progress 20
[04/05/2017 16:51:18 56c] Progress 22
[04/05/2017 16:51:18 56c] Progress 24
[04/05/2017 16:51:18 56c] Progress 24
[04/05/2017 16:51:18 56c] Progress 25
[04/05/2017 16:51:18 574] Progress 25
[04/05/2017 16:51:18 574] Cleanup
[04/05/2017 16:51:20 574] WimApply (E:\os.swm, 1, C:, 2, )
[04/05/2017 16:51:20 574] Calling WIMCreateFile
[04/05/2017 16:51:21 574] Calling WIMRegisterMessageCallback
[04/05/2017 16:51:21 574] Calling WIMGetAttributes
[04/05/2017 16:51:21 574] Calling WIMSetTemporaryPath
[04/05/2017 16:51:21 574] Calling WIMLoadImage
[04/05/2017 16:51:23 574] Search pattern for reference file: [E:\os.swm]
[04/05/2017 16:51:23 574] File [E:\OS.SWM] is skipped.
[04/05/2017 16:51:23 574] File [E:\OS2.SWM] added to ref list
[04/05/2017 16:51:23 574] File [E:\OS3.SWM] added to ref list
[04/05/2017 16:51:23 574] Calling WimSetReferenceFile [E:\OS2.SWM]
[04/05/2017 16:51:23 574] Calling WimSetReferenceFile [E:\OS3.SWM]
[04/05/2017 16:51:23 574] Calling WIMApplyImage
[04/05/2017 16:52:11 574] Progress 50
[04/05/2017 16:52:11 434] Progress 50
[04/05/2017 16:52:20 434] Progress 50
[04/05/2017 16:52:37 434] Progress 50
[04/05/2017 16:52:53 434] Progress 51
[04/05/2017 16:53:11 434] Progress 51
[04/05/2017 16:53:28 434] Progress 51
[04/05/2017 16:53:46 434] Progress 51
[04/05/2017 16:53:58 434] Progress 52
[04/05/2017 16:54:08 438] Progress 52
[04/05/2017 16:54:12 434] Progress 52
[04/05/2017 16:54:18 438] Progress 52
[04/05/2017 16:54:26 434] Progress 53
[04/05/2017 16:54:31 438] Progress 53
[04/05/2017 16:54:34 438] Progress 53
[04/05/2017 16:54:41 434] Progress 53
[04/05/2017 16:54:48 434] Progress 54
[04/05/2017 16:54:54 434] Progress 54
[04/05/2017 16:55:01 434] Progress 54
[04/05/2017 16:55:07 434] Progress 54
[04/05/2017 16:55:13 434] Progress 55
[04/05/2017 16:55:20 438] Progress 55
[04/05/2017 16:55:25 438] Progress 55
[04/05/2017 16:55:30 438] Progress 55
[04/05/2017 16:55:35 434] Progress 56
[04/05/2017 16:55:40 434] Progress 56
[04/05/2017 16:55:45 438] Progress 56
[04/05/2017 16:55:50 434] Progress 56
[04/05/2017 16:55:54 434] Progress 57
[04/05/2017 16:56:00 438] Progress 57
[04/05/2017 16:56:06 434] Progress 57
[04/05/2017 16:56:15 438] Progress 57
[04/05/2017 16:56:23 434] Progress 58
[04/05/2017 16:56:30 434] Progress 58
[04/05/2017 16:56:39 434] Progress 58
[04/05/2017 16:56:46 434] Progress 58
[04/05/2017 16:56:57 434] Progress 59
[04/05/2017 16:57:03 434] Progress 59
[04/05/2017 16:57:15 434] Progress 59
[04/05/2017 16:57:38 434] Progress 59
[04/05/2017 16:58:08 434] Progress 60
[04/05/2017 16:58:48 434] Progress 60
[04/05/2017 16:59:23 434] Progress 60
[04/05/2017 16:59:41 438] Progress 60
[04/05/2017 17:00:05 434] Progress 61
[04/05/2017 17:00:27 438] Progress 61
[04/05/2017 17:00:34 438] Progress 61
[04/05/2017 17:00:39 438] Progress 61
[04/05/2017 17:00:50 438] Progress 62
[04/05/2017 17:00:51 438] Progress 62
[04/05/2017 17:00:54 434] Progress 62
[04/05/2017 17:00:59 438] Progress 62
[04/05/2017 17:01:05 434] Progress 63
[04/05/2017 17:01:10 434] Progress 63
[04/05/2017 17:01:27 434] Progress 63
[04/05/2017 17:01:32 438] Progress 63
[04/05/2017 17:01:36 438] Progress 64
[04/05/2017 17:01:40 434] Progress 64
[04/05/2017 17:01:45 434] Progress 64
[04/05/2017 17:02:06 438] Progress 64
[04/05/2017 17:02:28 434] Progress 65
[04/05/2017 17:02:44 434] Progress 65
[04/05/2017 17:03:02 438] Progress 65
[04/05/2017 17:03:15 438] Progress 65
[04/05/2017 17:03:24 438] Progress 66
[04/05/2017 17:03:30 434] Progress 66
[04/05/2017 17:03:34 434] Progress 66
[04/05/2017 17:03:37 438] Progress 66
[04/05/2017 17:03:41 438] Progress 67
[04/05/2017 17:03:49 434] Progress 67
[04/05/2017 17:03:53 438] Progress 67
[04/05/2017 17:04:06 434] Progress 67
[04/05/2017 17:04:23 434] Progress 68
[04/05/2017 17:04:33 434] Progress 68
[04/05/2017 17:04:43 434] Progress 68
[04/05/2017 17:04:51 434] Progress 68
[04/05/2017 17:04:58 434] Progress 69
[04/05/2017 17:05:04 434] Progress 69
[04/05/2017 17:05:11 434] Progress 69
[04/05/2017 17:05:17 434] Progress 69
[04/05/2017 17:05:24 434] Progress 70
[04/05/2017 17:05:34 434] Progress 70
[04/05/2017 17:05:41 434] Progress 70
[04/05/2017 17:05:48 434] Progress 70
[04/05/2017 17:05:55 434] Progress 71
[04/05/2017 17:06:02 434] Progress 71
[04/05/2017 17:06:11 434] Progress 71
[04/05/2017 17:06:18 434] Progress 71
[04/05/2017 17:06:47 434] Progress 72
[04/05/2017 17:07:24 434] Progress 72
[04/05/2017 17:07:31 434] Progress 72
[04/05/2017 17:07:38 434] Progress 72
[04/05/2017 17:07:45 434] Progress 73
[04/05/2017 17:07:51 434] Progress 73
[04/05/2017 17:07:58 434] Progress 73
[04/05/2017 17:08:05 434] Progress 73
[04/05/2017 17:08:14 434] Progress 74
[04/05/2017 17:08:21 434] Progress 74
[04/05/2017 17:08:28 434] Progress 74
[04/05/2017 17:08:37 434] Progress 74
[04/05/2017 17:09:12 574] Progress 75
[04/05/2017 17:09:12 574] Cleanup
[04/05/2017 17:09:12 574] Execution [BCDEdit.exe /store a:\EFI\Microsoft\Boot\Bcd /set {bootmgr} device partition=a:]
[04/05/2017 17:09:13 574] Execution [BCDEdit.exe /store a:\EFI\Microsoft\Boot\Bcd /set {memdiag} device partition=a:]
[04/05/2017 17:09:13 574] Execution [BCDEdit.exe /store a:\EFI\Microsoft\Boot\Bcd /set {default} device partition=C:]
[04/05/2017 17:09:13 574] Execution [BCDEdit.exe /store a:\EFI\Microsoft\Boot\Bcd /set {default} osdevice partition=C:]
[04/05/2017 17:09:13 574] Execution [BCDEdit.exe /store a:\EFI\Microsoft\Boot\Bcd /set {FWbootmgr} displayorder {bootmgr} /addfirst]
[04/05/2017 17:09:14 574] Creating server recovery file
[04/05/2017 17:09:14 574] CoCreateInstance CLSID_DOMDocument60 succeeded.
[04/05/2017 17:09:14 574] Reset completed with hr = 0x0
[04/05/2017 17:09:14 574] Exiting BeginResetThread
[04/05/2017 17:09:17 63c] Processing reboot request.
[04/05/2017 17:09:17 63c] Stop listening for any requests.
[04/05/2017 17:09:17 63c] End processing reboot request.
[04/05/2017 17:09:17 63c] Calling ~CSession.
[04/05/2017 17:09:17 63c] SessionComplete: Number of active connections: 0.
[04/05/2017 17:09:17 63c] StartSession - close socket
[04/05/2017 17:09:17 63c] End StartSession
[04/05/2017 17:09:17 564] Stopping listener
[04/05/2017 17:09:17 564] Listener CListener destroyed
[04/05/2017 17:09:17 564] Listener UDP listener destroyed
[04/05/2017 17:09:17 564] Listener UDP listener destroyed
[04/05/2017 17:09:17 564] Listener TCPListener destroyed
[04/05/2017 17:09:17 564] Program exit with hr = 0x0
[04/05/2017 17:09:17 564] START ~CSessionMgr
[04/05/2017 17:09:17 564] END ~CSessionMgr

5

Found another log file under the wdrecovery directory on the thumb drive. It is recovery.log.

Too much info to cut and paste on the reply.

6

yea, the log I was pondering is gone if you used the same thumb.
so if you try to remote to the server from a pc with mstsc, user administrator and the password you set it says bad password?

7

It will actually login to a screen that shows the Administrator is logged in. If you click on the Administrator tab it then says “Logon failure: user account restriction. Possible reasons are blank passwords not allowed, logon hour restrictions, or a policy restriction has been enforced.”

8

If I try to connect a network drive I get the bad password.

9

I don’t know. I am beginning to think it may be broken. As in some file may have expired. So hard to troubleshoot without a monitor. FWIW I think if you did that middle step, recreate your data is gone anyway.
You said you had backups?
That log file you said was too big should have the drive/raid info in it.
I am on another project now and cannot try a recovery right now myself.
Look through all of this link below. One guy never got it, (but I really do not think he ever tried a different thumb drive) another it worked the second time. If you do try again use a different thumb drive please. I don’t mean one for one and one for the other, I mean a new fresh to the mix thumb for the bottom recover step.
If you have more questions just holler

10

Thanks for the help. I will try again with new thumb drives and post back with the results. Again many thanks for your time and response.

11

Jeff,

Frankly speaking I did not understand the logic from the WD support. In principle you should have only had to replace the drive without going through all the recovery process with the ISO install.

Sometimes when you cannot enter the login page is because following the recovery process you still have to complete the W2008r2 install process. This is generally done with explorer using for instance 192.168.0.66 (or whatever is the IP address of your DX4000 - or //wdsentinel if this is name of your dx4000), then a screen will appear, you download and run the file proposed by the o/s. Now you can complete the install process and when this is over you will then be able to access the unit as before

regards

Davide

12

DTMtech,

I didn’t understand the logic either and I wish that I would have questioned them further. WD told me that the recovery would make the failed drive go back to work (software issue instead of hardware). It wasn’t until a week later I got to a level 2 guy and he said the same thing you just said.

What has got me is that after I go to the NAS through the browser and name the NAS and give it a password it finishes setting up the NAS. The software says 100% complete, but then the NAS just sits at the “loading OS please wait” screen. I have tried everything to get into it, but it just will not let me. I assume the software is hung up and all of the services just haven’t started.

I left is like this for 7 days and the OS never loaded. Any ideas on how to get into this thing or how long I should wait? I feel like if it did happen in 7 days it would never happen so I repeated the 2 and 3 steps today. I even went out and bought two brand new USB drives as suggested by Gramps.

13

So you have done a recovery with the new thumb and surfed to it and gave it a name, admin password, and do not check for updates. It has rebooted a couple of times then says finished but with alerts? And you cannot go any further?

14

My gut feeling, if you got the message storage recreated, is that your data should be still there and the issue is with W2008r2 stuck somewhere during install. Did you try to shutdown the unit and then switch it on again? I would give it a try, if you still get the same “loading OS please wait” message forever I would try again the recovery process with the thumb created with the Recovery feature of the ISO distribution. I would advise to use the very latest version 1.7.6.21

regards

Davide

15

Gramps - You got it. Once it says it is finished with alerts the NAS says “loading OS please wait”. I can see it on the network, but can’t log in. I did repeat storage recovery and OS recovery steps today as advised with new USB thumb drives. The logs on the storage recovery look promising that my data is still there.

DTMtech - The ISO that I have is from WD and the version is 1.7.6.21. I checked the file also (hash). Every time I have done the OS recovery it has hung up here. I have turned off and back on also and still the same thing.

I hate to give up, but I have read everything you guys have posted over the last two weeks and haven’t been able to get it.

16

Hi Jeff, sorry, didn’t realize you already tried both power off/on and to restart the recovery process from scratch. Can you check whether the drive product number you replaced is one in the approved list of 1.7.6.21 ISO? If everything hangs somewhere during install maybe something has gone corrupt during the RAID rebuild and in that case you should recreate first and then recovery. This however means to loose your data.