bprd: 11:06:21.584 [3832.3844] <2> vnet_pbxAcceptSocket_ex: Accepted sock[844] from 192.168.80.41:38788 11:06:21.584 [3832.3844] <2> daemon_select_and_accept_ex: pbx setsockopt SO_KEEPALIVE succeeded 11:06:21.584 [3832.3844] <2> bprd: socket fd from accept() is 844 11:06:21.584 [3832.3844] <2> listen_loop: request complete 11:06:21.834 [6968.5824] <2> daemon_proxy_proto: Preparing to do daemon protocol for (192.168.80.194:1556 <- 192.168.80.41:38788) 11:06:21.850 [6968.5824] <2> logconnections: BPRD ACCEPT FROM 192.168.80.41.38788 TO 192.168.80.194.1556 fd = 844 11:06:21.850 [6968.5824] <2> process_request: setsockopt SO_LINGER on 844 succeeded. 11:06:21.850 [6968.5824] <2> connected_peer: Connection from host informix11, 192.168.80.41, on non-reserved port 38788 11:06:21.850 [6968.5824] <2> db_valid_master_server: informix11 is not a valid server 11:06:21.850 [6968.5824] <2> ConnectionCache::connectAndCache: Acquiring new connection for host nbu811, query type 84 11:06:21.866 [6968.5824] <2> logconnections: BPDBM CONNECT FROM 192.168.80.194.49890 TO 192.168.80.194.13721 fd = 812 11:06:21.866 [6968.5824] <2> db_getdata: timeout is 0 (infinite) 11:06:21.976 [6968.5824] <2> db_end: Need to collect reply 11:06:21.976 [6968.5824] <2> db_getdata: timeout is 0 (infinite) 11:06:21.991 [6968.5824] <2> bpcd_remote_ops_allowed: Known remote host is allowed 11:06:21.991 [6968.5824] <2> process_request: command C_GET_FEATURES (72) received 11:06:21.991 [6968.5824] <2> process_request: EXIT STATUS 0 11:06:22.084 [3832.3844] <2> vnet_pbxAcceptSocket_ex: Accepted sock[952] from 192.168.80.41:34024 11:06:22.084 [3832.3844] <2> daemon_select_and_accept_ex: pbx setsockopt SO_KEEPALIVE succeeded 11:06:22.084 [3832.3844] <2> bprd: socket fd from accept() is 952 11:06:22.084 [3832.3844] <2> listen_loop: request complete 11:06:22.381 [4716.4528] <2> daemon_proxy_proto: Preparing to do daemon protocol for (192.168.80.194:1556 <- 192.168.80.41:34024) 11:06:22.397 [4716.4528] <2> logconnections: BPRD ACCEPT FROM 192.168.80.41.34024 TO 192.168.80.194.1556 fd = 792 11:06:22.397 [4716.4528] <2> process_request: setsockopt SO_LINGER on 792 succeeded. 11:06:22.397 [4716.4528] <2> connected_peer: Connection from host informix11, 192.168.80.41, on non-reserved port 34024 11:06:22.397 [4716.4528] <2> db_valid_master_server: informix11 is not a valid server 11:06:22.397 [4716.4528] <2> process_request: command C_BPRESTORE_6_5_2_2 (126) received 11:06:22.412 [4716.4528] <2> ConnectionCache::connectAndCache: Acquiring new connection for host nbu811, query type 84 11:06:22.428 [4716.4528] <2> logconnections: BPDBM CONNECT FROM 192.168.80.194.49892 TO 192.168.80.194.13721 fd = 812 11:06:22.428 [4716.4528] <2> db_getdata: timeout is 0 (infinite) 11:06:22.459 [4716.4528] <2> db_end: Need to collect reply 11:06:22.459 [4716.4528] <2> db_getdata: timeout is 0 (infinite) 11:06:22.522 [4716.4528] <2> parse_6522_773_restreq: pre vm_fields 0 0 0 0 0 0 0 0 0 0 NONE -1 0 0 0 0 NONE NONE 11:06:22.522 [4716.4528] <2> parse_6522_773_restreq: vm_fields 0 NONE -1 0 0 0 0 NONE NONE 11:06:22.522 [4716.4528] <2> process_request: Make sure the requesting client is the peer 11:06:22.522 [4716.4528] <2> doGetCertMapInfo: Entering 11:06:22.522 [4716.4528] <2> doGetCertMapInfo: Using default path 11:06:22.522 [4716.4528] <2> doGetCertMapInfo: Checking if server entry is present for server nbu811 11:06:22.522 [4716.4528] <2> getMatchingServerFromCertMapInfo: Master server [nbu811] will be overwritten with the serverName [nbu811] from certmapinfo.json file 11:06:22.522 [4716.4528] <2> web_sync: invoking:https://nbu811:1556/netbackup/config/hosts/validate 11:06:22.522 [4716.4528] <2> NBClientCURL::NBClientCURL: Performing curl_easy_init() 11:06:22.522 [4716.4528] <2> NBClientCURL::NBClientCURL: Disabling any proxy option set in the environment. 11:06:22.522 [4716.4528] <2> NBClientCURL::NBClientCURL: Using paths as caCertFilePath [C:\Program Files\Veritas\NetBackup\var\webtruststore\cacert.pem] privateKeyPath [C:\Program Files\Veritas\NetBackup\var\vxss\credentials\keystore\PrivKeyFile.pem] 11:06:22.522 [4716.4528] <2> NBClientCURL::NBClientCURL: Setting default CURL timeout = [300] seconds 11:06:22.522 [4716.4528] <2> NBClientCURL::NBClientCURL: Disable signal during name resolution. 11:06:22.522 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: ENABLE_NBCURL_VERBOSE is not set 11:06:22.522 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: DISABLE_NBCURL_CONNECTIONS is not set 11:06:22.522 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: DISABLE_NBCURL_PREFERRED_NETWORKS is not set 11:06:22.522 [4716.4528] <2> web_sync: performing POST call with payload:{"hostName": "informix11"} 11:06:22.522 [4716.4528] <2> NBClientCURL::Post: POSTing to URL https://nbu811:1556/netbackup/config/hosts/validate 11:06:22.522 [4716.4528] <2> NBClientCURL::Post: Sending payload ... 11:06:22.522 [4716.4528] <2> NBClientCURL::setPostOptions: setting curl post options ... 11:06:22.522 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenIssueTimeSec = 0 11:06:22.522 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenExpTimeSec = 0 11:06:22.522 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenLastFailTimeSec = 1536375785 11:06:22.522 [4716.4528] <2> LoginWithCertManager::isJWTRefreshRequired: Server = nbu811 11:06:22.522 [4716.4528] <2> getCertModTime: Retrieved Host Id [ bf012ed4-e999-45ae-bdf2-56b22228f5a9 ] 11:06:22.522 [4716.4528] <2> getCertPath: Host Certificate path is [C:\Program Files\Veritas\NetBackup\var\vxss\credentials\bf012ed4-e999-45ae-bdf2-56b22228f5a9] 11:06:22.522 [4716.4528] <2> LoginWithCertManager::isJWTRefreshRequired: tokenlastFailTimeSec = 1536375785, timeNow = 1536375982, will fail more for = 103 11:06:22.522 [4716.4528] <16> LoginWithCertManager::getToken: Avoiding token refresh: 5930 11:06:22.522 [4716.4528] <16> LoginWithCertManager::getToken: Exception encountered, status code: 5930 11:06:22.522 [4716.4528] <16> NBClientCURL::performCurlOperation: Failed to fetch authorization token. Actual Error code 5930return code :5930 11:06:22.522 [4716.4528] <2> NBClientCURL:~NBClientCURL: Performing curl_easy_cleanup() 11:06:22.522 [4716.4528] <16> validateHostDetailsFromSyncWebapi: curl call fail with error:5930 11:06:22.522 [4716.4528] <8> validateHostDetails: Server call failed; hence clearing host validation cache 11:06:22.537 [4716.4528] <2> db_valid_master_server: informix11 is not a valid server 11:06:22.537 [4716.4528] <2> process_request: Requesting client is NOT a valid master server 11:06:22.553 [4716.4528] <2> job_connect: SO_KEEPALIVE set on socket 772 for client nbu811 11:06:22.553 [4716.4528] <2> logconnections: BPJOBD CONNECT FROM 192.168.80.194.49893 TO 192.168.80.194.13723 fd = 772 11:06:22.553 [4716.4528] <2> job_authenticate_connection: VxSS authentication check for now... 11:06:22.553 [4716.4528] <2> job_connect: Connected to the host nbu811 contype 53 jobid <26> socket <772> 11:06:22.553 [4716.4528] <2> job_connect: Connected on port 49893 11:06:22.553 [4716.4528] <4> JobdSockList::RegisterSocket: Register socket (772). 11:06:22.553 [4716.4528] <2> doGetCertMapInfo: Entering 11:06:22.553 [4716.4528] <2> doGetCertMapInfo: Using default path 11:06:22.553 [4716.4528] <2> doGetCertMapInfo: Checking if server entry is present for server nbu811 11:06:22.553 [4716.4528] <2> getMatchingServerFromCertMapInfo: Master server [nbu811] will be overwritten with the serverName [nbu811] from certmapinfo.json file 11:06:22.553 [4716.4528] <2> web_sync: invoking:https://nbu811:1556/netbackup/config/hosts/validate 11:06:22.553 [4716.4528] <2> NBClientCURL::NBClientCURL: Performing curl_easy_init() 11:06:22.553 [4716.4528] <2> NBClientCURL::NBClientCURL: Disabling any proxy option set in the environment. 11:06:22.553 [4716.4528] <2> NBClientCURL::NBClientCURL: Using paths as caCertFilePath [C:\Program Files\Veritas\NetBackup\var\webtruststore\cacert.pem] privateKeyPath [C:\Program Files\Veritas\NetBackup\var\vxss\credentials\keystore\PrivKeyFile.pem] 11:06:22.553 [4716.4528] <2> NBClientCURL::NBClientCURL: Setting default CURL timeout = [300] seconds 11:06:22.553 [4716.4528] <2> NBClientCURL::NBClientCURL: Disable signal during name resolution. 11:06:22.553 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: ENABLE_NBCURL_VERBOSE is not set 11:06:22.553 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: DISABLE_NBCURL_CONNECTIONS is not set 11:06:22.553 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: DISABLE_NBCURL_PREFERRED_NETWORKS is not set 11:06:22.553 [4716.4528] <2> web_sync: performing POST call with payload:{"hostName": "informix11"} 11:06:22.553 [4716.4528] <2> NBClientCURL::Post: POSTing to URL https://nbu811:1556/netbackup/config/hosts/validate 11:06:22.553 [4716.4528] <2> NBClientCURL::Post: Sending payload ... 11:06:22.553 [4716.4528] <2> NBClientCURL::setPostOptions: setting curl post options ... 11:06:22.553 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenIssueTimeSec = 0 11:06:22.553 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenExpTimeSec = 0 11:06:22.553 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenLastFailTimeSec = 1536375785 11:06:22.553 [4716.4528] <2> LoginWithCertManager::isJWTRefreshRequired: Server = nbu811 11:06:22.553 [4716.4528] <2> getCertModTime: Retrieved Host Id [ bf012ed4-e999-45ae-bdf2-56b22228f5a9 ] 11:06:22.553 [4716.4528] <2> getCertPath: Host Certificate path is [C:\Program Files\Veritas\NetBackup\var\vxss\credentials\bf012ed4-e999-45ae-bdf2-56b22228f5a9] 11:06:22.553 [4716.4528] <2> LoginWithCertManager::isJWTRefreshRequired: tokenlastFailTimeSec = 1536375785, timeNow = 1536375982, will fail more for = 103 11:06:22.553 [4716.4528] <16> LoginWithCertManager::getToken: Avoiding token refresh: 5930 11:06:22.553 [4716.4528] <16> LoginWithCertManager::getToken: Exception encountered, status code: 5930 11:06:22.553 [4716.4528] <16> NBClientCURL::performCurlOperation: Failed to fetch authorization token. Actual Error code 5930return code :5930 11:06:22.553 [4716.4528] <2> NBClientCURL:~NBClientCURL: Performing curl_easy_cleanup() 11:06:22.553 [4716.4528] <16> validateHostDetailsFromSyncWebapi: curl call fail with error:5930 11:06:22.553 [4716.4528] <8> validateHostDetails: Server call failed; hence clearing host validation cache 11:06:22.553 [4716.4528] <16> is_redirected_restore_allowed: One or more input parameters are invalid. 11:06:22.553 [4716.4528] <2> doGetCertMapInfo: Entering 11:06:22.553 [4716.4528] <2> doGetCertMapInfo: Using default path 11:06:22.553 [4716.4528] <2> doGetCertMapInfo: Checking if server entry is present for server nbu811 11:06:22.553 [4716.4528] <2> getMatchingServerFromCertMapInfo: Master server [nbu811] will be overwritten with the serverName [nbu811] from certmapinfo.json file 11:06:22.553 [4716.4528] <2> web_sync: invoking:https://nbu811:1556/netbackup/config/hosts/validate 11:06:22.553 [4716.4528] <2> NBClientCURL::NBClientCURL: Performing curl_easy_init() 11:06:22.553 [4716.4528] <2> NBClientCURL::NBClientCURL: Disabling any proxy option set in the environment. 11:06:22.553 [4716.4528] <2> NBClientCURL::NBClientCURL: Using paths as caCertFilePath [C:\Program Files\Veritas\NetBackup\var\webtruststore\cacert.pem] privateKeyPath [C:\Program Files\Veritas\NetBackup\var\vxss\credentials\keystore\PrivKeyFile.pem] 11:06:22.553 [4716.4528] <2> NBClientCURL::NBClientCURL: Setting default CURL timeout = [300] seconds 11:06:22.553 [4716.4528] <2> NBClientCURL::NBClientCURL: Disable signal during name resolution. 11:06:22.553 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: ENABLE_NBCURL_VERBOSE is not set 11:06:22.553 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: DISABLE_NBCURL_CONNECTIONS is not set 11:06:22.553 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: DISABLE_NBCURL_PREFERRED_NETWORKS is not set 11:06:22.553 [4716.4528] <2> web_sync: performing POST call with payload:{"hostName": "informix11"} 11:06:22.553 [4716.4528] <2> NBClientCURL::Post: POSTing to URL https://nbu811:1556/netbackup/config/hosts/validate 11:06:22.553 [4716.4528] <2> NBClientCURL::Post: Sending payload ... 11:06:22.553 [4716.4528] <2> NBClientCURL::setPostOptions: setting curl post options ... 11:06:22.553 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenIssueTimeSec = 0 11:06:22.553 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenExpTimeSec = 0 11:06:22.553 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenLastFailTimeSec = 1536375785 11:06:22.553 [4716.4528] <2> LoginWithCertManager::isJWTRefreshRequired: Server = nbu811 11:06:22.553 [4716.4528] <2> getCertModTime: Retrieved Host Id [ bf012ed4-e999-45ae-bdf2-56b22228f5a9 ] 11:06:22.553 [4716.4528] <2> getCertPath: Host Certificate path is [C:\Program Files\Veritas\NetBackup\var\vxss\credentials\bf012ed4-e999-45ae-bdf2-56b22228f5a9] 11:06:22.553 [4716.4528] <2> LoginWithCertManager::isJWTRefreshRequired: tokenlastFailTimeSec = 1536375785, timeNow = 1536375982, will fail more for = 103 11:06:22.553 [4716.4528] <16> LoginWithCertManager::getToken: Avoiding token refresh: 5930 11:06:22.553 [4716.4528] <16> LoginWithCertManager::getToken: Exception encountered, status code: 5930 11:06:22.553 [4716.4528] <16> NBClientCURL::performCurlOperation: Failed to fetch authorization token. Actual Error code 5930return code :5930 11:06:22.553 [4716.4528] <2> NBClientCURL:~NBClientCURL: Performing curl_easy_cleanup() 11:06:22.553 [4716.4528] <16> validateHostDetailsFromSyncWebapi: curl call fail with error:5930 11:06:22.553 [4716.4528] <8> validateHostDetails: Server call failed; hence clearing host validation cache 11:06:22.569 [4716.4528] <2> read_legacy_touch_file: Found C:\Program Files\Veritas\NetBackup\db\altnames\No.Restrictions; requested from (d:\nb\8.1.1\src\nb\dblib\dbreq.c.361). 11:06:22.569 [4716.4528] <2> db_valid_client: -all clients valid- 11:06:22.569 [4716.4528] <2> doGetCertMapInfo: Entering 11:06:22.569 [4716.4528] <2> doGetCertMapInfo: Using default path 11:06:22.569 [4716.4528] <2> doGetCertMapInfo: Checking if server entry is present for server nbu811 11:06:22.569 [4716.4528] <2> getMatchingServerFromCertMapInfo: Master server [nbu811] will be overwritten with the serverName [nbu811] from certmapinfo.json file 11:06:22.569 [4716.4528] <2> web_sync: invoking:https://nbu811:1556/netbackup/config/hosts/validate 11:06:22.569 [4716.4528] <2> NBClientCURL::NBClientCURL: Performing curl_easy_init() 11:06:22.569 [4716.4528] <2> NBClientCURL::NBClientCURL: Disabling any proxy option set in the environment. 11:06:22.569 [4716.4528] <2> NBClientCURL::NBClientCURL: Using paths as caCertFilePath [C:\Program Files\Veritas\NetBackup\var\webtruststore\cacert.pem] privateKeyPath [C:\Program Files\Veritas\NetBackup\var\vxss\credentials\keystore\PrivKeyFile.pem] 11:06:22.569 [4716.4528] <2> NBClientCURL::NBClientCURL: Setting default CURL timeout = [300] seconds 11:06:22.569 [4716.4528] <2> NBClientCURL::NBClientCURL: Disable signal during name resolution. 11:06:22.569 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: ENABLE_NBCURL_VERBOSE is not set 11:06:22.569 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: DISABLE_NBCURL_CONNECTIONS is not set 11:06:22.569 [4716.4528] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: DISABLE_NBCURL_PREFERRED_NETWORKS is not set 11:06:22.569 [4716.4528] <2> web_sync: performing POST call with payload:{"hostName": "informix11"} 11:06:22.569 [4716.4528] <2> NBClientCURL::Post: POSTing to URL https://nbu811:1556/netbackup/config/hosts/validate 11:06:22.569 [4716.4528] <2> NBClientCURL::Post: Sending payload ... 11:06:22.569 [4716.4528] <2> NBClientCURL::setPostOptions: setting curl post options ... 11:06:22.569 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenIssueTimeSec = 0 11:06:22.569 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenExpTimeSec = 0 11:06:22.569 [4716.4528] <2> LoginWithCertManager::getLocalToken: tokenLastFailTimeSec = 1536375785 11:06:22.569 [4716.4528] <2> LoginWithCertManager::isJWTRefreshRequired: Server = nbu811 11:06:22.569 [4716.4528] <2> getCertModTime: Retrieved Host Id [ bf012ed4-e999-45ae-bdf2-56b22228f5a9 ] 11:06:22.569 [4716.4528] <2> getCertPath: Host Certificate path is [C:\Program Files\Veritas\NetBackup\var\vxss\credentials\bf012ed4-e999-45ae-bdf2-56b22228f5a9] 11:06:22.569 [4716.4528] <2> LoginWithCertManager::isJWTRefreshRequired: tokenlastFailTimeSec = 1536375785, timeNow = 1536375982, will fail more for = 103 11:06:22.569 [4716.4528] <16> LoginWithCertManager::getToken: Avoiding token refresh: 5930 11:06:22.569 [4716.4528] <16> LoginWithCertManager::getToken: Exception encountered, status code: 5930 11:06:22.569 [4716.4528] <16> NBClientCURL::performCurlOperation: Failed to fetch authorization token. Actual Error code 5930return code :5930 11:06:22.569 [4716.4528] <2> NBClientCURL:~NBClientCURL: Performing curl_easy_cleanup() 11:06:22.569 [4716.4528] <16> validateHostDetailsFromSyncWebapi: curl call fail with error:5930 11:06:22.569 [4716.4528] <8> validateHostDetails: Server call failed; hence clearing host validation cache 11:06:22.569 [4716.4528] <2> process_request: Peer is NOT a configured server or accepted as a server, restore job is NOT a multi-stream child 11:06:22.569 [4716.4528] <16> process_request: Peer informix11 is not allowed to request restore to client informix11 11:06:22.584 [4716.4528] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 11:06:22.600 [4716.4528] <16> dump_proxy_info: statusmsg: Unable to perform peer host name validation. Curl error has occurred for peer name: informix11, self name: nbu811, nbu status = 0, severity = 2, Additional Message: [PROXY] Encountered error (VALIDATE_PEER_HOST_PROTOCOL_RUNNING) while processing(ValidatePeerHostProtocol)., nbu status = 1, severity = 1 11:06:22.600 [4716.4528] <16> dump_proxy_info: domain_constraints_set: 11:06:22.600 [4716.4528] <16> dump_proxy_info: ----process_hint_server_name: nbu811 11:06:22.600 [4716.4528] <16> dump_proxy_info: ----process_hint: bf012ed4-e999-45ae-bdf2-56b22228f5a9 11:06:22.600 [4716.4528] <16> dump_proxy_info: ----process_hint_reason: the domain was inherited from the local peer process 11:06:22.600 [4716.4528] <16> vnet_proxy_protocol_from_legacy: proxy returned status: 1 msg: {"status": 1, "local_proxy_info": {}, "domain_constraints_set": {"process_hint": "bf012ed4-e999-45ae-bdf2-56b22228f5a9", "process_hint_reason": "the domain was inherited from the local peer process", "process_hint_server_name": "nbu811"}, "statusmsgxml": "

