Video Screencast Help

Offline VM restore

Created: 30 Oct 2013 | 15 comments

Ok I have a policy that backups up offline VM

Policy is called  VM_Powered_Off_life

 

Clients it is grabing is below.  Using the Query.

Cluster equals "AHQ_IBM_Cluster

AND Powerstate Equals poweredOFF

 

Backups are working fine.  Now I have to test a restore.

 

I try to do a Alternate location and change the name so I can make sure it works before I delete the VM.  I have done with with powered on VM with no issues.

I have tried no restoring, and restoring the BIOS UUID

 

Every time I am getting a error 2820.  Which I can't really find any info on.

 

tried restore Destination as the VMcenter, and the server name.  Have no idea what is going on here.

 

I am running a master/meda

Netbackup 7.5.06

on a 2008 server

 

Clients are all 2003/2008 servers powered up.

what am I doning wrong?

 

Operating Systems:

Comments 15 CommentsJump to latest comment

Nagalla's picture

I have tried no restoring, and restoring the BIOS UUID ---> could you expline it again..

what is the Vcenter version/ and VMware hardware version?

are you trying SAN retstore or NBD?

also post the detail status of the failed job

NathanNieman's picture

UUID = Universally unique indentifier

 

So by using it I am creating the same server again.

by not using it I can use the server image and rename it so it has the same settings but has a different name

 

I want to test a restore before deleting the VM.  So I want to not use the UUID, and I have rename it.

 

I have done this fine with powered on VM with no issues.

 

Vcenter Client is 5.0.0 build 455964

 

I am doing the restore from the Backup , and restore console

 

 

I get no deails

Just status 2820

 

Here is what it says

begin restore

Media 0397l3 required

restoring image uns40-112.americas.ad.nsk.com_1383145560

info bprd(pid=6252)restoring from copy 1 of image created 10/30/13 11:06:00

end restore, elaspsed time 00:00:04

VMware policy restore error(2820)

 

 

 

So it knows where it is, and what tape it needs but that is it..

 

 

 

VMware Vcenter is 5.0.0 build 623373

NathanNieman's picture

Ok strange.  I just tested a running VM restore and got the same error.  UGH...!!

Nagalla's picture

what was the Primary VM identifier in the Backup policy.?

does it using the SAN transport or NBD?

NathanNieman's picture

We are using SAN Transport as the first option.

 

Not sure about the first question I am rebooting the server now.  I will attach screen shots once it is back up.  Since on VM restores are working.

NathanNieman's picture

Ok just did a normal restore over righting one of my test server and it worked fine.

 

So it is something I am doing to restore this server with a different name under tha advanced settings.

NathanNieman's picture

Here is some of the bprd log

