cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
Veritas.com
Support

Restore was in mounting state only

Go to solution
john10
Level 6

‎12-16-2013 08:14 PM

Hello All,

We are using NBU 7.0.1 Master /2008

Client 7.0/ 2008

the backup was succesfully completed on "87w0001" and now we are trying to restore the data to "01w0016" client server, but in the job details it was was showing as "begin reading", in the operation tab in the activity monitor it was showing as "mounting" state only and the im not able to restore the data. could any help me on this. Thanks in Advance.

0 Kudos
1 ACCEPTED SOLUTION

Accepted Solutions

Go to solution
Marianne
Level 6
Partner    VIP    Accredited Certified

‎12-20-2013 02:20 AM

There is clearly something different about this particular server.

The OS/Firewall support team will have to investigate.


Handy NetBackup Links

View solution in original post

0 Kudos
23 REPLIES 23

Go to solution
john10
Level 6

‎12-16-2013 08:15 PM

Foget to post job details please find them below ,

12/17/2013 11:11:56 AM - begin Restore
12/17/2013 11:12:40 AM - 4 images required
12/17/2013 11:12:40 AM - media 0115L5 required
12/17/2013 11:12:40 AM - media 0116L5 required
12/17/2013 11:13:15 AM - restoring image sn87w0001_1386990003
12/17/2013 11:14:29 AM - requesting resource 0116L5
12/17/2013 11:14:29 AM - granted resource 0116L5
12/17/2013 11:14:29 AM - granted resource IBM.ULT3580-HH5.002
12/17/2013 11:14:30 AM - started process bptm (5656)
12/17/2013 11:14:30 AM - mounting 0116L5
12/17/2013 11:15:12 AM - connecting
12/17/2013 11:15:16 AM - mounted; mount time: 00:00:46
12/17/2013 11:15:16 AM - positioning 0116L5 to file 216
12/17/2013 11:17:10 AM - positioned 0116L5; position time: 00:01:54
12/17/2013 11:17:10 AM - begin reading

0 Kudos

Go to solution
Marianne
Level 6
Partner    VIP    Accredited Certified

‎12-16-2013 09:22 PM

The drive is busy reading data from the tape:

12/17/2013 11:14:30 AM - mounting 0116L5
12/17/2013 11:15:12 AM - connecting
12/17/2013 11:15:16 AM - mounted; mount time: 00:00:46
12/17/2013 11:15:16 AM - positioning 0116L5 to file 216
12/17/2013 11:17:10 AM - positioned 0116L5; position time: 00:01:54
12/17/2013 11:17:10 AM - begin reading

Problem is that we see 'connecting' but not 'connected'.

It seems that the media server is unable to connect to the destination client.

Please tell us more about client  "01w0016".
OS? NBU version?
Have you been able to back it up?

Can you connect to it from the master and/or media server?

Please do the following:
Create bpcd log folder on client  "01w0016" under ...netbackup\logs folder.

Test connection from master and/or media server with this command:
bptestbpcd -client  01w0016 -debug -verbose
(command is in ...\netbackup\bin\admincmd)
 

Please show us output of command as well as bpcd log on the client.
 


Handy NetBackup Links
0 Kudos

Go to solution
john10
Level 6

‎12-16-2013 10:17 PM

Hello Marianne,

Im not bale to back the "sn01w0016" machine it throwing 21 status,

Os Version:-2008,

NBU ver:-7.0,

Im not able to backup it throwing 21 status.

FYI

Job details:-Parent job:-

12/17/2013 1:55:58 PM - requesting resource sn01w0009
12/17/2013 1:55:58 PM - requesting resource sn01w0009.NBU_CLIENT.MAXJOBS.sn01w0016
12/17/2013 1:55:58 PM - requesting resource sn01w0009.NBU_POLICY.MAXJOBS.test-sn01w0016
12/17/2013 1:55:58 PM - granted resource sn01w0009.NBU_CLIENT.MAXJOBS.sn01w0016
12/17/2013 1:55:58 PM - granted resource sn01w0009.NBU_POLICY.MAXJOBS.test-sn01w0016
12/17/2013 1:55:58 PM - granted resource 0112L5
12/17/2013 1:55:58 PM - granted resource IBM.ULT3580-HH5.003
12/17/2013 1:55:58 PM - granted resource sn01w0009
12/17/2013 1:55:58 PM - estimated 0 Kbytes needed
12/17/2013 1:55:58 PM - begin Parent Job
12/17/2013 1:55:58 PM - begin Snapshot, Start Notify Script
12/17/2013 1:55:58 PM - started process RUNCMD (1492)
12/17/2013 1:55:58 PM - ended process 0 (1492)
Status 0
12/17/2013 1:55:58 PM - end Snapshot, Start Notify Script; elapsed time: 00:00:00
12/17/2013 1:55:58 PM - begin Snapshot, Step By Condition
Status 0
12/17/2013 1:55:58 PM - end Snapshot, Step By Condition; elapsed time: 00:00:00
12/17/2013 1:55:58 PM - begin Snapshot, Stream Discovery
Status 0
12/17/2013 1:55:58 PM - started
12/17/2013 1:55:59 PM - end Snapshot, Stream Discovery; elapsed time: 00:00:01
12/17/2013 1:55:59 PM - begin Snapshot, Read File List
Status 0
12/17/2013 1:55:59 PM - end Snapshot, Read File List; elapsed time: 00:00:00
12/17/2013 1:55:59 PM - begin Snapshot, Create Snapshot
12/17/2013 1:55:59 PM - started process bpbrm (5888)
12/17/2013 1:57:30 PM - begin Create Snapshot
12/17/2013 1:59:13 PM - end Create Snapshot; elapsed time: 00:01:43
12/17/2013 2:00:37 PM - end writing
Status 0
12/17/2013 2:00:37 PM - end Snapshot, Create Snapshot; elapsed time: 00:04:38
12/17/2013 2:00:37 PM - begin Snapshot, Policy Execution Manager Preprocessed

failed child job details:-

12/17/2013 2:00:37 PM - estimated 0 Kbytes needed
12/17/2013 2:05:41 PM - connecting
12/17/2013 2:07:01 PM - Error bpbrm(pid=2984) cannot create data socket, The operation completed successfully.  (0)   
12/17/2013 2:07:01 PM - end writing
socket open failed(21)

Bptestbpcd output:-

D:\Program Files\Veritas\NetBackup\bin\admincmd>bptestbpcd -client sn01w0016 -verbose -debug
14:10:59.611 [2664.5132] <2> bptestbpcd: VERBOSE = 5
14:10:59.611 [2664.5132] <2> read_client: dname=., offline=0, online_at=0
14:10:59.611 [2664.5132] <2> read_client: dname=.., offline=0, online_at=0
14:10:59.611 [2664.5132] <2> read_client: dname=CO_0, offline=0, online_at=0
14:10:59.611 [2664.5132] <2> read_client: dname=host_info, offline=0, online_at=0
14:10:59.611 [2664.5132] <2> read_client: dname=OA_2147483647, offline=0, online_at=2147483647
14:10:59.611 [2664.5132] <2> db_freeEXDB_INFO: ?
14:10:59.611 [2664.5132] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100
14:10:59.611 [2664.5132] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100
14:10:59.611 [2664.5132] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0
14:10:59.627 [2664.5132] <2> vnet_pcache_init_table: ../../libvlibs/vnet_private.c.240: 0: starting cache size: 200 0x000000c8
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: sn01w0016
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: NULL
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: sn01w0016
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: veritas_pbx
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: 199.63.83.119
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: NULL
14:10:59.627 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: 199.63.83.118
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: NULL
14:10:59.627 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: 159.99.129.170
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: NULL
14:10:59.627 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: sn01w0016
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: vnetd
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:10:59.627 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.118
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:10:59.627 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 159.99.129.170
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:10:59.627 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: sn01w0016
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: bpcd
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:10:59.627 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.118
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:10:59.627 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 159.99.129.170
14:10:59.627 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:10:59.627 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:10:59.627 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 1 0x00000001
14:11:09.658 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 2 0x00000002
14:11:19.673 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 3 0x00000003
14:11:29.688 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 4 0x00000004
14:11:29.766 [2664.5132] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2196: 0: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
14:11:29.766 [2664.5132] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2210: 0: service: bpcd
14:11:30.000 [2664.5132] <2> do_vnetd_service: ../../libvlibs/vnet_connect.c.1618: 0: via VNETD: bpcd CONNECT FROM 199.63.83.89.59286 TO 199.63.83.119.13724 fd = 676
14:11:30.000 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1307: 0: connect: async CONNECT FROM 199.63.83.89.59286 TO 199.63.83.119.13724 fd = 676
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: sn01w0016
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: NULL
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: veritas_pbx
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: NULL
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: vnetd
14:11:30.000 [2664.5132] <2> logconnections: BPCD CONNECT FROM 199.63.83.89.59286 TO 199.63.83.119.13724 fd = 676
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: 199.63.83.119
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: veritas_pbx
14:11:30.000 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:11:30.016 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:11:30.016 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:11:30.016 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: 199.63.83.119
14:11:30.016 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: vnetd
14:11:30.016 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:11:30.016 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:11:30.016 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:11:30.016 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 1 0x00000001
14:11:40.031 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 2 0x00000002
14:11:40.109 [2664.5132] <2> do_vnetd_service: ../../libvlibs/vnet_connect.c.1615: 0: connect: VNETD CONNECT FROM 199.63.83.89.59296 TO 199.63.83.119.13724 fd = 672
14:11:40.109 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1307: 0: connect: async CONNECT FROM 199.63.83.89.59296 TO 199.63.83.119.13724 fd = 672
14:11:40.109 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:11:40.109 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:11:40.109 [2664.5132] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.540: 0: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000a
14:11:40.343 [2664.5132] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.557: 0: ipc_string: 55960
14:11:40.546 [2664.5132] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.568: 0: hash_str1: a1b2df8e7c9db5ce42a768693fdc1628
14:11:40.780 [2664.5132] <2> local_bpcr_connect: expected reserved port, got 13724
14:11:40.999 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
14:11:40.999 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: veritas_pbx
14:11:40.999 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
14:11:40.999 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: vnetd
1 1 1
199.63.83.89:59286 -> 199.63.83.119:13724
14:11:40.999 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
14:11:40.999 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: vnetd
14:11:40.999 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
14:11:40.999 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: veritas_pbx
199.63.83.89:59296 -> 199.63.83.119:13724
14:11:41.217 [2664.5132] <2> bpcr_get_peername_rqst: Server peername length = 9
14:11:41.435 [2664.5132] <2> bpcr_get_hostname_rqst: Server hostname length = 9
14:11:41.654 [2664.5132] <2> bpcr_get_clientname_rqst: Server client name length = 9
14:11:42.059 [2664.5132] <2> bpcr_get_version_rqst: bpcd version: 07000000
14:11:42.278 [2664.5132] <2> bpcr_get_platform_rqst: Server client platform length = 7
14:11:42.496 [2664.5132] <2> bpcr_get_version_rqst: bpcd version: 07000000
14:11:42.949 [2664.5132] <2> bpcr_patch_version_rqst: theRest == > <
14:11:42.949 [2664.5132] <2> bpcr_get_version_rqst: bpcd version: 07000000
14:11:43.385 [2664.5132] <2> bpcr_patch_version_rqst: theRest == > <
14:11:43.385 [2664.5132] <2> bpcr_get_version_rqst: bpcd version: 07000000
PEER_NAME = sn01w0009
HOST_NAME = sn01w0016
CLIENT_NAME = sn01w0016
VERSION = 0x07000000
PLATFORM = win_x64
PATCH_VERSION = 7.0.0.0
SERVER_PATCH_VERSION = 7.0.0.0
MASTER_SERVER = sn01w0009
EMM_SERVER = sn01w0009
14:11:43.869 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
14:11:43.869 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: veritas_pbx
14:11:43.869 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
14:11:43.869 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: vnetd
14:11:43.869 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: veritas_pbx
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: vnetd
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: veritas_pbx
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:11:43.885 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: vnetd
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:11:43.885 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:11:43.885 [2664.5132] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
14:11:43.885 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 1 0x00000001
14:11:53.900 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 2 0x00000002
14:11:53.978 [2664.5132] <2> do_vnetd_service: ../../libvlibs/vnet_connect.c.1615: 0: connect: VNETD CONNECT FROM 199.63.83.89.59298 TO 199.63.83.119.13724 fd = 684
14:11:53.978 [2664.5132] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1307: 0: connect: async CONNECT FROM 199.63.83.89.59298 TO 199.63.83.119.13724 fd = 684
14:11:53.978 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
14:11:53.994 [2664.5132] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
14:11:53.994 [2664.5132] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.540: 0: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000a
14:11:54.212 [2664.5132] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.557: 0: ipc_string: 55963
14:11:54.431 [2664.5132] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.568: 0: hash_str1: ab36a572e221ccfe3a8452f6ba9e4c05
199.63.83.89:59298 -> 199.63.83.119:13724
<2>bptestbpcd: EXIT status = 0
14:11:54.680 [2664.5132] <2> bptestbpcd: EXIT status = 0

0 Kudos

Go to solution
Marianne
Level 6
Partner    VIP    Accredited Certified

‎12-16-2013 10:37 PM

status 21 is normally because of firewall limitations. Server can connect to client, but client cannot establish data socket (connect back) on port 13724.