informix11

nbu811

Unable to perform peer host name validation. Curl error has occurred for peer name: informix11, self name: nbu811

VALIDATE_PEER_HOST_PROTOCOL_RUNNING

ValidatePeerHostProtocol

[PROXY] Encountered error (VALIDATE_PEER_HOST_PROTOCOL_RUNNING) while processing(ValidatePeerHostProtocol).", "statusmsg": "Unable to perform peer host name validation. Curl error has occurred for peer name: informix11, self name: nbu811, nbu status = 0, severity = 2, Additional Message: [PROXY] Encountered error (VALIDATE_PEER_HOST_PROTOCOL_RUNNING) while processing(ValidatePeerHostProtocol)., nbu status = 1, severity = 1"} vnet status: 45 11:06:22.600 [4716.4528] <16> vnet_proxy_socket_swap: vnet_proxy_protocol_from_legacy() failed: 45 11:06:22.600 [4716.4528] <16> connect_to_service: vnet_proxy_socket_swap(NULL, -1, 310, 0, informix11, bpcd, 2) failed: 45 11:06:22.600 [4716.4528] <16> connect_to_service: JSON data = {"allow_large_status": {"timestamp": 1536375982, "who": "vnet_tss_init", "line_number": 32, "comment": "allow vnet status > 255", "data": false}, "direct_connect": {"timestamp": 1536375982, "who": "connect_to_service", "line_number": 834, "comment": "connect parameters", "data": {"who": "vnet_connect_to_bpcd", "host": "informix11", "service": "bpcd", "override_required_interface": null, "extra_tries_on_connect": 0, "getsock_disable_to": 0, "overide_connect_timeout": 0, "connect_options": {"server": null, "callback_kind": {"number": 1, "symbol": "NBCONF_CALLBACK_KIND_VNETD", "description": "Vnetd"}, "daemon_port_type": {"number": 0, "symbol": "NBCONF_DAEMON_PORT_TYPE_AUTOMATIC", "description": "Automatic"}, "reserved_port_kind": {"number": 0, "symbol": "NBCONF_RESERVED_PORT_KIND_LEGACY", "description": "Legacy"}}}}, "proxy_request": {"timestamp": 1536375982, "who": "vnet_proxy_build_request", "line_number": 3300, "comment": "request to local proxy", "data": {"bpcd_info": {"remote_client": "informix11", "remote_host": "informix11", "remote_probably_dhcp": false, "proxy_flag": 16, "proxy_flag_hex": "0x00000010"}, "i_am_daemon": false, "domain_constraints": {"process_hint": "bf012ed4-e999-45ae-bdf2-56b22228f5a9"}, "service": "bpcd", "auth_only": false, "pid": 4716, "ssa": false, "local_user": "NBU811$@WORKGROUP", "host": "informix11", "peer_host": "informix11", "peer_client": "informix11", "local_client": "nbu811", "local_dhcp_interval": 0, "peer_dhcp_info": null, "local_hostname": "nbu811", "secure_peer": false, "resilient": false, "nbrntd_peer_addr": null, "nbrntd_sock_addr": null, "hand_back": false, "request_timeout": 310}}, "proxy_response": {"timestamp": 1536375982, "who": "vnet_proxy_parse_response", "line_number": 3472, "comment": "response from local proxy", "data": {"status": 1, "local_proxy_info": {}, "domain_constraints_set": {"process_hint": "bf012ed4-e999-45ae-bdf2-56b22228f5a9", "process_hint_reason": "the domain was inherited from the local peer process", "process_hint_server_name": "nbu811"}, "statusmsgxml": "

