Video Screencast Help
Symantec to Separate Into Two Focused, Industry-Leading Technology Companies. Learn more.

Restoring Exchange Mailbox GRT - Error Status 2810

Created: 21 Mar 2013 • Updated: 01 Apr 2013 | 16 comments
This issue has been solved. See solution.

We are receiving good nightly GRT backups (always doing fulls nightly).  Today, I attempted to start a restore and I was able to drill down and see all the emails in the backup (full backup) but when I started the restore, I get: 

      10:11:32 INF - Server status = 2810
      10:11:32 (126434.xxx) INF - Status = MS-Exchange-Server policy restore error.

Any idea?

Thanks

--Keith

 

The log
----------

10:10:43 3/21/2013: Restore Started

10:11:14 (126434.001) Restoring from copy 1 of image created 3/19/2013 12:31:21 AM
10:11:21 (126434.001) Status of restore from copy 1 of image created 3/19/2013 12:31:21 AM = access to the client was not allowed

10:11:21 (126434.001) The following files/folders were not restored:
10:11:21 (126434.001) UTF - /Microsoft Information Store/MB04_DB01/Database/Bobby Flake [bflake]/Top of Information Store/Inbox/

10:11:22 (126434.001) Retrying restore from copy 1 of image created 3/19/2013 12:31:21 AM
10:11:29 (126434.001) Status of restore from copy 1 of image created 3/19/2013 12:31:21 AM = access to the client was not allowed

10:11:29 (126434.001) The following files/folders were not restored:
10:11:29 (126434.001) UTF - /Microsoft Information Store/MB04_DB01/Database/Bobby Flake [bflake]/Top of Information Store/Inbox/

10:11:32 INF - Server status = 2810
10:11:32 (126434.xxx) INF - Status = MS-Exchange-Server policy restore error.

Operating Systems:

Comments 16 CommentsJump to latest comment

Kwinter's picture

This exchange server is version 2010.  No DAG.

I am going thur Marianne's info regarding changing media server (her post from Jun-2010): 

  • C:\>bperror -S 59 -r
  • access to the client was not allowed
  • The master or the media server tries to access the client, but the client does not recognize the server as a valid server.
  • The restore is by default sent to the media server that did the backup. It seems the 2007 server does not recognise the 2003 server as a valid NBU server.
  • If you want the new media server to do the restores, you will have to change ownership of media:
  • bpmedia -m <media-id> -movedb -m <media_id> -newserver <hostname>.
  • I hope you have confirmed rest of requirements for mailbox restores to a new server? This is covered in the NBU for Exchange Admin Guide. You also did not mention your NBU version - hope you have double-checked NBU/ Exchange compatibility?
Mark_Solutions's picture

Where are you running the restore from - it should be done from the Master or the client itself and not a remote admin console

Setup bpcd logging on the client so that we can see exactly what it says when you try the restore

Authorised Symantec Consultant

Don't forget to "Mark as Solution" if someones advice has solved your issue - and please bring back the Thumbs Up!!.

Kwinter's picture

I ran the restore from the master server and the media server where the backup was created from.

Same result.  Tried again running the restore from the master server (right-click "run as administrator") and "bpcd" enabled verbose logging.

below is the bpcd log verbose from the media server where the backup was created from:

----------------------------------------------