5:43:43.594 [7044.2228] <2> job_monitoring_exex: ACK disconnect
15:43:43.594 [7044.2228] <2> job_disconnect: Disconnected
15:43:43.594 [7044.2228] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100
15:43:43.594 [7044.2228] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100
15:43:43.594 [7044.2228] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0
15:43:43.594 [7044.2228] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0
15:43:43.594 [7044.2228] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
15:43:43.594 [7044.2228] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
15:43:43.594 [7044.2228] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
15:43:43.594 [7044.2228] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
15:43:43.594 [7044.2228] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
15:43:43.594 [7044.2228] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
15:43:43.594 [7044.2228] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
15:43:43.594 [7044.2228] <2> vnet_pbxConnect: pbxConnectEx Succeeded
15:43:43.594 [7044.2228] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 131.40.10.88.55731 TO 131.40.10.88.1556 fd = 704
15:43:43.594 [7044.2228] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 131.40.10.88.55731 TO 131.40.10.88.1556 fd = 704
15:43:43.610 [7044.2228] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO UNS40-88 131.40.10.88 bpcd VIA pbx
15:43:43.610 [7044.2228] <2> logconnections: BPCD CONNECT FROM 131.40.10.88.55731 TO 131.40.10.88.1556 fd = 704
15:43:43.610 [7044.2228] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0
15:43:43.610 [7044.2228] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
15:43:43.610 [7044.2228] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
15:43:43.610 [7044.2228] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
15:43:43.610 [7044.2228] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
15:43:43.610 [7044.2228] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
15:43:43.626 [7044.2228] <2> vnet_pbxConnect: pbxConnectEx Succeeded
15:43:43.641 [7044.2228] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 131.40.10.88.55732 TO 131.40.10.88.1556 fd = 684
15:43:43.641 [7044.2228] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 131.40.10.88.55732 TO 131.40.10.88.1556 fd = 684
15:43:43.641 [7044.2228] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 131.40.10.88 131.40.10.88 vnetd VIA pbx
15:43:43.641 [7044.2228] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
15:43:43.657 [7044.2228] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 55733
15:43:43.657 [7044.2228] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 997c8e104e35d258358977a542fea406
15:43:43.704 [7044.2228] <2> bpcr_get_clientname_rqst: Server clientname length = 8
15:43:43.704 [7044.2228] <2> add_msgs_to_progress_file: CLIENT_CMD_SOCK from bpcr = 704
15:43:43.704 [7044.2228] <2> add_msgs_to_progress_file: CLIENT_STAT_SOCK from bpcr = 684
15:43:43.704 [7044.2228] <2> terminate_restore: EXIT STATUS 2820
15:43:43.704 [7044.2228] <2> bprd: C:\Program Files\Veritas\NetBackup\bin\userreq_notify.cmd restore uns40-88 root
15:43:46.200 [6904.7596] <4> pingpem: pinging nbpem
15:43:46.200 [6904.7596] <4> pingpem: waiting for pem to reply to ping
15:43:48.212 [6904.7596] <4> pingpem: ping of nbpem succeeded
15:44:05.449 [6904.7596] <4> msgbackup: waiting for response from nbpem
15:44:16.603 [6904.7596] <4> pingpem: pinging nbpem
15:44:16.603 [6904.7596] <4> pingpem: waiting for pem to reply to ping
15:44:18.615 [6904.7596] <4> pingpem: ping of nbpem succeeded
15:44:37.881 [6904.7596] <4> msgbackup: waiting for response from nbpem
15:44:47.006 [6904.7596] <4> pingpem: pinging nbpem
15:44:47.006 [6904.7596] <4> pingpem: waiting for pem to reply to ping
15:44:49.019 [6904.7596] <4> pingpem: ping of nbpem succeeded
15:45:10.313 [6904.7596] <4> msgbackup: waiting for response from nbpem
15:45:17.410 [6904.7596] <4> pingpem: pinging nbpem
15:45:17.410 [6904.7596] <4> pingpem: waiting for pem to reply to ping
15:45:19.423 [6904.7596] <4> pingpem: ping of nbpem succeeded
15:45:42.745 [6904.7596] <4> msgbackup: waiting for response from nbpem
15:45:47.814 [6904.7596] <4> pingpem: pinging nbpem
15:45:47.814 [6904.7596] <4> pingpem: waiting for pem to reply to ping
15:45:49.827 [6904.7596] <4> pingpem: ping of nbpem succeeded
15:46:12.181 [4916.4920] <2> ConnectionCache::connectAndCache: Acquiring new connection for host uns40-88, query type 98
15:46:12.181 [4916.4920] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0
15:46:12.181 [4916.4920] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
15:46:12.181 [4916.4920] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
15:46:12.181 [4916.4920] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
15:46:12.181 [4916.4920] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
15:46:12.181 [4916.4920] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
15:46:12.181 [4916.4920] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
15:46:12.181 [4916.4920] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
15:46:12.181 [4916.4920] <2> vnet_pbxConnect: pbxConnectEx Succeeded
15:46:12.181 [4916.4920] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 131.40.10.88.55779 TO 131.40.10.88.1556 fd = 700
15:46:12.181 [4916.4920] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 131.40.10.88.55779 TO 131.40.10.88.1556 fd = 700
15:46:12.181 [4916.4920] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO uns40-88 131.40.10.88 bpdbm VIA pbx
15:46:12.181 [4916.4920] <2> logconnections: BPDBM CONNECT FROM 131.40.10.88.55779 TO 131.40.10.88.1556 fd = 700
15:46:12.181 [4916.4920] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
15:46:12.181 [4916.4920] <2> db_end: Need to collect reply
15:46:12.213 [4916.4920] <2> launch: C:\Program Files\Veritas\NetBackup\bin\admincmd\bpstsinfo.exe -UPDATE, pid=3808
15:46:15.177 [6904.7596] <4> msgbackup: waiting for response from nbpem
15:46:17.205 [6904.7596] <4> pingpem: pinging nbpem
15:46:17.205 [6904.7596] <4> pingpem: waiting for pem to reply to ping
15:46:19.217 [6904.7596] <4> pingpem: ping of nbpem succeeded
15:46:47.609 [6904.7596] <4> msgbackup: waiting for response from nbpem
15:46:47.609 [6904.7596] <4> pingpem: pinging nbpem
15:46:47.609 [6904.7596] <4> pingpem: waiting for pem to reply to ping
15:46:49.621 [6904.7596] <4> pingpem: ping of nbpem succeeded
15:47:18.013 [6904.7596] <4> pingpem: pinging nbpem
15:47:18.013 [6904.7596] <4> pingpem: waiting for pem to reply to ping
15:47:20.025 [6904.7596] <4> pingpem: ping of nbpem succeeded
15:47:22.053 [6904.7596] <4> msgbackup: waiting for response from nbpem
 
NathanNieman's picture

