Suspending the display of XX additional messages for this image

[kamalbhuttoo]

hi..

am doing a restore from my master server to another client. am getting this message:

Restore started 11/13/2013 13:46:51

13:46:59 (97542.xxx) Restore job id 97542 will require 1 image.

13:46:59 (97542.xxx) Media id LTO553 is needed for the restore.

13:47:02 (97542.001) Restoring from copy 1 of image created Tue Nov 12 18:43:30 WST 2013

13:47:06 (97542.001) INF - If Media id LTO553 is not in a robotic library administrative interaction may be required to satisfy this mount request.

13:47:08 (97542.001) Status of restore from copy 1 of image created Tue Nov 12 18:43:30 WST 2013 = client hostname could not be found

13:47:08 (97542.001) The following files/folders were not restored:

13:47:08 (97542.001) UTF - /db1/

13:47:08 (97542.001) UTF - /db1/lost+found/

13:47:08 (97542.001) UTF - /db1/SEAB_data/

13:47:08 (97542.001) UTF - /db1/SEAB_data/ctrl_2_SEAB.ctl

13:47:08 (97542.001) UTF - /db1/SEAB_data/system_1_SEAB.dbf

13:47:08 (97542.001) UTF - /db1/SEAB_data/acct_details_1_1_SEAB.dbf

13:47:08 (97542.001) UTF - /db1/SEAB_data/acct_details_2_1_SEAB.dbf

13:47:08 (97542.001) UTF - /db1/SEAB_data/acct_details_3_1_SEAB.dbf

13:47:08 (97542.001) UTF - /db1/SEAB_data/acct_master_1_SEAB.dbf

13:47:08 (97542.001) UTF - /db1/SEAB_data/acct_trans_1_SEAB.dbf

13:47:08 (97542.001) Suspending the display of 64 additional messages for this image.

I don't even know if its restoring or not. Can someone help me out please.

Regards,

Kamal

[Yasuhisa_Ishika · Accepted solution]

Restore failed by this error - client hostname could not be found.

Please check on the master and media server of the destination cloent name can be resolved to correct IP address.

[Marianne]

Have you tested basic hostname lookup at OS level?

Can you ping this client hostname from Master and/or media server?

Add /etc/hosts entries on master/media server.

Once OS-level hostname is resolved, clear NBU host cache on master/media server:
bpclntcmd -clear_host_cache

check connectivity before trying another restore:

Create bpcd log folder on client, then run this command on master and/or media server:

bptestbpcd -client <client-name> -verbose -debug

[Nicolai]

Suspending the display of 64 additional messages for this image.

This is shown to prevent a flodding of messages - e.g. when a restore of 100.000 files fail at file 1.

[kamalbhuttoo]

client is connected with master server. am able to see the IP of the server on selecting client's properties tab.

[Marianne]

client is connected with master server. am able to see the IP of the server on selecting client's properties tab.

Do you have a separate media server?

Have you checked connectivity from media server as well?

One more thing - how was destination client specified? 
Did you type in the name? 
Any possibility that typo error was made?
 

[kamalbhuttoo]

Dear Marianne,

ping is possible on both master and clent.

Entry of clent ip and hostname is already on etc/hosts

Please find logs after debug:

nbk-prod1(root)/usr/openv/netbackup/bin/admincmd # bptestbpcd -client fincbs1 -verbose -debug

15:36:13.980 [10354852] <2> bptestbpcd: VERBOSE = 5

15:36:13.981 [10354852] <2> ConnectToBPCD: db_getCLIENT(fincbs1) failed: 227

15:36:13.982 [10354852] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01000000 connect_opts2 = 0x01000000

15:36:13.982 [10354852] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000000

15:36:13.982 [10354852] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0

15:36:13.986 [10354852] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/033/6ff6fc33+0,1,41,0,1,0+fincbs1.txt

15:36:14.404 [10354852] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=fincbs1 SVC=NULL

15:36:14.406 [10354852] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0

15:36:14.408 [10354852] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/033/6ff6fc33+veritas_pbx,1,8,2,1,0+fincbs1.txt

