Restore Entire VM via AIR Data

[backup-botw]

I have a decommissioned Netbackup site where all backups were written to disk and then replicated over to my new primary site. I can see the data that I want to use to restore this VM, but when I run through the process and click start recovery the job will launch, go active and then do nothing. What am I missing in this process? From what I understand it should be pretty straight forward as the images are already imported when using AIR.

[RiaanBadenhorst]

You should get a better idea of what to look at when it fails and provides an error code.

[Nicolai]

Try :

vxlogview -p 51216 -X "jobid=3223808"

3223808 is the jobid in the activity monitor.

[backup-botw]

After 7hrs and 8 mins it failed with a status code 50.

[backup-botw]

That command just hung. Are the quotes required? Does the job have to be still running?

[watsons]

The job seems to have "stuck" at the very beginning... can you provide more details of how you performed the restore, such as policy type (assume VMware) and the options you choose.

Also enable verbose=5 bprd logs on master server because that is the request being sent to.

[Nicolai]

Yes the quotes are required.

[backup-botw]

That command doesnt return anything. It just hangs for me.

[sdo]

Assuming the VM was backed-up using 'allow individual file restore', then how about trying an individual file restore of just one file from the VM backup to the master server or media server, or a.n.other server - to prove that the VM backup is at least readable.

If you can answer these questions, we may be able to be more specific:

1) Exact O/S and NetBackup versions of master

2) Exact O/S and NetBackup versions of the media server (if the media server is not the master/media)

3) Exact version of the target ESX server.

4) Exact O/S and NetBackup versions of the guest O/S that is/was within the VM to be restored.

[sdo]

You may have other problems in your environment if that command hangs and never completes.

How have you verified that the DR site is 'working'?

[backup-botw]

Solaris 10 and Netbackup 7.6.0.2

Linux 2.6.32-504.16.2.el6.x86_64 #1 SMP Tue Mar 10 17:01:00 EDT 2015 x86_64 x86_64 x86_64 GNU/Linux and Netbackup 7.6.0.2

5.5 Update 1

Not sure as the server no longer exists. I do know it was Windows and backup using the VMware API as opposed to the client. Most likely 2003 or 2008.

[sdo]

Try a single file restore from the VM style backup?

[Andrew_Madsen]

Have you tried an instant restore of the machine?

[backup-botw]

Thats what I am working on now is ensuring we can restore from replicated disk backups.

[backup-botw]

That part works without issue...