Did you remember to create bpcd log on the client as mentioned in my previous post?

Please post bpcd log file after failed backup attempt.


Handy NetBackup Links
0 Kudos

Go to solution
john10
Level 6

‎12-16-2013 10:43 PM

Hello Marianne,

It seems for client machine we are having two ips, and i found in above Logs its trying to connect on :199.63.83.119" for sn01w0016, so changed the host entries to same and tried the backup again, but failed with status 25, please find the job details and bpcd logs from client side below. Hope any one can help.

 

Job details:-

12/17/2013 2:32:05 PM - requesting resource sn01w0009
12/17/2013 2:32:05 PM - requesting resource sn01w0009.NBU_CLIENT.MAXJOBS.sn01w0016
12/17/2013 2:32:05 PM - requesting resource sn01w0009.NBU_POLICY.MAXJOBS.test-sn01w0016
12/17/2013 2:32:05 PM - granted resource sn01w0009.NBU_CLIENT.MAXJOBS.sn01w0016
12/17/2013 2:32:05 PM - granted resource sn01w0009.NBU_POLICY.MAXJOBS.test-sn01w0016
12/17/2013 2:32:05 PM - granted resource 0113L5
12/17/2013 2:32:05 PM - granted resource IBM.ULT3580-HH5.001
12/17/2013 2:32:05 PM - granted resource sn01w0009
12/17/2013 2:32:06 PM - estimated 0 Kbytes needed
12/17/2013 2:32:07 PM - started process bpbrm (4360)
12/17/2013 2:35:15 PM - mounting 0113L5
12/17/2013 2:35:15 PM - mounted; mount time: 00:00:00
12/17/2013 2:35:15 PM - positioning 0113L5 to file 680
12/17/2013 2:35:26 PM - positioned 0113L5; position time: 00:00:11
12/17/2013 2:35:47 PM - connecting
12/17/2013 2:36:55 PM - Error bpbrm(pid=3960) cannot create data socket, The operation completed successfully.  (0)   
12/17/2013 2:36:55 PM - end writing
cannot connect on socket(25)

 

BPCD log from client:-