Here is what I get when trying to do a normal restore of a offline VM

 

 
10:18:31.042 [1272.7432] <2> job_monitoring_exex: ACK disconnect
10:18:31.042 [1272.7432] <2> job_disconnect: Disconnected
10:18:31.042 [1272.7432] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100
10:18:31.042 [1272.7432] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100
10:18:31.042 [1272.7432] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0
10:18:31.042 [1272.7432] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0
10:18:31.042 [1272.7432] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
10:18:31.042 [1272.7432] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
10:18:31.042 [1272.7432] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
10:18:31.042 [1272.7432] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
10:18:31.042 [1272.7432] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
10:18:31.042 [1272.7432] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
10:18:31.042 [1272.7432] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
10:18:31.042 [1272.7432] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:18:31.042 [1272.7432] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 131.40.10.88.50976 TO 131.40.10.88.1556 fd = 896
10:18:31.042 [1272.7432] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 131.40.10.88.50976 TO 131.40.10.88.1556 fd = 896
10:18:31.042 [1272.7432] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO uns40-88 131.40.10.88 bpcd VIA pbx
10:18:31.042 [1272.7432] <2> logconnections: BPCD CONNECT FROM 131.40.10.88.50976 TO 131.40.10.88.1556 fd = 896
10:18:31.042 [1272.7432] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0
10:18:31.042 [1272.7432] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
10:18:31.042 [1272.7432] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
10:18:31.042 [1272.7432] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1
10:18:31.042 [1272.7432] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface  ANY
10:18:31.042 [1272.7432] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
10:18:31.042 [1272.7432] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:18:31.088 [1272.7432] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 131.40.10.88.50977 TO 131.40.10.88.1556 fd = 900
10:18:31.088 [1272.7432] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 131.40.10.88.50977 TO 131.40.10.88.1556 fd = 900
10:18:31.088 [1272.7432] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 131.40.10.88 131.40.10.88 vnetd VIA pbx
10:18:31.088 [1272.7432] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:18:31.088 [1272.7432] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 50978
10:18:31.088 [1272.7432] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 eea6f46ea7c37fa0380d3a8138fcf264
10:18:31.104 [1272.7432] <2> add_msgs_to_progress_file: CLIENT_CMD_SOCK from bpcr = 896
10:18:31.104 [1272.7432] <2> add_msgs_to_progress_file: CLIENT_STAT_SOCK from bpcr = 900
10:18:31.135 [1272.7432] <2> terminate_restore: EXIT STATUS 2820
10:18:31.135 [1272.7432] <2> bprd: C:\Program Files\Veritas\NetBackup\bin\userreq_notify.cmd restore uns40-88 root
10:18:31.244 [1272.7432] <2> readline: EXIT STATUS 24
10:18:31.244 [1272.7432] <2> read_bpbrm_stderr: bpbrm exit status = 24
 
Jaykullar's picture

Are you restoring orginal provisioning, can you try restoring at thick?

Are your restoring to the same datastore / folder / ESX host / vCenter? If not Im guessing service account has the correct permissions to restore to an alternate location if on a different vCenter?

Does the final check pass on all counts successfully?

I dont think you will get much info from legacy logging, its best to follow this set of instructions to gather full logs for this, you will get much more information.

 

http://www.symantec.com/business/support/index?pag...

NathanNieman's picture

I am doing a advanced restore of a powered off VM. The backup was taken in the power off state.

 

Now I want to restore the VM but I want to change the name so I can test the restore.  I can do this with a powered on VM with no issues.

 

All checks pass that is what is throwing me off.

 

I will look at the legacy logging.

 

Thank you.

DrR's picture

I am getting error 2820 on VM restore.

In my case I can see that NetBackup is not asking VC to create the virtual machine.  Eventually the restore times out.

This is clearly spelled out in my logs however I am now running 7.6

My 7.5.0.4 and 7.5.0.5 systems are not showing this problem (although I think I did have this issue previously on them but i never followed it up and no can’t reproduce the error.)

As yet I don’t have a solution but can you see the create VM task in the VC/ESX Recent Tasks view?

NathanNieman's picture

Thank for the input.   No I never see the VM task start.  what is strange though is I can do a restore to the same server with no issues.  But if I try to change the setting I get the 2820 error.

DrR's picture

That’s sounds a lot what I was experiencing on my 7.5 system – can restore to original location but not anywhere else.  At the time I was thinking the change of datastore was causing the issue - but that was just a thought.  I didn’t trouble shoot any further as I was waiting for various upgrades (NBU 7.5.0.5 and ESX 5.1).

 I am currently unable to fault my 7.5 install so one of the upgrades or changes has sorted that out.

  Turns out my issue above with 7.6 was fixed in tech note 211382. I wasn’t able to restore to original location either so while it looked similar it was a different issue.

NathanNieman's picture

Thanks for the input.  I am still working on this.  I have been able to do it so I am hoping it is a issue on my side.