Forum Discussion

stivi65's avatar
stivi65
Level 3
13 years ago

Netbackup Restore VM

Hi We have Netbackup 7.1.0.2 on a Windows 2008 Server. We have 6 ESX Server. I Installed a ESX Host (Windows 2008)with NetBackup 7.1.0.2. The Backup of the VM work fine. Then i test the restore. First test was to restore a singe file from a VM to the ESX Host. Worked fine. Second Test was to restore a single file (the same file as before) to the vm. DIDNT WORK the error was: 13.03.2012 13:14:02 - Info bptm(pid=6052) completed reading backup image 13.03.2012 13:14:29 - Error bpbrm(pid=3000) Cannot connect to srvsnbz3041vm The thired test was to restore a whole vm (vmdk files) DIDNT WORK the error was: 13.03.2012 15:51:41 - Critical bpbrm(pid=6836) from client srvsnbz4005bac.spitalzentrum.dom: FTL - Virtual machine restore: VxMS initialization failed 13.03.2012 15:51:41 - Critical bpbrm(pid=6836) from client srvsnbz4005bac.spitalzentrum.dom: FTL - VxMS initialization failed 13.03.2012 15:51:41 - begin reading 13.03.2012 15:52:52 - restored image srvsnbz3042vm_1331195121 - (the restore failed to recover the requested files(5)); restore time 00:23:19 For me it looks like i cant write to the LUN's. Do you have any idee? Thanks for your help. Regarts Stivi
7.1.x and Earlier
Agent for VMware Virtual Infrastructure
Backup and Recovery
NetBackup
  • Marianne's avatar
    Marianne
    Level 6
    13 years ago

    If you want to restore a file directly to the vm, you need to install the NBU client software in the vm.

    What most users do in a case like this is to restore the file(s) to the Windows backup host and copy from there to the vm.

    For whole vm restore, you need to follow steps as per NBU for VMware Admin Guide  http://www.symantec.com/docs/DOC3663

    See p. 120 onwards.
    What did you choose in 'Recovery Destination dialog box' - Original location or Alternate location?
    What did you choose in 'Recovery Options dialog box'?

  • stivi65's avatar
    stivi65
    Level 3
    13 years ago
    Hi Marianne I try a restore as written in the Guide. I try a "Alternate location". Didnt work. The error was: restore not initiated: the restore failed to recover the requested files (5) The "Rup Pre- Recovery Test" Passed without error. In the Activity Monitor i have no entry from this restore try. Any Idee? Thanks for help. Regarts Stivi
  • stivi65's avatar
    stivi65
    Level 3
    13 years ago
    Hi I made a new restore try. This time i right click on the "Backup Archive..." icon and chose "Run as Administrator" I Loged in as Admin. I hate windows. :( Now i start the restore "Alternate location" again. It mount the tape and now it stays. In the "View Status" i see this 10:18:01 INF - If Media id SN0030 is not in a robotic library administrative interaction may be required to satisfy this mount request. 10:18:03 INF - Waiting for mount of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 10:18:12 INF - Waiting for positioning of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 10:18:15 INF - Beginning read-blockmap on server srvsnbz4005bac.spitalzentrum.dom of client srvsnbz3042vm. In the Activity Monitor this: 14.03.2012 10:17:47 - begin Restore 14.03.2012 10:17:52 - media SN0030 required 14.03.2012 10:17:52 - restoring image srvsnbz3042vm_1331195121 14.03.2012 10:17:59 - Info bptm(pid=4312) start 14.03.2012 10:17:59 - started process bptm (4312) 14.03.2012 10:17:59 - Info bpdm(pid=4312) reading backup image 14.03.2012 10:17:59 - Info bptm(pid=4312) using 30 data buffers 14.03.2012 10:17:59 - Info bptm(pid=4312) spawning a child process 14.03.2012 10:17:59 - Info bptm(pid=4312) child pid: 5900 14.03.2012 10:17:59 - Info bptm(pid=5900) start 14.03.2012 10:17:59 - started process bptm (5900) 14.03.2012 10:18:01 - Info bptm(pid=4312) Waiting for mount of media id SN0030 (copy 1) on server srvsnbz4005bac.spitalzentrum.dom. 14.03.2012 10:18:01 - started process bptm (4312) 14.03.2012 10:18:01 - mounting SN0030 14.03.2012 10:18:01 - requesting resource SN0030 14.03.2012 10:18:01 - granted resource SN0030 14.03.2012 10:18:01 - granted resource SNB_VTL_1_038 14.03.2012 10:18:03 - Info bptm(pid=4312) INF - Waiting for mount of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 14.03.2012 10:18:11 - mounted; mount time: 00:00:10 14.03.2012 10:18:11 - Info bptm(pid=4312) SN0030 14.03.2012 10:18:13 - Info bptm(pid=4312) INF - Waiting for positioning of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 14.03.2012 10:18:13 - positioning SN0030 to file 3 14.03.2012 10:18:13 - positioned SN0030; position time: 00:00:00 14.03.2012 10:18:15 - begin reading 14.03.2012 10:18:15 - Info bptm(pid=4312) waited for empty buffer 1 times, delayed 1 times 14.03.2012 10:18:15 - end reading; read time: 00:00:00 14.03.2012 10:18:16 - Info bptm(pid=4312) completed reading backup image For me it looks like he can not writhe the data down to the LUN. He wriths the Datas direct back to the LUN. Correct? Or is the sending the datas to the ESX Server? Regarts Stivi
  • stivi65's avatar
    stivi65
    Level 3
    13 years ago
    It crashed now with error 2817 "View Stus" 10:18:01 INF - If Media id SN0030 is not in a robotic library administrative interaction may be required to satisfy this mount request. 10:18:03 INF - Waiting for mount of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 10:18:12 INF - Waiting for positioning of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 10:18:15 INF - Beginning read-blockmap on server srvsnbz4005bac.spitalzentrum.dom of client srvsnbz3042vm. 10:40:33 (751.001) INF - If Media id SN0030 is not in a robotic library administrative interaction may be required to satisfy this mount request. 10:40:35 (751.001) INF - Waiting for mount of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 10:40:44 (751.001) INF - Waiting for positioning of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 10:40:46 (751.001) INF - Beginning restore from server srvsnbz4005bac.spitalzentrum.dom to client srvsnbz4005bac.spitalzentrum.dom. 10:40:48 (751.001) (751.001) FTL - Virtual machine restore: VxMS initialization failed 10:41:56 (751.xxx) INF - Status = FlashBackup-Windows policy restore error. Activity Monitor" 14.03.2012 10:17:47 - begin Restore 14.03.2012 10:17:52 - media SN0030 required 14.03.2012 10:17:52 - restoring image srvsnbz3042vm_1331195121 14.03.2012 10:17:59 - Info bptm(pid=4312) start 14.03.2012 10:17:59 - started process bptm (4312) 14.03.2012 10:17:59 - Info bpdm(pid=4312) reading backup image 14.03.2012 10:17:59 - Info bptm(pid=4312) using 30 data buffers 14.03.2012 10:17:59 - Info bptm(pid=4312) spawning a child process 14.03.2012 10:17:59 - Info bptm(pid=4312) child pid: 5900 14.03.2012 10:17:59 - Info bptm(pid=5900) start 14.03.2012 10:17:59 - started process bptm (5900) 14.03.2012 10:18:01 - Info bptm(pid=4312) Waiting for mount of media id SN0030 (copy 1) on server srvsnbz4005bac.spitalzentrum.dom. 14.03.2012 10:18:01 - started process bptm (4312) 14.03.2012 10:18:01 - mounting SN0030 14.03.2012 10:18:01 - requesting resource SN0030 14.03.2012 10:18:01 - granted resource SN0030 14.03.2012 10:18:01 - granted resource SNB_VTL_1_038 14.03.2012 10:18:03 - Info bptm(pid=4312) INF - Waiting for mount of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 14.03.2012 10:18:11 - mounted; mount time: 00:00:10 14.03.2012 10:18:11 - Info bptm(pid=4312) SN0030 14.03.2012 10:18:13 - Info bptm(pid=4312) INF - Waiting for positioning of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 14.03.2012 10:18:13 - positioning SN0030 to file 3 14.03.2012 10:18:13 - positioned SN0030; position time: 00:00:00 14.03.2012 10:18:15 - begin reading 14.03.2012 10:18:15 - Info bptm(pid=4312) waited for empty buffer 1 times, delayed 1 times 14.03.2012 10:18:15 - end reading; read time: 00:00:00 14.03.2012 10:18:16 - Info bptm(pid=4312) completed reading backup image 14.03.2012 10:40:26 - Info bpbrm(pid=1804) srvsnbz4005bac.spitalzentrum.dom is the host to restore to 14.03.2012 10:40:26 - Info bpbrm(pid=1804) reading file list from client 14.03.2012 10:40:28 - connecting 14.03.2012 10:40:28 - Info bpbrm(pid=1804) starting bptm 14.03.2012 10:40:31 - Info tar32(pid=5348) Restore started 14.03.2012 10:40:31 - connected; connect time: 00:00:03 14.03.2012 10:40:31 - Info bptm(pid=7020) start 14.03.2012 10:40:31 - started process bptm (7020) 14.03.2012 10:40:31 - Info bpdm(pid=7020) reading backup image 14.03.2012 10:40:31 - Info bptm(pid=7020) using 30 data buffers 14.03.2012 10:40:31 - Info bptm(pid=7020) spawning a child process 14.03.2012 10:40:31 - Info bptm(pid=7020) child pid: 7032 14.03.2012 10:40:31 - Info bptm(pid=7032) start 14.03.2012 10:40:31 - started process bptm (7032) 14.03.2012 10:40:33 - Info bptm(pid=7020) Waiting for mount of media id SN0030 (copy 1) on server srvsnbz4005bac.spitalzentrum.dom. 14.03.2012 10:40:33 - started process bptm (7020) 14.03.2012 10:40:33 - mounting SN0030 14.03.2012 10:40:33 - requesting resource SN0030 14.03.2012 10:40:33 - granted resource SN0030 14.03.2012 10:40:33 - granted resource SNB_VTL_1_023 14.03.2012 10:40:35 - Info bptm(pid=7020) INF - Waiting for mount of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 14.03.2012 10:40:43 - mounted; mount time: 00:00:10 14.03.2012 10:40:43 - Info bptm(pid=7020) SN0030 14.03.2012 10:40:45 - Info bptm(pid=7020) INF - Waiting for positioning of media id SN0030 on server srvsnbz4005bac.spitalzentrum.dom for reading. 14.03.2012 10:40:45 - positioning SN0030 to file 3 14.03.2012 10:40:45 - positioned SN0030; position time: 00:00:00 14.03.2012 10:40:47 - Critical bpbrm(pid=1804) from client srvsnbz4005bac.spitalzentrum.dom: FTL - Virtual machine restore: VxMS initialization failed 14.03.2012 10:40:47 - begin reading 14.03.2012 10:40:53 - Info tar32(pid=5348) done. status: 24: socket write failed 14.03.2012 10:40:53 - Error bpbrm(pid=1804) client restore EXIT STATUS 24: socket write failed 14.03.2012 10:41:55 - restored image srvsnbz3042vm_1331195121 - (the restore failed to recover the requested files(5)); restore time 00:24:03 14.03.2012 10:41:56 - end Restore; elapsed time: 00:24:09 FlashBackup-Windows policy restore error(2817) Thanks for help Regarts Stivi
  • Marianne's avatar
    Marianne
    Level 6
    13 years ago

    Have you checked permissions as per Omar's post?

    NetBackup status code 2817
    Message: FlashBackup Windows policy restore error
    Explanation: An error caused some or all of the files to fail.the restore operation.
    RecommendedAction: Try the following possible solutions in the order presented:
    ■ Ensure that the client server list contains entries for the master server and
    any media servers that can be used during a backup or restore.
    ■ Examine the status or the progress log on the client for messages on why the
    restore failed. Also, check the All Log Entries report on the server.
    ■ Check ownership and permission on directories where files are restored.
    ■ Check the NetBackup tar log for additional failure information.
    ■ Correct the problems that you find and retry the restore.
     

  • stivi65's avatar
    stivi65
    Level 3
    13 years ago
    Hi Marianne I send the Technode from Omar to the ESX Admin and he told me he has it done. Server list is OK. I have only the Master. The ESX Host is a Media. And Backup work fine. Wher can i find the progress log and the alllog. I only have logs like bpbkar etc. Ownership and Permissons. I thougt The ESX Host send the Files direkt to the LUN. So Permissons are not importand. Is that correct? Were can i find the tar log? Regarts Stivi
  • Marianne's avatar
    Marianne
    Level 6
    13 years ago

    All log entries: NBU GUI: Reports -> All log entries report

    Progress log: BAR GUI : View Status tab, or else in ...Netbackup\logs\user_ops\<user-name>\logs

    tar log folder does not exists by default - you need to create it under ...Netbackup\logs\.

    If we have another look at Job details, it seems we will need VxMS log as well: 'VxMS initialization failed'. See 'Enabling VxMS logging' on p. 149 in the NBU for VMware manual (link above).

    Permissions are important - files need to be written via ESX server to datastore.

  • stivi65's avatar
    stivi65
    Level 3
    13 years ago
    Hi Marianne Just to verify. I start a restore from my ESX Host oder Master. The Datas are send from the VTL over SAn to the ESX Host. From there with TCP/IP to the ESX Server The ESX Server write the datas to his LUN. The ESX Host didnt write it to the `LUN's direkt. Is that correct? In the All Log entrys i have this: 14.03.2012 10:40:47 srvsnbz4005bac.spitalzentrum.dom srvsnbz4005bac.spitalzentrum.dom Critical 751 Retrieve from client srvsnbz4005bac.spitalzentrum.dom: FTL - Virtual machine restore: VxMS initialization failed 14.03.2012 10:40:47 srvsnbz4005bac.spitalzentrum.dom srvsnbz4005bac.spitalzentrum.dom Info 751 Media Device begin reading backup id srvsnbz3042vm_1331195121 (restore), copy 1, fragment 1, from media id SN0030 on drive SNB_VTL_1_023 (index 2) 14.03.2012 10:40:47 srvsnbz4005bac.spitalzentrum.dom srvsnbz4005bac.spitalzentrum.dom Error 751 Retrieve cannot write data to socket, 10053 14.03.2012 10:40:47 srvsnbz4004bac.spitalzentrum.dom Critical 0 General flush_to_bptm: put_string returned -4 14.03.2012 10:40:47 srvsnbz4004bac.spitalzentrum.dom Error 0 General sfr: error 24: - aborting restore of all files in partition /\\.\VMFILE0 and continuing 14.03.2012 10:40:47 srvsnbz4004bac.spitalzentrum.dom Error 0 General bptm_end: put_string returned -4 14.03.2012 10:40:53 srvsnbz4005bac.spitalzentrum.dom srvsnbz4005bac.spitalzentrum.dom Error 751 Retrieve client restore EXIT STATUS 24: socket write failed Looks like the data can not send to the ESX Server correct? Regarts Stivi
  • stivi65's avatar
    stivi65
    Level 3
    13 years ago
    Hi Question I start a restore from my ESX Host oder Master. The Datas are send from the VTL over SAn to the ESX Host. From there with TCP/IP to the ESX Server The ESX Server write the datas to his LUN. The ESX Host didnt write it to the `LUN's direkt. Is that correct? Regarts Stivi