12:20:59.918 [5572.2284] <2> setup_debug_log: switched debug log file for bpcd
12:20:59.918 [5572.2284] <2> bpcd main: VERBOSE = 5
12:20:59.918 [5572.2284] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:20:59.918 [5572.2284] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:20:59.918 [5572.2284] <2> process_requests: offset to GMT 18000
12:20:59.933 [5572.2284] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55318 TO 10.10.2.104.1556 fd = 536
12:20:59.933 [5572.2284] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:20:59.933 [5572.2284] <2> process_requests: setup_sockopts complete
12:20:59.933 [5572.2284] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:20:59.933 [5572.2284] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:20:59.933 [5572.2284] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55318
12:20:59.933 [5572.2284] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:20:59.933 [5572.2284] <4> bpcd valid_server: hostname comparison succeeded
12:20:59.933 [5572.2284] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:00.121 [5572.2284] <2> process_requests: output socket port number = 1
12:21:00.557 [5572.2284] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:00.557 [5572.2284] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56540,1,400,2,1,0+127.0.0.1.txt
12:21:00.557 [5572.2284] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56540
12:21:00.557 [5572.2284] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:00.557 [5572.2284] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:00.557 [5572.2284] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:00.557 [5572.2284] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56541 TO 127.0.0.1.56540 fd = 488
12:21:00.557 [5572.2284] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56540
12:21:00.557 [5572.2284] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 50fb681885916bfe286f91f7a46df450
12:21:00.557 [5572.2284] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 7dd6091f8f9414f470ff0bcdabb949d1
12:21:00.557 [5572.2284] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 50fb681885916bfe286f91f7a46df450
12:21:00.557 [5572.2284] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 7dd6091f8f9414f470ff0bcdabb949d1
12:21:00.557 [5572.2284] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str 50fb681885916bfe286f91f7a46df450
12:21:00.557 [5572.2284] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:21:00.557 [5572.2284] <2> process_requests: Duplicated vnetd socket on stderr
12:21:00.557 [5572.2284] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:00.557 [5572.2284] <2> process_requests: VERBOSE = 5
12:21:00.557 [5572.2284] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:00.651 [5572.2284] <2> process_requests:
12:21:00.760 [5572.2284] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:21:00.979 [5572.2284] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\jobs\NBWIN002.j
12:21:00.979 [5572.2284] <2> process_requests: buffer length = 2048
12:21:00.979 [5572.2284] <2> process_requests: our open flags = 0x119
12:21:00.979 [5572.2284] <2> process_requests: client open flags = 0x109
12:21:00.979 [5572.2284] <2> process_requests: completed the write of the file number to the output socket
12:21:01.181 [5572.2284] <2> process_requests: BPCD_WRITE_RQST
12:21:01.400 [5572.2284] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:21:01.618 [5572.2284] <2> process_requests: BPCD_DISCONNECT_RQST
12:21:01.618 [5572.2284] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:03.615 [6808.4244] <2> setup_debug_log: switched debug log file for bpcd
12:21:03.615 [6808.4244] <2> bpcd main: VERBOSE = 5
12:21:03.615 [6808.4244] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:03.615 [6808.4244] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:03.615 [6808.4244] <2> process_requests: offset to GMT 18000
12:21:03.631 [6808.4244] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55321 TO 10.10.2.104.1556 fd = 536
12:21:03.631 [6808.4244] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:03.631 [6808.4244] <2> process_requests: setup_sockopts complete
12:21:03.631 [6808.4244] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:03.631 [6808.4244] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:03.631 [6808.4244] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55321
12:21:03.631 [6808.4244] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:03.631 [6808.4244] <4> bpcd valid_server: hostname comparison succeeded
12:21:03.631 [6808.4244] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:03.802 [6808.4244] <2> process_requests: output socket port number = 1
12:21:04.270 [6808.4244] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:04.270 [6808.4244] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56547,1,400,2,1,0+127.0.0.1.txt
12:21:04.270 [6808.4244] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56547
12:21:04.270 [6808.4244] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:04.270 [6808.4244] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:04.270 [6808.4244] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:04.270 [6808.4244] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56548 TO 127.0.0.1.56547 fd = 488
12:21:04.270 [6808.4244] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56547
12:21:04.270 [6808.4244] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 b4327c03118d23da21056481584cff32
12:21:04.270 [6808.4244] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 73c9b42584e034c29f562afa90c794f4
12:21:04.270 [6808.4244] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 b4327c03118d23da21056481584cff32
12:21:04.270 [6808.4244] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 73c9b42584e034c29f562afa90c794f4
12:21:04.270 [6808.4244] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str b4327c03118d23da21056481584cff32
12:21:04.270 [6808.4244] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:21:04.270 [6808.4244] <2> process_requests: Duplicated vnetd socket on stderr
12:21:04.270 [6808.4244] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:04.270 [6808.4244] <2> process_requests: VERBOSE = 5
12:21:04.270 [6808.4244] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:04.302 [6808.4244] <2> process_requests:
12:21:04.473 [6808.4244] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:21:04.692 [6808.4244] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\logs\NBWIN002
12:21:04.692 [6808.4244] <2> process_requests: buffer length = 4096
12:21:04.692 [6808.4244] <2> process_requests: our open flags = 0x119
12:21:04.692 [6808.4244] <2> process_requests: client open flags = 0x109
12:21:04.692 [6808.4244] <2> process_requests: completed the write of the file number to the output socket
12:21:04.910 [6808.4244] <2> process_requests: BPCD_LOG_RQST
12:21:04.941 [5692.6136] <2> setup_debug_log: switched debug log file for bpcd
12:21:04.941 [5692.6136] <2> bpcd main: VERBOSE = 5
12:21:04.941 [5692.6136] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:04.941 [5692.6136] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:04.941 [5692.6136] <2> process_requests: offset to GMT 18000
12:21:04.941 [5692.6136] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55323 TO 10.10.2.104.1556 fd = 536
12:21:04.941 [5692.6136] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:04.941 [5692.6136] <2> process_requests: setup_sockopts complete
12:21:04.941 [5692.6136] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:04.941 [5692.6136] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:04.941 [5692.6136] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55323
12:21:04.941 [5692.6136] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:04.941 [5692.6136] <4> bpcd valid_server: hostname comparison succeeded
12:21:04.941 [5692.6136] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:05.128 [5692.6136] <2> process_requests: output socket port number = 1
12:21:05.581 [5692.6136] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:05.581 [5692.6136] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56549,1,400,2,1,0+127.0.0.1.txt
12:21:05.581 [5692.6136] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56549
12:21:05.581 [5692.6136] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:05.581 [5692.6136] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:05.581 [5692.6136] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:05.581 [5692.6136] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56550 TO 127.0.0.1.56549 fd = 488
12:21:05.581 [5692.6136] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56549
12:21:05.581 [5692.6136] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 5a9e702ad2efb26458a77d72613427b2
12:21:05.581 [5692.6136] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 44e36cc42c6a7545fb84201e653ae6fa
12:21:05.581 [5692.6136] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 5a9e702ad2efb26458a77d72613427b2
12:21:05.581 [5692.6136] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 44e36cc42c6a7545fb84201e653ae6fa
12:21:05.581 [5692.6136] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str 5a9e702ad2efb26458a77d72613427b2
12:21:05.581 [5692.6136] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:21:05.581 [5692.6136] <2> process_requests: Duplicated vnetd socket on stderr
12:21:05.581 [5692.6136] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:05.581 [5692.6136] <2> process_requests: VERBOSE = 5
12:21:05.581 [5692.6136] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:05.612 [5692.6136] <2> process_requests:
12:21:05.784 [5692.6136] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:21:06.002 [5692.6136] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\jobs\NBWIN002.j
12:21:06.002 [5692.6136] <2> process_requests: buffer length = 2048
12:21:06.002 [5692.6136] <2> process_requests: our open flags = 0x119
12:21:06.002 [5692.6136] <2> process_requests: client open flags = 0x109
12:21:06.002 [5692.6136] <2> process_requests: completed the write of the file number to the output socket
12:21:06.220 [5692.6136] <2> process_requests: BPCD_WRITE_RQST
12:21:06.439 [5692.6136] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:21:06.642 [5692.6136] <2> process_requests: BPCD_DISCONNECT_RQST
12:21:06.642 [5692.6136] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:06.642 [6808.4244] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:21:06.860 [6808.4244] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:21:07.078 [6808.4244] <2> process_requests: BPCD_DISCONNECT_RQST
12:21:07.078 [6808.4244] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:12.461 [608.6024] <2> setup_debug_log: switched debug log file for bpcd
12:21:12.461 [608.6024] <2> bpcd main: VERBOSE = 5
12:21:12.461 [608.6024] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:12.461 [608.6024] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:12.461 [608.6024] <2> process_requests: offset to GMT 18000
12:21:12.461 [608.6024] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55326 TO 10.10.2.104.1556 fd = 568
12:21:12.461 [608.6024] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:12.461 [608.6024] <2> process_requests: setup_sockopts complete
12:21:12.461 [608.6024] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:12.461 [608.6024] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:12.461 [608.6024] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55326
12:21:12.461 [608.6024] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:12.461 [608.6024] <4> bpcd valid_server: hostname comparison succeeded
12:21:12.461 [608.6024] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:12.632 [608.6024] <2> process_requests: output socket port number = 1
12:21:13.100 [608.6024] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:13.100 [608.6024] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56554,1,400,2,1,0+127.0.0.1.txt
12:21:13.100 [608.6024] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56554
12:21:13.100 [608.6024] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:13.100 [608.6024] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:13.100 [608.6024] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:13.100 [608.6024] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56555 TO 127.0.0.1.56554 fd = 488
12:21:13.100 [608.6024] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56554
12:21:13.100 [608.6024] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 061e367bc639f749dbe9f8a4cbe976ae
12:21:13.100 [608.6024] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 cb443d47065422a912a40cbe9efad82e
12:21:13.100 [608.6024] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 061e367bc639f749dbe9f8a4cbe976ae
12:21:13.100 [608.6024] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 cb443d47065422a912a40cbe9efad82e
12:21:13.100 [608.6024] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str 061e367bc639f749dbe9f8a4cbe976ae
12:21:13.100 [608.6024] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:21:13.100 [608.6024] <2> process_requests: Duplicated vnetd socket on stderr
12:21:13.100 [608.6024] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:13.100 [608.6024] <2> process_requests: VERBOSE = 5
12:21:13.100 [608.6024] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:13.131 [608.6024] <2> process_requests:
12:21:13.303 [608.6024] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:21:13.521 [608.6024] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\jobs\NBWIN003.j
12:21:13.521 [608.6024] <2> process_requests: buffer length = 2048
12:21:13.521 [608.6024] <2> process_requests: our open flags = 0x119
12:21:13.521 [608.6024] <2> process_requests: client open flags = 0x109
12:21:13.521 [608.6024] <2> process_requests: completed the write of the file number to the output socket
12:21:13.740 [608.6024] <2> process_requests: BPCD_WRITE_RQST
12:21:13.958 [608.6024] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:21:14.161 [608.6024] <2> process_requests: BPCD_DISCONNECT_RQST
12:21:14.161 [608.6024] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:14.192 [744.6636] <2> setup_debug_log: switched debug log file for bpcd
12:21:14.192 [744.6636] <2> bpcd main: VERBOSE = 5
12:21:14.192 [744.6636] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:14.192 [744.6636] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:14.192 [744.6636] <2> process_requests: offset to GMT 18000
12:21:14.192 [744.6636] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55328 TO 10.10.2.104.1556 fd = 568
12:21:14.192 [744.6636] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:14.192 [744.6636] <2> process_requests: setup_sockopts complete
12:21:14.192 [744.6636] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:14.192 [744.6636] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:14.192 [744.6636] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55328
12:21:14.192 [744.6636] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:14.192 [744.6636] <4> bpcd valid_server: hostname comparison succeeded
12:21:14.192 [744.6636] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:14.364 [744.6636] <2> process_requests: output socket port number = 1
12:21:14.832 [744.6636] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:14.832 [744.6636] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56556,1,400,2,1,0+127.0.0.1.txt
12:21:14.832 [744.6636] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56556
12:21:14.832 [744.6636] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:14.832 [744.6636] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:14.832 [744.6636] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:14.832 [744.6636] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56557 TO 127.0.0.1.56556 fd = 488
12:21:14.832 [744.6636] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56556
12:21:14.832 [744.6636] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 fd9de578bde03ffc384d47658d69cade
12:21:14.832 [744.6636] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 db789ec4143f5d7fd915095e658c1cd2
12:21:14.832 [744.6636] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 fd9de578bde03ffc384d47658d69cade
12:21:14.832 [744.6636] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 db789ec4143f5d7fd915095e658c1cd2
12:21:14.832 [744.6636] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str fd9de578bde03ffc384d47658d69cade
12:21:14.832 [744.6636] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  508 0x1fc
12:21:14.832 [744.6636] <2> process_requests: Duplicated vnetd socket on stderr
12:21:14.832 [744.6636] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:14.832 [744.6636] <2> process_requests: VERBOSE = 5
12:21:14.832 [744.6636] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:14.863 [744.6636] <2> process_requests:
12:21:15.019 [744.6636] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:21:15.238 [744.6636] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\jobs\NBWIN003.j
12:21:15.238 [744.6636] <2> process_requests: buffer length = 2048
12:21:15.238 [744.6636] <2> process_requests: our open flags = 0x119
12:21:15.238 [744.6636] <2> process_requests: client open flags = 0x109
12:21:15.238 [744.6636] <2> process_requests: completed the write of the file number to the output socket
12:21:15.440 [744.6636] <2> process_requests: BPCD_WRITE_RQST
12:21:15.659 [744.6636] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:21:15.862 [744.6636] <2> process_requests: BPCD_DISCONNECT_RQST
12:21:15.862 [744.6636] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:17.344 [1980.3104] <2> setup_debug_log: switched debug log file for bpcd
12:21:17.344 [1980.3104] <2> bpcd main: VERBOSE = 5
12:21:17.344 [1980.3104] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:17.344 [1980.3104] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:17.344 [1980.3104] <2> process_requests: offset to GMT 18000
12:21:17.359 [1980.3104] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55336 TO 10.10.2.104.1556 fd = 568
12:21:17.359 [1980.3104] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:17.359 [1980.3104] <2> process_requests: setup_sockopts complete
12:21:17.359 [1980.3104] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:17.359 [1980.3104] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:17.359 [1980.3104] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55336
12:21:17.359 [1980.3104] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:17.359 [1980.3104] <4> bpcd valid_server: hostname comparison succeeded
12:21:17.359 [1980.3104] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:17.546 [1980.3104] <2> process_requests: output socket port number = 1
12:21:17.999 [1980.3104] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:17.999 [1980.3104] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56558,1,400,2,1,0+127.0.0.1.txt
12:21:17.999 [1980.3104] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56558
12:21:17.999 [1980.3104] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:17.999 [1980.3104] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:17.999 [1980.3104] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:17.999 [1980.3104] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56559 TO 127.0.0.1.56558 fd = 488
12:21:17.999 [1980.3104] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56558
12:21:17.999 [1980.3104] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 46a797edaae02f558f3eb0660ba2abef
12:21:17.999 [1980.3104] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 d1c4480d7057ba68a7fe91b0449e9523
12:21:17.999 [1980.3104] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 46a797edaae02f558f3eb0660ba2abef
12:21:17.999 [1980.3104] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 d1c4480d7057ba68a7fe91b0449e9523
12:21:17.999 [1980.3104] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str 46a797edaae02f558f3eb0660ba2abef
12:21:17.999 [1980.3104] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  508 0x1fc
12:21:17.999 [1980.3104] <2> process_requests: Duplicated vnetd socket on stderr
12:21:17.999 [1980.3104] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:17.999 [1980.3104] <2> process_requests: VERBOSE = 5
12:21:17.999 [1980.3104] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:18.030 [1980.3104] <2> process_requests:
12:21:18.217 [1980.3104] <2> process_requests: BPCD_START_NBFSD_RQST
12:21:18.436 [1980.3104] <2> start_nbfsd: nbfsdCmd: '"D:\Program Files\Veritas\NetBackup\bin\nbfsd.exe" -k -v 5 -p -j 126483 -B vm-hdcexmb04_1363753944 -U DBM -c vm-hdcexmb04 -f hcinetbkup02 -i \\HDCDDMN04A.hagerty.com\backup\nbu7\vm-hdcexmb04_1363753944_C1_F1'
12:21:20.713 [1980.3104] <2> process_requests: BPCD_DISCONNECT_RQST
12:21:20.713 [1980.3104] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:37.437 [6640.5408] <2> setup_debug_log: switched debug log file for bpcd
12:21:37.437 [6640.5408] <2> bpcd main: VERBOSE = 5
12:21:37.437 [6640.5408] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:37.437 [6640.5408] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:37.437 [6640.5408] <2> process_requests: offset to GMT 18000
12:21:37.437 [6640.5408] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55366 TO 10.10.2.104.1556 fd = 568
12:21:37.437 [6640.5408] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:37.437 [6640.5408] <2> process_requests: setup_sockopts complete
12:21:37.437 [6640.5408] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:37.437 [6640.5408] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:37.437 [6640.5408] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55366
12:21:37.437 [6640.5408] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:37.437 [6640.5408] <4> bpcd valid_server: hostname comparison succeeded
12:21:37.437 [6640.5408] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:37.624 [6640.5408] <2> process_requests: output socket port number = 1
12:21:38.077 [6640.5408] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:38.077 [6640.5408] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56561,1,400,2,1,0+127.0.0.1.txt
12:21:38.077 [6640.5408] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56561
12:21:38.077 [6640.5408] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:38.077 [6640.5408] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:38.077 [6640.5408] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:38.077 [6640.5408] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56562 TO 127.0.0.1.56561 fd = 488
12:21:38.077 [6640.5408] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56561
12:21:38.077 [6640.5408] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 a453aca5e75a5b5e8bbaa0d948eb9e53
12:21:38.077 [6640.5408] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 517ae948fedd93c7c99143821ff6c4f4
12:21:38.077 [6640.5408] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 a453aca5e75a5b5e8bbaa0d948eb9e53
12:21:38.077 [6640.5408] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 517ae948fedd93c7c99143821ff6c4f4
12:21:38.077 [6640.5408] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str a453aca5e75a5b5e8bbaa0d948eb9e53
12:21:38.077 [6640.5408] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:21:38.077 [6640.5408] <2> process_requests: Duplicated vnetd socket on stderr
12:21:38.077 [6640.5408] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:38.077 [6640.5408] <2> process_requests: VERBOSE = 5
12:21:38.077 [6640.5408] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:38.108 [6640.5408] <2> process_requests:
12:21:38.295 [6640.5408] <2> process_requests: BPCD_HOSTNAME_RQST
12:21:38.498 [6640.5408] <2> process_requests: BPCD_DISCONNECT_RQST
12:21:38.498 [6640.5408] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:39.902 [6816.5480] <2> setup_debug_log: switched debug log file for bpcd
12:21:39.902 [6816.5480] <2> bpcd main: VERBOSE = 5
12:21:39.902 [6816.5480] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:39.902 [6816.5480] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:39.902 [6816.5480] <2> process_requests: offset to GMT 18000
12:21:39.918 [6816.5480] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55376 TO 10.10.2.104.1556 fd = 568
12:21:39.918 [6816.5480] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:39.918 [6816.5480] <2> process_requests: setup_sockopts complete
12:21:39.918 [6816.5480] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:39.918 [6816.5480] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:39.918 [6816.5480] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55376
12:21:39.918 [6816.5480] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:39.918 [6816.5480] <4> bpcd valid_server: hostname comparison succeeded
12:21:39.918 [6816.5480] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:40.105 [6816.5480] <2> process_requests: output socket port number = 1
12:21:40.573 [6816.5480] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:40.573 [6816.5480] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56563,1,400,2,1,0+127.0.0.1.txt
12:21:40.573 [6816.5480] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56563
12:21:40.573 [6816.5480] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:40.573 [6816.5480] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:40.573 [6816.5480] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:40.573 [6816.5480] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56564 TO 127.0.0.1.56563 fd = 488
12:21:40.573 [6816.5480] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56563
12:21:40.573 [6816.5480] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 c4c04ea90e16cc88f41ec8fb21860257
12:21:40.573 [6816.5480] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 5134028d281f413605ac0dca5c6dd1b4
12:21:40.573 [6816.5480] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 c4c04ea90e16cc88f41ec8fb21860257
12:21:40.573 [6816.5480] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 5134028d281f413605ac0dca5c6dd1b4
12:21:40.573 [6816.5480] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str c4c04ea90e16cc88f41ec8fb21860257
12:21:40.573 [6816.5480] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:21:40.573 [6816.5480] <2> process_requests: Duplicated vnetd socket on stderr
12:21:40.573 [6816.5480] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:40.573 [6816.5480] <2> process_requests: VERBOSE = 5
12:21:40.573 [6816.5480] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:40.604 [6816.5480] <2> process_requests:
12:21:40.776 [6816.5480] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:21:40.994 [6816.5480] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\logs\NBWIN003
12:21:40.994 [6816.5480] <2> process_requests: buffer length = 4096
12:21:40.994 [6816.5480] <2> process_requests: our open flags = 0x119
12:21:40.994 [6816.5480] <2> process_requests: client open flags = 0x109
12:21:40.994 [6816.5480] <2> process_requests: completed the write of the file number to the output socket
12:21:42.273 [6816.5480] <2> process_requests: BPCD_LOG_RQST
12:21:42.523 [6940.6836] <2> setup_debug_log: switched debug log file for bpcd
12:21:42.523 [6940.6836] <2> bpcd main: VERBOSE = 5
12:21:42.523 [6940.6836] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:42.523 [6940.6836] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:42.523 [6940.6836] <2> process_requests: offset to GMT 18000
12:21:42.523 [6940.6836] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55389 TO 10.10.2.104.1556 fd = 568
12:21:42.523 [6940.6836] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:42.523 [6940.6836] <2> process_requests: setup_sockopts complete
12:21:42.538 [6940.6836] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:42.538 [6940.6836] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:42.538 [6940.6836] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55389
12:21:42.538 [6940.6836] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:42.538 [6940.6836] <4> bpcd valid_server: hostname comparison succeeded
12:21:42.538 [6940.6836] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:42.710 [6940.6836] <2> process_requests: output socket port number = 1
12:21:43.162 [6940.6836] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:43.162 [6940.6836] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56565,1,400,2,1,0+127.0.0.1.txt
12:21:43.162 [6940.6836] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56565
12:21:43.162 [6940.6836] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:43.162 [6940.6836] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:43.162 [6940.6836] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:43.162 [6940.6836] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56566 TO 127.0.0.1.56565 fd = 488
12:21:43.162 [6940.6836] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56565
12:21:43.162 [6940.6836] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 0dbffee419e52434b4a52c006c290652
12:21:43.162 [6940.6836] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 44a772cbda5832558fa709cec9f4cb78
12:21:43.162 [6940.6836] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 0dbffee419e52434b4a52c006c290652
12:21:43.162 [6940.6836] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 44a772cbda5832558fa709cec9f4cb78
12:21:43.162 [6940.6836] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str 0dbffee419e52434b4a52c006c290652
12:21:43.162 [6940.6836] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:21:43.162 [6940.6836] <2> process_requests: Duplicated vnetd socket on stderr
12:21:43.162 [6940.6836] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:43.162 [6940.6836] <2> process_requests: VERBOSE = 5
12:21:43.162 [6940.6836] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:43.194 [6940.6836] <2> process_requests:
12:21:43.365 [6940.6836] <2> process_requests: BPCD_FORK_CMD_RQST
12:21:43.584 [6940.6836] <2> process_requests: fork cmd = /usr/openv/netbackup/bin/bpbrm bpbrm -restore -mt 0 -mst 1 -k 1 -c EMAIL.HAGERTY.COM -hostname EMAIL.HAGERTY.COM -rclnt hcinetbkup02 -rclnthostname hcinetbkup02 -browse_clnt vm-hdcexmb04 -ru root -firstblk 0 -cl Exchange_2010_InformationStore_VM-HDCEXMB04_GRT_MB04_db01 -bt 1363753944 -st 0 -secure 1 -b vm-hdcexmb04_1363753944 -cn 1 -rst 1363882871 -to 0 -mud 180 -ct 16 -S vm-hdcnetbkup00 -masterversion 750000 -backup_copy 0 -latest_image -flport 0 -flipc 55388 -p \\HDCDDMN04A.hagerty.com\backup\nbu7 -cm -db 1 -td / -grt_enabled -rg root -L /D/Program��Files/Veritas/NetBackup/logs/user_ops/kwinter/logs/NBWIN003 -clnt_lc_messages en -clnt_lc_time en -clnt_lc_ctype en -clnt_lc_collate en -clnt_lc_numeric en -connect_options 0x01030202 -jobid 126484 -restoreid 126484.001 -job_total 1
12:21:43.584 [6940.6836] <2> process_requests: filter type = -1
12:21:43.584 [6940.6836] <2> process_requests: Convert args to CreateProcess format
12:21:43.584 [6940.6836] <2> process_requests: Done converting args to CreateProcess format
12:21:43.584 [6940.6836] <2> process_requests: new fork cmd = D:\Program Files\Veritas\NetBackup\bin\bpbrm.exe -restore -mt 0 -mst 1 -k 1 -c EMAIL.HAGERTY.COM -hostname EMAIL.HAGERTY.COM -rclnt hcinetbkup02 -rclnthostname hcinetbkup02 -browse_clnt vm-hdcexmb04 -ru root -firstblk 0 -cl Exchange_2010_InformationStore_VM-HDCEXMB04_GRT_MB04_db01 -bt 1363753944 -st 0 -secure 1 -b vm-hdcexmb04_1363753944 -cn 1 -rst 1363882871 -to 0 -mud 180 -ct 16 -S vm-hdcnetbkup00 -masterversion 750000 -backup_copy 0 -latest_image -flport 0 -flipc 55388 -p \\HDCDDMN04A.hagerty.com\backup\nbu7 -cm -db 1 -td / -grt_enabled -rg root -L /D/Program��Files/Veritas/NetBackup/logs/user_ops/kwinter/logs/NBWIN003 -clnt_lc_messages en -clnt_lc_time en -clnt_lc_ctype en -clnt_lc_collate en -clnt_lc_numeric en -connect_options 0x01030202 -jobid 126484 -restoreid 126484.001 -job_total 1
12:21:43.584 [6940.6836] <2> process_requests: Before CreateProcess
12:21:43.584 [6940.6836] <2> process_requests: StdOutput assigned the value STDOUTSOCK
12:21:43.584 [6940.6836] <2> process_requests: final fork cmd = "D:\Program Files\Veritas\NetBackup\bin\bpbrm.exe" -restore -mt 0 -mst 1 -k 1 -c EMAIL.HAGERTY.COM -hostname EMAIL.HAGERTY.COM -rclnt hcinetbkup02 -rclnthostname hcinetbkup02 -browse_clnt vm-hdcexmb04 -ru root -firstblk 0 -cl Exchange_2010_InformationStore_VM-HDCEXMB04_GRT_MB04_db01 -bt 1363753944 -st 0 -secure 1 -b vm-hdcexmb04_1363753944 -cn 1 -rst 1363882871 -to 0 -mud 180 -ct 16 -S vm-hdcnetbkup00 -masterversion 750000 -backup_copy 0 -latest_image -flport 0 -flipc 55388 -p \\HDCDDMN04A.hagerty.com\backup\nbu7 -cm -db 1 -td / -grt_enabled -rg root -L /D/Program��Files/Veritas/NetBackup/logs/user_ops/kwinter/logs/NBWIN003 -clnt_lc_messages en -clnt_lc_time en -clnt_lc_ctype en -clnt_lc_collate en -clnt_lc_numeric en -connect_options 0x01030202 -jobid 126484 -restoreid 126484.001 -job_total 1
12:21:43.584 [6940.6836] <2> process_requests: After CreateProcess, pid = 7064
12:21:43.584 [6940.6836] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:49.387 [6816.5480] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:21:49.605 [6816.5480] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:21:49.605 [6816.5480] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:21:49.605 [6816.5480] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:21:49.808 [6816.5480] <2> process_requests: BPCD_DISCONNECT_RQST
12:21:49.808 [6816.5480] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:51.025 [1932.6472] <2> setup_debug_log: switched debug log file for bpcd
12:21:51.025 [1932.6472] <2> bpcd main: VERBOSE = 5
12:21:51.025 [1932.6472] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:51.025 [1932.6472] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:51.041 [1932.6472] <2> process_requests: offset to GMT 18000
12:21:51.041 [1932.6472] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55402 TO 10.10.2.104.1556 fd = 568
12:21:51.041 [1932.6472] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:51.041 [1932.6472] <2> process_requests: setup_sockopts complete
12:21:51.041 [1932.6472] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:51.041 [1932.6472] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:51.041 [1932.6472] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55402
12:21:51.041 [1932.6472] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:51.041 [1932.6472] <4> bpcd valid_server: hostname comparison succeeded
12:21:51.041 [1932.6472] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:51.228 [1932.6472] <2> process_requests: output socket port number = 1
12:21:51.665 [1932.6472] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:51.665 [1932.6472] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56577,1,400,2,1,0+127.0.0.1.txt
12:21:51.665 [1932.6472] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56577
12:21:51.665 [1932.6472] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:51.665 [1932.6472] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:51.665 [1932.6472] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:51.665 [1932.6472] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56578 TO 127.0.0.1.56577 fd = 488
12:21:51.665 [1932.6472] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56577
12:21:51.665 [1932.6472] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 d41e06c094e417b1764179629c484b89
12:21:51.665 [1932.6472] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 dfcb734a88dcf02257cbb6919fb815bd
12:21:51.665 [1932.6472] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 d41e06c094e417b1764179629c484b89
12:21:51.665 [1932.6472] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 dfcb734a88dcf02257cbb6919fb815bd
12:21:51.665 [1932.6472] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str d41e06c094e417b1764179629c484b89
12:21:51.665 [1932.6472] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:21:51.665 [1932.6472] <2> process_requests: Duplicated vnetd socket on stderr
12:21:51.665 [1932.6472] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:51.665 [1932.6472] <2> process_requests: VERBOSE = 5
12:21:51.665 [1932.6472] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:51.696 [1932.6472] <2> process_requests:
12:21:51.868 [1932.6472] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:21:52.086 [1932.6472] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\logs\NBWIN003
12:21:52.086 [1932.6472] <2> process_requests: buffer length = 4096
12:21:52.086 [1932.6472] <2> process_requests: our open flags = 0x119
12:21:52.086 [1932.6472] <2> process_requests: client open flags = 0x109
12:21:52.086 [1932.6472] <2> process_requests: completed the write of the file number to the output socket
12:21:52.117 [3108.6380] <2> setup_debug_log: switched debug log file for bpcd
12:21:52.117 [3108.6380] <2> bpcd main: VERBOSE = 5
12:21:52.117 [3108.6380] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:52.117 [3108.6380] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:52.117 [3108.6380] <2> process_requests: offset to GMT 18000
12:21:52.133 [3108.6380] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55404 TO 10.10.2.104.1556 fd = 568
12:21:52.133 [3108.6380] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:52.133 [3108.6380] <2> process_requests: setup_sockopts complete
12:21:52.133 [3108.6380] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:52.133 [3108.6380] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:52.133 [3108.6380] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55404
12:21:52.133 [3108.6380] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:52.133 [3108.6380] <4> bpcd valid_server: hostname comparison succeeded
12:21:52.133 [3108.6380] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:52.304 [1932.6472] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:21:52.304 [3108.6380] <2> process_requests: output socket port number = 1
12:21:52.772 [3108.6380] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:52.772 [3108.6380] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56579,1,400,2,1,0+127.0.0.1.txt
12:21:52.772 [3108.6380] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56579
12:21:52.772 [3108.6380] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:52.772 [3108.6380] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:52.772 [3108.6380] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:52.772 [3108.6380] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56580 TO 127.0.0.1.56579 fd = 488
12:21:52.772 [3108.6380] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56579
12:21:52.772 [3108.6380] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 d36e32a480cf87029b8e9828a452636b
12:21:52.772 [3108.6380] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 66cbb3c007d3ad6685edb06fc0dc9978
12:21:52.772 [3108.6380] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 d36e32a480cf87029b8e9828a452636b
12:21:52.772 [3108.6380] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 66cbb3c007d3ad6685edb06fc0dc9978
12:21:52.772 [3108.6380] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str d36e32a480cf87029b8e9828a452636b
12:21:52.772 [3108.6380] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:21:52.772 [3108.6380] <2> process_requests: Duplicated vnetd socket on stderr
12:21:52.772 [3108.6380] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:52.772 [3108.6380] <2> process_requests: VERBOSE = 5
12:21:52.772 [3108.6380] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:21:52.804 [3108.6380] <2> process_requests:
12:21:52.975 [3108.6380] <2> process_requests: BPCD_FORK_CMD_RQST
12:21:53.194 [3108.6380] <2> process_requests: fork cmd = /usr/openv/netbackup/bin/bpbrm bpbrm -restore -mt 0 -mst 1 -k 1 -c EMAIL.HAGERTY.COM -hostname EMAIL.HAGERTY.COM -rclnt hcinetbkup02 -rclnthostname hcinetbkup02 -browse_clnt vm-hdcexmb04 -ru root -firstblk 0 -cl Exchange_2010_InformationStore_VM-HDCEXMB04_GRT_MB04_db01 -bt 1363753944 -st 0 -secure 1 -b vm-hdcexmb04_1363753944 -cn 1 -rst 1363882871 -to 0 -mud 180 -ct 16 -S vm-hdcnetbkup00 -masterversion 750000 -backup_copy 0 -latest_image -flport 0 -flipc 55401 -p \\HDCDDMN04A.hagerty.com\backup\nbu7 -cm -db 1 -td / -grt_enabled -rg root -L /D/Program��Files/Veritas/NetBackup/logs/user_ops/kwinter/logs/NBWIN003 -clnt_lc_messages en -clnt_lc_time en -clnt_lc_ctype en -clnt_lc_collate en -clnt_lc_numeric en -connect_options 0x01030202 -jobid 126484 -restoreid 126484.001 -job_total 1
12:21:53.194 [3108.6380] <2> process_requests: filter type = -1
12:21:53.194 [3108.6380] <2> process_requests: Convert args to CreateProcess format
12:21:53.194 [3108.6380] <2> process_requests: Done converting args to CreateProcess format
12:21:53.194 [3108.6380] <2> process_requests: new fork cmd = D:\Program Files\Veritas\NetBackup\bin\bpbrm.exe -restore -mt 0 -mst 1 -k 1 -c EMAIL.HAGERTY.COM -hostname EMAIL.HAGERTY.COM -rclnt hcinetbkup02 -rclnthostname hcinetbkup02 -browse_clnt vm-hdcexmb04 -ru root -firstblk 0 -cl Exchange_2010_InformationStore_VM-HDCEXMB04_GRT_MB04_db01 -bt 1363753944 -st 0 -secure 1 -b vm-hdcexmb04_1363753944 -cn 1 -rst 1363882871 -to 0 -mud 180 -ct 16 -S vm-hdcnetbkup00 -masterversion 750000 -backup_copy 0 -latest_image -flport 0 -flipc 55401 -p \\HDCDDMN04A.hagerty.com\backup\nbu7 -cm -db 1 -td / -grt_enabled -rg root -L /D/Program��Files/Veritas/NetBackup/logs/user_ops/kwinter/logs/NBWIN003 -clnt_lc_messages en -clnt_lc_time en -clnt_lc_ctype en -clnt_lc_collate en -clnt_lc_numeric en -connect_options 0x01030202 -jobid 126484 -restoreid 126484.001 -job_total 1
12:21:53.194 [3108.6380] <2> process_requests: Before CreateProcess
12:21:53.194 [3108.6380] <2> process_requests: StdOutput assigned the value STDOUTSOCK
12:21:53.194 [3108.6380] <2> process_requests: final fork cmd = "D:\Program Files\Veritas\NetBackup\bin\bpbrm.exe" -restore -mt 0 -mst 1 -k 1 -c EMAIL.HAGERTY.COM -hostname EMAIL.HAGERTY.COM -rclnt hcinetbkup02 -rclnthostname hcinetbkup02 -browse_clnt vm-hdcexmb04 -ru root -firstblk 0 -cl Exchange_2010_InformationStore_VM-HDCEXMB04_GRT_MB04_db01 -bt 1363753944 -st 0 -secure 1 -b vm-hdcexmb04_1363753944 -cn 1 -rst 1363882871 -to 0 -mud 180 -ct 16 -S vm-hdcnetbkup00 -masterversion 750000 -backup_copy 0 -latest_image -flport 0 -flipc 55401 -p \\HDCDDMN04A.hagerty.com\backup\nbu7 -cm -db 1 -td / -grt_enabled -rg root -L /D/Program��Files/Veritas/NetBackup/logs/user_ops/kwinter/logs/NBWIN003 -clnt_lc_messages en -clnt_lc_time en -clnt_lc_ctype en -clnt_lc_collate en -clnt_lc_numeric en -connect_options 0x01030202 -jobid 126484 -restoreid 126484.001 -job_total 1
12:21:53.194 [3108.6380] <2> process_requests: After CreateProcess, pid = 6732
12:21:53.194 [3108.6380] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:58.888 [1932.6472] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:21:59.106 [1932.6472] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:21:59.106 [1932.6472] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:21:59.106 [1932.6472] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:21:59.325 [1932.6472] <2> process_requests: BPCD_DISCONNECT_RQST
12:21:59.325 [1932.6472] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:21:59.356 [3336.6208] <2> setup_debug_log: switched debug log file for bpcd
12:21:59.356 [3336.6208] <2> bpcd main: VERBOSE = 5
12:21:59.356 [3336.6208] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:21:59.356 [3336.6208] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:21:59.356 [3336.6208] <2> process_requests: offset to GMT 18000
12:21:59.356 [3336.6208] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55410 TO 10.10.2.104.1556 fd = 568
12:21:59.356 [3336.6208] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:21:59.356 [3336.6208] <2> process_requests: setup_sockopts complete
12:21:59.356 [3336.6208] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:59.356 [3336.6208] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:21:59.356 [3336.6208] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55410
12:21:59.356 [3336.6208] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:21:59.356 [3336.6208] <4> bpcd valid_server: hostname comparison succeeded
12:21:59.356 [3336.6208] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:21:59.543 [3336.6208] <2> process_requests: output socket port number = 1
12:21:59.995 [3336.6208] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:21:59.995 [3336.6208] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56591,1,400,2,1,0+127.0.0.1.txt
12:21:59.995 [3336.6208] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56591
12:21:59.995 [3336.6208] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:21:59.995 [3336.6208] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:21:59.995 [3336.6208] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:21:59.995 [3336.6208] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56592 TO 127.0.0.1.56591 fd = 488
12:21:59.995 [3336.6208] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56591
12:21:59.995 [3336.6208] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 ad6b44f50b26051fa878a7e47822ceb5
12:21:59.995 [3336.6208] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 5ab51386b53b1ec72b7a18d26f17f005
12:21:59.995 [3336.6208] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 ad6b44f50b26051fa878a7e47822ceb5
12:21:59.995 [3336.6208] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 5ab51386b53b1ec72b7a18d26f17f005
12:21:59.995 [3336.6208] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str ad6b44f50b26051fa878a7e47822ceb5
12:21:59.995 [3336.6208] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  508 0x1fc
12:21:59.995 [3336.6208] <2> process_requests: Duplicated vnetd socket on stderr
12:21:59.995 [3336.6208] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:21:59.995 [3336.6208] <2> process_requests: VERBOSE = 5
12:21:59.995 [3336.6208] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:22:00.027 [3336.6208] <2> process_requests:
12:22:00.198 [3336.6208] <2> process_requests: BPCD_GET_VERSION_RQST
12:22:00.417 [3336.6208] <2> process_requests: BPCD_GET_VERSION_RQST
12:22:00.635 [3336.6208] <2> process_requests: BPCD_READ_USERS_CONFIG_RQST
12:22:01.072 [3336.6208] <2> process_requests: BPCD_GET_VERSION_RQST
12:22:01.290 [3336.6208] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
12:22:04.145 [3336.6208] <2> process_requests: BPCD_DISCONNECT_RQST
12:22:04.145 [3336.6208] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:22:04.192 [5412.2716] <2> setup_debug_log: switched debug log file for bpcd
12:22:04.192 [5412.2716] <2> bpcd main: VERBOSE = 5
12:22:04.192 [5412.2716] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:22:04.192 [5412.2716] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:22:04.192 [5412.2716] <2> process_requests: offset to GMT 18000
12:22:04.192 [3448.6504] <2> setup_debug_log: switched debug log file for bpcd
12:22:04.192 [3448.6504] <2> bpcd main: VERBOSE = 5
12:22:04.192 [3448.6504] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:22:04.192 [3448.6504] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:22:04.192 [3448.6504] <2> process_requests: offset to GMT 18000
12:22:04.208 [5412.2716] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55414 TO 10.10.2.104.1556 fd = 536
12:22:04.208 [5412.2716] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:22:04.208 [5412.2716] <2> process_requests: setup_sockopts complete
12:22:04.208 [5412.2716] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:22:04.208 [5412.2716] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:22:04.208 [5412.2716] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55414
12:22:04.208 [5412.2716] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:22:04.208 [5412.2716] <4> bpcd valid_server: hostname comparison succeeded
12:22:04.208 [5412.2716] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:22:04.208 [3448.6504] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55413 TO 10.10.2.104.1556 fd = 536
12:22:04.208 [3448.6504] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:22:04.208 [3448.6504] <2> process_requests: setup_sockopts complete
12:22:04.208 [3448.6504] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:22:04.208 [3448.6504] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:22:04.208 [3448.6504] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55413
12:22:04.208 [3448.6504] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:22:04.208 [3448.6504] <4> bpcd valid_server: hostname comparison succeeded
12:22:04.208 [3448.6504] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:22:04.395 [3448.6504] <2> process_requests: output socket port number = 1
12:22:04.395 [5412.2716] <2> process_requests: output socket port number = 1
12:22:04.832 [3448.6504] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:22:04.832 [3448.6504] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56593,1,400,2,1,0+127.0.0.1.txt
12:22:04.832 [3448.6504] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56593
12:22:04.832 [3448.6504] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:22:04.832 [3448.6504] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:22:04.832 [3448.6504] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:22:04.832 [3448.6504] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56595 TO 127.0.0.1.56593 fd = 488
12:22:04.832 [3448.6504] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56593
12:22:04.832 [3448.6504] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 0f75176d51048b1b17d48c587e4f8f48
12:22:04.832 [3448.6504] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 fa1fcf4eb80e79790deb02587381d6a8
12:22:04.832 [3448.6504] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 0f75176d51048b1b17d48c587e4f8f48
12:22:04.832 [3448.6504] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 fa1fcf4eb80e79790deb02587381d6a8
12:22:04.832 [3448.6504] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str 0f75176d51048b1b17d48c587e4f8f48
12:22:04.832 [3448.6504] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  508 0x1fc
12:22:04.832 [3448.6504] <2> process_requests: Duplicated vnetd socket on stderr
12:22:04.832 [3448.6504] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:22:04.832 [3448.6504] <2> process_requests: VERBOSE = 5
12:22:04.832 [3448.6504] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:22:04.847 [5412.2716] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:22:04.847 [5412.2716] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56594,1,400,2,1,0+127.0.0.1.txt
12:22:04.847 [5412.2716] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56594
12:22:04.847 [5412.2716] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:22:04.847 [5412.2716] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:22:04.847 [5412.2716] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:22:04.847 [5412.2716] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56596 TO 127.0.0.1.56594 fd = 488
12:22:04.847 [5412.2716] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56594
12:22:04.847 [5412.2716] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 e88326b817f003134bc3d0af3479409e
12:22:04.847 [5412.2716] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 d9e6c4db5f1bc2fb890624525a3bb316
12:22:04.847 [5412.2716] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 e88326b817f003134bc3d0af3479409e
12:22:04.847 [5412.2716] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 d9e6c4db5f1bc2fb890624525a3bb316
12:22:04.847 [5412.2716] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str e88326b817f003134bc3d0af3479409e
12:22:04.847 [5412.2716] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  508 0x1fc
12:22:04.847 [5412.2716] <2> process_requests: Duplicated vnetd socket on stderr
12:22:04.847 [5412.2716] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:22:04.847 [5412.2716] <2> process_requests: VERBOSE = 5
12:22:04.847 [5412.2716] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:22:04.863 [3448.6504] <2> process_requests:
12:22:04.878 [5412.2716] <2> process_requests:
12:22:05.050 [3448.6504] <2> process_requests: BPCD_GET_VERSION_RQST
12:22:05.050 [5412.2716] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:22:05.253 [3448.6504] <2> process_requests: BPCD_GET_VERSION_RQST
12:22:05.268 [5412.2716] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\logs\NBWIN003
12:22:05.268 [5412.2716] <2> process_requests: buffer length = 4096
12:22:05.268 [5412.2716] <2> process_requests: our open flags = 0x119
12:22:05.268 [5412.2716] <2> process_requests: client open flags = 0x109
12:22:05.268 [5412.2716] <2> process_requests: completed the write of the file number to the output socket
12:22:05.300 [5044.6136] <2> setup_debug_log: switched debug log file for bpcd
12:22:05.300 [5044.6136] <2> bpcd main: VERBOSE = 5
12:22:05.300 [5044.6136] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:22:05.300 [5044.6136] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:22:05.300 [5044.6136] <2> process_requests: offset to GMT 18000
12:22:05.315 [5044.6136] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55417 TO 10.10.2.104.1556 fd = 536
12:22:05.315 [5044.6136] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:22:05.315 [5044.6136] <2> process_requests: setup_sockopts complete
12:22:05.315 [5044.6136] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:22:05.315 [5044.6136] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:22:05.315 [5044.6136] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55417
12:22:05.315 [5044.6136] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:22:05.315 [5044.6136] <4> bpcd valid_server: hostname comparison succeeded
12:22:05.315 [5044.6136] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:22:05.471 [3448.6504] <2> process_requests: BPCD_READ_USERS_CONFIG_RQST
12:22:05.487 [5412.2716] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:22:05.487 [5044.6136] <2> process_requests: output socket port number = 1
12:22:05.674 [3448.6504] <2> process_requests: BPCD_GET_VERSION_RQST
12:22:05.892 [3448.6504] <2> process_requests: BPCD_READ_HOST_CONFIG_RQST
12:22:05.955 [5044.6136] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:22:05.955 [5044.6136] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56597,1,400,2,1,0+127.0.0.1.txt
12:22:05.955 [5044.6136] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56597
12:22:05.955 [5044.6136] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:22:05.955 [5044.6136] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:22:05.955 [5044.6136] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:22:05.955 [5044.6136] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56598 TO 127.0.0.1.56597 fd = 488
12:22:05.955 [5044.6136] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56597
12:22:05.955 [5044.6136] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 5b1f1f760e5342efb88d57ef22f45a9f
12:22:05.955 [5044.6136] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 a739d1b2ccc55d030813be3009e60bea
12:22:05.955 [5044.6136] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 5b1f1f760e5342efb88d57ef22f45a9f
12:22:05.955 [5044.6136] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 a739d1b2ccc55d030813be3009e60bea
12:22:05.955 [5044.6136] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str 5b1f1f760e5342efb88d57ef22f45a9f
12:22:05.955 [5044.6136] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:22:05.955 [5044.6136] <2> process_requests: Duplicated vnetd socket on stderr
12:22:05.955 [5044.6136] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:22:05.955 [5044.6136] <2> process_requests: VERBOSE = 5
12:22:05.955 [5044.6136] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:22:05.986 [5044.6136] <2> process_requests:
12:22:06.158 [5044.6136] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:22:06.376 [5044.6136] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\jobs\NBWIN003.j
12:22:06.376 [5044.6136] <2> process_requests: buffer length = 2048
12:22:06.376 [5044.6136] <2> process_requests: our open flags = 0x119
12:22:06.376 [5044.6136] <2> process_requests: client open flags = 0x109
12:22:06.376 [5044.6136] <2> process_requests: completed the write of the file number to the output socket
12:22:06.594 [5044.6136] <2> process_requests: BPCD_WRITE_RQST
12:22:06.813 [5044.6136] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:22:07.031 [5044.6136] <2> process_requests: BPCD_DISCONNECT_RQST
12:22:07.031 [5044.6136] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:22:07.031 [5412.2716] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:22:07.265 [5412.2716] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:22:07.484 [5412.2716] <2> process_requests: BPCD_DISCONNECT_RQST
12:22:07.484 [5412.2716] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:22:08.732 [3448.6504] <2> process_requests: BPCD_DISCONNECT_RQST
12:22:08.732 [3448.6504] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:22:08.763 [4244.6808] <2> setup_debug_log: switched debug log file for bpcd
12:22:08.763 [4244.6808] <2> bpcd main: VERBOSE = 5
12:22:08.763 [4244.6808] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:22:08.763 [4244.6808] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:22:08.763 [4244.6808] <2> process_requests: offset to GMT 18000
12:22:08.763 [4244.6808] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55424 TO 10.10.2.104.1556 fd = 536
12:22:08.763 [4244.6808] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:22:08.763 [4244.6808] <2> process_requests: setup_sockopts complete
12:22:08.763 [4244.6808] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:22:08.778 [4244.6808] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:22:08.778 [4244.6808] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55424
12:22:08.778 [4244.6808] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:22:08.778 [4244.6808] <4> bpcd valid_server: hostname comparison succeeded
12:22:08.778 [4244.6808] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:22:08.950 [4244.6808] <2> process_requests: output socket port number = 1
12:22:09.418 [4244.6808] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:22:09.418 [4244.6808] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56601,1,400,2,1,0+127.0.0.1.txt
12:22:09.418 [4244.6808] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56601
12:22:09.418 [4244.6808] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:22:09.418 [4244.6808] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:22:09.418 [4244.6808] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:22:09.418 [4244.6808] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56602 TO 127.0.0.1.56601 fd = 488
12:22:09.418 [4244.6808] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56601
12:22:09.418 [4244.6808] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 b459490a202cefa1cb4b4397145123ae
12:22:09.418 [4244.6808] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 4e402c5e75d18c4ab6ef8e72eb738112
12:22:09.418 [4244.6808] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 b459490a202cefa1cb4b4397145123ae
12:22:09.418 [4244.6808] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 4e402c5e75d18c4ab6ef8e72eb738112
12:22:09.418 [4244.6808] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str b459490a202cefa1cb4b4397145123ae
12:22:09.418 [4244.6808] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  508 0x1fc
12:22:09.418 [4244.6808] <2> process_requests: Duplicated vnetd socket on stderr
12:22:09.418 [4244.6808] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:22:09.418 [4244.6808] <2> process_requests: VERBOSE = 5
12:22:09.418 [4244.6808] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:22:09.449 [4244.6808] <2> process_requests:
12:22:09.636 [4244.6808] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:22:09.855 [4244.6808] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\logs\NBWIN003
12:22:09.855 [4244.6808] <2> process_requests: buffer length = 4096
12:22:09.855 [4244.6808] <2> process_requests: our open flags = 0x119
12:22:09.855 [4244.6808] <2> process_requests: client open flags = 0x109
12:22:09.855 [4244.6808] <2> process_requests: completed the write of the file number to the output socket
12:22:10.073 [4244.6808] <2> process_requests: BPCD_LOG_RQST
12:22:10.120 [5972.1016] <2> setup_debug_log: switched debug log file for bpcd
12:22:10.120 [5972.1016] <2> bpcd main: VERBOSE = 5
12:22:10.120 [5972.1016] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
12:22:10.120 [5972.1016] <2> ReadKeyfile: keyfile D:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist

