03-21-2013 07:22 AM
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.
Solved! Go to Solution.
04-01-2013 06:35 AM
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
03-21-2013 08:34 AM
This exchange server is version 2010. No DAG.
I am going thur Marianne's info regarding changing media server (her post from Jun-2010):
03-21-2013 08:43 AM
03-21-2013 09:23 AM
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
03-21-2013 09:34 AM
03-21-2013 09:37 AM
the backup does exists, see attached screen shot.
03-21-2013 09:53 AM
03-21-2013 09:56 AM
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.
03-21-2013 10:57 AM
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.
03-21-2013 11:40 AM
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.
03-21-2013 11:58 AM
Hi Marianne,
I have attached the bpcd log from the client exchange server. I hope it helps.
Thanks !!!
03-21-2013 12:32 PM
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.
03-21-2013 02:25 PM
I have added the log files to a zip file. If you need anything else, please let me know.
Thanks you !!!
03-21-2013 02:37 PM
03-21-2013 10:57 PM
Seems you have indeed DAG setup and not standalone Exchange.
This is what I see in bprd log:
bpbrm on media server cannot connect to this hostname:
04-01-2013 06:35 AM
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
04-02-2013 01:48 AM