14:27:54.164 [4624.5756] <2> setup_debug_log: switched debug log file for BPCD
14:27:54.164 [4624.5756] <2> bpcd main: VERBOSE = 0
14:27:54.164 [4624.5756] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\BPCD.EXE
14:27:54.164 [4624.5756] <2> bpcd main: Got socket for input 812
14:27:54.179 [4624.5756] <2> process_requests: offset to GMT -28800
14:27:54.179 [4624.5756] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.718 TO 199.63.83.119.13782
14:27:54.179 [4624.5756] <2> process_requests: setup_sockopts complete
14:27:54.179 [4624.5756] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 718
14:27:54.195 [4624.5756] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:27:54.195 [4624.5756] <4> bpcd valid_server: hostname comparison succeeded
14:27:54.320 [4624.5756] <2> process_requests: output socket port number = 13782
14:27:54.320 [4624.5756] <2> check_required_interface_bysocket: getpeername() failed: 10014
14:27:54.320 [4624.5756] <2> vnet_connect_to_vnetd_extra: ../../libvlibs/vnet_vnetd.c.188: msg: VNETD CONNECT FROM 199.63.83.118.599 TO 199.63.83.89.13724 fd = 724
14:27:54.398 [4624.5756] <2> connect_back_socket: ../../libvlibs/vnet_vnetd.c.371: VN_REQUEST_CONNECT_BACK_SOCKET: 3 0x00000003
14:27:54.398 [4624.5756] <2> connect_back_socket: ../../libvlibs/vnet_vnetd.c.385: ipc_string: 64352
14:27:54.897 [4624.5756] <2> get_vnetd_socket: connected to vnetd socket 724
14:27:54.897 [4624.5756] <2> process_requests: Duplicated vnetd socket on stderr
14:27:54.897 [4624.5756] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:27:54.897 [4624.5756] <2> process_requests: VERBOSE = 0
14:27:54.897 [4624.5756] <2> process_requests: Not using VxSS authentication with sn01w0009
14:27:55.115 [4624.5756] <2> process_requests: BPCD_GET_PLATFORM_RQST
14:27:55.334 [4624.5756] <2> process_requests: BPCD_GET_VERSION_RQST
14:27:55.552 [4624.5756] <2> process_requests: BPCD_READ_DIR_RQST_EX
14:27:59.125 [4624.5756] <2> process_requests: BPCD_GET_VERSION_RQST
14:27:59.343 [4624.5756] <2> process_requests: BPCD_READ_DIR_RQST_EX
14:28:01.589 [4624.5756] <2> process_requests: BPCD_GET_VERSION_RQST
14:28:01.808 [4624.5756] <2> process_requests: BPCD_READ_DIR_RQST_EX
14:28:04.101 [4624.5756] <2> process_requests: BPCD_GET_VERSION_RQST
14:28:04.319 [4624.5756] <2> process_requests: BPCD_READ_DIR_RQST_EX
14:28:05.801 [4624.5756] <2> process_requests: BPCD_GET_VERSION_RQST
14:28:06.020 [4624.5756] <2> process_requests: BPCD_READ_DIR_RQST_EX
14:28:09.140 [4624.5756] <2> process_requests: BPCD_GET_VERSION_RQST
14:28:09.374 [4624.5756] <2> process_requests: BPCD_READ_DIR_RQST_EX
14:28:13.227 [4624.5756] <2> process_requests: BPCD_DISCONNECT_RQST
14:28:13.227 [4624.5756] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:28:51.759 [6012.4880] <2> setup_debug_log: switched debug log file for bpcd
14:28:51.759 [6012.4880] <2> bpcd main: VERBOSE = 0
14:28:51.759 [6012.4880] <2> logparams: bpcd
14:28:51.759 [6012.4880] <2> bpcd main: Got socket for input 704
14:28:51.759 [6012.4880] <2> process_requests: offset to GMT -28800
14:28:51.774 [6012.4880] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.55598 TO 199.63.83.118.13724
14:28:51.774 [6012.4880] <2> process_requests: setup_sockopts complete
14:28:51.774 [6012.4880] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 55598
14:28:51.774 [6012.4880] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:28:51.774 [6012.4880] <4> bpcd valid_server: hostname comparison succeeded
14:28:51.915 [6012.4880] <2> process_requests: output socket port number = 1
14:28:52.398 [6220.1112] <2> setup_debug_log: switched debug log file for bpcd
14:28:52.398 [6220.1112] <2> bpcd main: VERBOSE = 0
14:28:52.398 [6220.1112] <2> logparams: bpcd
14:28:52.398 [6220.1112] <2> bpcd main: Got socket for input 812
14:28:52.398 [6220.1112] <2> process_requests: offset to GMT -28800
14:28:52.414 [6220.1112] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.55600 TO 199.63.83.118.13724
14:28:52.414 [6220.1112] <2> process_requests: setup_sockopts complete
14:28:52.414 [6220.1112] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 55600
14:28:52.414 [6220.1112] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:28:52.414 [6220.1112] <4> bpcd valid_server: hostname comparison succeeded
14:28:52.554 [6220.1112] <2> process_requests: output socket port number = 1
14:29:00.120 [7104.1184] <2> setup_debug_log: switched debug log file for bpcd
14:29:00.120 [7104.1184] <2> bpcd main: VERBOSE = 0
14:29:00.120 [7104.1184] <2> logparams: bpcd
14:29:00.120 [7104.1184] <2> bpcd main: Got socket for input 704
14:29:00.120 [7104.1184] <2> process_requests: offset to GMT -28800
14:29:00.120 [7104.1184] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.55602 TO 199.63.83.119.13724
14:29:00.120 [7104.1184] <2> process_requests: setup_sockopts complete
14:29:00.136 [7104.1184] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 55602
14:29:00.136 [7104.1184] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:29:00.136 [7104.1184] <4> bpcd valid_server: hostname comparison succeeded
14:29:00.261 [7104.1184] <2> process_requests: output socket port number = 1
14:29:03.084 [6220.1112] <2> process_requests: Duplicated vnetd socket on stderr
14:29:03.084 [6220.1112] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:29:03.084 [6220.1112] <2> process_requests: VERBOSE = 0
14:29:03.084 [6220.1112] <2> process_requests: Not using VxSS authentication with sn01w0009
14:29:04.270 [6220.1112] <2> process_requests: BPCD_HOSTNAME_RQST
14:29:04.473 [6220.1112] <2> process_requests: BPCD_DISCONNECT_RQST
14:29:04.473 [6220.1112] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:29:05.471 [6012.4880] <2> process_requests: Duplicated vnetd socket on stderr
14:29:05.471 [6012.4880] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:29:05.471 [6012.4880] <2> process_requests: VERBOSE = 0
14:29:05.471 [6012.4880] <2> process_requests: Not using VxSS authentication with sn01w0009
14:29:06.282 [6012.4880] <2> process_requests: BPCD_HOSTNAME_RQST
14:29:06.516 [6012.4880] <2> process_requests: BPCD_DISCONNECT_RQST
14:29:06.516 [6012.4880] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:29:10.806 [7104.1184] <2> process_requests: Duplicated vnetd socket on stderr
14:29:10.806 [7104.1184] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:29:10.806 [7104.1184] <2> process_requests: VERBOSE = 0
14:29:10.806 [7104.1184] <2> process_requests: Not using VxSS authentication with sn01w0009
14:29:11.009 [7104.1184] <2> process_requests: BPCD_GET_VERSION_RQST
14:29:11.243 [7104.1184] <2> process_requests: BPCD_GET_VERSION_RQST
14:29:11.461 [7104.1184] <2> process_requests: BPCD_READ_USERS_CONFIG_RQST
14:29:11.680 [7104.1184] <2> process_requests: BPCD_GET_VERSION_RQST
14:29:12.491 [7104.1184] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
14:29:12.975 [7104.1184] <2> process_requests: BPCD_DISCONNECT_RQST
14:29:12.975 [7104.1184] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:29:24.893 [1280.1276] <2> setup_debug_log: switched debug log file for bpcd
14:29:24.893 [1280.1276] <2> bpcd main: VERBOSE = 0
14:29:24.893 [1280.1276] <2> logparams: bpcd
14:29:24.893 [1280.1276] <2> bpcd main: Got socket for input 704
14:29:24.893 [1280.1276] <2> process_requests: offset to GMT -28800
14:29:24.908 [1280.1276] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.64530 TO 199.63.83.118.13724
14:29:24.908 [1280.1276] <2> process_requests: setup_sockopts complete
14:29:24.908 [1280.1276] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 64530
14:29:24.908 [1280.1276] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:29:24.908 [1280.1276] <4> bpcd valid_server: hostname comparison succeeded
14:29:25.033 [1280.1276] <2> process_requests: output socket port number = 1
14:29:26.936 [6300.5920] <2> setup_debug_log: switched debug log file for bpcd
14:29:26.936 [6300.5920] <2> bpcd main: VERBOSE = 0
14:29:26.936 [6300.5920] <2> logparams: bpcd
14:29:26.936 [6300.5920] <2> bpcd main: Got socket for input 812
14:29:26.936 [6300.5920] <2> process_requests: offset to GMT -28800
14:29:26.936 [6300.5920] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.64532 TO 199.63.83.118.13724
14:29:26.936 [6300.5920] <2> process_requests: setup_sockopts complete
14:29:26.952 [6300.5920] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 64532
14:29:26.952 [6300.5920] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:29:26.952 [6300.5920] <4> bpcd valid_server: hostname comparison succeeded
14:29:27.077 [6300.5920] <2> process_requests: output socket port number = 1
14:29:35.860 [1280.1276] <2> process_requests: Duplicated vnetd socket on stderr
14:29:35.860 [1280.1276] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:29:35.860 [1280.1276] <2> process_requests: VERBOSE = 0
14:29:35.860 [1280.1276] <2> process_requests: Not using VxSS authentication with sn01w0009
14:29:36.094 [1280.1276] <2> process_requests: BPCD_GET_VERSION_RQST
14:29:36.312 [1280.1276] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
14:29:37.154 [1280.1276] <2> process_requests: BPCD_GET_VERSION_RQST
14:29:37.373 [1280.1276] <2> process_requests: BPCD_GET_NB_VERSION_RQST
14:29:37.373 [1280.1276] <4> process_requests: Version string is NetBackup    7.0    700000    "C:\Program Files\Veritas\NetBackup\bin"
14:29:37.591 [1280.1276] <2> process_requests: BPCD_GET_PLATFORM_RQST
14:29:37.622 [6300.5920] <2> process_requests: Duplicated vnetd socket on stderr
14:29:37.622 [6300.5920] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:29:37.622 [6300.5920] <2> process_requests: VERBOSE = 0
14:29:37.622 [6300.5920] <2> process_requests: Not using VxSS authentication with sn01w0009
14:29:37.810 [1280.1276] <2> process_requests: BPCD_GET_UNAME_RQST
14:29:37.810 [1280.1276] <2> getUnameInfo: UNAME_SYSNAME = Windows2008
14:29:37.810 [1280.1276] <2> getUnameInfo: UNAME_NODENAME = SN01W0016
14:29:37.810 [1280.1276] <2> getUnameInfo: UNAME_RELEASE = 6
14:29:37.810 [1280.1276] <2> getUnameInfo: UNAME_VERSION = 1
14:29:37.810 [1280.1276] <2> getUnameInfo: UNAME_MACHINE = Unknown - Unknown -  Revision x2d07
14:29:37.810 [1280.1276] <2> getUnameInfo: END
14:29:37.825 [6300.5920] <2> process_requests: BPCD_HOSTNAME_RQST
14:29:38.044 [1280.1276] <2> process_requests: BPCD_GET_VERSION_RQST
14:29:38.262 [1280.1276] <2> process_requests: BPCD_READ_TEXT_FILE_RQST
14:29:38.262 [1280.1276] <2> read_text_file: .\bpcd_util.c.2278: file_id = 3
14:29:38.262 [1280.1276] <2> read_text_file: .\bpcd_util.c.2326: file_name = <C:\Program Files\Veritas\NetBackup\bin\version.txt>
14:29:38.262 [1280.1276] <2> read_text_file: .\bpcd_util.c.2350: v status = 0
14:29:38.262 [1280.1276] <2> read_text_file: .\bpcd_util.c.2353: n status = 0
14:29:38.262 [1280.1276] <2> read_text_file: .\bpcd_util.c.2401: v status = 0
14:29:38.262 [1280.1276] <2> read_text_file: .\bpcd_util.c.2404: n status = 0
14:29:38.480 [1280.1276] <2> process_requests: BPCD_READ_TEXT_FILE_RQST
14:29:38.480 [1280.1276] <2> read_text_file: .\bpcd_util.c.2278: file_id = 4
14:29:38.480 [1280.1276] <2> read_text_file: .\bpcd_util.c.2326: file_name = <C:\Program Files\Veritas\NetBackup\share\ciphers.txt>
14:29:38.480 [1280.1276] <2> read_text_file: .\bpcd_util.c.2350: v status = 0
14:29:38.480 [1280.1276] <2> read_text_file: .\bpcd_util.c.2353: n status = 0
14:29:38.480 [1280.1276] <2> read_text_file: .\bpcd_util.c.2401: v status = 0
14:29:38.480 [1280.1276] <2> read_text_file: .\bpcd_util.c.2404: n status = 0
14:29:38.652 [6300.5920] <2> process_requests: BPCD_FORK_CMD_RQST
14:29:38.699 [1280.1276] <2> process_requests: BPCD_OPEN_FOR_READ_RQST
14:29:38.699 [1280.1276] <2> process_requests: open for read file name = C:\Program Files\Veritas\NetBackup\bin\version.txt
14:29:38.699 [1280.1276] <2> process_requests: buffer length = 16383
14:29:38.699 [1280.1276] <2> process_requests: our open flags = 0x0
14:29:38.699 [1280.1276] <2> process_requests: client open flags = 0x8000
14:29:38.699 [1280.1276] <2> process_requests: file size = 109
14:29:38.699 [1280.1276] <2> process_requests: completed the write of the open file number 4 to the output socket
14:29:38.870 [6300.5920] <2> process_requests: fork cmd = /usr/openv/netbackup/bin/bpfis bpfis delete -nbu -id sn01w0016_1387259758 -bpstart_to 300 -bpend_to 300 -clnt sn01w0016 -S sn01w0009
14:29:38.870 [6300.5920] <2> process_requests: filter type = -1
14:29:38.870 [6300.5920] <2> process_requests: Convert args to CreateProcess format
14:29:38.870 [6300.5920] <2> process_requests: Done converting args to CreateProcess format
14:29:38.870 [6300.5920] <2> process_requests: new fork cmd = C:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id sn01w0016_1387259758 -bpstart_to 300 -bpend_to 300 -clnt sn01w0016 -S sn01w0009
14:29:38.870 [6300.5920] <2> process_requests: Before CreateProcess
14:29:38.870 [6300.5920] <2> process_requests: StdOutput assigned the value STDOUTSOCK
14:29:38.870 [6300.5920] <2> process_requests: final fork cmd = "C:\Program Files\Veritas\NetBackup\bin\bpfis.exe" delete -nbu -id sn01w0016_1387259758 -bpstart_to 300 -bpend_to 300 -clnt sn01w0016 -S sn01w0009
14:29:38.870 [6300.5920] <2> process_requests: After CreateProcess, pid = 6824
14:29:38.870 [6300.5920] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:29:38.917 [1280.1276] <2> process_requests: BPCD_READ_RQST
14:29:38.917 [1280.1276] <2> process_requests: read request on file number 4
14:29:38.917 [1280.1276] <2> process_requests: read request of 16383 bytes
14:29:38.917 [1280.1276] <2> process_requests: read 109 bytes
14:29:38.917 [1280.1276] <2> process_requests: Before write of read_count = 109
14:29:38.917 [1280.1276] <2> process_requests: After write of read_count
14:29:38.917 [1280.1276] <2> process_requests: completed write of file info
14:29:39.136 [1280.1276] <2> process_requests: BPCD_CLOSE_FILE_RQST
14:29:39.354 [1280.1276] <2> process_requests: BPCD_DISCONNECT_RQST
14:29:39.354 [1280.1276] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:29:59.774 [4688.5684] <2> setup_debug_log: switched debug log file for bpcd
14:29:59.774 [4688.5684] <2> bpcd main: VERBOSE = 0
14:29:59.774 [4688.5684] <2> logparams: bpcd
14:29:59.774 [4688.5684] <2> bpcd main: Got socket for input 704
14:29:59.774 [4688.5684] <2> process_requests: offset to GMT -28800
14:29:59.790 [4688.5684] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.64718 TO 199.63.83.118.13724
14:29:59.790 [4688.5684] <2> process_requests: setup_sockopts complete
14:29:59.790 [4688.5684] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 64718
14:29:59.790 [4688.5684] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:29:59.790 [4688.5684] <4> bpcd valid_server: hostname comparison succeeded
14:29:59.915 [4688.5684] <2> process_requests: output socket port number = 1
14:30:00.710 [6812.6784] <2> setup_debug_log: switched debug log file for bpcd
14:30:00.710 [6812.6784] <2> bpcd main: VERBOSE = 0
14:30:00.710 [6812.6784] <2> logparams: bpcd
14:30:00.710 [6812.6784] <2> bpcd main: Got socket for input 812
14:30:00.710 [6812.6784] <2> process_requests: offset to GMT -28800
14:30:00.710 [6812.6784] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.64720 TO 199.63.83.118.13724
14:30:00.710 [6812.6784] <2> process_requests: setup_sockopts complete
14:30:00.726 [6812.6784] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 64720
14:30:00.726 [6812.6784] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:30:00.726 [6812.6784] <4> bpcd valid_server: hostname comparison succeeded
14:30:00.866 [6812.6784] <2> process_requests: output socket port number = 1
14:30:10.460 [4688.5684] <2> process_requests: Duplicated vnetd socket on stderr
14:30:10.460 [4688.5684] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:30:10.460 [4688.5684] <2> process_requests: VERBOSE = 0
14:30:10.460 [4688.5684] <2> process_requests: Not using VxSS authentication with sn01w0009
14:30:11.271 [4688.5684] <2> process_requests: BPCD_HOSTNAME_RQST
14:30:11.724 [6812.6784] <2> process_requests: Duplicated vnetd socket on stderr
14:30:11.724 [6812.6784] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:30:11.724 [6812.6784] <2> process_requests: VERBOSE = 0
14:30:11.724 [6812.6784] <2> process_requests: Not using VxSS authentication with sn01w0009
14:30:11.942 [6812.6784] <2> process_requests: BPCD_GET_VERSION_RQST
14:30:12.083 [4688.5684] <2> process_requests: BPCD_FORK_CMD_RQST
14:30:12.176 [6812.6784] <2> bpcd_bpfis_state_xfer_prepare: BPCD_BPFIS_STATE_XFER_PREPARE_RQST
14:30:12.176 [6812.6784] <4> copy_remote_file: BPCD_REMOTE_FILE_COPY
14:30:12.176 [6812.6784] <4> copy_remote_file: put C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.sn01w0016_1387259758.0
14:30:12.301 [4688.5684] <2> process_requests: fork cmd = /usr/openv/netbackup/bin/bpfis bpfis create -nbu -owner NBU -id sn01w0016_1387261710 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 13 -clnt sn01w0016 -rg other -class test-sn01w0016 -sched Incremental -fso -S sn01w0009 -WOFB -fim VSS
14:30:12.301 [4688.5684] <2> process_requests: filter type = -1
14:30:12.301 [4688.5684] <2> process_requests: Convert args to CreateProcess format
14:30:12.301 [4688.5684] <2> process_requests: Done converting args to CreateProcess format
14:30:12.301 [4688.5684] <2> process_requests: new fork cmd = C:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id sn01w0016_1387261710 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 13 -clnt sn01w0016 -rg other -class test-sn01w0016 -sched Incremental -fso -S sn01w0009 -WOFB -fim VSS
14:30:12.301 [4688.5684] <2> process_requests: Before CreateProcess
14:30:12.301 [4688.5684] <2> process_requests: StdOutput assigned the value STDOUTSOCK
14:30:12.301 [4688.5684] <2> process_requests: final fork cmd = "C:\Program Files\Veritas\NetBackup\bin\bpfis.exe" create -nbu -owner NBU -id sn01w0016_1387261710 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 13 -clnt sn01w0016 -rg other -class test-sn01w0016 -sched Incremental -fso -S sn01w0009 -WOFB -fim VSS
14:30:12.301 [4688.5684] <2> process_requests: After CreateProcess, pid = 7020
14:30:12.301 [4688.5684] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:30:12.613 [6812.6784] <4> copy_remote_file: receipt of file successful (1108 bytes)
14:30:12.629 [6812.6784] <4> copy_remote_file: BPCD_REMOTE_FILE_COPY
14:30:12.629 [6812.6784] <4> copy_remote_file: put C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.sn01w0016_1387259758.0.fiid
14:30:12.644 [6812.6784] <4> copy_remote_file: receipt of file successful (3188 bytes)
14:30:12.660 [6812.6784] <2> process_requests: BPCD_DISCONNECT_RQST
14:30:12.660 [6812.6784] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:30:42.674 [6436.6708] <2> setup_debug_log: switched debug log file for bpcd
14:30:42.674 [6436.6708] <2> bpcd main: VERBOSE = 0
14:30:42.674 [6436.6708] <2> logparams: bpcd
14:30:42.674 [6436.6708] <2> bpcd main: Got socket for input 704
14:30:42.674 [6436.6708] <2> process_requests: offset to GMT -28800
14:30:42.674 [6436.6708] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.61610 TO 199.63.83.118.13724
14:30:42.674 [6436.6708] <2> process_requests: setup_sockopts complete
14:30:42.690 [6436.6708] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 61610
14:30:42.690 [6436.6708] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:30:42.690 [6436.6708] <4> bpcd valid_server: hostname comparison succeeded
14:30:42.814 [6436.6708] <2> process_requests: output socket port number = 1
14:30:49.148 [6668.1076] <2> setup_debug_log: switched debug log file for bpcd
14:30:49.148 [6668.1076] <2> bpcd main: VERBOSE = 0
14:30:49.148 [6668.1076] <2> logparams: bpcd
14:30:49.148 [6668.1076] <2> bpcd main: Got socket for input 812
14:30:49.148 [6668.1076] <2> process_requests: offset to GMT -28800
14:30:49.148 [6668.1076] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.61612 TO 199.63.83.118.13724
14:30:49.148 [6668.1076] <2> process_requests: setup_sockopts complete
14:30:49.148 [6668.1076] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 61612
14:30:49.164 [6668.1076] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:30:49.164 [6668.1076] <4> bpcd valid_server: hostname comparison succeeded
14:30:49.288 [6668.1076] <2> process_requests: output socket port number = 1
14:30:53.360 [6436.6708] <2> process_requests: Duplicated vnetd socket on stderr
14:30:53.360 [6436.6708] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:30:53.360 [6436.6708] <2> process_requests: VERBOSE = 0
14:30:53.360 [6436.6708] <2> process_requests: Not using VxSS authentication with sn01w0009
14:30:53.563 [6436.6708] <2> process_requests: BPCD_GET_VERSION_RQST
14:30:53.765 [6436.6708] <2> bpcd_bpfis_state_xfer_prepare: BPCD_BPFIS_STATE_XFER_PREPARE_RQST
14:30:53.765 [6436.6708] <2> bpcd_bpfis_state_xfer_prepare: Looking at directory entry: .
14:30:53.765 [6436.6708] <2> bpcd_bpfis_state_xfer_prepare: Looking at directory entry: ..
14:30:53.765 [6436.6708] <2> bpcd_bpfis_state_xfer_prepare: Looking at directory entry: bpfis.fim.sn01w0016_1387261710.0
14:30:53.765 [6436.6708] <2> bpcd_bpfis_state_xfer_prepare: Looking at directory entry: bpfis.fim.sn01w0016_1387261710.0.fiid
14:30:53.765 [6436.6708] <2> process_requests: BPCD_DISCONNECT_RQST
14:30:53.765 [6436.6708] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:30:59.803 [6668.1076] <2> process_requests: Duplicated vnetd socket on stderr
14:30:59.803 [6668.1076] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:30:59.803 [6668.1076] <2> process_requests: VERBOSE = 0
14:30:59.803 [6668.1076] <2> process_requests: Not using VxSS authentication with sn01w0009
14:31:00.021 [6668.1076] <2> process_requests: BPCD_GET_VERSION_RQST
14:31:00.224 [6668.1076] <2> bpcd_bpfis_state_xfer_prepare: BPCD_BPFIS_STATE_XFER_PREPARE_RQST
14:31:00.224 [6668.1076] <2> bpcd_bpfis_state_xfer_prepare: Looking at directory entry: .
14:31:00.224 [6668.1076] <2> bpcd_bpfis_state_xfer_prepare: Looking at directory entry: ..
14:31:00.224 [6668.1076] <2> bpcd_bpfis_state_xfer_prepare: Looking at directory entry: bpfis.fim.sn01w0016_1387261710.0
14:31:00.224 [6668.1076] <2> bpcd_bpfis_state_xfer_prepare: Looking at directory entry: bpfis.fim.sn01w0016_1387261710.0.fiid
14:31:00.224 [6668.1076] <4> copy_remote_file: BPCD_REMOTE_FILE_COPY
14:31:00.224 [6668.1076] <4> copy_remote_file: get C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.sn01w0016_1387261710.0
14:31:00.239 [6668.1076] <4> copy_remote_file: send of file successful (1210 bytes)
14:31:00.458 [6668.1076] <4> copy_remote_file: BPCD_REMOTE_FILE_COPY
14:31:00.458 [6668.1076] <4> copy_remote_file: get C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.sn01w0016_1387261710.0.fiid
14:31:00.458 [6668.1076] <4> copy_remote_file: send of file successful (3188 bytes)
14:31:00.505 [6668.1076] <2> process_requests: BPCD_DISCONNECT_RQST
14:31:00.505 [6668.1076] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:31:14.170 [5148.6644] <2> setup_debug_log: switched debug log file for bpcd
14:31:14.170 [5148.6644] <2> bpcd main: VERBOSE = 0
14:31:14.170 [5148.6644] <2> logparams: bpcd
14:31:14.170 [5148.6644] <2> bpcd main: Got socket for input 704
14:31:14.170 [5148.6644] <2> process_requests: offset to GMT -28800
14:31:14.170 [5148.6644] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.55053 TO 199.63.83.118.13724
14:31:14.170 [5148.6644] <2> process_requests: setup_sockopts complete
14:31:14.170 [5148.6644] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 55053
14:31:14.186 [5148.6644] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:31:14.186 [5148.6644] <4> bpcd valid_server: hostname comparison succeeded
14:31:14.311 [5148.6644] <2> process_requests: output socket port number = 1
14:31:20.909 [5152.7132] <2> setup_debug_log: switched debug log file for bpcd
14:31:20.909 [5152.7132] <2> bpcd main: VERBOSE = 0
14:31:20.909 [5152.7132] <2> logparams: bpcd
14:31:20.909 [5152.7132] <2> bpcd main: Got socket for input 812
14:31:20.909 [5152.7132] <2> process_requests: offset to GMT -28800
14:31:20.909 [5152.7132] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.55110 TO 199.63.83.118.13724
14:31:20.909 [5152.7132] <2> process_requests: setup_sockopts complete
14:31:20.925 [5152.7132] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 55110
14:31:20.925 [5152.7132] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:31:20.925 [5152.7132] <4> bpcd valid_server: hostname comparison succeeded
14:31:21.065 [5152.7132] <2> process_requests: output socket port number = 1
14:31:24.872 [5148.6644] <2> process_requests: Duplicated vnetd socket on stderr
14:31:24.872 [5148.6644] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:31:24.872 [5148.6644] <2> process_requests: VERBOSE = 0
14:31:24.872 [5148.6644] <2> process_requests: Not using VxSS authentication with sn01w0009
14:31:25.090 [5148.6644] <2> process_requests: BPCD_GET_VERSION_RQST
14:31:25.308 [5148.6644] <2> process_requests: BPCD_GET_VERSION_RQST
14:31:25.527 [5148.6644] <2> process_requests: BPCD_READ_USERS_CONFIG_RQST
14:31:25.745 [5148.6644] <2> process_requests: BPCD_GET_VERSION_RQST
14:31:25.964 [5148.6644] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
14:31:26.182 [5148.6644] <2> process_requests: BPCD_DISCONNECT_RQST
14:31:26.182 [5148.6644] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:31:31.548 [5152.7132] <2> process_requests: Duplicated vnetd socket on stderr
14:31:31.548 [5152.7132] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:31:31.548 [5152.7132] <2> process_requests: VERBOSE = 0
14:31:31.548 [5152.7132] <2> process_requests: Not using VxSS authentication with sn01w0009
14:31:31.782 [5152.7132] <2> process_requests: BPCD_GET_VERSION_RQST
14:31:32.001 [5152.7132] <2> process_requests: BPCD_DISCONNECT_RQST
14:31:32.001 [5152.7132] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:31:52.421 [1460.1184] <2> setup_debug_log: switched debug log file for bpcd
14:31:52.421 [1460.1184] <2> bpcd main: VERBOSE = 0
14:31:52.421 [1460.1184] <2> logparams: bpcd
14:31:52.421 [1460.1184] <2> bpcd main: Got socket for input 704
14:31:52.421 [1460.1184] <2> process_requests: offset to GMT -28800
14:31:52.421 [1460.1184] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.55134 TO 199.63.83.118.13724
14:31:52.421 [1460.1184] <2> process_requests: setup_sockopts complete
14:31:52.437 [1460.1184] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 55134
14:31:52.437 [1460.1184] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:31:52.437 [1460.1184] <4> bpcd valid_server: hostname comparison succeeded
14:31:52.562 [1460.1184] <2> process_requests: output socket port number = 1
14:32:03.091 [1460.1184] <2> process_requests: Duplicated vnetd socket on stderr
14:32:03.091 [1460.1184] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:32:03.091 [1460.1184] <2> process_requests: VERBOSE = 0
14:32:03.091 [1460.1184] <2> process_requests: Not using VxSS authentication with sn01w0009
14:32:03.325 [1460.1184] <2> process_requests: BPCD_GET_VERSION_RQST
14:32:03.544 [1460.1184] <2> process_requests: BPCD_GET_VERSION_RQST
14:32:03.762 [1460.1184] <2> process_requests: BPCD_READ_USERS_CONFIG_RQST
14:32:03.981 [1460.1184] <2> process_requests: BPCD_GET_VERSION_RQST
14:32:04.183 [1460.1184] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
14:32:04.417 [1460.1184] <2> process_requests: BPCD_DISCONNECT_RQST
14:32:04.417 [1460.1184] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:32:27.396 [5636.6508] <2> setup_debug_log: switched debug log file for bpcd
14:32:27.396 [5636.6508] <2> bpcd main: VERBOSE = 0
14:32:27.396 [5636.6508] <2> logparams: bpcd
14:32:27.396 [5636.6508] <2> bpcd main: Got socket for input 704
14:32:27.396 [5636.6508] <2> process_requests: offset to GMT -28800
14:32:27.396 [5636.6508] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.55211 TO 199.63.83.118.13724
14:32:27.396 [5636.6508] <2> process_requests: setup_sockopts complete
14:32:27.412 [5636.6508] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 55211
14:32:27.412 [5636.6508] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:32:27.412 [5636.6508] <4> bpcd valid_server: hostname comparison succeeded
14:32:27.552 [5636.6508] <2> process_requests: output socket port number = 1
14:32:35.945 [1580.5588] <2> setup_debug_log: switched debug log file for bpcd
14:32:35.945 [1580.5588] <2> bpcd main: VERBOSE = 0
14:32:35.945 [1580.5588] <2> logparams: bpcd
14:32:35.945 [1580.5588] <2> bpcd main: Got socket for input 812
14:32:35.945 [1580.5588] <2> process_requests: offset to GMT -28800
14:32:35.945 [1580.5588] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.55213 TO 199.63.83.119.13724
14:32:35.945 [1580.5588] <2> process_requests: setup_sockopts complete
14:32:35.945 [1580.5588] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 55213
14:32:35.960 [1580.5588] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:32:35.960 [1580.5588] <4> bpcd valid_server: hostname comparison succeeded
14:32:36.085 [1580.5588] <2> process_requests: output socket port number = 1
14:32:38.082 [5636.6508] <2> process_requests: Duplicated vnetd socket on stderr
14:32:38.082 [5636.6508] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:32:38.082 [5636.6508] <2> process_requests: VERBOSE = 0
14:32:38.082 [5636.6508] <2> process_requests: Not using VxSS authentication with sn01w0009
14:32:38.316 [5636.6508] <2> process_requests: BPCD_GET_VERSION_RQST
14:32:38.534 [5636.6508] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
14:32:39.330 [5636.6508] <2> process_requests: BPCD_GET_VERSION_RQST
14:32:39.548 [5636.6508] <2> process_requests: BPCD_GET_NB_VERSION_RQST
14:32:39.548 [5636.6508] <4> process_requests: Version string is NetBackup    7.0    700000    "C:\Program Files\Veritas\NetBackup\bin"
14:32:39.767 [5636.6508] <2> process_requests: BPCD_GET_PLATFORM_RQST
14:32:39.985 [5636.6508] <2> process_requests: BPCD_GET_UNAME_RQST
14:32:39.985 [5636.6508] <2> getUnameInfo: UNAME_SYSNAME = Windows2008
14:32:39.985 [5636.6508] <2> getUnameInfo: UNAME_NODENAME = SN01W0016
14:32:39.985 [5636.6508] <2> getUnameInfo: UNAME_RELEASE = 6
14:32:39.985 [5636.6508] <2> getUnameInfo: UNAME_VERSION = 1
14:32:39.985 [5636.6508] <2> getUnameInfo: UNAME_MACHINE = Unknown - Unknown -  Revision x2d07
14:32:39.985 [5636.6508] <2> getUnameInfo: END
14:32:40.204 [5636.6508] <2> process_requests: BPCD_GET_VERSION_RQST
14:32:40.422 [5636.6508] <2> process_requests: BPCD_READ_TEXT_FILE_RQST
14:32:40.422 [5636.6508] <2> read_text_file: .\bpcd_util.c.2278: file_id = 3
14:32:40.422 [5636.6508] <2> read_text_file: .\bpcd_util.c.2326: file_name = <C:\Program Files\Veritas\NetBackup\bin\version.txt>
14:32:40.422 [5636.6508] <2> read_text_file: .\bpcd_util.c.2350: v status = 0
14:32:40.422 [5636.6508] <2> read_text_file: .\bpcd_util.c.2353: n status = 0
14:32:40.422 [5636.6508] <2> read_text_file: .\bpcd_util.c.2401: v status = 0
14:32:40.422 [5636.6508] <2> read_text_file: .\bpcd_util.c.2404: n status = 0
14:32:40.640 [5636.6508] <2> process_requests: BPCD_READ_TEXT_FILE_RQST
14:32:40.640 [5636.6508] <2> read_text_file: .\bpcd_util.c.2278: file_id = 4
14:32:40.640 [5636.6508] <2> read_text_file: .\bpcd_util.c.2326: file_name = <C:\Program Files\Veritas\NetBackup\share\ciphers.txt>
14:32:40.640 [5636.6508] <2> read_text_file: .\bpcd_util.c.2350: v status = 0
14:32:40.640 [5636.6508] <2> read_text_file: .\bpcd_util.c.2353: n status = 0
14:32:40.640 [5636.6508] <2> read_text_file: .\bpcd_util.c.2401: v status = 0
14:32:40.640 [5636.6508] <2> read_text_file: .\bpcd_util.c.2404: n status = 0
14:32:40.859 [5636.6508] <2> process_requests: BPCD_OPEN_FOR_READ_RQST
14:32:41.077 [5636.6508] <2> process_requests: open for read file name = C:\Program Files\Veritas\NetBackup\bin\version.txt
14:32:41.077 [5636.6508] <2> process_requests: buffer length = 16383
14:32:41.077 [5636.6508] <2> process_requests: our open flags = 0x0
14:32:41.077 [5636.6508] <2> process_requests: client open flags = 0x8000
14:32:41.077 [5636.6508] <2> process_requests: file size = 109
14:32:41.077 [5636.6508] <2> process_requests: completed the write of the open file number 4 to the output socket
14:32:41.311 [5636.6508] <2> process_requests: BPCD_READ_RQST
14:32:41.514 [5636.6508] <2> process_requests: read request on file number 4
14:32:41.514 [5636.6508] <2> process_requests: read request of 16383 bytes
14:32:41.514 [5636.6508] <2> process_requests: read 109 bytes
14:32:41.514 [5636.6508] <2> process_requests: Before write of read_count = 109
14:32:41.514 [5636.6508] <2> process_requests: After write of read_count
14:32:41.514 [5636.6508] <2> process_requests: completed write of file info
14:32:41.748 [5636.6508] <2> process_requests: BPCD_CLOSE_FILE_RQST
14:32:42.169 [5636.6508] <2> process_requests: BPCD_DISCONNECT_RQST
14:32:42.169 [5636.6508] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:32:46.662 [1580.5588] <2> process_requests: Duplicated vnetd socket on stderr
14:32:46.662 [1580.5588] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:32:46.662 [1580.5588] <2> process_requests: VERBOSE = 0
14:32:46.662 [1580.5588] <2> process_requests: Not using VxSS authentication with sn01w0009
14:32:47.473 [1580.5588] <2> process_requests: BPCD_HOSTNAME_RQST
14:32:47.707 [1580.5588] <2> process_requests: BPCD_DISCONNECT_RQST
14:32:47.707 [1580.5588] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:33:03.042 [296.6948] <2> setup_debug_log: switched debug log file for bpcd
14:33:03.042 [296.6948] <2> bpcd main: VERBOSE = 0
14:33:03.042 [296.6948] <2> logparams: bpcd
14:33:03.042 [296.6948] <2> bpcd main: Got socket for input 704
14:33:03.057 [296.6948] <2> process_requests: offset to GMT -28800
14:33:03.057 [296.6948] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.52513 TO 199.63.83.118.13724
14:33:03.057 [296.6948] <2> process_requests: setup_sockopts complete
14:33:03.057 [296.6948] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 52513
14:33:03.073 [296.6948] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:33:03.073 [296.6948] <4> bpcd valid_server: hostname comparison succeeded
14:33:03.182 [296.6948] <2> process_requests: output socket port number = 1
14:33:13.681 [296.6948] <2> process_requests: Duplicated vnetd socket on stderr
14:33:13.681 [296.6948] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:33:13.681 [296.6948] <2> process_requests: VERBOSE = 0
14:33:13.681 [296.6948] <2> process_requests: Not using VxSS authentication with sn01w0009
14:33:14.523 [296.6948] <2> process_requests: BPCD_HOSTNAME_RQST
14:33:14.742 [296.6948] <2> process_requests: BPCD_DISCONNECT_RQST
14:33:14.742 [296.6948] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:33:46.378 [6008.1572] <2> setup_debug_log: switched debug log file for bpcd
14:33:46.378 [6008.1572] <2> bpcd main: VERBOSE = 0
14:33:46.378 [6008.1572] <2> logparams: bpcd
14:33:46.378 [6008.1572] <2> bpcd main: Got socket for input 704
14:33:46.378 [6008.1572] <2> process_requests: offset to GMT -28800
14:33:46.378 [6008.1572] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.50656 TO 199.63.83.118.13724
14:33:46.378 [6008.1572] <2> process_requests: setup_sockopts complete
14:33:46.394 [6008.1572] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 50656
14:33:46.394 [6008.1572] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:33:46.394 [6008.1572] <4> bpcd valid_server: hostname comparison succeeded
14:33:46.519 [6008.1572] <2> process_requests: output socket port number = 1
14:33:57.080 [6008.1572] <2> process_requests: Duplicated vnetd socket on stderr
14:33:57.080 [6008.1572] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:33:57.080 [6008.1572] <2> process_requests: VERBOSE = 0
14:33:57.080 [6008.1572] <2> process_requests: Not using VxSS authentication with sn01w0009
14:33:57.267 [5444.1884] <2> setup_debug_log: switched debug log file for bpcd
14:33:57.267 [5444.1884] <2> bpcd main: VERBOSE = 0
14:33:57.267 [5444.1884] <2> logparams: bpcd
14:33:57.267 [5444.1884] <2> bpcd main: Got socket for input 704
14:33:57.267 [5444.1884] <2> process_requests: offset to GMT -28800
14:33:57.267 [5444.1884] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.50660 TO 199.63.83.118.13724
14:33:57.267 [5444.1884] <2> process_requests: setup_sockopts complete
14:33:57.283 [5444.1884] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 50660
14:33:57.283 [5444.1884] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:33:57.283 [5444.1884] <4> bpcd valid_server: hostname comparison succeeded
14:33:57.408 [5444.1884] <2> process_requests: output socket port number = 1
14:33:57.876 [6008.1572] <2> process_requests: BPCD_HOSTNAME_RQST
14:33:58.094 [6008.1572] <2> process_requests: BPCD_DISCONNECT_RQST
14:33:58.094 [6008.1572] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:34:07.969 [5444.1884] <2> process_requests: Duplicated vnetd socket on stderr
14:34:07.969 [5444.1884] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:34:07.969 [5444.1884] <2> process_requests: VERBOSE = 0
14:34:07.969 [5444.1884] <2> process_requests: Not using VxSS authentication with sn01w0009
14:34:08.203 [5444.1884] <2> process_requests: BPCD_GET_VERSION_RQST
14:34:08.421 [5444.1884] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
14:34:08.639 [5444.1884] <2> process_requests: BPCD_GET_VERSION_RQST
14:34:08.858 [5444.1884] <2> process_requests: BPCD_GET_NB_VERSION_RQST
14:34:08.858 [5444.1884] <4> process_requests: Version string is NetBackup    7.0    700000    "C:\Program Files\Veritas\NetBackup\bin"
14:34:09.076 [5444.1884] <2> process_requests: BPCD_GET_PLATFORM_RQST
14:34:09.279 [5444.1884] <2> process_requests: BPCD_GET_UNAME_RQST
14:34:09.279 [5444.1884] <2> getUnameInfo: UNAME_SYSNAME = Windows2008
14:34:09.279 [5444.1884] <2> getUnameInfo: UNAME_NODENAME = SN01W0016
14:34:09.279 [5444.1884] <2> getUnameInfo: UNAME_RELEASE = 6
14:34:09.279 [5444.1884] <2> getUnameInfo: UNAME_VERSION = 1
14:34:09.279 [5444.1884] <2> getUnameInfo: UNAME_MACHINE = Unknown - Unknown -  Revision x2d07
14:34:09.279 [5444.1884] <2> getUnameInfo: END
14:34:09.497 [5444.1884] <2> process_requests: BPCD_GET_VERSION_RQST
14:34:09.716 [5444.1884] <2> process_requests: BPCD_READ_TEXT_FILE_RQST
14:34:09.716 [5444.1884] <2> read_text_file: .\bpcd_util.c.2278: file_id = 3
14:34:09.716 [5444.1884] <2> read_text_file: .\bpcd_util.c.2326: file_name = <C:\Program Files\Veritas\NetBackup\bin\version.txt>
14:34:09.716 [5444.1884] <2> read_text_file: .\bpcd_util.c.2350: v status = 0
14:34:09.716 [5444.1884] <2> read_text_file: .\bpcd_util.c.2353: n status = 0
14:34:09.716 [5444.1884] <2> read_text_file: .\bpcd_util.c.2401: v status = 0
14:34:09.716 [5444.1884] <2> read_text_file: .\bpcd_util.c.2404: n status = 0
14:34:09.934 [5444.1884] <2> process_requests: BPCD_READ_TEXT_FILE_RQST
14:34:09.934 [5444.1884] <2> read_text_file: .\bpcd_util.c.2278: file_id = 4
14:34:09.934 [5444.1884] <2> read_text_file: .\bpcd_util.c.2326: file_name = <C:\Program Files\Veritas\NetBackup\share\ciphers.txt>
14:34:09.934 [5444.1884] <2> read_text_file: .\bpcd_util.c.2350: v status = 0
14:34:09.934 [5444.1884] <2> read_text_file: .\bpcd_util.c.2353: n status = 0
14:34:09.934 [5444.1884] <2> read_text_file: .\bpcd_util.c.2401: v status = 0
14:34:09.934 [5444.1884] <2> read_text_file: .\bpcd_util.c.2404: n status = 0
14:34:10.153 [5444.1884] <2> process_requests: BPCD_OPEN_FOR_READ_RQST
14:34:10.153 [5444.1884] <2> process_requests: open for read file name = C:\Program Files\Veritas\NetBackup\bin\version.txt
14:34:10.153 [5444.1884] <2> process_requests: buffer length = 16383
14:34:10.153 [5444.1884] <2> process_requests: our open flags = 0x0
14:34:10.153 [5444.1884] <2> process_requests: client open flags = 0x8000
14:34:10.153 [5444.1884] <2> process_requests: file size = 109
14:34:10.153 [5444.1884] <2> process_requests: completed the write of the open file number 4 to the output socket
14:34:10.387 [5444.1884] <2> process_requests: BPCD_READ_RQST
14:34:10.589 [5444.1884] <2> process_requests: read request on file number 4
14:34:10.589 [5444.1884] <2> process_requests: read request of 16383 bytes
14:34:10.589 [5444.1884] <2> process_requests: read 109 bytes
14:34:10.589 [5444.1884] <2> process_requests: Before write of read_count = 109
14:34:10.589 [5444.1884] <2> process_requests: After write of read_count
14:34:10.589 [5444.1884] <2> process_requests: completed write of file info
14:34:10.808 [5444.1884] <2> process_requests: BPCD_CLOSE_FILE_RQST
14:34:11.026 [5444.1884] <2> process_requests: BPCD_DISCONNECT_RQST
14:34:11.026 [5444.1884] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:34:18.514 [5920.5384] <2> setup_debug_log: switched debug log file for bpcd
14:34:18.514 [5920.5384] <2> bpcd main: VERBOSE = 0
14:34:18.514 [5920.5384] <2> logparams: bpcd
14:34:18.514 [5920.5384] <2> bpcd main: Got socket for input 704
14:34:18.514 [5920.5384] <2> process_requests: offset to GMT -28800
14:34:18.514 [5920.5384] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.57952 TO 199.63.83.118.13724
14:34:18.514 [5920.5384] <2> process_requests: setup_sockopts complete
14:34:18.530 [5920.5384] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 57952
14:34:18.530 [5920.5384] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:34:18.530 [5920.5384] <4> bpcd valid_server: hostname comparison succeeded
14:34:18.655 [5920.5384] <2> process_requests: output socket port number = 1
14:34:29.200 [5920.5384] <2> process_requests: Duplicated vnetd socket on stderr
14:34:29.200 [5920.5384] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:34:29.200 [5920.5384] <2> process_requests: VERBOSE = 0
14:34:29.200 [5920.5384] <2> process_requests: Not using VxSS authentication with sn01w0009
14:34:29.512 [5920.5384] <2> process_requests: BPCD_GET_VERSION_RQST
14:34:29.731 [5920.5384] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
14:34:30.526 [5920.5384] <2> process_requests: BPCD_GET_VERSION_RQST
14:34:30.745 [5920.5384] <2> process_requests: BPCD_GET_NB_VERSION_RQST
14:34:30.745 [5920.5384] <4> process_requests: Version string is NetBackup    7.0    700000    "C:\Program Files\Veritas\NetBackup\bin"
14:34:30.963 [5920.5384] <2> process_requests: BPCD_GET_PLATFORM_RQST
14:34:31.166 [5920.5384] <2> process_requests: BPCD_GET_UNAME_RQST
14:34:31.166 [5920.5384] <2> getUnameInfo: UNAME_SYSNAME = Windows2008
14:34:31.166 [5920.5384] <2> getUnameInfo: UNAME_NODENAME = SN01W0016
14:34:31.166 [5920.5384] <2> getUnameInfo: UNAME_RELEASE = 6
14:34:31.166 [5920.5384] <2> getUnameInfo: UNAME_VERSION = 1
14:34:31.166 [5920.5384] <2> getUnameInfo: UNAME_MACHINE = Unknown - Unknown -  Revision x2d07
14:34:31.166 [5920.5384] <2> getUnameInfo: END
14:34:31.384 [5920.5384] <2> process_requests: BPCD_GET_VERSION_RQST
14:34:31.431 [300.6556] <2> setup_debug_log: switched debug log file for bpcd
14:34:31.431 [300.6556] <2> bpcd main: VERBOSE = 0
14:34:31.431 [300.6556] <2> logparams: bpcd
14:34:31.431 [300.6556] <2> bpcd main: Got socket for input 704
14:34:31.431 [300.6556] <2> process_requests: offset to GMT -28800
14:34:31.431 [300.6556] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.65234 TO 199.63.83.118.13724
14:34:31.431 [300.6556] <2> process_requests: setup_sockopts complete
14:34:31.447 [300.6556] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 65234
14:34:31.447 [300.6556] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:34:31.447 [300.6556] <4> bpcd valid_server: hostname comparison succeeded
14:34:31.587 [300.6556] <2> process_requests: output socket port number = 1
14:34:31.603 [5920.5384] <2> process_requests: BPCD_READ_TEXT_FILE_RQST
14:34:31.603 [5920.5384] <2> read_text_file: .\bpcd_util.c.2278: file_id = 3
14:34:31.603 [5920.5384] <2> read_text_file: .\bpcd_util.c.2326: file_name = <C:\Program Files\Veritas\NetBackup\bin\version.txt>
14:34:31.603 [5920.5384] <2> read_text_file: .\bpcd_util.c.2350: v status = 0
14:34:31.603 [5920.5384] <2> read_text_file: .\bpcd_util.c.2353: n status = 0
14:34:31.603 [5920.5384] <2> read_text_file: .\bpcd_util.c.2401: v status = 0
14:34:31.603 [5920.5384] <2> read_text_file: .\bpcd_util.c.2404: n status = 0
14:34:31.821 [5920.5384] <2> process_requests: BPCD_READ_TEXT_FILE_RQST
14:34:31.821 [5920.5384] <2> read_text_file: .\bpcd_util.c.2278: file_id = 4
14:34:31.821 [5920.5384] <2> read_text_file: .\bpcd_util.c.2326: file_name = <C:\Program Files\Veritas\NetBackup\share\ciphers.txt>
14:34:31.821 [5920.5384] <2> read_text_file: .\bpcd_util.c.2350: v status = 0
14:34:31.821 [5920.5384] <2> read_text_file: .\bpcd_util.c.2353: n status = 0
14:34:31.821 [5920.5384] <2> read_text_file: .\bpcd_util.c.2401: v status = 0
14:34:31.821 [5920.5384] <2> read_text_file: .\bpcd_util.c.2404: n status = 0
14:34:32.039 [5920.5384] <2> process_requests: BPCD_OPEN_FOR_READ_RQST
14:34:32.039 [5920.5384] <2> process_requests: open for read file name = C:\Program Files\Veritas\NetBackup\bin\version.txt
14:34:32.039 [5920.5384] <2> process_requests: buffer length = 16383
14:34:32.039 [5920.5384] <2> process_requests: our open flags = 0x0
14:34:32.039 [5920.5384] <2> process_requests: client open flags = 0x8000
14:34:32.039 [5920.5384] <2> process_requests: file size = 109
14:34:32.039 [5920.5384] <2> process_requests: completed the write of the open file number 4 to the output socket
14:34:32.258 [5920.5384] <2> process_requests: BPCD_READ_RQST
14:34:32.258 [5920.5384] <2> process_requests: read request on file number 4
14:34:32.258 [5920.5384] <2> process_requests: read request of 16383 bytes
14:34:32.258 [5920.5384] <2> process_requests: read 109 bytes
14:34:32.258 [5920.5384] <2> process_requests: Before write of read_count = 109
14:34:32.258 [5920.5384] <2> process_requests: After write of read_count
14:34:32.258 [5920.5384] <2> process_requests: completed write of file info
14:34:32.476 [5920.5384] <2> process_requests: BPCD_CLOSE_FILE_RQST
14:34:32.694 [5920.5384] <2> process_requests: BPCD_DISCONNECT_RQST
14:34:32.694 [5920.5384] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:34:42.101 [300.6556] <2> process_requests: Duplicated vnetd socket on stderr
14:34:42.101 [300.6556] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:34:42.101 [300.6556] <2> process_requests: VERBOSE = 0
14:34:42.101 [300.6556] <2> process_requests: Not using VxSS authentication with sn01w0009
14:34:42.335 [300.6556] <2> process_requests: BPCD_HOSTNAME_RQST
14:34:42.554 [300.6556] <2> process_requests: BPCD_DISCONNECT_RQST
14:34:42.554 [300.6556] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:34:53.099 [4688.1076] <2> setup_debug_log: switched debug log file for bpcd
14:34:53.099 [4688.1076] <2> bpcd main: VERBOSE = 0
14:34:53.099 [4688.1076] <2> logparams: bpcd
14:34:53.099 [4688.1076] <2> bpcd main: Got socket for input 704
14:34:53.099 [4688.1076] <2> process_requests: offset to GMT -28800
14:34:53.099 [4688.1076] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.65251 TO 199.63.83.118.13724
14:34:53.099 [4688.1076] <2> process_requests: setup_sockopts complete
14:34:53.099 [4688.1076] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 65251
14:34:53.115 [4688.1076] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:34:53.115 [4688.1076] <4> bpcd valid_server: hostname comparison succeeded
14:34:53.240 [4688.1076] <2> process_requests: output socket port number = 1
14:35:02.958 [6684.4720] <2> setup_debug_log: switched debug log file for bpcd
14:35:02.958 [6684.4720] <2> bpcd main: VERBOSE = 0
14:35:02.958 [6684.4720] <2> logparams: bpcd
14:35:02.958 [6684.4720] <2> bpcd main: Got socket for input 812
14:35:02.958 [6684.4720] <2> process_requests: offset to GMT -28800
14:35:02.958 [6684.4720] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.65253 TO 199.63.83.118.13724
14:35:02.958 [6684.4720] <2> process_requests: setup_sockopts complete
14:35:02.974 [6684.4720] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 65253
14:35:02.974 [6684.4720] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:35:02.974 [6684.4720] <4> bpcd valid_server: hostname comparison succeeded
14:35:03.114 [6684.4720] <2> process_requests: output socket port number = 1
14:35:03.769 [4688.1076] <2> process_requests: Duplicated vnetd socket on stderr
14:35:03.769 [4688.1076] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:35:03.769 [4688.1076] <2> process_requests: VERBOSE = 0
14:35:03.769 [4688.1076] <2> process_requests: Not using VxSS authentication with sn01w0009
14:35:03.988 [4688.1076] <2> process_requests: BPCD_GET_VERSION_RQST
14:35:04.222 [4688.1076] <2> process_requests: BPCD_DISCONNECT_RQST
14:35:04.222 [4688.1076] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:35:13.675 [6684.4720] <2> process_requests: Duplicated vnetd socket on stderr
14:35:13.675 [6684.4720] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:35:13.675 [6684.4720] <2> process_requests: VERBOSE = 0
14:35:13.675 [6684.4720] <2> process_requests: Not using VxSS authentication with sn01w0009
14:35:13.909 [6684.4720] <2> process_requests: BPCD_GET_VERSION_RQST
14:35:14.128 [6684.4720] <2> process_requests: BPCD_DISCONNECT_RQST
14:35:14.128 [6684.4720] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:35:24.642 [6800.3148] <2> setup_debug_log: switched debug log file for bpcd
14:35:24.642 [6800.3148] <2> bpcd main: VERBOSE = 0
14:35:24.642 [6800.3148] <2> logparams: bpcd
14:35:24.642 [6800.3148] <2> bpcd main: Got socket for input 704
14:35:24.642 [6800.3148] <2> process_requests: offset to GMT -28800
14:35:24.642 [6800.3148] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.65335 TO 199.63.83.118.13724
14:35:24.642 [6800.3148] <2> process_requests: setup_sockopts complete
14:35:24.658 [6800.3148] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 65335
14:35:24.658 [6800.3148] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:35:24.658 [6800.3148] <4> bpcd valid_server: hostname comparison succeeded
14:35:24.783 [6800.3148] <2> process_requests: output socket port number = 1
14:35:35.312 [6712.7132] <2> setup_debug_log: switched debug log file for bpcd
14:35:35.312 [6712.7132] <2> bpcd main: VERBOSE = 0
14:35:35.312 [6712.7132] <2> logparams: bpcd
14:35:35.312 [6712.7132] <2> bpcd main: Got socket for input 704
14:35:35.312 [6712.7132] <2> process_requests: offset to GMT -28800
14:35:35.312 [6712.7132] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.65351 TO 199.63.83.118.13724
14:35:35.312 [6712.7132] <2> process_requests: setup_sockopts complete
14:35:35.328 [6712.7132] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 65351
14:35:35.328 [6712.7132] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:35:35.328 [6712.7132] <4> bpcd valid_server: hostname comparison succeeded
14:35:35.328 [6800.3148] <2> process_requests: Duplicated vnetd socket on stderr
14:35:35.328 [6800.3148] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:35:35.328 [6800.3148] <2> process_requests: VERBOSE = 0
14:35:35.328 [6800.3148] <2> process_requests: Not using VxSS authentication with sn01w0009
14:35:35.453 [6712.7132] <2> process_requests: output socket port number = 1
14:35:35.921 [6800.3148] <2> process_requests: BPCD_GET_VERSION_RQST
14:35:36.155 [6800.3148] <2> process_requests: BPCD_GET_CHARSET_RQST
14:35:36.155 [6800.3148] <16> process_requests: put_long of locale code succeeded.  locale code is: 84
14:35:36.373 [6800.3148] <2> process_requests: BPCD_DISCONNECT_RQST
14:35:36.373 [6800.3148] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:35:45.998 [6712.7132] <2> process_requests: Duplicated vnetd socket on stderr
14:35:45.998 [6712.7132] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:35:45.998 [6712.7132] <2> process_requests: VERBOSE = 0
14:35:45.998 [6712.7132] <2> process_requests: Not using VxSS authentication with sn01w0009
14:35:46.232 [6712.7132] <2> process_requests: BPCD_GET_VERSION_RQST
14:35:46.451 [6712.7132] <2> process_requests: BPCD_GET_CHARSET_RQST
14:35:46.451 [6712.7132] <16> process_requests: put_long of locale code succeeded.  locale code is: 84
14:35:46.669 [6712.7132] <2> process_requests: BPCD_DISCONNECT_RQST
14:35:46.669 [6712.7132] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:35:56.996 [5860.5804] <2> setup_debug_log: switched debug log file for bpcd
14:35:56.996 [5860.5804] <2> bpcd main: VERBOSE = 0
14:35:56.996 [5860.5804] <2> logparams: bpcd
14:35:56.996 [5860.5804] <2> bpcd main: Got socket for input 704
14:35:56.996 [5860.5804] <2> process_requests: offset to GMT -28800
14:35:56.996 [5860.5804] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.65361 TO 199.63.83.118.13724
14:35:56.996 [5860.5804] <2> process_requests: setup_sockopts complete
14:35:57.012 [5860.5804] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 65361
14:35:57.012 [5860.5804] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:35:57.012 [5860.5804] <4> bpcd valid_server: hostname comparison succeeded
14:35:57.152 [5860.5804] <2> process_requests: output socket port number = 1
14:36:07.292 [6944.5228] <2> setup_debug_log: switched debug log file for bpcd
14:36:07.292 [6944.5228] <2> bpcd main: VERBOSE = 0
14:36:07.292 [6944.5228] <2> logparams: bpcd
14:36:07.292 [6944.5228] <2> bpcd main: Got socket for input 812
14:36:07.292 [6944.5228] <2> process_requests: offset to GMT -28800
14:36:07.292 [6944.5228] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.65364 TO 199.63.83.118.13724
14:36:07.292 [6944.5228] <2> process_requests: setup_sockopts complete
14:36:07.308 [6944.5228] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 65364
14:36:07.308 [6944.5228] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:36:07.308 [6944.5228] <4> bpcd valid_server: hostname comparison succeeded
14:36:07.433 [6944.5228] <2> process_requests: output socket port number = 1
14:36:07.698 [5860.5804] <2> process_requests: Duplicated vnetd socket on stderr
14:36:07.698 [5860.5804] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:36:07.698 [5860.5804] <2> process_requests: VERBOSE = 0
14:36:07.698 [5860.5804] <2> process_requests: Not using VxSS authentication with sn01w0009
14:36:08.493 [5860.5804] <2> process_requests: BPCD_HOSTNAME_RQST
14:36:08.727 [5860.5804] <2> process_requests: BPCD_GET_STDOUT_HOST_SOCKET_RQST
14:36:08.930 [5860.5804] <2> process_requests: socket port number = 1
14:36:09.960 [5860.5804] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:12.003 [5860.5804] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:15.045 [5860.5804] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:17.978 [6944.5228] <2> process_requests: Duplicated vnetd socket on stderr
14:36:17.978 [6944.5228] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:36:17.978 [6944.5228] <2> process_requests: VERBOSE = 0
14:36:17.978 [6944.5228] <2> process_requests: Not using VxSS authentication with sn01w0009
14:36:18.197 [6944.5228] <2> process_requests: BPCD_HOSTNAME_RQST
14:36:18.415 [6944.5228] <2> process_requests: BPCD_GET_STDOUT_HOST_SOCKET_RQST
14:36:18.415 [6944.5228] <2> process_requests: socket port number = 1
14:36:19.445 [6944.5228] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:20.069 [5860.5804] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:21.473 [6944.5228] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:24.499 [6944.5228] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:29.101 [5860.5804] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:29.522 [6944.5228] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:38.554 [6944.5228] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:46.105 [5860.5804] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:46.105 [5860.5804] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.271: timo: 32 0x00000020
14:36:46.105 [5860.5804] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.273: connect_refused_tries: 16 0x00000010
14:36:46.105 [5860.5804] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.297: Function failed: 10 0x0000000a
14:36:46.105 [5860.5804] <2> vnet_bind_and_connect: ../../libvlibs/vnet_bind.c.175: Function failed: 10 0x0000000a
14:36:46.105 [5860.5804] <2> vnet_connect_by_vnetd: ../../libvlibs/vnet_vnetd.c.1425: status: 10 0x0000000a
14:36:46.105 [5860.5804] <2> get_vnetd_forward_socket: vnet_connect_by_vnetd failed: 10
14:36:46.105 [5860.5804] <16> process_requests: get_vnetd_forward_socket failed: 21
14:36:55.558 [6944.5228] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.231: save_errno: 10061 0x0000274d
14:36:55.558 [6944.5228] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.271: timo: 32 0x00000020
14:36:55.558 [6944.5228] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.273: connect_refused_tries: 16 0x00000010
14:36:55.558 [6944.5228] <2> vnet_bind_and_connect_extra: ../../libvlibs/vnet_bind.c.297: Function failed: 10 0x0000000a
14:36:55.558 [6944.5228] <2> vnet_bind_and_connect: ../../libvlibs/vnet_bind.c.175: Function failed: 10 0x0000000a
14:36:55.558 [6944.5228] <2> vnet_connect_by_vnetd: ../../libvlibs/vnet_vnetd.c.1425: status: 10 0x0000000a
14:36:55.558 [6944.5228] <2> get_vnetd_forward_socket: vnet_connect_by_vnetd failed: 10
14:36:55.558 [6944.5228] <16> process_requests: get_vnetd_forward_socket failed: 21
14:37:16.197 [5868.5660] <2> setup_debug_log: switched debug log file for bpcd
14:37:16.197 [5868.5660] <2> bpcd main: VERBOSE = 0
14:37:16.197 [5868.5660] <2> logparams: bpcd
14:37:16.197 [5868.5660] <2> bpcd main: Got socket for input 704
14:37:16.197 [5868.5660] <2> process_requests: offset to GMT -28800
14:37:16.213 [5868.5660] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.50047 TO 199.63.83.118.13724
14:37:16.213 [5868.5660] <2> process_requests: setup_sockopts complete
14:37:16.213 [5868.5660] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 50047
14:37:16.213 [5868.5660] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:37:16.213 [5868.5660] <4> bpcd valid_server: hostname comparison succeeded
14:37:16.337 [5868.5660] <2> process_requests: output socket port number = 1
14:37:19.660 [4440.6388] <2> setup_debug_log: switched debug log file for bpcd
14:37:19.660 [4440.6388] <2> bpcd main: VERBOSE = 0
14:37:19.660 [4440.6388] <2> logparams: bpcd
14:37:19.660 [4440.6388] <2> bpcd main: Got socket for input 812
14:37:19.660 [4440.6388] <2> process_requests: offset to GMT -28800
14:37:19.660 [4440.6388] <2> logconnections: BPCD ACCEPT FROM 199.63.83.89.50049 TO 199.63.83.119.13724
14:37:19.660 [4440.6388] <2> process_requests: setup_sockopts complete
14:37:19.660 [4440.6388] <2> bpcd peer_hostname: Connection from host sn01w0009 (199.63.83.89) port 50049
14:37:19.676 [4440.6388] <2> bpcd valid_server: comparing sn01w0009 and sn01w0009
14:37:19.676 [4440.6388] <4> bpcd valid_server: hostname comparison succeeded
14:37:19.801 [4440.6388] <2> process_requests: output socket port number = 1
14:37:26.852 [5868.5660] <2> process_requests: Duplicated vnetd socket on stderr
14:37:26.852 [5868.5660] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:37:26.852 [5868.5660] <2> process_requests: VERBOSE = 0
14:37:26.852 [5868.5660] <2> process_requests: Not using VxSS authentication with sn01w0009
14:37:27.070 [5868.5660] <2> process_requests: BPCD_GET_VERSION_RQST
14:37:27.289 [5868.5660] <2> process_requests: BPCD_GET_VERSION_RQST
14:37:27.491 [5868.5660] <2> process_requests: BPCD_READ_USERS_CONFIG_RQST
14:37:27.710 [5868.5660] <2> process_requests: BPCD_GET_VERSION_RQST
14:37:27.913 [5868.5660] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
14:37:28.131 [5868.5660] <2> process_requests: BPCD_DISCONNECT_RQST
14:37:28.131 [5868.5660] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
14:37:30.346 [4440.6388] <2> process_requests: Duplicated vnetd socket on stderr
14:37:30.346 [4440.6388] <2> process_requests: <---- NetBackup 7.0 0 ------------initiated
14:37:30.346 [4440.6388] <2> process_requests: VERBOSE = 0
14:37:30.346 [4440.6388] <2> process_requests: Not using VxSS authentication with sn01w0009
14:37:30.596 [4440.6388] <2> process_requests: BPCD_GET_VERSION_RQST
14:37:30.799 [4440.6388] <2> process_requests: BPCD_GET_VERSION_RQST
14:37:31.017 [4440.6388] <2> process_requests: BPCD_READ_USERS_CONFIG_RQST
14:37:31.235 [4440.6388] <2> process_requests: BPCD_GET_VERSION_RQST
14:37:31.454 [4440.6388] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
14:37:31.672 [4440.6388] <2> process_requests: BPCD_DISCONNECT_RQST
14:37:31.672 [4440.6388] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
 

 

 

 

