Restore on wmware Status 2817-

[Dianubis]

Hi

I try to send a restore of VM machine but is failed, select transfer type nbd. Whe i try to send by san mode start to restoring but i have to cancel because on vcenter apeared on task window many process called "allocate block size"

2/07/2013 12:50:19 PM - Info bptm (pid=1356) start
2/07/2013 12:50:19 PM - started process bptm (pid=1356)
2/07/2013 12:50:19 PM - Info bpdm (pid=1356) reading backup image
2/07/2013 12:50:19 PM - Info bptm (pid=1356) using 512 data buffers
2/07/2013 12:50:19 PM - Info bptm (pid=1356) spawning a child process
2/07/2013 12:50:19 PM - Info bptm (pid=1356) child pid: 3736
2/07/2013 12:50:19 PM - Info bptm (pid=3736) start
2/07/2013 12:50:19 PM - started process bptm (pid=3736)
2/07/2013 12:50:22 PM - Info bptm (pid=1356) Waiting for mount of media id I9YOV9 (copy 1) on serverXXXXXX.
2/07/2013 12:50:22 PM - started process bptm (pid=1356)
2/07/2013 12:50:22 PM - mounting I9YOV9
2/07/2013 12:50:23 PM - Info bptm (pid=1356) INF - Waiting for mount of media id I9YOV9 on server mediaserver for reading.
2/07/2013 12:50:32 PM - mounted I9YOV9; mount time: 0:00:10
2/07/2013 12:50:32 PM - Info bptm (pid=1356) I9YOV9
2/07/2013 12:50:33 PM - Info bptm (pid=1356) INF - Waiting for positioning of media id I9YOV9 on server mediaserver for reading.
2/07/2013 12:50:33 PM - positioning I9YOV9 to file 3
2/07/2013 12:50:34 PM - positioned I9YOV9; position time: 0:00:01
2/07/2013 12:50:36 PM - begin reading
2/07/2013 12:50:37 PM - Info bptm (pid=1356) waited for empty buffer 0 times, delayed 0 times
2/07/2013 12:50:37 PM - end reading; read time: 0:00:01
2/07/2013 12:50:38 PM - Info bptm (pid=1356) completed reading backup image
2/07/2013 12:51:13 PM - Info bpbrm (pid=4808) mediaserver is the host to restore to
2/07/2013 12:51:13 PM - Info bpbrm (pid=4808) reading file list from client
2/07/2013 12:51:14 PM - connecting
2/07/2013 12:51:14 PM - Info bpbrm (pid=4808) starting bptm
2/07/2013 12:51:16 PM - Info tar32 (pid=2424) Restore started
2/07/2013 12:51:16 PM - connected; connect time: 0:00:00
2/07/2013 12:51:16 PM - Info bptm (pid=1772) start
2/07/2013 12:51:16 PM - started process bptm (pid=1772)
2/07/2013 12:51:17 PM - Info bpdm (pid=1772) reading backup image
2/07/2013 12:51:17 PM - Info bptm (pid=1772) using 512 data buffers
2/07/2013 12:51:17 PM - Info bptm (pid=1772) spawning a child process
2/07/2013 12:51:17 PM - Info bptm (pid=1772) child pid: 2788
2/07/2013 12:51:17 PM - Info bptm (pid=2788) start
2/07/2013 12:51:17 PM - started process bptm (pid=2788)
2/07/2013 12:51:19 PM - mounted I9YOV9
2/07/2013 12:51:19 PM - Info bptm (pid=1772) I9YOV9
2/07/2013 12:51:21 PM - Info bptm (pid=1772) INF - Waiting for positioning of media id I9YOV9 on server mediaserver for reading.
2/07/2013 12:51:21 PM - positioning I9YOV9 to file 3
2/07/2013 12:51:21 PM - positioned I9YOV9; position time: 0:00:00
2/07/2013 12:51:24 PM - begin reading
2/07/2013 12:51:25 PM - Critical bpbrm (pid=4808) from client mediaserver: FTL - Virtual machine restore: VxMS initialization failed
2/07/2013 12:51:25 PM - Info bptm (pid=1772) waited for empty buffer 0 times, delayed 0 times
2/07/2013 12:51:31 PM - Info tar32 (pid=2424) done. status: 24: socket write failed
2/07/2013 12:51:31 PM - Error bpbrm (pid=4808) client restore EXIT STATUS 24: socket write failed
2/07/2013 12:55:25 PM - begin Restore
2/07/2013 12:55:42 PM - media needed: I9YOV9
2/07/2013 12:55:42 PM - restoring from image CLON-aonildb01_1372786089
2/07/2013 12:56:01 PM - requesting resource I9YOV9
2/07/2013 12:56:02 PM - granted resource  I9YOV9
2/07/2013 12:56:02 PM - granted resource  HP.ULTRIUM3-SCSI.019
2/07/2013 12:56:59 PM - requesting resource I9YOV9
2/07/2013 12:56:59 PM - granted resource  I9YOV9
2/07/2013 12:56:59 PM - granted resource  HP.ULTRIUM3-SCSI.019
2/07/2013 12:58:15 PM - restored from image CLON-xxxx_1372786089; restore time: 0:02:33
2/07/2013 12:58:16 PM - end Restore; elapsed time 0:02:51
FlashBackup Windows policy restore error  (2817)

[RamNagalla]

"allocate block size" is the normal notification  you seen in Vcenter when you do the restore of Full VM. does not needs to bother about this..

so you can let the SAN restore compleate..

for NDB restore

does backup host able to communicate with the port 902 of the ESXI host?

does it ever worked?

[Yasuhisa_Ishika]

Please check if port 902 used by NBD transport is not blocked by firewall. If blocked, restore via NBD fails.

http://www.symantec.com/docs/TECH159577

If not blocked, enable VxMS logging and reproduce this issue. Some relevant technotes here.

http://www.symantec.com/docs/TECH178177

http://www.symantec.com/docs/TECH147551

http://www.symantec.com/docs/TECH140288

http://www.symantec.com/docs/TECH188993

BTW, please tell us more about your environment like version of NetBackup/VMware/OS.  "allocate block size" logging is normal behavior in SAN transport, don't worry about it.just keep running.

[Dianubis]

Hi All

i did the restorarion by san method , and it was suscessfully but the machine don´t start,

i did  many tests with other date and.. the same result

M proxy hosts is a windows 2008 R2 and the VCENTER is 5.0

[RamNagalla]

i did the restorarion by san method , and it was suscessfully but the machine don´t start,

its know issue ..

when you do the restore through SAN does the storage LUNs in the backup host are in Online or offline

the LUNs in the backup hosts should be online to let the restore successfull.

when the LUNs are offline though the netbackup reports as successfull its not a successfull Restore.