informix11

nbu811

Unable to perform peer host name validation. Curl error has occurred for peer name: informix11, self name: nbu811

VALIDATE_PEER_HOST_PROTOCOL_RUNNING

ValidatePeerHostProtocol

[PROXY] Encountered error (VALIDATE_PEER_HOST_PROTOCOL_RUNNING) while processing(ValidatePeerHostProtocol).", "statusmsg": "Unable to perform peer host name validation. Curl error has occurred for peer name: informix11, self name: nbu811, nbu status = 0, severity = 2, Additional Message: [PROXY] Encountered error (VALIDATE_PEER_HOST_PROTOCOL_RUNNING) while processing(ValidatePeerHostProtocol)., nbu status = 1, severity = 1"}}, "status": {"timestamp": 1536375982, "who": "connect_to_service", "line_number": 981, "comment": "vnet status", "data": 45}} 11:06:22.600 [4716.4528] <8> vnet_connect_to_bpcd: [vnet_connect.c:565] connect_to_service() failed 45 0x2d 11:06:22.600 [4716.4528] <16> local_bpcr_connect: vnet_connect_to_bpcd(informix11) failed: 45 11:06:22.600 [4716.4528] <2> local_bpcr_connect: Can't connect to client informix11 11:06:22.600 [4716.4528] <2> ConnectToBPCD: bpcd_connect_and_verify(informix11, informix11) failed: 227 11:06:22.600 [4716.4528] <2> log_to_progress_file: BPCD on informix11 exited with status 227: no entity was found 11:06:22.616 [4716.4528] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 11:06:30.710 [4716.4528] <16> dump_proxy_info: statusmsg: Unable to perform peer host name validation. Curl error has occurred for peer name: informix11, self name: nbu811, nbu status = 0, severity = 2, Additional Message: [PROXY] Encountered error (VALIDATE_PEER_HOST_PROTOCOL_RUNNING) while processing(ValidatePeerHostProtocol)., nbu status = 1, severity = 1 11:06:30.710 [4716.4528] <16> dump_proxy_info: domain_constraints_set: 11:06:30.710 [4716.4528] <16> dump_proxy_info: ----process_hint_server_name: nbu811 11:06:30.710 [4716.4528] <16> dump_proxy_info: ----process_hint: bf012ed4-e999-45ae-bdf2-56b22228f5a9 11:06:30.710 [4716.4528] <16> dump_proxy_info: ----process_hint_reason: the domain was inherited from the local peer process 11:06:30.710 [4716.4528] <16> vnet_proxy_protocol_from_legacy: proxy returned status: 1 msg: {"status": 1, "local_proxy_info": {}, "domain_constraints_set": {"process_hint": "bf012ed4-e999-45ae-bdf2-56b22228f5a9", "process_hint_reason": "the domain was inherited from the local peer process", "process_hint_server_name": "nbu811"}, "statusmsgxml": "