0 Kudos

Go to solution
Marianne
Level 6
Partner    VIP    Accredited Certified

‎12-16-2013 11:15 PM

bpcd log is showing connection on 199.63.83.118.

Which IP address should be used?

Please show us output of 'bpclntcmd -self' on the client.
Command is in ...\netbackup\bin.

Best to add hosts entries for the client itself in own hosts file to bind the 2 IP addresses to different hostnames.
 


Handy NetBackup Links
0 Kudos

Go to solution
john10
Level 6

‎12-17-2013 12:28 AM

Hello Marianne,

Below is the out put and i already added ip "199.63.83.119" in host for client and also master/media same, do you want me to add both the ip's in host file for client and master too?


C:\Program Files\Veritas\NetBackup\bin>bpclntcmd -self
gethostname() returned: sn01w0016
host sn01w0016: SN01W0016.global.ds.honeywell.com at 199.63.83.118 (0x76533fc7)
host sn01w0016: SN01W0016.global.ds.honeywell.com at 199.63.83.119 (0x77533fc7)
aliases:

 

0 Kudos

Go to solution
Marianne
Level 6
Partner    VIP    Accredited Certified

‎12-17-2013 12:54 AM

Best to add both IP in client's hosts file to bind each IP address to a different hostname.

You can see that the client is using the same IP address for both hostname. 
This will cause client to accept connect from server on IP 199.63.83.119 but may then attempt to connect back on 199.63.83.118 as source IP. This will cause caonnect-back to fail.