12:22:10.120 [5972.1016] <2> process_requests: offset to GMT 18000
12:22:10.120 [5972.1016] <2> logconnections: BPCD ACCEPT FROM 10.10.2.212.55426 TO 10.10.2.104.1556 fd = 536
12:22:10.120 [5972.1016] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
12:22:10.120 [5972.1016] <2> process_requests: setup_sockopts complete
12:22:10.120 [5972.1016] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:22:10.120 [5972.1016] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=vm-hdcnetbkup00 IPSTR=10.10.2.212
12:22:10.120 [5972.1016] <2> bpcd peer_hostname: Connection from host vm-hdcnetbkup00 (10.10.2.212) port 55426
12:22:10.120 [5972.1016] <2> bpcd valid_server: comparing vm-hdcnetbkup00 and vm-hdcnetbkup00
12:22:10.120 [5972.1016] <4> bpcd valid_server: hostname comparison succeeded
12:22:10.120 [5972.1016] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
12:22:10.307 [5972.1016] <2> process_requests: output socket port number = 1
12:22:10.775 [5972.1016] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8740] ignoring local host 0 0x0
12:22:10.775 [5972.1016] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=D:\Program Files\Veritas\NetBackup\var\host_cache\14f\6cf9fd4f+56603,1,400,2,1,0+127.0.0.1.txt
12:22:10.775 [5972.1016] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1630] found via getaddrinfo NAME=127.0.0.1 SVC=56603
12:22:10.775 [5972.1016] <2> vnet_sortaddrs: [vnet_addrinfo.c:3945] sorted addrs: 1 0x1
12:22:10.775 [5972.1016] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4776] Local [strong] check, using interface  ANY
12:22:10.775 [5972.1016] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1
12:22:10.775 [5972.1016] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 127.0.0.1.56604 TO 127.0.0.1.56603 fd = 488
12:22:10.775 [5972.1016] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 127.0.0.1 127.0.0.1 56603
12:22:10.775 [5972.1016] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 72dc6c8287fae4e775dab1c1d5e9bbc4
12:22:10.775 [5972.1016] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 408cb535e28f5d8648cd8c077381addb
12:22:10.775 [5972.1016] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 72dc6c8287fae4e775dab1c1d5e9bbc4
12:22:10.775 [5972.1016] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 408cb535e28f5d8648cd8c077381addb
12:22:10.775 [5972.1016] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str 72dc6c8287fae4e775dab1c1d5e9bbc4
12:22:10.775 [5972.1016] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is  512 0x200
12:22:10.775 [5972.1016] <2> process_requests: Duplicated vnetd socket on stderr
12:22:10.775 [5972.1016] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
12:22:10.775 [5972.1016] <2> process_requests: VERBOSE = 5
12:22:10.775 [5972.1016] <2> process_requests: Not using VxSS authentication with vm-hdcnetbkup00
12:22:10.807 [5972.1016] <2> process_requests:
12:22:10.978 [5972.1016] <2> process_requests: BPCD_OPEN_FOR_WRITE_RQST
12:22:11.197 [5972.1016] <2> process_requests: open for write file name = D:\Program Files\Veritas\NetBackup\logs\user_ops\kwinter\jobs\NBWIN003.j
12:22:11.197 [5972.1016] <2> process_requests: buffer length = 2048
12:22:11.197 [5972.1016] <2> process_requests: our open flags = 0x119
12:22:11.197 [5972.1016] <2> process_requests: client open flags = 0x109
12:22:11.197 [5972.1016] <2> process_requests: completed the write of the file number to the output socket
12:22:11.415 [5972.1016] <2> process_requests: BPCD_WRITE_RQST
12:22:11.633 [5972.1016] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:22:11.852 [5972.1016] <2> process_requests: BPCD_DISCONNECT_RQST
12:22:11.852 [5972.1016] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
12:22:11.852 [4244.6808] <2> process_requests: BPCD_LOG_RQST_NO_STATUS
12:22:12.070 [4244.6808] <2> process_requests: BPCD_CLOSE_FILE_RQST
12:22:12.273 [4244.6808] <2> process_requests: BPCD_DISCONNECT_RQST
12:22:12.273 [4244.6808] <2> bpcd exit_bpcd: exit status 0  ----------->exiting

 

 

 