15:36:14.410 [10354852] <2> retry_getaddrinfo_for_real: [vnet_addrinfo.c:1056] getaddrinfo() failed RV=8 NAME=NULL SVC=veritas_pbx errno=2

15:36:14.410 [10354852] <2> get_sig_pbx_port: [vnet_addrinfo.c:4455] configured PBX port 1556 0x614

15:36:14.410 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:882] using SIG PBX port 1556 0x614

15:36:14.410 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:910] adjusted service name 1556

15:36:14.417 [10354852] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=fincbs1 SVC=veritas_pbx

15:36:14.420 [10354852] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1

15:36:14.420 [10354852] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/1e6/a60f77e6+0,1,41,0,1,0+10.10.20.175.txt

15:36:14.992 [10354852] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=10.10.20.175 SVC=NULL

15:36:14.997 [10354852] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4809] using interface  ANY

15:36:14.997 [10354852] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/033/6ff6fc33+vnetd,1,8,2,1,0+fincbs1.txt

15:36:15.000 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:906] using SVC=vnetd PORT=13724

15:36:15.000 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:910] adjusted service name 13724

15:36:15.007 [10354852] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=fincbs1 SVC=vnetd

15:36:15.009 [10354852] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1

15:36:15.010 [10354852] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4809] using interface  ANY

15:36:15.010 [10354852] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/033/6ff6fc33+bpcd,1,8,2,1,0+fincbs1.txt

15:36:15.017 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:906] using SVC=bpcd PORT=13782

15:36:15.017 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:910] adjusted service name 13782

15:36:15.019 [10354852] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=fincbs1 SVC=bpcd

15:36:15.021 [10354852] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1

15:36:15.021 [10354852] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4809] using interface  ANY

15:36:15.022 [10354852] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1

15:36:15.027 [10354852] <2> vnet_pbxConnect: pbxConnectEx Succeeded

15:36:15.027 [10354852] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 10.10.10.123.49775 TO 10.10.20.175.1556 fd = 3

15:36:15.028 [10354852] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.10.10.123.49775 TO 10.10.20.175.1556 fd = 3

15:36:15.028 [10354852] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO fincbs1 10.10.20.175 bpcd VIA pbx

15:36:15.030 [10354852] <2> logconnections: BPCD CONNECT FROM 10.10.10.123.49775 TO 10.10.20.175.1556 fd = 3

15:36:15.030 [10354852] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/1e6/a60f77e6+veritas_pbx,1,8,2,1,0+10.10.20.175.txt

15:36:15.038 [10354852] <2> retry_getaddrinfo_for_real: [vnet_addrinfo.c:1056] getaddrinfo() failed RV=8 NAME=NULL SVC=veritas_pbx errno=2

15:36:15.039 [10354852] <2> get_sig_pbx_port: [vnet_addrinfo.c:4455] configured PBX port 1556 0x614

15:36:15.039 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:882] using SIG PBX port 1556 0x614

15:36:15.039 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:910] adjusted service name 1556

15:36:15.039 [10354852] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=10.10.20.175 SVC=veritas_pbx

15:36:15.047 [10354852] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1

15:36:15.048 [10354852] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4809] using interface  ANY

15:36:15.048 [10354852] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/1e6/a60f77e6+vnetd,1,8,2,1,0+10.10.20.175.txt

15:36:15.051 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:906] using SVC=vnetd PORT=13724

15:36:15.051 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:910] adjusted service name 13724

15:36:15.051 [10354852] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=10.10.20.175 SVC=vnetd

15:36:15.058 [10354852] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1

15:36:15.059 [10354852] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4809] using interface  ANY

15:36:15.059 [10354852] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1

15:36:15.067 [10354852] <2> vnet_pbxConnect: pbxConnectEx Succeeded

15:36:15.071 [10354852] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.10.10.123.49776 TO 10.10.20.175.1556 fd = 4

15:36:15.072 [10354852] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.10.10.123.49776 TO 10.10.20.175.1556 fd = 4

15:36:15.072 [10354852] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.10.20.175 10.10.20.175 vnetd VIA pbx

15:36:15.075 [10354852] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa

15:36:15.277 [10354852] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-07860384342504714755000000234--RahtG

15:36:15.370 [10354852] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 7c9d0f1c328f622add4fa54d1af257b8