If you want to use IP address 199.63.83.119, add hosts entries on the client, e.g:

199.63.83.119 sn01w0016 SN01W0016.global.ds.honeywell.com 
199.63.83.118 sn01w0016-1 

In Client Host Properties, set Preferred Network to 199.63.83.119.

It is only necessary for Master and/or Media server to know about 199.63.83.119 sn01w0016.


Handy NetBackup Links
0 Kudos

Go to solution
john10
Level 6

‎12-17-2013 01:41 AM

Ok i will add both ip's in host but, now i tried to reinstal Nbu client on same sn01w0016 server but now it was showing as error

FYI

NBu_Installation error.png

0 Kudos

Go to solution
Marianne
Level 6
Partner    VIP    Accredited Certified

‎12-17-2013 01:51 AM

Not sure why you feel you need to reinstall?

The error is a Windows OS error - not NBU.
Please check with your Windows admin...


Handy NetBackup Links
0 Kudos

Go to solution
SymTerry
Level 6
Employee Accredited

‎12-17-2013 04:51 PM

Hello John,

As Marianne pointed out, reinstallation is not needed here. Besides that fact, the install error that your getting could mean your your not right clicking and running the install as administrator or you have restrictive group policies preventing local administrator and server admnistrator from installing MSI applications.

As Marianne also mentioned, using the Preferred Network entry on the client is the next best couse of action.