informix11

nbu811

Unable to perform peer host name validation. Curl error has occurred for peer name: informix11, self name: nbu811

VALIDATE_PEER_HOST_PROTOCOL_RUNNING

ValidatePeerHostProtocol

[PROXY] Encountered error (VALIDATE_PEER_HOST_PROTOCOL_RUNNING) while processing(ValidatePeerHostProtocol).", "statusmsg": "Unable to perform peer host name validation. Curl error has occurred for peer name: informix11, self name: nbu811, nbu status = 0, severity = 2, Additional Message: [PROXY] Encountered error (VALIDATE_PEER_HOST_PROTOCOL_RUNNING) while processing(ValidatePeerHostProtocol)., nbu status = 1, severity = 1"} vnet status: 45 11:06:30.710 [4716.4528] <16> vnet_proxy_socket_swap: vnet_proxy_protocol_from_legacy() failed: 45 11:06:30.710 [4716.4528] <16> connect_to_service: vnet_proxy_socket_swap(NULL, -1, 310, 0, informix11, bpcd, 2) failed: 45 11:06:30.710 [4716.4528] <16> connect_to_service: JSON data = {"allow_large_status": {"timestamp": 1536375982, "who": "vnet_tss_init", "line_number": 32, "comment": "allow vnet status > 255", "data": false}, "direct_connect": {"timestamp": 1536375982, "who": "connect_to_service", "line_number": 834, "comment": "connect parameters", "data": {"who": "vnet_connect_to_bpcd", "host": "informix11", "service": "bpcd", "override_required_interface": null, "extra_tries_on_connect": 0, "getsock_disable_to": 0, "overide_connect_timeout": 0, "connect_options": {"server": null, "callback_kind": {"number": 1, "symbol": "NBCONF_CALLBACK_KIND_VNETD", "description": "Vnetd"}, "daemon_port_type": {"number": 0, "symbol": "NBCONF_DAEMON_PORT_TYPE_AUTOMATIC", "description": "Automatic"}, "reserved_port_kind": {"number": 0, "symbol": "NBCONF_RESERVED_PORT_KIND_LEGACY", "description": "Legacy"}}}}, "proxy_request": {"timestamp": 1536375982, "who": "vnet_proxy_build_request", "line_number": 3300, "comment": "request to local proxy", "data": {"bpcd_info": {"remote_client": "informix11", "remote_host": "informix11", "remote_probably_dhcp": false, "proxy_flag": 16, "proxy_flag_hex": "0x00000010"}, "i_am_daemon": false, "domain_constraints": {"process_hint": "bf012ed4-e999-45ae-bdf2-56b22228f5a9"}, "service": "bpcd", "auth_only": false, "pid": 4716, "ssa": false, "local_user": "NBU811$@WORKGROUP", "host": "informix11", "peer_host": "informix11", "peer_client": "informix11", "local_client": "nbu811", "local_dhcp_interval": 0, "peer_dhcp_info": null, "local_hostname": "nbu811", "secure_peer": false, "resilient": false, "nbrntd_peer_addr": null, "nbrntd_sock_addr": null, "hand_back": false, "request_timeout": 310}}, "proxy_response": {"timestamp": 1536375990, "who": "vnet_proxy_parse_response", "line_number": 3472, "comment": "response from local proxy", "data": {"status": 1, "local_proxy_info": {}, "domain_constraints_set": {"process_hint": "bf012ed4-e999-45ae-bdf2-56b22228f5a9", "process_hint_reason": "the domain was inherited from the local peer process", "process_hint_server_name": "nbu811"}, "statusmsgxml": "