Mark_Solutions's picture

That looks OK then ... so another thought .. is your DAG name different to the server name (usually is but not sure with a stand alone 2010 Exchange install)

If so have you completed the Distributed Application Mappings Section on the Master Servers Host Properties?

DAG on the left, server names on the right - especially important if it is a cluster

Also worth adding the \netbackup\db\altnames\No.Restrictions file on the Master - and one other thing (which does seem odd but I have seen it work) - add the clients name to its own Server List and also to the additional Servers list on the Master - just helps with the ammount of communication that goes on during DAG restores

See if any of this helps

Authorised Symantec Consultant

Don't forget to "Mark as Solution" if someones advice has solved your issue - and please bring back the Thumbs Up!!.

Kwinter's picture

the backup does exists, see attached screen shot.

 

AttachmentSize
Source_Backup_Files.docx 44.84 KB
Mark_Solutions's picture

Not sure what you mean - the image file is over 70GB in that screen shot

Two other things:
1. Are the NetBackup Client Service and the NetBackup Legacy Client Service using exchange admin accounts?

2. Are you using a GRT restore Proxy Host (exchange server client host properties - Exchange tab)

Authorised Symantec Consultant

Don't forget to "Mark as Solution" if someones advice has solved your issue - and please bring back the Thumbs Up!!.