06/23/2015 12:26:54 - begin Restore
06/23/2015 12:26:59 - restoring from image 33prt004vm_1433035082
06/23/2015 12:26:59 - Info bprd (pid=7936) Restoring from copy 1 of image created Sat May 30 20:18:02 2015 from policy MPSC-vSTORAGE-PROD04-P10
06/23/2015 12:26:59 - requesting resource @aaaai
06/23/2015 12:26:59 - granted resource  MediaID=@aaaai;DiskVolume=PureDiskVolume;DiskPool=XP53TAPE010;Path=PureDiskVolume;StorageServer=xp53tape010;MediaServer=xp53tape010
06/23/2015 12:27:03 - Info bpdm (pid=49274) started
06/23/2015 12:27:03 - started process bpdm (pid=49274)
06/23/2015 12:27:03 - Info bpdm (pid=49274) reading backup image
06/23/2015 12:27:03 - Info bpdm (pid=49274) requesting nbjm for media
06/23/2015 12:27:04 - Info bpdm (pid=49274) using 30 data buffers
06/23/2015 12:27:04 - Info bpdm (pid=49274) spawning a child process
06/23/2015 12:27:04 - Info bpbrm (pid=49274) child pid: 49283
06/23/2015 12:27:06 - begin reading
06/23/2015 12:27:08 - requesting resource @aaaai
06/23/2015 12:27:08 - granted resource  MediaID=@aaaai;DiskVolume=PureDiskVolume;DiskPool=XP53TAPE010;Path=PureDiskVolume;StorageServer=xp53tape010;MediaServer=xp53tape010
06/23/2015 12:27:09 - end reading; read time: 0:00:03
06/23/2015 12:27:09 - Info bpbrm (pid=49356) lab53apps026 is the host to restore to
06/23/2015 12:27:09 - Info bpbrm (pid=49356) reading file list for client
06/23/2015 12:27:11 - connecting
06/23/2015 12:27:11 - Info bpbrm (pid=49356) starting bptm
06/23/2015 12:27:11 - Info bpdm (pid=49274) completed reading backup image
06/23/2015 12:27:11 - Info bpdm (pid=49274) EXITING with status 0
06/23/2015 12:27:11 - Info xp53tape010 (pid=49274) StorageServer=PureDisk:xp53tape010; Report=PDDO Stats for (xp53tape010): read: 100761 KB, CR received: 27551 KB, CR received over FC: 0 KB, dedup: 0.0%
06/23/2015 12:27:14 - Info tar (pid=4720) Restore started
06/23/2015 12:27:14 - connected; connect time: 0:00:00
06/23/2015 12:27:14 - Info bpbrm (pid=49356) bptm pid: 49720
06/23/2015 12:27:14 - Info bptm (pid=49720) start
06/23/2015 12:27:14 - started process bptm (pid=49720)
06/23/2015 12:27:14 - Info bptm (pid=49720) reading backup image
06/23/2015 12:27:14 - Info bptm (pid=49720) using 30 data buffers
06/23/2015 12:27:14 - Info bptm (pid=49720) spawning a child process
06/23/2015 12:27:14 - Info bptm (pid=49720) child pid: 49721
06/23/2015 12:27:16 - begin reading
06/23/2015 12:27:19 - Info bptm (pid=49720) waited for empty buffer 0 times, delayed 0 times
06/23/2015 12:27:20 - begin reading
06/23/2015 12:27:22 - Info bptm (pid=49720) waited for empty buffer 2 times, delayed 13 times
06/23/2015 12:27:22 - begin reading
06/23/2015 12:27:24 - Info bptm (pid=49720) waited for empty buffer 0 times, delayed 0 times
06/23/2015 12:27:24 - begin reading
06/23/2015 12:27:26 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 49 times
06/23/2015 12:27:26 - begin reading
06/23/2015 12:27:29 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 60 times
06/23/2015 12:27:29 - begin reading
06/23/2015 12:27:35 - Info bptm (pid=49720) waited for empty buffer 2 times, delayed 32 times
06/23/2015 12:27:35 - begin reading
06/23/2015 12:27:37 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 70 times
06/23/2015 12:27:37 - begin reading
06/23/2015 12:27:39 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 64 times
06/23/2015 12:27:39 - begin reading
06/23/2015 12:27:41 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 57 times
06/23/2015 12:27:41 - begin reading
06/23/2015 12:27:43 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 81 times
06/23/2015 12:27:43 - begin reading
06/23/2015 12:27:45 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 58 times
06/23/2015 12:27:45 - begin reading
06/23/2015 12:27:47 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 55 times
06/23/2015 12:27:47 - begin reading
06/23/2015 12:27:49 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 69 times
06/23/2015 12:27:49 - begin reading
06/23/2015 12:27:51 - Info bptm (pid=49720) waited for empty buffer 2 times, delayed 66 times
06/23/2015 12:27:51 - begin reading
06/23/2015 12:27:53 - Info bptm (pid=49720) waited for empty buffer 2 times, delayed 76 times
06/23/2015 12:27:53 - begin reading
06/23/2015 12:27:55 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 52 times
06/23/2015 12:27:55 - begin reading
06/23/2015 12:27:57 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 31 times
06/23/2015 12:27:57 - begin reading
06/23/2015 12:28:04 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 41 times
06/23/2015 12:28:04 - begin reading
06/23/2015 12:28:05 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 45 times
06/23/2015 12:28:06 - begin reading
06/23/2015 12:28:07 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 59 times
06/23/2015 12:28:08 - begin reading
06/23/2015 12:28:10 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 39 times
06/23/2015 12:28:10 - begin reading
06/23/2015 12:28:12 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 51 times
06/23/2015 12:28:12 - begin reading
06/23/2015 12:28:14 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 61 times
06/23/2015 12:28:14 - begin reading
06/23/2015 12:28:17 - Info bptm (pid=49720) waited for empty buffer 2 times, delayed 65 times
06/23/2015 12:28:17 - begin reading
06/23/2015 12:28:19 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 59 times
06/23/2015 12:28:19 - begin reading
06/23/2015 12:28:21 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 49 times
06/23/2015 12:28:21 - begin reading
06/23/2015 12:28:23 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 31 times
06/23/2015 12:28:23 - begin reading
06/23/2015 12:28:25 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 79 times
06/23/2015 12:28:25 - begin reading
06/23/2015 12:28:27 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 92 times
06/23/2015 12:28:27 - begin reading
06/23/2015 12:28:29 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 63 times
06/23/2015 12:28:29 - begin reading
06/23/2015 12:28:31 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 59 times
06/23/2015 12:28:31 - begin reading
06/23/2015 12:28:33 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 69 times
06/23/2015 12:28:33 - begin reading
06/23/2015 12:28:36 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 64 times
06/23/2015 12:28:36 - begin reading
06/23/2015 12:28:38 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 67 times
06/23/2015 12:28:38 - begin reading
06/23/2015 12:28:40 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 63 times
06/23/2015 12:28:40 - begin reading
06/23/2015 12:28:42 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 50 times
06/23/2015 12:28:42 - begin reading
06/23/2015 12:28:44 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 62 times
06/23/2015 12:28:45 - begin reading
06/23/2015 12:28:47 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 59 times
06/23/2015 12:28:47 - begin reading
06/23/2015 12:28:49 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 65 times
06/23/2015 12:28:49 - begin reading
06/23/2015 12:28:51 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 45 times
06/23/2015 12:28:51 - begin reading
06/23/2015 12:28:53 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 84 times
06/23/2015 12:28:53 - begin reading
06/23/2015 12:28:55 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 82 times
06/23/2015 12:28:55 - begin reading
06/23/2015 12:28:57 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 42 times
06/23/2015 12:28:57 - begin reading
06/23/2015 12:28:59 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 75 times
06/23/2015 12:28:59 - begin reading
06/23/2015 12:29:01 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 51 times
06/23/2015 12:29:01 - begin reading
06/23/2015 12:29:04 - Info bptm (pid=49720) waited for empty buffer 3 times, delayed 77 times
06/23/2015 12:29:04 - begin reading
06/23/2015 12:29:06 - Info bptm (pid=49720) waited for empty buffer 3 times, delayed 51 times
06/23/2015 12:29:06 - begin reading
06/23/2015 12:29:09 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 47 times
06/23/2015 12:29:09 - begin reading
06/23/2015 12:29:11 - Info bptm (pid=49720) waited for empty buffer 1 times, delayed 91 times
06/23/2015 12:29:11 - Info tar (pid=4720) done. status 0
06/23/2015 12:29:11 - end reading; read time: 0:00:02
06/23/2015 12:29:11 - Info bptm (pid=49720) completed reading backup image
06/23/2015 12:29:11 - Info tar (pid=4720) done. status: 0
06/23/2015 12:29:11 - Info bptm (pid=49720) EXITING with status 0 <----------
06/23/2015 12:29:11 - Info xp53tape010 (pid=49720) StorageServer=PureDisk:xp53tape010; Report=PDDO Stats for (xp53tape010): read: 1231513 KB, CR received: 1283285 KB, CR received over FC: 0 KB, dedup: 0.0%
06/23/2015 12:29:11 - Info tar (pid=4720) done. status: 0: the requested operation was successfully completed
06/23/2015 12:29:11 - restored from image 33prt004vm_1433035082; restore time: 0:02:12
06/23/2015 12:29:11 - end Restore; elapsed time 0:02:17
the requested operation was successfully completed  (0)