1 1 1

10.10.10.123:49775 -> 10.10.20.175:1556

10.10.10.123:49776 -> 10.10.20.175:1556

15:36:15.580 [10354852] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=0 FILE=/usr/openv/var/host_cache/1ff/ffffffff+vnetd,1,2,0,1,0+.txt

15:36:15.587 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:906] using SVC=vnetd PORT=13724

15:36:15.588 [10354852] <2> retry_getaddrinfo: [vnet_addrinfo.c:910] adjusted service name 13724

15:36:15.588 [10354852] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=NULL SVC=vnetd

15:36:15.771 [10354852] <2> bpcr_get_peername_rqst: Server peername length = 9

15:36:15.971 [10354852] <2> bpcr_get_hostname_rqst: Server hostname length = 7

15:36:16.171 [10354852] <2> bpcr_get_clientname_rqst: Server clientname length = 7

15:36:16.371 [10354852] <2> bpcr_get_version_rqst: bpcd version: 07500000

15:36:16.572 [10354852] <2> bpcr_get_platform_rqst: Server platform length = 9

15:36:16.772 [10354852] <2> bpcr_get_version_rqst: bpcd version: 07500000

15:36:16.972 [10354852] <2> bpcr_patch_version_rqst: theRest == > <

15:36:16.974 [10354852] <2> bpcr_get_version_rqst: bpcd version: 07500000

15:36:17.172 [10354852] <2> bpcr_patch_version_rqst: theRest == > <

15:36:17.174 [10354852] <2> bpcr_get_version_rqst: bpcd version: 07500000

PEER_NAME = nbk-prod1

HOST_NAME = fincbs1

CLIENT_NAME = fincbs1

VERSION = 0x07500000

PLATFORM = rs6000_53

PATCH_VERSION = 7.5.0.0

SERVER_PATCH_VERSION = -1.-1.-1.-1

MASTER_SERVER = nbk-prod1

EMM_SERVER = nbk-prod1

NB_MACHINE_TYPE = CLIENT

15:36:17.376 [10354852] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1

15:36:17.377 [10354852] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4809] using interface  ANY

15:36:17.377 [10354852] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1

15:36:17.378 [10354852] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4809] using interface  ANY

15:36:17.378 [10354852] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1

15:36:17.382 [10354852] <2> vnet_pbxConnect: pbxConnectEx Succeeded

15:36:17.386 [10354852] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.10.10.123.49778 TO 10.10.20.175.1556 fd = 5

15:36:17.386 [10354852] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.10.10.123.49778 TO 10.10.20.175.1556 fd = 5

15:36:17.387 [10354852] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.10.20.175 10.10.20.175 vnetd VIA pbx

15:36:17.387 [10354852] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa

15:36:17.480 [10354852] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-07862384342506917937000000234--Rah2G

15:36:17.573 [10354852] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 0cd4b91b42498fdf2a003c0a71b56d7d

10.10.10.123:49778 -> 10.10.20.175:1556

<2>bptestbpcd: EXIT status = 0

15:36:17.581 [10354852] <2> bptestbpcd: EXIT status = 0

[kamalbhuttoo]

yes i do have another media server. 

The other one is used for fibre transport. 

I created a dummy policy and added the client on that. It is automatically listed under client. 

I have checked the hostname, it is right.

[kamalbhuttoo]

Please find log.

11/13/2013 15:56:43 - begin Restore

11/13/2013 15:56:47 - number of images required: 1

11/13/2013 15:56:47 - media needed: LTO553

11/13/2013 15:56:50 - restoring from image f7029db_1384267410

11/13/2013 16:00:59 - Info bpbrm (pid=18217) connect failed STATUS (18) CONNECT_FAILED

11/13/2013 16:00:59 - Info bpbrm (pid=18217) status: FAILED, (44) CONNECT_TIMEOUT; system: (115) Operation now in progress; FROM 0.0.0.0 TO fincbs1 10.10.20.175 bpcd VIA pbx

11/13/2013 16:00:59 - Info bpbrm (pid=18217) status: FAILED, (44) CONNECT_TIMEOUT; system: (115) Operation now in progress; FROM 0.0.0.0 TO fincbs1 10.10.20.175 bpcd VIA vnetd