Kwinter's picture

Thank you for the insight; but ww do not have DAG and the No.Restrictions file is on the Master and we are using the Distributed Application Mappings Section.

Weird.

 

 

Kwinter's picture

1. both the  NetBackup client & Legacy client services are using the exchange admin account.

2. we are not using Proxy Host.

Note:  i just added in the Distributed Application Mappings Section the short name of the server and the fully qualified name.

but it still fails.

not good.

Marianne's picture

Kwinter, you have showed us the media server bpcd log, right?

We need to see the destination client's bpcd log.

Please copy client's bpcd log to bpcd.txt and post as File attachment.

Supporting Storage Foundation and VCS on Unix and Windows as well as NetBackup on Unix and Windows
Handy NBU Links

Kwinter's picture

Hi Marianne,

I have attached the bpcd log from the client exchange server.  I hope it helps.

Thanks !!!

AttachmentSize
bpcd.txt 63.43 KB
Marianne's picture

bpcd log shows connection attempts from the master (10.10.2.212) nothing from the media server.

We can see that browsing was done from master server, but we do not see connection attempt from the media server when actual restore was started.

Please show us all the text in Activity Monitor job details?

We are going to need the following logs as well to trace the process flow:

On master: bprd

On media server: bpbrm

Level 0 logs are fine for now - we are simply tracing process flow and connectivity.