informix11

nbu811

Unable to perform peer host name validation. Curl error has occurred for peer name: informix11, self name: nbu811

VALIDATE_PEER_HOST_PROTOCOL_RUNNING

ValidatePeerHostProtocol

[PROXY] Encountered error (VALIDATE_PEER_HOST_PROTOCOL_RUNNING) while processing(ValidatePeerHostProtocol).", "statusmsg": "Unable to perform peer host name validation. Curl error has occurred for peer name: informix11, self name: nbu811, nbu status = 0, severity = 2, Additional Message: [PROXY] Encountered error (VALIDATE_PEER_HOST_PROTOCOL_RUNNING) while processing(ValidatePeerHostProtocol)., nbu status = 1, severity = 1"}}, "status": {"timestamp": 1536375990, "who": "connect_to_service", "line_number": 981, "comment": "vnet status", "data": 45}} 11:06:30.710 [4716.4528] <8> vnet_connect_to_bpcd: [vnet_connect.c:565] connect_to_service() failed 45 0x2d 11:06:30.710 [4716.4528] <16> local_bpcr_connect: vnet_connect_to_bpcd(informix11) failed: 45 11:06:30.710 [4716.4528] <2> local_bpcr_connect: Can't connect to client informix11 11:06:30.710 [4716.4528] <2> ConnectToBPCD: bpcd_connect_and_verify(informix11, informix11) failed: 227 11:06:30.710 [4716.4528] <2> log_to_progress_file: BPCD on informix11 exited with status 227: no entity was found 11:06:30.710 [4716.4528] <8> vnet_close_socket_safely_ex: [vnet.c:1158] error on read EOF 10053 0x2745 11:06:30.710 [4716.4528] <16> process_request: Error occurred during closure of socket(newsockfd) - socket read failed (23) 11:06:30.710 [4716.4528] <8> JobdSockList::~JobdSockList: 1 socket left. 11:06:30.710 [4716.4528] <4> JobdSockList::UnregisterSocket: Unregister socket (772). 11:06:30.725 [4716.4528] <2> job_monitoring_exex: ACK disconnect 11:06:30.725 [4716.4528] <2> job_disconnect: Disconnected bpcd: 11:01:46.843 [2719] <2> setup_debug_log: switched debug log file for bpcd 11:01:46.843 [2719] <2> bpcd main: VERBOSE = 0 11:01:46.843 [2719] <2> logparams: /usr/openv/netbackup/bin/bpcd -standalone 11:01:46.848 [2721] <2> setup_debug_log: switched debug log file for bpcd 11:01:46.873 [2721] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=0 FILE=/usr/openv/var/host_cache/1ff/ffffffff+bpcd,1,1,0,1,0+.txt 11:01:46.881 [2721] <2> daemon_startup_listeners: 4 listening for legacy service bpcd 11:01:46.881 [2721] <2> daemon_startup_listeners: listening for vnetd service bpcd 11:01:46.932 [2721] <2> vnet_registerPBXServer: ../../libvlibs/vnet_pbx.c.132: pbxRegisterEx successful at 192.168.80.41:5126/bpcd, returns with 0 alt_addrs 11:01:46.932 [2721] <2> daemon_resource_checker: last_brk = (nil), new_brk = 0x1b1a2000 11:01:46.932 [2721] <2> daemon_resource_checker: last max_fd = 0, new_fd = 10 11:02:26.019 [2721] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 192.168.80.194:49863 11:02:26.019 [2721] <2> bpcd main: accept sock = 10 11:02:26.020 [2806] <2> process_requests: offset to GMT -28800 11:02:26.020 [2806] <2> logconnections: BPCD ACCEPT FROM 192.168.80.194.49863 TO 192.168.80.41.1556 fd = 0 11:02:26.020 [2806] <2> process_requests: setup_sockopts complete 11:02:26.020 [2806] <2> bpcd peer_hostname: Connection from host nbu811 (192.168.80.194) port 49863 11:02:26.020 [2806] <2> bpcd valid_server: comparing nbu811 and nbu811 11:02:26.020 [2806] <4> bpcd valid_server: hostname comparison succeeded 11:02:26.048 [2806] <16> process_requests: read failed: Success 11:07:02.316 [2721] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 192.168.80.194:49894 11:07:02.316 [2721] <2> bpcd main: accept sock = 10 11:07:02.318 [2923] <2> process_requests: offset to GMT -28800 11:07:02.318 [2923] <2> logconnections: BPCD ACCEPT FROM 192.168.80.194.49894 TO 192.168.80.41.1556 fd = 0 11:07:02.319 [2923] <2> process_requests: setup_sockopts complete 11:07:02.319 [2923] <2> bpcd peer_hostname: Connection from host nbu811 (192.168.80.194) port 49894 11:07:02.319 [2923] <2> bpcd valid_server: comparing nbu811 and nbu811 11:07:02.319 [2923] <4> bpcd valid_server: hostname comparison succeeded 11:07:02.335 [2923] <16> process_requests: read failed: Success 11:07:02.347 [2721] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 192.168.80.194:49896 11:07:02.347 [2721] <2> bpcd main: accept sock = 10 11:07:02.348 [2924] <2> process_requests: offset to GMT -28800 11:07:02.348 [2924] <2> logconnections: BPCD ACCEPT FROM 192.168.80.194.49896 TO 192.168.80.41.1556 fd = 0 11:07:02.348 [2924] <2> process_requests: setup_sockopts complete 11:07:02.349 [2924] <2> bpcd peer_hostname: Connection from host nbu811 (192.168.80.194) port 49896 11:07:02.349 [2924] <2> bpcd valid_server: comparing nbu811 and nbu811 11:07:02.349 [2924] <4> bpcd valid_server: hostname comparison succeeded 11:07:10.449 [2924] <16> process_requests: read failed: Success 11:09:07.570 [2721] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 192.168.80.194:49919 11:09:07.570 [2721] <2> bpcd main: accept sock = 10 11:09:07.571 [2927] <2> process_requests: offset to GMT -28800 11:09:07.571 [2927] <2> logconnections: BPCD ACCEPT FROM 192.168.80.194.49919 TO 192.168.80.41.1556 fd = 0 11:09:07.571 [2927] <2> process_requests: setup_sockopts complete 11:09:07.571 [2927] <2> bpcd peer_hostname: Connection from host nbu811 (192.168.80.194) port 49919 11:09:07.571 [2927] <2> bpcd valid_server: comparing nbu811 and nbu811 11:09:07.571 [2927] <4> bpcd valid_server: hostname comparison succeeded 11:09:08.125 [2927] <16> process_requests: read failed: Success 11:09:08.222 [2721] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 192.168.80.194:49928 11:09:08.222 [2721] <2> bpcd main: accept sock = 10 11:09:08.223 [2928] <2> process_requests: offset to GMT -28800 11:09:08.223 [2928] <2> logconnections: BPCD ACCEPT FROM 192.168.80.194.49928 TO 192.168.80.41.1556 fd = 0 11:09:08.224 [2928] <2> process_requests: setup_sockopts complete 11:09:08.224 [2928] <2> bpcd peer_hostname: Connection from host nbu811 (192.168.80.194) port 49928 11:09:08.224 [2928] <2> bpcd valid_server: comparing nbu811 and nbu811 11:09:08.224 [2928] <4> bpcd valid_server: hostname comparison succeeded 11:09:15.309 [2928] <16> process_requests: read failed: Success