Problems with restoring VM

[bst740]

Hello!

I've a problem in restoring full VM.

I Use Restore from virtual machine backup, using SAN then NBD.

In restore wizzard I choose original location, Restore BIOS UUID, Overwrite existing VM and remove backing information for DVD/CDROM. All prerecovery check pass successfully.

In vCenter console I can see that netbackup creates my VM and try to restore it, but fails

There is some information in log:

7/31/2013 4:53:31 PM - begin Restore
7/31/2013 4:53:34 PM - restoring image NetBackupTest_1375255272
7/31/2013 4:53:41 PM - Info bprd(pid=4372) Restoring from copy 1 of image created 07/31/13 11:21:12   
7/31/2013 4:53:41 PM - requesting resource @aaaab
7/31/2013 4:53:41 PM - granted resource MediaID=@aaaab;DiskVolume=E:\;DiskPool=Test_Pool;Path=E:\;StorageServer=sdc01ntbak;MediaServer=sdc01...
7/31/2013 4:53:44 PM - Info bpdm(pid=1764) started           
7/31/2013 4:53:44 PM - started process bpdm (1764)
7/31/2013 4:53:45 PM - Info bpdm(pid=1764) reading backup image         
7/31/2013 4:53:45 PM - Info bpdm(pid=1764) requesting nbjm for media        
7/31/2013 4:53:45 PM - Info bpdm(pid=1764) using 30 data buffers        
7/31/2013 4:53:45 PM - Info bpdm(pid=1764) spawning a child process        
7/31/2013 4:53:45 PM - Info bpbrm(pid=1764) child pid: 3540         
7/31/2013 4:53:45 PM - Info bpdm(pid=3540) started           
7/31/2013 4:53:45 PM - started process bpdm (3540)
7/31/2013 4:53:48 PM - begin reading
7/31/2013 4:53:48 PM - end reading; read time: 00:00:00
7/31/2013 4:53:48 PM - Info bpdm(pid=1764) completed reading backup image        
7/31/2013 4:54:19 PM - requesting resource @aaaab
7/31/2013 4:54:19 PM - granted resource MediaID=@aaaab;DiskVolume=E:\;DiskPool=Test_Pool;Path=E:\;StorageServer=sdc01ntbak;MediaServer=sdc01...
7/31/2013 4:54:22 PM - Info bpbrm(pid=4376) sdc01-pintbak05.dc-prod.tn.corp is the host to restore to     
7/31/2013 4:54:22 PM - Info bpbrm(pid=4376) reading file list from client       
7/31/2013 4:54:22 PM - connecting
7/31/2013 4:54:22 PM - Info bpbrm(pid=4376) starting bptm          
7/31/2013 4:54:24 PM - Info tar32(pid=1796) Restore started          
7/31/2013 4:54:24 PM - connected; connect time: 00:00:02
7/31/2013 4:54:24 PM - Info bptm(pid=1620) start           
7/31/2013 4:54:24 PM - started process bptm (1620)
7/31/2013 4:54:25 PM - Info bpdm(pid=1620) reading backup image         
7/31/2013 4:54:25 PM - Info bptm(pid=1620) using 30 data buffers        
7/31/2013 4:54:25 PM - Info bptm(pid=1620) spawning a child process        
7/31/2013 4:54:25 PM - Info bptm(pid=1620) child pid: 4192         
7/31/2013 4:54:25 PM - Info bptm(pid=4192) start           
7/31/2013 4:54:25 PM - started process bptm (4192)
7/31/2013 4:54:28 PM - begin reading
7/31/2013 4:56:40 PM - Critical bpbrm(pid=4376) from client sdc01ntbak: FTL - Virtual machine restore: file write failed 
7/31/2013 4:56:40 PM - Critical bpbrm(pid=4376) from client sdc01ntbak: FTL - Virtual machine restore: file write failed 
7/31/2013 4:57:04 PM - Info tar32(pid=1796) done. status 5         
7/31/2013 4:57:05 PM - Info bptm(pid=1620) EXITING with status 24 <----------       
7/31/2013 4:57:05 PM - Info tar32(pid=1796) done. status: 185         
7/31/2013 4:57:05 PM - Info tar32(pid=1796) done. status: 24: socket write failed      
7/31/2013 4:57:05 PM - Error bpbrm(pid=4376) client restore EXIT STATUS 24: socket write failed    
7/31/2013 4:58:18 PM - restored image NetBackupTest_1375255272 - (the restore failed to recover the requested files(5)); restore time 00:04:44
7/31/2013 4:58:22 PM - end Restore; elapsed time: 00:04:51
VMware policy restore error(2820)

Netbackup version is 7.5.0.6

Can You help with it?

Thanks.

[babu_beesetty]

Could You please share VxMs log & hostd log on the ESX host?

http://www.symantec.com/business/support/index?page=content&id=TECH144235&profileURL=https%3A%2F%2Fsymaccount-profile.symantec.com%2FSSO%2Findex.jsp%3FssoID%3D1375281152606OCsk7049mZ761qiiLnfRTPC1Q0iEVkscX8i0M

[Jaykullar]

By the sounds of it, a VMDK is being restored.

Are all storage LUNS presented to your backup host. If you are restoring by SAN the presented LUNs need to be online.

Also check if the service account your using in credentials for vCenters or ESX hosts have restore permissions in vCenter.

[bst740]

Logs in attach.

Thanks!

[babu_beesetty]

Could you please check whether all the Data stores are available online and have enough disk space to recover the data.

Please try to restore the data to different Data store by manual change Data store option in the VM ware recovery option.

Also check if the service account your using in credentials for vCenters or ESX hosts have restore permissions in vCenter

[bst740]

Could you please check whether all the Data stores are available online and have enough disk space to recover the data.

They are online and have enough free space

Please try to restore the data to different Data store by manual change Data store option in the VM ware recovery option.

When I restore to another datastore, it's ok!

Thanks! But I don't understand why it fails with original datastore with nbd transfer? What do I need to check?

Also check if the service account your using in credentials for vCenters or ESX hosts have restore permissions in vCenter

Account has full permissions

[babu_beesetty]

Can you please share hostd log after restoration completed successfuuly?

[bst740]

-

[bst740]

Done!

[babu_beesetty]

Sorry for delay,

Could you please request your VM team to perform health check on the original Data Store?

[bpdown · Accepted solution]

Don't use SAN to restore... use NBD. NBU might not have access to write to the Datastore (only read).

[babu_beesetty]

Hello,

Could you please confirm or share the details whether issue has been resolved or not?