Supporting Storage Foundation and VCS on Unix and Windows as well as NetBackup on Unix and Windows
Handy NBU Links

Kwinter's picture

I have added the log files to a zip file.  If you need anything else, please let me know.

Thanks you !!!

AttachmentSize
Master_Media_logs.zip 1.02 MB
Mark_Solutions's picture

Jusr a check - it should be the NetBackup Legacy Network Service that is set as a Exchange account - is that also set?

Authorised Symantec Consultant

Don't forget to "Mark as Solution" if someones advice has solved your issue - and please bring back the Thumbs Up!!.

Marianne's picture

Seems you have indeed DAG setup and not standalone Exchange.

This is what I see in bprd log:

 

13:56:18.879 [6772.840] <2> restorefiles:    browse_client = vm-hdcexmb04
13:56:18.879 [6772.840] <2> restorefiles:    requesting_client = vm-hdcnetbkup00
13:56:18.879 [6772.840] <2> restorefiles:    destination_client = vm-hdcexmb04
13:56:18.879 [6772.840] <2> restorefiles:    requesting_client_hostname = VM-HDCNETBKUP00.HAGERTY.com
13:56:18.879 [6772.840] <2> restorefiles:    destination_client_hostname = vm-hdcexmb04
 
13:56:36.940 [6772.840] <2> get_string: INF - RESOLVER START 2896
13:56:36.940 [6772.840] <2> make_bpresolver_call: Sent restore_cas -c vm-hdcexmb04 -pt 16 -v to bpresolver
13:56:36.940 [6772.840] <2> make_bpresolver_call: Sent 5 to bpresolver
13:56:36.940 [6772.840] <2> make_bpresolver_call: Sent CONTINUE to bpresolver
13:56:37.940 [6772.840] <4> make_bpresolver_call: TRV - BPRESOLVER has executed on server (VM-HDCEXMB04)
13:56:41.940 [6772.840] <4> make_bpresolver_call: INF - RESOLVER EXCHANGE14 WORKLIST EMAIL.HAGERTY.COM Microsoft Exchange Database Availability Groups:\MB04_DB01
 