[backup-botw]

I have not.

[cruisen]

Hello backup-botw,

what I can propose to you,why do not execute the restore from commandline,  with the bprestore command in version 7.5 or the nbrestorevm command in 7.6,  for being capable to gather more information about the error with the -L option, that will trace a log file about the restore.

bprestore writes informative and error messages to a progress-log file
      if you create the file prior to the execution of the bprestore command
      and then specify the file with the -L progress_log option. If
      bprestore cannot restore the requested files or directories, you can
      use the progress log to find the reason for the failure.

I believe this is the same for nbrestorevm, where you can take the options for your environment.

You will also find  the options -L for the progress_log file

From the command and reference guide:

SYNOPSIS
For VSphere Restore:
nbrestorevm -vmw -C vm_client [-S master_server] [-O] [-R rename_file]
[-L progress_log [-en]] [-k "keyword phrase"] [-s mm/dd/yyyy
[ hh:mm:ss]] [-e mm/dd/yyyy [ hh:mm:ss]] [-w [ hh:mm:ss]] [-vmtm
vm_transport_mode] [-vmserver vm_server] [-vmproxy vm_proxy] [-vmpo]
[-vmtd] [-vmfd] [-vmbz] [-vmvmxd] [-vmkeephv] [-vmid] [-vmsn] [-vmrb]
[-vcd] [-vcdred] [-vcdovw] [-vcdrte] [-vcdtemplate] [-vcdlfree]
[-vcdremv]

I hope for you,  that you will get more meaningful information using the comandline by approching the your solution step by step and by better understanding your results.

best regards, 

Cruisen

[backup-botw]

I dont want to do the restore via command line as that is now how the Windows/VM engineers would do restores. I havent had any time to get back to this so if I run into anymore issues I will let you know. Last time I tried it I can restore files, but not the entire VM.