11/13/2013 16:00:59 - Info bpbrm (pid=18217) status: FAILED, (44) CONNECT_TIMEOUT; system: (115) Operation now in progress; FROM 0.0.0.0 TO fincbs1 10.10.20.175 bpcd

11/13/2013 16:00:59 - Error bpbrm (pid=18217) Cannot connect to fincbs1

11/13/2013 16:00:59 - Info bpbrm (pid=18217) telling media manager to start restore on client

11/13/2013 16:00:59 - Info bpbrm (pid=18217) spawning a brm child process

11/13/2013 16:00:59 - Info bpbrm (pid=18217) child pid: 18346

11/13/2013 16:00:59 - connecting

11/13/2013 16:01:53 - requesting resource LTO553

11/13/2013 16:01:54 - awaiting resource LTO553. Waiting for resources. 

          Reason: Drives are in use, Media server: nbuftsvr, 

          Robot Type(Number): TLD(0), Media ID: N/A, Drive Name: N/A, 

          Volume Pool: N/A, Storage Unit: N/A, Drive Scan Host: N/A, 

          Disk Pool: N/A, Disk Volume: N/A 

11/13/2013 16:06:00 - Info bpbrm (pid=18217) connect failed STATUS (18) CONNECT_FAILED

11/13/2013 16:06:00 - Info bpbrm (pid=18217) status: FAILED, (44) CONNECT_TIMEOUT; system: (115) Operation now in progress; FROM 0.0.0.0 TO fincbs1 10.10.20.175 bpcd VIA pbx

11/13/2013 16:06:00 - Info bpbrm (pid=18217) status: FAILED, (44) CONNECT_TIMEOUT; system: (115) Operation now in progress; FROM 0.0.0.0 TO fincbs1 10.10.20.175 bpcd VIA vnetd

11/13/2013 16:06:00 - Info bpbrm (pid=18217) status: FAILED, (44) CONNECT_TIMEOUT; system: (115) Operation now in progress; FROM 0.0.0.0 TO fincbs1 10.10.20.175 bpcd

11/13/2013 16:06:00 - Error bpbrm (pid=18346) timed out trying to connect to fincbs1

11/13/2013 16:06:00 - Info bpbrm (pid=18217) sending message to media manager: STOP RESTORE f7029db_1384267410

11/13/2013 16:06:03 - Info bpbrm (pid=18217) media manager for backup id f7029db_1384267410 exited with status 150: termination requested by administrator

11/13/2013 16:06:56 - restored from image f7029db_1384267410; restore time: 0:10:06

[Marianne]

It seems that Media server nbuftsvr is used to perform the restore.

Media server is unable to connect to the client.

[kamalbhuttoo]

[root@nbuftsvr bin]# ./bpclntcmd -hn fincbs1

host fincbs1: fincbs1 at 10.10.20.175

aliases:     fincbs1     10.10.20.175

nbuftsvr is being able to see the client.

[Marianne]

Media server tried to connect to client via port 1556, then tried port 13724, then tried 13782, and eventually timed out:

11/13/2013 16:06:00 - Info bpbrm (pid=18217) connect failed STATUS (18) CONNECT_FAILED

11/13/2013 16:06:00 - Info bpbrm (pid=18217) status: FAILED, (44) CONNECT_TIMEOUT; system: (115) Operation now in progress; FROM 0.0.0.0 TO fincbs1 10.10.20.175 bpcd VIA pbx

11/13/2013 16:06:00 - Info bpbrm (pid=18217) status: FAILED, (44) CONNECT_TIMEOUT; system: (115) Operation now in progress; FROM 0.0.0.0 TO fincbs1 10.10.20.175 bpcd VIA vnetd

11/13/2013 16:06:00 - Info bpbrm (pid=18217) status: FAILED, (44) CONNECT_TIMEOUT; system: (115) Operation now in progress; FROM 0.0.0.0 TO fincbs1 10.10.20.175 bpcd

11/13/2013 16:06:00 - Error bpbrm (pid=18346) timed out trying to connect to fincbs1

Ensure bpcd log folder exists on client, then perform bptestbpcd test from media server.