At this point restore destination changes as follows:
 
13:56:43.486 [2852.6352] <2> restorefiles:    browse_client = vm-hdcexmb04
13:56:43.486 [2852.6352] <2> restorefiles:    requesting_client = vm-hdcnetbkup00
13:56:43.486 [2852.6352] <2> restorefiles:    destination_client = EMAIL.HAGERTY.COM
13:56:43.486 [2852.6352] <2> restorefiles:    requesting_client_hostname = VM-HDCNETBKUP00.HAGERTY.com
13:56:43.486 [2852.6352] <2> restorefiles:    destination_client_hostname = EMAIL.HAGERTY.COM11
 

bpbrm on media server cannot connect to this hostname:

 

13:41:48.202 [6304.4528] <2> ConnectToBPCD: bpcd_connect_and_verify(EMAIL.HAGERTY.COM, EMAIL.HAGERTY.COM) failed: 46
13:41:48.202 [6304.4528] <16> bpbrm start_bpcd_stat: bpcd on EMAIL.HAGERTY.COM exited with status 59: access to the client was not allowed
 
 
Please config Distributed Application Restore Mapping as per this TN: http://www.symantec.com/docs/TECH164954
 
Ensure correct SERVER list on all DAG members.
 

Supporting Storage Foundation and VCS on Unix and Windows as well as NetBackup on Unix and Windows
Handy NBU Links