0 Kudos

Go to solution
john10
Level 6

‎12-17-2013 07:54 PM

Hello John,

No im running as Admin only by right click on .exe, yes may be that is correct reinstallation is not require but previously for 1 server the backups are failing continously with 25 so reinstallation fixed the issue. and comming to local admin groups i had full rights on the machine. hope im clear, any more suggestions regarding the issue please.

0 Kudos

Go to solution
Marianne
Level 6
Partner    VIP    Accredited Certified

‎12-17-2013 09:49 PM

NBU seemed to be running fine on this client. 

The only issues was with the 2 IP addresses on the same subnet bound to the same hostname, resulting in TCP/IP choosing the wrong IP to connect back to server connection request.
Hosts and Preferred Network entry would have fixed this.

I cannot see how reinstallation will fix this.

Reinstallation should be fine if  installation is corrupt and NBU services do not come up. This will also lead to connection errors such as status 25.


Handy NetBackup Links
0 Kudos

Go to solution
john10
Level 6

‎12-17-2013 09:54 PM

Ok Marianne, i will do require changes to use only one ip, now we are reinstalling the "OS", once it was done i will instal NBU again and update you the status. thanks

0 Kudos

Go to solution
john10
Level 6

‎12-19-2013 01:25 AM

Hello Marrianne,