Kwinter's picture

Thank you “Mark_Solutions” & Marianne !!!

It did look like we have a DAG, because we have a network load balancer (NetScaller) which was making it look like a DAG.

What was happening:  We have three NetBackup Servers, Master (and it’s a media server) and two media servers.  On the NetScaller, it routes all message requests to our two Exchange HUB Case Servers (email.hagerty.com   points to the NetScaller, then the NetScaller load balances’ the traffic to the two CAS servers).  On the Master server we did have an entry in the HOST file for “email.hagerty.com” as an alias for one of our HUB-CAS  servers:  

    10.x.x.x              HUBCAS-01           HUBCAS-01.hagerty.com       email.hagerty.com

And of course, the “Distributed Application Restore Mapping” had all the Exchange Mailboxes mapping to “email.hagerty.com”.

Problem was, we restoring from a one of the two media servers with this error of “Error Status 2810 (access to the client was not allowed)”.

Solution:  the two media servers didn’t have the “email.hagerty.com” alias for their HOST file.  Once I did that, it worked great.

I cannot say how I appreciate your help Marianne & Mark_Solutions,

~ Kwinter

SOLUTION
Mark_Solutions's picture

Glad it is sorted - would have been nice to have marked the tip that guided you to the solution, though i understand that your final answer does cover it

Authorised Symantec Consultant

Don't forget to "Mark as Solution" if someones advice has solved your issue - and please bring back the Thumbs Up!!.