I have entered both the ip's in same client host file and alos in Master server host file also again the backup are failing with same error, Any more suggestion please. and also where to set "preffered network" in client ?

 

FYI

12/19/2013 5:09:38 PM - requesting resource sn01w0009
12/19/2013 5:09:38 PM - requesting resource sn01w0009.NBU_CLIENT.MAXJOBS.sn01w0016
12/19/2013 5:09:38 PM - requesting resource sn01w0009.NBU_POLICY.MAXJOBS.test-sn01w0016
12/19/2013 5:09:38 PM - granted resource sn01w0009.NBU_CLIENT.MAXJOBS.sn01w0016
12/19/2013 5:09:38 PM - granted resource sn01w0009.NBU_POLICY.MAXJOBS.test-sn01w0016
12/19/2013 5:09:38 PM - granted resource 0113L5
12/19/2013 5:09:38 PM - granted resource IBM.ULT3580-HH5.001
12/19/2013 5:09:38 PM - granted resource sn01w0009
12/19/2013 5:09:38 PM - estimated 0 Kbytes needed
12/19/2013 5:09:38 PM - started process bpbrm (1492)
12/19/2013 5:12:49 PM - mounting 0113L5
12/19/2013 5:13:21 PM - connecting
12/19/2013 5:13:21 PM - mounted; mount time: 00:00:32
12/19/2013 5:13:21 PM - positioning 0113L5 to file 1187
12/19/2013 5:14:30 PM - Error bpbrm(pid=7236) cannot create data socket, The operation completed successfully.  (0)   
12/19/2013 5:15:22 PM - positioned 0113L5; position time: 00:02:01
12/19/2013 5:15:22 PM - end writing
cannot connect on socket(25)

0 Kudos

Go to solution
Marianne
Level 6
Partner    VIP    Accredited Certified

‎12-19-2013 02:07 AM

Did you remember to disable Windows firewall on the client after OS reinstall?

Please show us the client's hosts entries.

Have you created bpcd log folder after the reinstallation? Anything in the log?

If server cannot connect to the client, I doubt that you will be able to use Host Properties from the master server.

Try Host Properties -> Clients.
Select Client name, Preferred Network

If connection from the server fails, you will have to manually add it to the Client's registry.
I can unfortunately not find any documentation of what this registry entry should look like and I don't have access to a NBU installation to check...
Hopefully someone else can help here....

TN about Preferred Network: http://www.symantec.com/docs/HOWTO60779

bpclntcmd -self on the client should now only show the specified IP address with the hostname.

Also have a look at this TN: http://www.symantec.com/docs/TECH63457


Handy NetBackup Links
0 Kudos

Go to solution
john10
Level 6

‎12-19-2013 03:36 AM

Hello Marianne,

Below are the driver entries and outputs, please verify them and suggest me to resolve the issue, and i able to load the client host properties from Master server.

 

Host Entries in client:-


199.63.83.118 sn01w0016 sn01w0016.global.ds.honeywell.com
199.63.83.119 sn01w0016 sn01w0016.global.ds.honeywell.com
199.63.83.89  sn01w0009 sn01w0009.global.ds.honeywell.com

Bpclntcmd -self output:-


C:\Program Files\Veritas\NetBackup\bin>bpclntcmd.exe -self
gethostname() returned: sn01w0016
host sn01w0016: SN01W0016.global.ds.honeywell.com at 199.63.83.118 (0x76533fc7)
host sn01w0016: SN01W0016.global.ds.honeywell.com at 199.63.83.119 (0x77533fc7)
host sn01w0016: SN01W0016.global.ds.honeywell.com at 169.254.3.246 (0xf603fea9)
aliases:

 

Client Host properties:-

1.png

 

2_0.png


3.png Bpcd Log in Client:-

 


D:\Program Files\Veritas\NetBackup\bin\admincmd>bptestbpcd -client sn01w0016 -debug -verbose
13:27:26.138 [6312.7372] <2> bptestbpcd: VERBOSE = 5
13:27:26.138 [6312.7372] <2> read_client: dname=., offline=0, online_at=0
13:27:26.138 [6312.7372] <2> read_client: dname=.., offline=0, online_at=0
13:27:26.138 [6312.7372] <2> read_client: dname=CO_0, offline=0, online_at=0
13:27:26.138 [6312.7372] <2> read_client: dname=host_info, offline=0, online_at=0
13:27:26.138 [6312.7372] <2> read_client: dname=OA_2147483647, offline=0, online_at=2147483647
13:27:26.138 [6312.7372] <2> db_freeEXDB_INFO: ?
13:27:26.153 [6312.7372] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100
13:27:26.153 [6312.7372] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100
13:27:26.153 [6312.7372] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0
13:27:26.153 [6312.7372] <2> vnet_pcache_init_table: ../../libvlibs/vnet_private.c.240: 0: starting cache size: 200 0x000000c8
13:27:26.153 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5661: 0: fopen() failed: 2 0x00000002
13:27:26.153 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5662: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\
13:27:27.105 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1317: 0: found via getaddrinfo name: sn01w0016
13:27:27.105 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1319: 0: found via getaddrinfo service: NULL
13:27:27.120 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5661: 0: fopen() failed: 2 0x00000002
13:27:27.120 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5662: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\
13:27:27.120 [6312.7372] <2> retry_getaddrinfo_for_real: ../../libvlibs/vnet_addrinfo.c.793: 0: getaddrinfo() failed: 10109 0x0000277d
13:27:27.120 [6312.7372] <2> retry_getaddrinfo_for_real: ../../libvlibs/vnet_addrinfo.c.795: 0: getaddrinfo() failed name: NULL
13:27:27.120 [6312.7372] <2> retry_getaddrinfo_for_real: ../../libvlibs/vnet_addrinfo.c.797: 0: getaddrinfo() failed service: veritas_pbx
13:27:27.120 [6312.7372] <2> get_sig_pbx_port: ../../libvlibs/vnet_addrinfo.c.3604: 0: RegOpenKeyEx failed: 2 0x00000002
13:27:27.120 [6312.7372] <2> get_sig_pbx_port: ../../libvlibs/vnet_addrinfo.c.3605: 0: VNET_PBX_REGISTRY: Software\VERITAS\VxPBX
13:27:27.120 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.646: 0: using IANA service: veritas_pbx
13:27:27.120 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.647: 0: using IANA port: 1556 0x00000614
13:27:27.120 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.673: 0: adjusted service name: 1556
13:27:27.120 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1317: 0: found via getaddrinfo name: sn01w0016
13:27:27.120 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1319: 0: found via getaddrinfo service: veritas_pbx
13:27:27.136 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5661: 0: fopen() failed: 2 0x00000002
13:27:27.136 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5662: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\
13:27:27.136 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1317: 0: found via getaddrinfo name: 199.63.83.119
13:27:27.136 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1319: 0: found via getaddrinfo service: NULL
13:27:27.136 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:27:27.136 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:27:27.152 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:27:27.152 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5661: 0: fopen() failed: 2 0x00000002
13:27:27.152 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5662: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\
13:27:27.152 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1317: 0: found via getaddrinfo name: 199.63.83.118
13:27:27.152 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1319: 0: found via getaddrinfo service: NULL
13:27:27.152 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.118
13:27:27.152 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:27:27.167 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:27:27.167 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5661: 0: fopen() failed: 2 0x00000002
13:27:27.167 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5662: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\
13:27:27.167 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1317: 0: found via getaddrinfo name: 159.99.129.170
13:27:27.167 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1319: 0: found via getaddrinfo service: NULL
13:27:27.167 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 159.99.129.170
13:27:27.167 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:27:27.183 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:27:27.183 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5661: 0: fopen() failed: 2 0x00000002
13:27:27.183 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5662: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\
13:27:27.183 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.652: 0: using service: vnetd
13:27:27.183 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.669: 0: using port: 13724 0x0000359c
13:27:27.183 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.673: 0: adjusted service name: 13724
13:27:27.183 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1317: 0: found via getaddrinfo name: sn01w0016
13:27:27.183 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1319: 0: found via getaddrinfo service: vnetd
13:27:27.183 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:27:27.183 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:27:27.183 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:27:27.183 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.118
13:27:27.183 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:27:27.183 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:27:27.183 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 159.99.129.170
13:27:27.183 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:27:27.183 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:27:27.183 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5661: 0: fopen() failed: 2 0x00000002
13:27:27.198 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5662: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\
13:27:27.198 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.652: 0: using service: bpcd
13:27:27.198 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.669: 0: using port: 13782 0x000035d6
13:27:27.198 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.673: 0: adjusted service name: 13782
13:27:27.198 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1317: 0: found via getaddrinfo name: sn01w0016
13:27:27.198 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1319: 0: found via getaddrinfo service: bpcd
13:27:27.198 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:27:27.198 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:27:27.198 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:27:27.198 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.118
13:27:27.198 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:27:27.198 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:27:27.198 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 159.99.129.170
13:27:27.198 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:27:27.198 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:27:27.198 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 1 0x00000001
13:27:37.214 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 2 0x00000002
13:27:47.229 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 3 0x00000003
13:27:57.245 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 4 0x00000004
13:28:07.260 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 5 0x00000005
13:28:17.275 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 6 0x00000006
13:28:27.291 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 7 0x00000007
13:28:37.306 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 8 0x00000008
13:28:47.322 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 9 0x00000009
13:29:04.341 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:29:04.341 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:29:04.341 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:29:04.341 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:29:04.341 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:29:04.341 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:29:04.341 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:29:04.341 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:29:08.351 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:29:09.365 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 1 0x00000001
13:29:19.380 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 2 0x00000002
13:29:29.396 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 3 0x00000003
13:29:39.411 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 4 0x00000004
13:29:49.426 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 5 0x00000005
13:29:59.442 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 6 0x00000006
13:30:09.457 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 7 0x00000007
13:30:19.473 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 8 0x00000008
13:30:29.488 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 9 0x00000009
13:30:39.503 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:30:39.503 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:30:39.503 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:30:39.503 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:30:39.503 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:30:39.503 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:30:39.503 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:30:40.517 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:30:50.533 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 2 0x00000002
13:31:00.548 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 3 0x00000003
13:31:10.564 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 4 0x00000004
13:31:20.579 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 5 0x00000005
13:31:30.594 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 6 0x00000006
13:31:40.610 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 7 0x00000007
13:31:50.625 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 8 0x00000008
13:32:00.640 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 9 0x00000009
13:32:10.656 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:32:12.668 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:32:12.668 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:32:12.668 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:32:12.668 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:32:12.668 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:32:12.668 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:32:12.668 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:32:21.654 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1316: 0: getsockopt SO_ERROR returned: 10060 0x0000274c
13:32:21.654 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 1 0x00000001
13:32:31.669 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 2 0x00000002
13:32:41.685 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 3 0x00000003
13:32:51.700 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 4 0x00000004
13:33:01.716 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 5 0x00000005
13:33:01.794 [6312.7372] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2196: 0: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
13:33:01.794 [6312.7372] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2210: 0: service: bpcd
13:33:02.028 [6312.7372] <2> do_vnetd_service: ../../libvlibs/vnet_connect.c.1618: 0: via VNETD: bpcd CONNECT FROM 199.63.83.89.62626 TO 199.63.83.119.13
13:33:02.028 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1307: 0: connect: async CONNECT FROM 199.63.83.89.62626 TO 199.63.83.119.1
13:33:02.028 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: sn01w0016
13:33:02.028 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:33:02.028 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: NULL
13:33:02.028 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: veritas_pbx
13:33:02.028 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1259: 0: found in file cache name: NULL
13:33:02.028 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1260: 0: found in file cache service: vnetd
13:33:02.028 [6312.7372] <2> logconnections: BPCD CONNECT FROM 199.63.83.89.62626 TO 199.63.83.119.13724 fd = 680
13:33:02.028 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:33:02.028 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:33:02.028 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5661: 0: fopen() failed: 2 0x00000002
13:33:02.028 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5662: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\
13:33:02.028 [6312.7372] <2> retry_getaddrinfo_for_real: ../../libvlibs/vnet_addrinfo.c.793: 0: getaddrinfo() failed: 10109 0x0000277d
13:33:02.028 [6312.7372] <2> retry_getaddrinfo_for_real: ../../libvlibs/vnet_addrinfo.c.795: 0: getaddrinfo() failed name: NULL
13:33:02.028 [6312.7372] <2> retry_getaddrinfo_for_real: ../../libvlibs/vnet_addrinfo.c.797: 0: getaddrinfo() failed service: veritas_pbx
13:33:02.028 [6312.7372] <2> get_sig_pbx_port: ../../libvlibs/vnet_addrinfo.c.3604: 0: RegOpenKeyEx failed: 2 0x00000002
13:33:02.028 [6312.7372] <2> get_sig_pbx_port: ../../libvlibs/vnet_addrinfo.c.3605: 0: VNET_PBX_REGISTRY: Software\VERITAS\VxPBX
13:33:02.028 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.646: 0: using IANA service: veritas_pbx
13:33:02.028 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.647: 0: using IANA port: 1556 0x00000614
13:33:02.028 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.673: 0: adjusted service name: 1556
13:33:02.028 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1317: 0: found via getaddrinfo name: 199.63.83.119
13:33:02.028 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1319: 0: found via getaddrinfo service: veritas_pbx
13:33:02.043 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:33:02.043 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:33:02.043 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:33:02.043 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5661: 0: fopen() failed: 2 0x00000002
13:33:02.043 [6312.7372] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.5662: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\
13:33:02.059 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.652: 0: using service: vnetd
13:33:02.059 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.669: 0: using port: 13724 0x0000359c
13:33:02.059 [6312.7372] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.673: 0: adjusted service name: 13724
13:33:02.059 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1317: 0: found via getaddrinfo name: 199.63.83.119
13:33:02.059 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1319: 0: found via getaddrinfo service: vnetd
13:33:02.059 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:33:02.059 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:33:02.059 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:33:02.074 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 1 0x00000001
13:33:12.090 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 2 0x00000002
13:33:12.168 [6312.7372] <2> do_vnetd_service: ../../libvlibs/vnet_connect.c.1615: 0: connect: VNETD CONNECT FROM 199.63.83.89.50445 TO 199.63.83.119.137
13:33:12.168 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1307: 0: connect: async CONNECT FROM 199.63.83.89.50445 TO 199.63.83.119.1
13:33:12.168 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:33:12.168 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:33:12.183 [6312.7372] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.540: 0: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000
13:33:12.386 [6312.7372] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.557: 0: ipc_string: 53617
13:33:12.589 [6312.7372] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.568: 0: hash_str1: 18e6b04e29b02b8bb74b8dca0289d8b4
13:33:12.823 [6312.7372] <2> local_bpcr_connect: expected reserved port, got 13724
13:33:13.026 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
13:33:13.026 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: veritas_pbx
13:33:13.026 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
13:33:13.026 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: vnetd
1 1 1
199.63.83.89:62626 -> 199.63.83.119:13724
13:33:13.026 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
13:33:13.026 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: vnetd
13:33:13.026 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
13:33:13.026 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: veritas_pbx
199.63.83.89:50445 -> 199.63.83.119:13724
13:33:13.244 [6312.7372] <2> bpcr_get_peername_rqst: Server peername length = 9
13:33:13.463 [6312.7372] <2> bpcr_get_hostname_rqst: Server hostname length = 9
13:33:13.681 [6312.7372] <2> bpcr_get_clientname_rqst: Server client name length = 9
13:33:13.899 [6312.7372] <2> bpcr_get_version_rqst: bpcd version: 07000000
13:33:14.118 [6312.7372] <2> bpcr_get_platform_rqst: Server client platform length = 7
13:33:14.336 [6312.7372] <2> bpcr_get_version_rqst: bpcd version: 07000000
13:33:14.586 [6312.7372] <2> bpcr_patch_version_rqst: theRest == > <
13:33:14.586 [6312.7372] <2> bpcr_get_version_rqst: bpcd version: 07000000
13:33:15.023 [6312.7372] <2> bpcr_patch_version_rqst: theRest == > <
13:33:15.038 [6312.7372] <2> bpcr_get_version_rqst: bpcd version: 07000000
PEER_NAME = sn01w0009
HOST_NAME = sn01w0016
CLIENT_NAME = sn01w0016
VERSION = 0x07000000
PLATFORM = win_x64
PATCH_VERSION = 7.0.0.0
SERVER_PATCH_VERSION = 7.0.0.0
MASTER_SERVER = sn01w0009
EMM_SERVER = sn01w0009
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: veritas_pbx
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: vnetd
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: veritas_pbx
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: NULL
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: vnetd
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: veritas_pbx
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:33:15.491 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:33:15.506 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:33:15.506 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:33:15.506 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: vnetd
13:33:15.506 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:33:15.506 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:33:15.506 [6312.7372] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.3963: 0: using interface : ANY
13:33:15.506 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 1 0x00000001
13:33:25.522 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1140: 0: connect in progress: 2 0x00000002
13:33:25.600 [6312.7372] <2> do_vnetd_service: ../../libvlibs/vnet_connect.c.1615: 0: connect: VNETD CONNECT FROM 199.63.83.89.54315 TO 199.63.83.119.137
13:33:25.600 [6312.7372] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1307: 0: connect: async CONNECT FROM 199.63.83.89.54315 TO 199.63.83.119.1
13:33:25.600 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1121: 0: found in cache name: 199.63.83.119
13:33:25.600 [6312.7372] <2> vnet_cached_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.1122: 0: found in cache service: NULL
13:33:25.600 [6312.7372] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.540: 0: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000
13:33:25.818 [6312.7372] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.557: 0: ipc_string: 53620
13:33:26.037 [6312.7372] <2> vnet_vnetd_connect_forward_socket_begin: ../../libvlibs/vnet_vnetd.c.568: 0: hash_str1: fd8bada8b2c4b18754e50b0b4a89b013
199.63.83.89:54315 -> 199.63.83.119:13724
<2>bptestbpcd: EXIT status = 0
13:33:26.271 [6312.7372] <2> bptestbpcd: EXIT status = 0

 

0 Kudos

Go to solution
john10
Level 6

‎12-19-2013 09:12 PM

Hello Marianne,

We disabled windows firewall and tried for backup and restore, its working fine, but when i use firewall enadle the backuop are again failing with same status code 25, could you please suggest us to make smooth go on with firewall enable? i already opened the ports, 13782, 13724, 13720 in both inbound and outbound rules in firewall on client. thanks in advance.

0 Kudos

Go to solution
Marianne
Level 6
Partner    VIP    Accredited Certified

‎12-19-2013 09:26 PM

Please leave firewall disabled.

Your Company external firewall provides protection for all of your servers.

bpcd log file seems to indicate that the firewall is preventing socket setup with the 10060 TCP errors.

 


Handy NetBackup Links
0 Kudos