15:01:30.899 [12142] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.235.3.213:63148 15:01:30.899 [12142] <2> bprd: socket fd from accept() is 10 15:01:30.902 [12142] <2> listen_loop: request complete 15:01:30.909 [21892] <2> logconnections: BPRD ACCEPT FROM 10.235.3.213.63148 TO 10.235.3.213.1556 fd = 10 15:01:30.910 [21892] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:01:30.911 [21892] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8 15:01:30.912 [21892] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1904] found via getnameinfo OUR_HOST=quaoar IPSTR=10.235.3.213 15:01:30.912 [21892] <2> connected_peer: Connection from host quaoar, 10.235.3.213, on non-reserved port 63148 15:01:30.913 [21892] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:01:30.913 [21892] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 138 0x8a 15:01:30.913 [21892] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:01:30.913 [21892] <2> process_request: command C_BPRESTORE_7_0 (138) received 15:01:30.913 [21892] <2> get_ccname: determine configured name for quaoar 15:01:30.914 [21892] <2> ConnectionCache::connectAndCache: Acquiring new connection for host quaoar, query type 84 15:01:30.914 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:30.914 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.914 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.914 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.914 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.914 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.914 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.915 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:30.915 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.235.3.213.63149 TO 10.235.3.213.1556 fd = 6 15:01:30.915 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63149 TO 10.235.3.213.1556 fd = 6 15:01:30.915 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpdbm VIA pbx 15:01:30.915 [21892] <2> logconnections: BPDBM CONNECT FROM 10.235.3.213.63149 TO 10.235.3.213.1556 fd = 6 15:01:30.916 [21892] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:01:30.954 [21892] <2> db_end: Need to collect reply 15:01:30.955 [21892] <2> get_ccname: configured name is: quaoar 15:01:30.955 [21892] <2> process_request: restore request 138, bufr = 329199 138 root root LDNWDFSP01 quaoar LDNWDFSP01 quaoar /usr/openv/netbackup/logs/user_ops/root/logs/jbp-12788423494090883574000000127-kjaG.y.log NONE NONE 0 1423494090 1409936440 1409936440 1423494090 4 1 0 0 0 12 0 13 0 0 0 3 0 1 en_UK en_UK en_UK en_UK en_UK 0 1 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 5 0 NONE -1 0 0 0 0 NONE NONE NONE 0 0 0 15:01:30.956 [21892] <2> process_request: pre vm_fields 0 0 0 0 0 0 0 0 5 0 NONE -1 0 0 0 0 NONE NONE NONE 0 0 0 15:01:30.956 [21892] <2> process_request: vm_fields 0 NONE -1 0 0 0 0 NONE NONE NONE 0 0 0 15:01:30.956 [21892] <2> process_request: As rcvd from client: 15:01:30.956 [21892] <2> process_request: browse_clnt: LDNWDFSP01 15:01:30.956 [21892] <2> process_request: requesting_clnt: quaoar 15:01:30.956 [21892] <2> process_request: destination_clnt: LDNWDFSP01 15:01:30.956 [21892] <2> process_request: clnt_bp_conf_name: quaoar 15:01:30.956 [21892] <2> process_request: peername: quaoar 15:01:30.956 [21892] <2> process_request: ccname: quaoar 15:01:30.956 [21892] <2> process_request: keyword = 15:01:30.956 [21892] <2> process_request: restore_format: 3 15:01:30.956 [21892] <2> process_request: true_image: 0 15:01:30.956 [21892] <2> process_request: mpx_restore_possible: 1 15:01:30.956 [21892] <2> process_request: secondary_restore: 0 15:01:30.956 [21892] <2> process_request: priority = -1 15:01:30.956 [21892] <2> process_request: disk_media_server = NONE 15:01:30.956 [21892] <2> process_request: spsrestoreoptions = 0 15:01:30.956 [21892] <2> process_request: vhd_file_name = NONE 15:01:30.956 [21892] <2> process_request: vhd_type = 0 15:01:30.956 [21892] <2> process_request: vhd_disk_size = 0 15:01:30.956 [21892] <2> process_request: vhd_delete_on_failure = 0 15:01:30.957 [21892] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:01:30.957 [21892] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:01:30.957 [21892] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:01:30.957 [21892] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:01:30.957 [21892] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:01:30.958 [21892] <2> get_type_of_client_port: connect_options = 0x01030202 15:01:30.958 [21892] <2> process_request: After get_client_and_host_names: 15:01:30.958 [21892] <2> process_request: destination_clnt: LDNWDFSP01 15:01:30.958 [21892] <2> process_request: destination_bp_conf_name: LDNWDFSP01 15:01:30.958 [21892] <2> process_request: retval: 0 15:01:30.958 [21892] <2> db_freeEXDB_INFO: ? 15:01:30.959 [21892] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:01:30.959 [21892] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:01:30.959 [21892] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:01:30.959 [21892] <2> get_type_of_client_list_restore: list and restore not specified for quaoar 15:01:30.961 [21892] <2> append_to_client_log: ../dbmisc.c.3319: about to call ConnectToBPCD connect_opts = 0x01030202 15:01:30.961 [21892] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100 15:01:30.961 [21892] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100 15:01:30.962 [21892] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0 15:01:30.962 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:30.962 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.962 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.962 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.962 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.962 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.962 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.963 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:30.963 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 10.235.3.213.63150 TO 10.235.3.213.1556 fd = 6 15:01:30.964 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63150 TO 10.235.3.213.1556 fd = 6 15:01:30.964 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpcd VIA pbx 15:01:30.964 [21892] <2> logconnections: BPCD CONNECT FROM 10.235.3.213.63150 TO 10.235.3.213.1556 fd = 6 15:01:30.965 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:30.965 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.965 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.965 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.965 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.966 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:30.970 [21892] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.235.3.213.63151 TO 10.235.3.213.1556 fd = 7 15:01:30.970 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63151 TO 10.235.3.213.1556 fd = 7 15:01:30.970 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.235.3.213 10.235.3.213 vnetd VIA pbx 15:01:30.970 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:01:30.972 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-21895423494090971197000000000-Bda4WQ 15:01:30.972 [21892] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 b7bca63afe36ea6c6449655fa92b72ca 15:01:30.983 [21892] <2> append_to_client_log: ../dbmisc.c.3319: about to call ConnectToBPCD connect_opts = 0x01030202 15:01:30.983 [21892] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100 15:01:30.983 [21892] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100 15:01:30.983 [21892] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0 15:01:30.984 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:30.984 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.984 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.984 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.984 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.984 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.984 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.985 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:30.985 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 10.235.3.213.63152 TO 10.235.3.213.1556 fd = 6 15:01:30.985 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63152 TO 10.235.3.213.1556 fd = 6 15:01:30.985 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpcd VIA pbx 15:01:30.985 [21892] <2> logconnections: BPCD CONNECT FROM 10.235.3.213.63152 TO 10.235.3.213.1556 fd = 6 15:01:30.985 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:30.985 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.985 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.985 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.986 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.986 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:30.991 [21892] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.235.3.213.63153 TO 10.235.3.213.1556 fd = 7 15:01:30.991 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63153 TO 10.235.3.213.1556 fd = 7 15:01:30.991 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.235.3.213 10.235.3.213 vnetd VIA pbx 15:01:30.991 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:01:30.992 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-21897423494090991977000000000-WdaiXQ 15:01:30.993 [21892] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 6ca18e2ea3a8dee4b75bff7c7022fe3f 15:01:30.999 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:30.999 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.999 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:30.999 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:30.999 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.000 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.000 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.001 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:31.001 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpjobd CONNECT FROM 10.235.3.213.63154 TO 10.235.3.213.1556 fd = 6 15:01:31.001 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63154 TO 10.235.3.213.1556 fd = 6 15:01:31.001 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpjobd VIA pbx 15:01:31.001 [21892] <2> job_connect: SO_KEEPALIVE set on socket 6 for client quaoar 15:01:31.001 [21892] <2> logconnections: BPJOBD CONNECT FROM 10.235.3.213.63154 TO 10.235.3.213.1556 fd = 6 15:01:31.001 [21892] <2> job_authenticate_connection: ignoring VxSS authentication check for now... 15:01:31.002 [21892] <2> job_connect: Connected to the host quaoar contype 53 jobid <67786> socket <6> 15:01:31.002 [21892] <2> job_connect: Connected on port 63154 15:01:31.002 [21892] <2> set_job_details: jobData (67786) 15:01:31.002 [21892] <2> send_structure_data: Index 0 Field m_nJobid Value <67786> 15:01:31.002 [21892] <2> send_structure_data: Index 4 Field m_nMainPid Value <21892> 15:01:31.002 [21892] <2> send_structure_data: Index 7 Field m_nType Value <2> 15:01:31.002 [21892] <2> send_structure_data: Index 14 Field m_szClient Value 15:01:31.002 [21892] <2> send_structure_data: Index 16 Field m_szOwner Value 15:01:31.002 [21892] <2> send_structure_data: Index 17 Field m_szGroup Value 15:01:31.002 [21892] <2> send_structure_data: Index 21 Field m_nState Value <0> 15:01:31.002 [21892] <2> send_structure_data: Index 29 Field m_dtStarted Value <1423494090> 15:01:31.002 [21892] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1423494090> 15:01:31.003 [21892] <2> set_job_details: jobRunData (67786) 15:01:31.003 [21892] <2> send_structure_data: Index 66 Field m_nKillable Value <1> 15:01:31.003 [21892] <2> set_job_details: Tfile (67786): BEGIN_OPERATION 1423494091 RESTORE 15:01:31.003 [21892] <2> send_job_file: job ID 67786, ftype = 3 msg len = 35, msg = BEGIN_OPERATION 1423494091 RESTORE 15:01:31.003 [21892] <2> get_type_of_client_free_browse: Free browse allowed for quaoar 15:01:31.003 [21892] <2> process_request: After get_client_and_host_names: 15:01:31.003 [21892] <2> process_request: browse_clnt: LDNWDFSP01 15:01:31.003 [21892] <2> process_request: clnt_bp_conf_name: quaoar 15:01:31.003 [21892] <2> process_request: retval: 0 15:01:31.003 [21892] <2> db_freeEXDB_INFO: ? 15:01:31.003 [21892] <2> adjust_clientname: adjust_clientname(quaoar, quaoar, quaoar, quaoar, 1) 15:01:31.003 [21892] <2> get_ccname: determine configured name for quaoar 15:01:31.003 [21892] <2> ConnectionCache::connectAndCache: Acquiring new connection for host quaoar, query type 84 15:01:31.003 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:31.003 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.003 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.003 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.003 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.003 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.003 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.004 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:31.004 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.235.3.213.63155 TO 10.235.3.213.1556 fd = 7 15:01:31.004 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63155 TO 10.235.3.213.1556 fd = 7 15:01:31.004 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpdbm VIA pbx 15:01:31.005 [21892] <2> logconnections: BPDBM CONNECT FROM 10.235.3.213.63155 TO 10.235.3.213.1556 fd = 7 15:01:31.005 [21892] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:01:31.046 [21892] <2> db_end: Need to collect reply 15:01:31.047 [21892] <2> get_ccname: configured name is: quaoar 15:01:31.047 [21892] <2> adjust_clientname: adjusted clientname = quaoar, client_bp_conf_name = quaoar 15:01:31.047 [21892] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100 15:01:31.047 [21892] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100 15:01:31.047 [21892] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0 15:01:31.047 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.047 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 15:01:31.048 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.048 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 15:01:31.048 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.048 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 15:01:31.048 [21892] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 15:01:31.050 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:31.051 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 10.235.3.213.63156 TO 10.235.4.14.1556 fd = 7 15:01:31.051 [21892] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.235.3.213.63156 TO 10.235.4.14.1556 fd = 7 15:01:31.051 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO LDNWDFSP01 10.235.4.14 bpcd VIA pbx 15:01:31.051 [21892] <2> logconnections: BPCD CONNECT FROM 10.235.3.213.63156 TO 10.235.4.14.1556 fd = 7 15:01:31.051 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.051 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 15:01:31.051 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.051 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 15:01:31.052 [21892] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 15:01:31.054 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:31.077 [21892] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.235.3.213.63157 TO 10.235.4.14.1556 fd = 8 15:01:31.077 [21892] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.235.3.213.63157 TO 10.235.4.14.1556 fd = 8 15:01:31.077 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.235.4.14 10.235.4.14 vnetd VIA pbx 15:01:31.077 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:01:31.288 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 49156 15:01:31.406 [21892] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 8fa2b7a603724ed3ab888b0187027ce7 15:01:31.645 [21892] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:01:31.646 [21892] <2> process_request: Before restorefiles 15:01:31.646 [21892] <2> process_request: requesting_clnt_hostname = ccname quaoar 15:01:31.647 [21892] <2> restorefiles: mpx_restore_possible = 1 15:01:31.647 [21892] <2> restorefiles: browse_client = LDNWDFSP01 15:01:31.647 [21892] <2> restorefiles: requesting_client = quaoar 15:01:31.647 [21892] <2> restorefiles: destination_client = LDNWDFSP01 15:01:31.647 [21892] <2> restorefiles: requesting_client_hostname = quaoar 15:01:31.647 [21892] <2> restorefiles: destination_client_hostname = LDNWDFSP01 15:01:31.647 [21892] <2> restorefiles: requesting_user = root 15:01:31.647 [21892] <2> restorefiles: requesting_group = root 15:01:31.647 [21892] <2> restorefiles: progress_file = /usr/openv/netbackup/logs/user_ops/root/logs/jbp-12788423494090883574000000127-kjaG.y.log 15:01:31.647 [21892] <2> restorefiles: rename_file = NONE 15:01:31.647 [21892] <2> restorefiles: policy = NONE 15:01:31.647 [21892] <2> restorefiles: keyword = 15:01:31.647 [21892] <2> restorefiles: sched_type = 12 15:01:31.647 [21892] <2> restorefiles: keep = 1 15:01:31.647 [21892] <2> restorefiles: restore_retries = 0 15:01:31.647 [21892] <2> restorefiles: raw_restore = 0 15:01:31.647 [21892] <2> restorefiles: max_apollo_restore_arg_chars = 9000 15:01:31.647 [21892] <2> restorefiles: rename_linktos = 0 15:01:31.647 [21892] <2> restorefiles: client_type = 13 15:01:31.647 [21892] <2> restorefiles: rest_start_time = 1423494090 15:01:31.647 [21892] <2> restorefiles: cmd_start_date = 1409936440 15:01:31.647 [21892] <2> restorefiles: cmd_end_date = 1409936440 15:01:31.647 [21892] <2> restorefiles: unixtime = 1423494090 15:01:31.647 [21892] <2> restorefiles: timetype = 4 15:01:31.647 [21892] <2> restorefiles: user_interface = 0 15:01:31.647 [21892] <2> restorefiles: monitor_restore_job = 1 15:01:31.647 [21892] <2> restorefiles: restore_jobidstr = 67786 15:01:31.647 [21892] <2> restorefiles: client_locales_available = 1 15:01:31.647 [21892] <2> restorefiles: clnt_lc_messages = en_UK 15:01:31.647 [21892] <2> restorefiles: clnt_lc_time = en_UK 15:01:31.647 [21892] <2> restorefiles: clnt_lc_ctype = en_UK 15:01:31.647 [21892] <2> restorefiles: clnt_lc_collate = en_UK 15:01:31.647 [21892] <2> restorefiles: clnt_lc_numeric = en_UK 15:01:31.647 [21892] <2> restorefiles: cverbose = 5 15:01:31.647 [21892] <2> restorefiles: true_image = 0 15:01:31.647 [21892] <2> restorefiles: restorable_format = 3 15:01:31.647 [21892] <2> restorefiles: bpcd_connect_opts = 16974338 15:01:31.647 [21892] <2> restorefiles: filesystem_only = 0 15:01:31.647 [21892] <2> restorefiles: clntuid = 0 15:01:31.647 [21892] <2> restorefiles: clntgid = 0 15:01:31.647 [21892] <2> restorefiles: acknowledge_status = 0 15:01:31.648 [21892] <2> restorefiles: keep_progress_log_open = 1 15:01:31.648 [21892] <2> restorefiles: clnt_flops = 0 15:01:31.648 [21892] <2> restorefiles: proxy_copy = 0 15:01:31.648 [21892] <2> restorefiles: clnt_v2_1_or_later = 1 15:01:31.648 [21892] <2> restorefiles: commit = 0 15:01:31.648 [21892] <2> restorefiles: dirByteCount = 0 15:01:31.648 [21892] <2> restorefiles: tempDirectory = NULL 15:01:31.648 [21892] <2> restorefiles: mountDatabase = 0 15:01:31.648 [21892] <2> restorefiles: bePathByteCount = 0 15:01:31.648 [21892] <2> restorefiles: beRedirectionPath = NULL 15:01:31.648 [21892] <2> restorefiles: restore_sync_command = 0 15:01:31.648 [21892] <2> restorefiles: use_alt_buf = 0 15:01:31.648 [21892] <2> restorefiles: generate_english_logs = 0 15:01:31.648 [21892] <2> restorefiles: dont_restore_security = 0 15:01:31.648 [21892] <2> restorefiles: redirWebSrvrByteCount = 0 15:01:31.648 [21892] <2> restorefiles: redirWebSrvr = NULL 15:01:31.648 [21892] <2> restorefiles: granular_restore = 0 15:01:31.648 [21892] <2> restorefiles: alt_rest_copy_num = 0 15:01:31.648 [21892] <2> restorefiles: vm_opts = 0 15:01:31.648 [21892] <2> restorefiles: vm_server_string = NONE 15:01:31.648 [21892] <2> restorefiles: priority = -1 15:01:31.648 [21892] <2> restorefiles: disk_media_server = NONE 15:01:31.648 [21892] <2> restorefiles: spsrestoreoptions = 0 15:01:31.648 [21892] <2> restorefiles: vhd_file_name = NONE 15:01:31.648 [21892] <2> restorefiles: vhd_type = 0 15:01:31.648 [21892] <2> restorefiles: vhd_disk_size = 0 15:01:31.648 [21892] <2> restorefiles: vhd_delete_on_failure = 0 15:01:31.648 [21892] <2> restorefiles: vm_db_uri_str_len = 0 15:01:31.648 [21892] <2> open_job_info_file: open restore job params /usr/openv/netbackup/db/jobs/restart/67786.parms for w 15:01:31.649 [21892] <2> ConnectionCache::connectAndCache: Acquiring new connection for host quaoar, query type 76 15:01:31.649 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:31.649 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.649 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.649 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.649 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.649 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.649 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.650 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:31.650 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.235.3.213.63158 TO 10.235.3.213.1556 fd = 8 15:01:31.651 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63158 TO 10.235.3.213.1556 fd = 8 15:01:31.651 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpdbm VIA pbx 15:01:31.651 [21892] <2> logconnections: BPDBM CONNECT FROM 10.235.3.213.63158 TO 10.235.3.213.1556 fd = 8 15:01:31.651 [21892] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:01:31.749 [21892] <2> add_image_to_list: Null query->keyword detected. Backup_time = 1409936440 15:01:31.749 [21892] <2> add_image_to_list: Null query->obj_desc detected. Backup_time = 1409936440 15:01:31.749 [21892] <2> add_image_to_list: Null query->obj_desc detected. Backup_time = 1409936440 15:01:31.765 [21892] <2> get_next_13_tuple: received string: 4334820 0 0 0 4334592 80 75541789 -1 1 75 1409936440 1409936440 /D/LaptopBackups/NB1787-ABE.carmicht/Outlook/backup_competition_results.pst 15:01:31.766 [21892] <2> get_next_13_tuple: received string: CONTINUE RESTORE 15:01:31.766 [21892] <2> set_job_details: Ffile (67786): /D/LaptopBackups/NB1787-ABE.carmicht/Outlook/backup_competition_results.pst 15:01:31.766 [21892] <2> send_job_file: job ID 67786, ftype = 4 msg len = 76, msg = /D/LaptopBackups/NB1787-ABE.carmicht/Outlook/backup_competition_results.pst 15:01:31.766 [21892] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100 15:01:31.766 [21892] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100 15:01:31.766 [21892] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0 15:01:31.766 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:31.766 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.766 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.766 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.766 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.766 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.766 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.775 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:31.775 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 10.235.3.213.63159 TO 10.235.3.213.1556 fd = 7 15:01:31.776 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63159 TO 10.235.3.213.1556 fd = 7 15:01:31.776 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpcd VIA pbx 15:01:31.776 [21892] <2> logconnections: BPCD CONNECT FROM 10.235.3.213.63159 TO 10.235.3.213.1556 fd = 7 15:01:31.776 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:31.776 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.776 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.776 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.776 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.777 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:31.781 [21892] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.235.3.213.63160 TO 10.235.3.213.1556 fd = 8 15:01:31.781 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63160 TO 10.235.3.213.1556 fd = 8 15:01:31.781 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.235.3.213 10.235.3.213 vnetd VIA pbx 15:01:31.781 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:01:31.782 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-21901423494091782034000000000-gqaOXQ 15:01:31.783 [21892] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 9c411e2a05fb673bb0e56a6e260e6fe5 15:01:31.785 [21892] <2> add_msgs_to_progress_file: CLIENT_CMD_SOCK from bpcr = 7 15:01:31.785 [21892] <2> add_msgs_to_progress_file: CLIENT_STAT_SOCK from bpcr = 8 15:01:31.794 [21892] <2> emmlib_initialize: (-) Connection attempt #<0> 15:01:31.795 [21892] <4> ValidateConnectionID: (-) Created new Connection ID 0 15:01:31.795 [21892] <2> emmlib_initializeEx: (-) Connecting to the Server Port <1556>, App OrbOpt 15:01:31.808 [21892] <2> Orb::setDebugLevelFromVxul: Orb logging configuration level set to 0(Orb.cpp:2298) 15:01:31.809 [21892] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:518) 15:01:31.809 [21892] <2> BpConfigHolder::BpConfigHolder: +++ ENTERING +++ : obj = 100930658 (../BpConf.cpp:34) 15:01:31.810 [21892] <2> BpConfigHolder::reload: +++ ENTERING +++ : obj = 100930658 (../BpConf.cpp:46) 15:01:31.810 [21892] <2> BpConfigHolder::reload: --- EXITING --- : obj = 100930658 (../BpConf.cpp:46) 15:01:31.810 [21892] <2> BpConfigHolder::BpConfigHolder: --- EXITING --- : obj = 100930658 (../BpConf.cpp:34) 15:01:31.813 [21892] <2> EndpointSelectorFactory::EndpointSelectorFactory: +++ ENTERING +++ : obj = 1009443a0 (../Endpoint_Selector.cpp:1934) 15:01:31.813 [21892] <2> EndpointSelector_R2::EndpointSelector_R2: +++ ENTERING +++ : obj = 100944420 (../Endpoint_Selector.cpp:362) 15:01:31.814 [21892] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf LastUpd=1423484005 bp.conf LastLook=0(Endpoint_Selector.cpp:403) 15:01:31.814 [21892] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:407) 15:01:31.814 [21892] <2> EndpointSelector_R2::updateConfMembers: epsr2: tardy lookup log time value set to 5 seconds(Endpoint_Selector.cpp:442) 15:01:31.814 [21892] <2> EndpointSelector_R2::updateConfMembers: epsr2: USE_VXSS = PROHIBITED(Endpoint_Selector.cpp:453) 15:01:31.814 [21892] <2> EndpointSelector_R2::EndpointSelector_R2: --- EXITING --- : obj = 100944420 (../Endpoint_Selector.cpp:362) 15:01:31.814 [21892] <2> EndpointSelectorFactory::EndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0(Endpoint_Selector.cpp:1981) 15:01:31.814 [21892] <2> EndpointSelectorFactory::EndpointSelectorFactory: --- EXITING --- : obj = 1009443a0 (../Endpoint_Selector.cpp:1934) 15:01:31.814 [21892] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: +++ ENTERING +++ : obj = 1009444d0 (../Endpoint_Selector.cpp:2028) 15:01:31.814 [21892] <2> EndpointSelector_R2::EndpointSelector_R2: +++ ENTERING +++ : obj = 100944550 (../Endpoint_Selector.cpp:362) 15:01:31.814 [21892] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf LastUpd=1423484005 bp.conf LastLook=0(Endpoint_Selector.cpp:403) 15:01:31.814 [21892] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:407) 15:01:31.814 [21892] <2> EndpointSelector_R2::updateConfMembers: epsr2: tardy lookup log time value set to 5 seconds(Endpoint_Selector.cpp:442) 15:01:31.814 [21892] <2> EndpointSelector_R2::updateConfMembers: epsr2: USE_VXSS = PROHIBITED(Endpoint_Selector.cpp:453) 15:01:31.814 [21892] <2> EndpointSelector_R2::EndpointSelector_R2: --- EXITING --- : obj = 100944550 (../Endpoint_Selector.cpp:362) 15:01:31.814 [21892] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0 [Ignoring BPCONF server list](Endpoint_Selector.cpp:2076) 15:01:31.814 [21892] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: --- EXITING --- : obj = 1009444d0 (../Endpoint_Selector.cpp:2028) 15:01:31.815 [21892] <2> checkService: found service: UTF16_BOM_Factory(Orb.cpp:494) 15:01:31.815 [21892] <2> checkService: found service: UTF8-Latin1_Factory(Orb.cpp:494) 15:01:31.815 [21892] <2> LogMaxFileNum: current max fileno: 8192, max fileno hard limit: 8192(SetMaxFileNum.cpp:113) 15:01:31.815 [21892] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:412) 15:01:31.815 [21892] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:464) 15:01:31.815 [21892] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:604) 15:01:31.816 [21892] <2> Orb::init: initializing ORB with: bprd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'"(Orb.cpp:657) 15:01:31.816 [21892] <2> Orb::init: added args has value of: (Orb.cpp:787) 15:01:31.820 [21892] <2> Orb::init: initializing ORB EMMlib_Orb with: bprd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:842) 15:01:31.827 [21892] <2> NBEvaluatorFactory::init: +++ ENTERING +++ : obj = 100943d10 (../SecureEvaluator.cpp:498) 15:01:31.827 [21892] <2> NBEvaluatorFactory::init: --- EXITING --- : obj = 100943d10 (../SecureEvaluator.cpp:498) 15:01:31.829 [21892] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 1009457f0 (../NBOrbInitializer.cpp:45) 15:01:31.829 [21892] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 1009457f0 (../NBOrbInitializer.cpp:45) 15:01:31.832 [21892] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 1009457f0 (../NBOrbInitializer.cpp:53) 15:01:31.832 [21892] <2> NBOrbInitializer::post_init: Adding interceptors(NBOrbInitializer.cpp:74) 15:01:31.832 [21892] <2> NBIORInterceptor::NBIORInterceptor: Interceptor Name: EMMlib_Orb_EMMlib_Orb(NBIORInterceptor.cpp:47) 15:01:31.833 [21892] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 1009457f0 (../NBOrbInitializer.cpp:53) 15:01:31.834 [21892] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:857) 15:01:31.834 [21892] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 800(Orb.cpp:1521) 15:01:31.836 [21892] <2> Orb::activate: +++ ENTERING +++ : obj = 100941480 (../Orb.cpp:1248) 15:01:31.836 [21892] <2> Orb::activate: Client Orb called activate; Ignored(Orb.cpp:1253) 15:01:31.836 [21892] <2> Orb::activate: --- EXITING --- : obj = 100941480 (../Orb.cpp:1248) 15:01:31.836 [21892] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 60(Orb.cpp:1521) 15:01:31.836 [21892] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1498) 15:01:31.836 [21892] <2> emmlib_initializeEx: (-) Setting connect timeout to <60> sec 15:01:31.836 [21892] <2> Orb::setOrbTimeoutPolicy: setting ORB request timeout policy: tv = 1800(Orb.cpp:1521) 15:01:31.836 [21892] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1507) 15:01:31.836 [21892] <2> emmlib_initializeEx: (-) Setting request timeout to <1800> sec 15:01:31.836 [21892] <2> Orb::connectToObject: Connection attempt #1 mW:60 rW:0(Orb.cpp:1184) 15:01:31.836 [21892] <2> Orb::createInsecureObjectRef: +++ ENTERING +++ : obj = 100941480 (../Orb.cpp:1449) 15:01:31.837 [21892] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:quaoar:1556:EMM/EMMServer(Orb.cpp:1457) 15:01:31.839 [21892] <2> Orb::createInsecureObjectRef: --- EXITING --- : obj = 100941480 (../Orb.cpp:1449) 15:01:31.839 [21892] <2> Orb::connectToObjectOnce: attempt to create insecure connection(Orb.cpp:1051) 15:01:31.839 [21892] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 15:01:31.840 [21892] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 15:01:31.840 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 1 endpoints(Endpoint_Selector.cpp:631) 15:01:31.840 [21892] <2> ConnectList::search: host quaoar not found in cache(ConnectList.cpp:116) 15:01:31.840 [21892] <2> VxssContext::useVxss: epsr2: Setting up useVxss=2(Endpoint_Selector.cpp:157) 15:01:31.841 [21892] <2> VxssContext::begin: epsr2: Setup context, passes. vxss=2 passes=1(Endpoint_Selector.cpp:187) 15:01:31.841 [21892] <2> VxssContext::end: epsr2: all done? passes=1 curridx=0(Endpoint_Selector.cpp:193) 15:01:31.841 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: pass: 0 secure_mode:2/0(Endpoint_Selector.cpp:778) 15:01:31.841 [21892] <2> ConnectList::search: host quaoar not found in cache(ConnectList.cpp:116) 15:01:31.841 [21892] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: PBXIOP endpoint host: quaoar(Endpoint_Selector.cpp:1471) 15:01:31.841 [21892] <2> ConnectList::search: host 10.235.3.213 not found in cache(ConnectList.cpp:116) 15:01:31.841 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.841 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.235.3.213: ANY(Endpoint_Selector.cpp:1725) 15:01:31.841 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname quaoar has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.842 [21892] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Endpoint host quaoar is not checked against server list(Endpoint_Selector.cpp:1590) 15:01:31.842 [21892] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Found 1 usable IP addresses for host quaoar:[10.235.3.213] (Endpoint_Selector.cpp:1599) 15:01:31.842 [21892] <2> ConnectList::search: host 10.235.3.213 not found in cache(ConnectList.cpp:116) 15:01:31.842 [21892] <2> ExternalResolver::set: Resolved host_name 10.235.3.213[10.235.3.213](ExternalResolver.cpp:56) 15:01:31.843 [21892] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Target: 10.235.3.213, source: ANY(Endpoint_Selector.cpp:976) 15:01:31.843 [21892] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=1 useVxss=2(Endpoint_Selector.cpp:243) 15:01:31.847 [21892] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.235.3.213 SUCCESS(Endpoint_Selector.cpp:1186) 15:01:31.847 [21892] <2> ConnectList::update: added host 10.235.3.213 (1 total) as GOOD(ConnectList.cpp:211) 15:01:31.847 [21892] <2> VxssContext::end: epsr2: all done? passes=1 curridx=1(Endpoint_Selector.cpp:193) 15:01:31.851 [21892] <2> EndpointSelector_R2::forward: epsr2: Adding 1 forward profiles(Endpoint_Selector.cpp:555) 15:01:31.852 [21892] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 15:01:31.852 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:631) 15:01:31.852 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.852 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.852 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.235.3.213: ANY(Endpoint_Selector.cpp:1725) 15:01:31.852 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.235.3.213 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.852 [21892] <2> ConnectList::search: host 169.254.182.77 not found in cache(ConnectList.cpp:116) 15:01:31.852 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.852 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 169.254.182.77: ANY(Endpoint_Selector.cpp:1725) 15:01:31.852 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 169.254.182.77 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.852 [21892] <2> ConnectList::search: host quaoar.global.ad not found in cache(ConnectList.cpp:116) 15:01:31.853 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.853 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.235.3.213, source: ANY(Endpoint_Selector.cpp:687) 15:01:31.853 [21892] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 15:01:31.853 [21892] <2> ExternalResolver::set: Resolved host_name 10.235.3.213[10.235.3.213](ExternalResolver.cpp:56) 15:01:31.853 [21892] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.235.3.213 SUCCESS(Endpoint_Selector.cpp:1186) 15:01:31.853 [21892] <2> ConnectList::update: updated host 10.235.3.213 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 15:01:31.854 [21892] <2> Orb::connectToObjectOnce: connection created(Orb.cpp:1069) 15:01:31.854 [21892] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 15:01:31.854 [21892] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 15:01:31.854 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:631) 15:01:31.854 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.854 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.854 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.235.3.213: ANY(Endpoint_Selector.cpp:1725) 15:01:31.854 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.235.3.213 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.855 [21892] <2> ConnectList::search: host 169.254.182.77 not found in cache(ConnectList.cpp:116) 15:01:31.855 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.855 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 169.254.182.77: ANY(Endpoint_Selector.cpp:1725) 15:01:31.855 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 169.254.182.77 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.855 [21892] <2> ConnectList::search: host quaoar.global.ad not found in cache(ConnectList.cpp:116) 15:01:31.855 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.855 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.235.3.213, source: ANY(Endpoint_Selector.cpp:687) 15:01:31.855 [21892] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 15:01:31.855 [21892] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.235.3.213 SUCCESS(Endpoint_Selector.cpp:1186) 15:01:31.855 [21892] <2> ConnectList::update: updated host 10.235.3.213 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 15:01:31.856 [21892] <4> CreateConnectionID: (-) Using existing uninitialized Connection ID 0 15:01:31.856 [21892] <4> emmlib_initializeEx: (0) Successfully initialized EMM session 15:01:31.856 [21892] <2> emmlib_GetHost: (-) MachineName Machine type <1> 15:01:31.857 [21892] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 15:01:31.857 [21892] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 15:01:31.857 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:631) 15:01:31.857 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.857 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.857 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.235.3.213: ANY(Endpoint_Selector.cpp:1725) 15:01:31.857 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.235.3.213 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.857 [21892] <2> ConnectList::search: host 169.254.182.77 not found in cache(ConnectList.cpp:116) 15:01:31.857 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.857 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 169.254.182.77: ANY(Endpoint_Selector.cpp:1725) 15:01:31.857 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 169.254.182.77 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.857 [21892] <2> ConnectList::search: host quaoar.global.ad not found in cache(ConnectList.cpp:116) 15:01:31.857 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.857 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.235.3.213, source: ANY(Endpoint_Selector.cpp:687) 15:01:31.857 [21892] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 15:01:31.857 [21892] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.235.3.213 SUCCESS(Endpoint_Selector.cpp:1186) 15:01:31.857 [21892] <2> ConnectList::update: updated host 10.235.3.213 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 15:01:31.859 [21892] <4> emmlib_GetHost: (0) Reading Machine record < quaoar > from EMM database 15:01:31.860 [21892] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 15:01:31.860 [21892] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 15:01:31.860 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:631) 15:01:31.860 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.860 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.860 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.235.3.213: ANY(Endpoint_Selector.cpp:1725) 15:01:31.860 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.235.3.213 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.860 [21892] <2> ConnectList::search: host 169.254.182.77 not found in cache(ConnectList.cpp:116) 15:01:31.860 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.860 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 169.254.182.77: ANY(Endpoint_Selector.cpp:1725) 15:01:31.860 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 169.254.182.77 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.860 [21892] <2> ConnectList::search: host quaoar.global.ad not found in cache(ConnectList.cpp:116) 15:01:31.860 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.860 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.235.3.213, source: ANY(Endpoint_Selector.cpp:687) 15:01:31.860 [21892] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 15:01:31.860 [21892] <2> ExternalResolver::set: Resolved host_name 10.235.3.213[10.235.3.213](ExternalResolver.cpp:56) 15:01:31.860 [21892] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.235.3.213 SUCCESS(Endpoint_Selector.cpp:1186) 15:01:31.860 [21892] <2> ConnectList::update: updated host 10.235.3.213 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 15:01:31.870 [21892] <2> emmlib_GetHost: (0) MachineName = < quaoar > FQName = < quaoar.global.ad > MachineGuid = < 633058554 -27882 4573 8000E1737942BEAA > MasterServerName = < quaoar > ClusterName = < > ActiveNodeName = < > MachineNbuType = < 3 > MachineState = < 14 > MachineFlags = < 247 > MachineDescription = < > OperatingSystem = < 2 > ScanAbility = < 5 > NetBackupVersion = < 750600 > LocalDriveSeed = < > GlobalDriveSeed = < VEND:#.:PROD:#.:IDX > HeartbeatFrequency = < 300 > 15:01:31.870 [21892] <2> emmlib_GetHost: (0) QueryMachine successful, emmError = 0, nbError = 0 15:01:31.870 [21892] <2> get_media_server_version: NBU version of quaoar is 750600 15:01:31.870 [21892] <4> emmlib_uninitialize: (0) Uninitialize connection <0> and shutdown Orb if no other connections exists 15:01:31.870 [21892] <4> DeleteConnectionID: (-) Cleared Connection ID <0> for <> 15:01:31.870 [21892] <4> uninitialize: (0) We still have <0> open connections 15:01:31.870 [21892] <4> shutdown_orb: (-) Destroying the orb 15:01:31.870 [21892] <2> Orb::destroyOrb: +++ ENTERING +++ : obj = 100941480 (../Orb.cpp:1669) 15:01:31.872 [21892] <2> NBIORInterceptor::destroy: Destroying Interceptor: EMMlib_Orb_EMMlib_Orb(NBIORInterceptor.cpp:61) 15:01:31.877 [21892] <2> Orb::destroyOrb: orb has reference count: 0(Orb.cpp:1676) 15:01:31.877 [21892] <2> Orb::destroyOrb: --- EXITING --- : obj = 100941480 (../Orb.cpp:1669) 15:01:31.877 [21892] <2> Evictor::~Evictor(): +++ ENTERING +++ : obj = 1009417c8 (../Evictor.cpp:204) 15:01:31.877 [21892] <2> Evictor::shutdown(): +++ ENTERING +++ : obj = 1009417c8 (../Evictor.cpp:580) 15:01:31.877 [21892] <2> Evictor::shutdown(): --- EXITING --- : obj = 1009417c8 (../Evictor.cpp:580) 15:01:31.877 [21892] <2> Evictor::~Evictor(): --- EXITING --- : obj = 1009417c8 (../Evictor.cpp:204) 15:01:31.877 [21892] <4> emmlib_uninitialize: (0) Successfully released EMM session and database objects 15:01:31.877 [21892] <2> set_job_details: jobData (67786) 15:01:31.877 [21892] <2> send_structure_data: Index 3 Field m_nActivePid Value <21892> 15:01:31.877 [21892] <2> send_structure_data: Index 21 Field m_nState Value <1> 15:01:31.878 [21892] <2> send_structure_data: Index 31 Field m_dtActiveStart Value <1423494091> 15:01:31.878 [21892] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1423494091> 15:01:31.878 [21892] <2> set_job_details: jobRunData (67786) 15:01:31.878 [21892] <2> send_structure_data: Index 64 Field m_nSuspendable Value <1> 15:01:31.878 [21892] <2> send_structure_data: Index 66 Field m_nKillable Value <1> 15:01:31.898 [21892] <2> restorefiles: User Identity (Name/Domain/Type): root/quaoar/7. Restore IMG id list: LDNWDFSP01_1409936440 15:01:31.898 [21892] <2> set_job_details: jobData (67786) 15:01:31.898 [21892] <2> send_structure_data: Index 54 Field m_szUsername Value 15:01:31.898 [21892] <2> send_structure_data: Index 55 Field m_szDomainName Value 15:01:31.898 [21892] <2> send_structure_data: Index 56 Field m_nDomainType Value <7> 15:01:31.898 [21892] <2> send_structure_data: Index 57 Field m_szRestoreBackupIDs Value 15:01:31.907 [21892] <2> set_job_details: jobRunData (67786) 15:01:31.907 [21892] <2> send_structure_data: Index 60 Field m_nCompletion Value <0> 15:01:31.907 [21892] <2> send_structure_data: Index 62 Field m_nEstFiles Value <1> 15:01:31.907 [21892] <2> open_checkpoint_file: open restore job ckpts /usr/openv/netbackup/db/jobs/restart/67786.ckpts for a+ 15:01:31.908 [21892] <2> restorefiles: Restoring from image = LDNWDFSP01_1409936440 (browse_client = LDNWDFSP01, requesting_client = quaoar, user = root) 15:01:31.908 [21892] <2> set_job_details: Tfile (67786): RESTORING_FROM_IMAGE 1423494091 LDNWDFSP01_1409936440 15:01:31.908 [21892] <2> send_job_file: job ID 67786, ftype = 3 msg len = 54, msg = RESTORING_FROM_IMAGE 1423494091 LDNWDFSP01_1409936440 15:01:31.908 [21892] <2> set_job_details: jobRunData (67786) 15:01:31.908 [21892] <2> send_structure_data: Index 59 Field m_szPathname Value 15:01:31.908 [21892] <4> emmlib_initialize: (-) Log function is already defined. Ignoring.. 15:01:31.908 [21892] <2> emmlib_initialize: (-) Connection attempt #<0> 15:01:31.908 [21892] <2> emmlib_initializeEx: (-) Connecting to the Server Port <1556>, App OrbOpt 15:01:31.908 [21892] <2> Evictor::Evictor(): +++ ENTERING +++ : obj = 100945bc8 (../Evictor.cpp:198) 15:01:31.908 [21892] <2> Evictor::Evictor(): --- EXITING --- : obj = 100945bc8 (../Evictor.cpp:198) 15:01:31.908 [21892] <2> Orb::Orb: +++ ENTERING +++ : obj = 100945880 (../Orb.cpp:320) 15:01:31.908 [21892] <2> SetMaxFileNum: current max fileno: 8192, max fileno hard limit: 8192(SetMaxFileNum.cpp:34) 15:01:31.908 [21892] <2> SetMaxFileNum: successfully set max fileno: current=8192, max=8192(SetMaxFileNum.cpp:90) 15:01:31.908 [21892] <2> Orb::Orb: --- EXITING --- : obj = 100945880 (../Orb.cpp:320) 15:01:31.908 [21892] <2> Orb::init: +++ ENTERING +++ : obj = 100945880 (../Orb.cpp:501) 15:01:31.908 [21892] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:518) 15:01:31.908 [21892] <2> checkService: found service: UTF16_BOM_Factory(Orb.cpp:494) 15:01:31.908 [21892] <2> checkService: found service: UTF8-Latin1_Factory(Orb.cpp:494) 15:01:31.908 [21892] <2> LogMaxFileNum: current max fileno: 8192, max fileno hard limit: 8192(SetMaxFileNum.cpp:113) 15:01:31.909 [21892] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:412) 15:01:31.909 [21892] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:464) 15:01:31.909 [21892] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:604) 15:01:31.909 [21892] <2> Orb::init: initializing ORB with: bprd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'"(Orb.cpp:657) 15:01:31.909 [21892] <2> Orb::init: added args has value of: (Orb.cpp:787) 15:01:31.909 [21892] <2> Orb::init: initializing ORB EMMlib_Orb with: bprd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:842) 15:01:31.909 [21892] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 1009457f0 (../NBOrbInitializer.cpp:45) 15:01:31.909 [21892] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 1009457f0 (../NBOrbInitializer.cpp:45) 15:01:31.909 [21892] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 100945700 (../NBOrbInitializer.cpp:45) 15:01:31.909 [21892] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 100945700 (../NBOrbInitializer.cpp:45) 15:01:31.910 [21892] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 1009457f0 (../NBOrbInitializer.cpp:53) 15:01:31.910 [21892] <2> NBOrbInitializer::post_init: Post init with the same orb name called for many times for the same orb EMMlib_Orb/EMMlib_Orb(NBOrbInitializer.cpp:65) 15:01:31.910 [21892] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 1009457f0 (../NBOrbInitializer.cpp:53) 15:01:31.910 [21892] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 100945700 (../NBOrbInitializer.cpp:53) 15:01:31.910 [21892] <2> NBOrbInitializer::post_init: Post init with the same orb name called for many times for the same orb EMMlib_Orb/EMMlib_Orb(NBOrbInitializer.cpp:65) 15:01:31.910 [21892] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 100945700 (../NBOrbInitializer.cpp:53) 15:01:31.911 [21892] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:857) 15:01:31.911 [21892] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 800(Orb.cpp:1521) 15:01:31.912 [21892] <2> Orb::activate: +++ ENTERING +++ : obj = 100945880 (../Orb.cpp:1248) 15:01:31.912 [21892] <2> Orb::activate: Client Orb called activate; Ignored(Orb.cpp:1253) 15:01:31.912 [21892] <2> Orb::activate: --- EXITING --- : obj = 100945880 (../Orb.cpp:1248) 15:01:31.912 [21892] <2> Orb::init: --- EXITING --- : obj = 100945880 (../Orb.cpp:501) 15:01:31.912 [21892] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 60(Orb.cpp:1521) 15:01:31.912 [21892] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1498) 15:01:31.912 [21892] <2> emmlib_initializeEx: (-) Setting connect timeout to <60> sec 15:01:31.912 [21892] <2> Orb::setOrbTimeoutPolicy: setting ORB request timeout policy: tv = 1800(Orb.cpp:1521) 15:01:31.912 [21892] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1507) 15:01:31.912 [21892] <2> emmlib_initializeEx: (-) Setting request timeout to <1800> sec 15:01:31.912 [21892] <2> Orb::connectToObject: Connection attempt #1 mW:60 rW:0(Orb.cpp:1184) 15:01:31.912 [21892] <2> Orb::createInsecureObjectRef: +++ ENTERING +++ : obj = 100945880 (../Orb.cpp:1449) 15:01:31.912 [21892] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:quaoar:1556:EMM/EMMServer(Orb.cpp:1457) 15:01:31.912 [21892] <2> Orb::createInsecureObjectRef: --- EXITING --- : obj = 100945880 (../Orb.cpp:1449) 15:01:31.912 [21892] <2> Orb::connectToObjectOnce: attempt to create insecure connection(Orb.cpp:1051) 15:01:31.912 [21892] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 15:01:31.912 [21892] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 15:01:31.912 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 1 endpoints(Endpoint_Selector.cpp:631) 15:01:31.912 [21892] <2> ConnectList::search: host quaoar not found in cache(ConnectList.cpp:116) 15:01:31.912 [21892] <2> VxssContext::useVxss: epsr2: Setting up useVxss=2(Endpoint_Selector.cpp:157) 15:01:31.913 [21892] <2> VxssContext::begin: epsr2: Setup context, passes. vxss=2 passes=1(Endpoint_Selector.cpp:187) 15:01:31.913 [21892] <2> VxssContext::end: epsr2: all done? passes=1 curridx=0(Endpoint_Selector.cpp:193) 15:01:31.913 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: pass: 0 secure_mode:2/0(Endpoint_Selector.cpp:778) 15:01:31.913 [21892] <2> ConnectList::search: host quaoar not found in cache(ConnectList.cpp:116) 15:01:31.913 [21892] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: PBXIOP endpoint host: quaoar(Endpoint_Selector.cpp:1471) 15:01:31.913 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.913 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.913 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.235.3.213: ANY(Endpoint_Selector.cpp:1725) 15:01:31.913 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname quaoar has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.913 [21892] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Endpoint host quaoar is not checked against server list(Endpoint_Selector.cpp:1590) 15:01:31.913 [21892] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Found 1 usable IP addresses for host quaoar:[10.235.3.213] (Endpoint_Selector.cpp:1599) 15:01:31.913 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.913 [21892] <2> ExternalResolver::set: Resolved host_name 10.235.3.213[10.235.3.213](ExternalResolver.cpp:56) 15:01:31.913 [21892] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Host resolved to GOOD address, target: 10.235.3.213, source: ANY(Endpoint_Selector.cpp:940) 15:01:31.913 [21892] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=1 useVxss=2(Endpoint_Selector.cpp:243) 15:01:31.914 [21892] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.235.3.213 SUCCESS(Endpoint_Selector.cpp:1186) 15:01:31.914 [21892] <2> ConnectList::update: updated host 10.235.3.213 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 15:01:31.914 [21892] <2> VxssContext::end: epsr2: all done? passes=1 curridx=1(Endpoint_Selector.cpp:193) 15:01:31.916 [21892] <2> EndpointSelector_R2::forward: epsr2: Adding 1 forward profiles(Endpoint_Selector.cpp:555) 15:01:31.916 [21892] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 15:01:31.916 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:631) 15:01:31.916 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.916 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.916 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.235.3.213: ANY(Endpoint_Selector.cpp:1725) 15:01:31.916 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.235.3.213 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.916 [21892] <2> ConnectList::search: host 169.254.182.77 not found in cache(ConnectList.cpp:116) 15:01:31.916 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.916 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 169.254.182.77: ANY(Endpoint_Selector.cpp:1725) 15:01:31.917 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 169.254.182.77 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.917 [21892] <2> ConnectList::search: host quaoar.global.ad not found in cache(ConnectList.cpp:116) 15:01:31.917 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.917 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.235.3.213, source: ANY(Endpoint_Selector.cpp:687) 15:01:31.917 [21892] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 15:01:31.917 [21892] <2> ExternalResolver::set: Resolved host_name 10.235.3.213[10.235.3.213](ExternalResolver.cpp:56) 15:01:31.917 [21892] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.235.3.213 SUCCESS(Endpoint_Selector.cpp:1186) 15:01:31.917 [21892] <2> ConnectList::update: updated host 10.235.3.213 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 15:01:31.917 [21892] <2> Orb::connectToObjectOnce: connection created(Orb.cpp:1069) 15:01:31.917 [21892] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 15:01:31.917 [21892] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 15:01:31.917 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:631) 15:01:31.917 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.917 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.917 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.235.3.213: ANY(Endpoint_Selector.cpp:1725) 15:01:31.918 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.235.3.213 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.918 [21892] <2> ConnectList::search: host 169.254.182.77 not found in cache(ConnectList.cpp:116) 15:01:31.918 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.918 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 169.254.182.77: ANY(Endpoint_Selector.cpp:1725) 15:01:31.918 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 169.254.182.77 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.918 [21892] <2> ConnectList::search: host quaoar.global.ad not found in cache(ConnectList.cpp:116) 15:01:31.918 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.918 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.235.3.213, source: ANY(Endpoint_Selector.cpp:687) 15:01:31.918 [21892] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 15:01:31.918 [21892] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.235.3.213 SUCCESS(Endpoint_Selector.cpp:1186) 15:01:31.918 [21892] <2> ConnectList::update: updated host 10.235.3.213 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 15:01:31.918 [21892] <4> CreateConnectionID: (-) Using existing uninitialized Connection ID 0 15:01:31.918 [21892] <4> emmlib_initializeEx: (0) Successfully initialized EMM session 15:01:31.918 [21892] <2> emmlib_GetHost: (-) MachineName Machine type <1> 15:01:31.918 [21892] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 15:01:31.918 [21892] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 15:01:31.918 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:631) 15:01:31.919 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.919 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.919 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.235.3.213: ANY(Endpoint_Selector.cpp:1725) 15:01:31.919 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.235.3.213 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.919 [21892] <2> ConnectList::search: host 169.254.182.77 not found in cache(ConnectList.cpp:116) 15:01:31.919 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.919 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 169.254.182.77: ANY(Endpoint_Selector.cpp:1725) 15:01:31.919 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 169.254.182.77 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.919 [21892] <2> ConnectList::search: host quaoar.global.ad not found in cache(ConnectList.cpp:116) 15:01:31.919 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.919 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.235.3.213, source: ANY(Endpoint_Selector.cpp:687) 15:01:31.919 [21892] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 15:01:31.919 [21892] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.235.3.213 SUCCESS(Endpoint_Selector.cpp:1186) 15:01:31.919 [21892] <2> ConnectList::update: updated host 10.235.3.213 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 15:01:31.920 [21892] <4> emmlib_GetHost: (0) Reading Machine record < quaoar > from EMM database 15:01:31.920 [21892] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 15:01:31.920 [21892] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 15:01:31.920 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:631) 15:01:31.921 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.921 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.921 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.235.3.213: ANY(Endpoint_Selector.cpp:1725) 15:01:31.921 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.235.3.213 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.921 [21892] <2> ConnectList::search: host 169.254.182.77 not found in cache(ConnectList.cpp:116) 15:01:31.921 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.921 [21892] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 169.254.182.77: ANY(Endpoint_Selector.cpp:1725) 15:01:31.921 [21892] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 169.254.182.77 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 15:01:31.921 [21892] <2> ConnectList::search: host quaoar.global.ad not found in cache(ConnectList.cpp:116) 15:01:31.921 [21892] <2> ConnectList::search: host 10.235.3.213 found GOOD(ConnectList.cpp:89) 15:01:31.921 [21892] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.235.3.213, source: ANY(Endpoint_Selector.cpp:687) 15:01:31.921 [21892] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 15:01:31.921 [21892] <2> ExternalResolver::set: Resolved host_name 10.235.3.213[10.235.3.213](ExternalResolver.cpp:56) 15:01:31.921 [21892] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.235.3.213 SUCCESS(Endpoint_Selector.cpp:1186) 15:01:31.921 [21892] <2> ConnectList::update: updated host 10.235.3.213 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 15:01:31.930 [21892] <2> emmlib_GetHost: (0) MachineName = < quaoar > FQName = < quaoar.global.ad > MachineGuid = < 633058554 -27882 4573 8000E1737942BEAA > MasterServerName = < quaoar > ClusterName = < > ActiveNodeName = < > MachineNbuType = < 3 > MachineState = < 14 > MachineFlags = < 247 > MachineDescription = < > OperatingSystem = < 2 > ScanAbility = < 5 > NetBackupVersion = < 750600 > LocalDriveSeed = < > GlobalDriveSeed = < VEND:#.:PROD:#.:IDX > HeartbeatFrequency = < 300 > 15:01:31.930 [21892] <2> emmlib_GetHost: (0) QueryMachine successful, emmError = 0, nbError = 0 15:01:31.930 [21892] <2> get_media_server_version: NBU version of quaoar is 750600 15:01:31.930 [21892] <4> emmlib_uninitialize: (0) Uninitialize connection <0> and shutdown Orb if no other connections exists 15:01:31.930 [21892] <4> DeleteConnectionID: (-) Cleared Connection ID <0> for <> 15:01:31.930 [21892] <4> uninitialize: (0) We still have <0> open connections 15:01:31.930 [21892] <4> shutdown_orb: (-) Destroying the orb 15:01:31.930 [21892] <2> Orb::destroyOrb: +++ ENTERING +++ : obj = 100945880 (../Orb.cpp:1669) 15:01:31.932 [21892] <2> Orb::destroyOrb: orb has reference count: 0(Orb.cpp:1676) 15:01:31.932 [21892] <2> Orb::destroyOrb: --- EXITING --- : obj = 100945880 (../Orb.cpp:1669) 15:01:31.932 [21892] <2> Evictor::~Evictor(): +++ ENTERING +++ : obj = 100945bc8 (../Evictor.cpp:204) 15:01:31.932 [21892] <2> Evictor::shutdown(): +++ ENTERING +++ : obj = 100945bc8 (../Evictor.cpp:580) 15:01:31.932 [21892] <2> Evictor::shutdown(): --- EXITING --- : obj = 100945bc8 (../Evictor.cpp:580) 15:01:31.932 [21892] <2> Evictor::~Evictor(): --- EXITING --- : obj = 100945bc8 (../Evictor.cpp:204) 15:01:31.932 [21892] <4> emmlib_uninitialize: (0) Successfully released EMM session and database objects 15:01:31.933 [21892] <2> bprd_connect: Ignoring reserved port request. 15:01:31.933 [21892] <2> bprd_connect: Ignoring local_name quaoar. 15:01:31.933 [21892] <2> bprd_connect: Ignoring owner_name root. 15:01:31.933 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:31.933 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.933 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.933 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.933 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.933 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.933 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:31.934 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:31.934 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bprd CONNECT FROM 10.235.3.213.63163 TO 10.235.3.213.1556 fd = 11 15:01:31.934 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63163 TO 10.235.3.213.1556 fd = 11 15:01:31.934 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bprd VIA pbx 15:01:31.934 [21892] <2> logconnections: BPRD CONNECT FROM 10.235.3.213.63163 TO 10.235.3.213.1556 fd = 11 15:01:31.934 [12142] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.235.3.213:63163 15:01:31.935 [21892] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:01:31.935 [12142] <2> bprd: socket fd from accept() is 10 15:01:31.938 [12142] <2> listen_loop: request complete 15:01:31.943 [21902] <2> logconnections: BPRD ACCEPT FROM 10.235.3.213.63163 TO 10.235.3.213.1556 fd = 10 15:01:31.945 [21902] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:01:31.945 [21902] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8 15:01:31.947 [21902] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1904] found via getnameinfo OUR_HOST=quaoar IPSTR=10.235.3.213 15:01:31.947 [21902] <2> connected_peer: Connection from host quaoar, 10.235.3.213, on non-reserved port 63163 15:01:31.947 [21902] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:01:31.947 [21902] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 73 0x49 15:01:31.947 [21902] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:01:31.948 [21902] <2> process_request: command C_READ_HOST_CONFIG (73) received 15:01:31.949 [21902] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:01:31.949 [21902] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:01:31.949 [21902] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:01:31.949 [21902] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:01:31.949 [21902] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:01:31.950 [21902] <2> get_type_of_client_port: connect_options = 0x01030202 15:01:31.950 [21902] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100 15:01:31.951 [21902] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100 15:01:31.951 [21902] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0 15:01:31.952 [21902] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.952 [21902] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 15:01:31.952 [21902] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.952 [21902] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 15:01:31.952 [21902] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.952 [21902] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 15:01:31.952 [21902] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 15:01:31.955 [21902] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:31.955 [21902] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 10.235.3.213.63164 TO 10.235.4.14.1556 fd = 6 15:01:31.955 [21902] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.235.3.213.63164 TO 10.235.4.14.1556 fd = 6 15:01:31.955 [21902] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO LDNWDFSP01 10.235.4.14 bpcd VIA pbx 15:01:31.955 [21902] <2> logconnections: BPCD CONNECT FROM 10.235.3.213.63164 TO 10.235.4.14.1556 fd = 6 15:01:31.956 [21902] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.956 [21902] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 15:01:31.956 [21902] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:31.956 [21902] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 15:01:31.956 [21902] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 15:01:31.958 [21902] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:31.981 [21902] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.235.3.213.63165 TO 10.235.4.14.1556 fd = 7 15:01:31.981 [21902] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.235.3.213.63165 TO 10.235.4.14.1556 fd = 7 15:01:31.981 [21902] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.235.4.14 10.235.4.14 vnetd VIA pbx 15:01:31.981 [21902] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:01:32.193 [21902] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 49158 15:01:32.306 [21902] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 b637c0aef36d983ae969b9c122e7dde0 15:01:32.545 [21902] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:01:32.666 [21892] <2> client_is_SAN_client: client LDNWDFSP01 is NOT configured as a SAN client 15:01:32.666 [21892] <2> set_job_details: jobData (67786) 15:01:32.666 [21892] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1423494092> 15:01:32.666 [21892] <2> send_structure_data: Index 51 Field m_nTransportType Value <0> 15:01:32.666 [21892] <2> set_job_details: jobData (67786) 15:01:32.666 [21892] <2> send_structure_data: Index 24 Field m_szSrcMedia_server Value 15:01:32.666 [21892] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1423494092> 15:01:32.667 [21892] <2> restore_image_files: file_list_ipc: /tmp/vnet-21892423494092666853000000004-6DaGWQ 15:01:32.667 [21892] <2> set_job_details: Tfile (67786): LOG 1423494092 4 bprd 21892 Restoring from copy 1 of image created Fri Sep 05 18:00:40 2014 15:01:32.668 [21892] <2> send_job_file: job ID 67786, ftype = 3 msg len = 93, msg = LOG 1423494092 4 bprd 21892 Restoring from copy 1 of image created Fri Sep 05 18:00:40 2014 15:01:32.673 [21892] <2> start_brm: pfi_type 0, backup_copy 0, copy num 1 data_format 1 15:01:32.673 [21892] <2> start_brm: backup restore server = quaoar 15:01:32.673 [21892] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01000100 connect_opts2 = 0x01000100 15:01:32.673 [21892] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100 15:01:32.673 [21892] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0 15:01:32.673 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:32.673 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:32.673 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:32.673 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:32.674 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:32.674 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:32.674 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:32.675 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:32.675 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 10.235.3.213.63166 TO 10.235.3.213.1556 fd = 12 15:01:32.675 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63166 TO 10.235.3.213.1556 fd = 12 15:01:32.675 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpcd VIA pbx 15:01:32.675 [21892] <2> logconnections: BPCD CONNECT FROM 10.235.3.213.63166 TO 10.235.3.213.1556 fd = 12 15:01:32.675 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:01:32.675 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:32.675 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:32.675 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:01:32.675 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:01:32.676 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:01:32.680 [21892] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.235.3.213.63167 TO 10.235.3.213.1556 fd = 13 15:01:32.680 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63167 TO 10.235.3.213.1556 fd = 13 15:01:32.680 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.235.3.213 10.235.3.213 vnetd VIA pbx 15:01:32.680 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:01:32.682 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-21904423494092681329000000000-jEaaYQ 15:01:32.682 [21892] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 1fe2a15db2305c2f53fe9733f62d2a41 15:01:32.684 [21892] <2> start_brm: BPBRM_CMD_SOCK from bpcr = 12 15:01:32.684 [21892] <2> start_brm: BPBRM_STAT_SOCK from bpcr = 13 15:01:32.684 [21892] <2> start_brm: /usr/openv/netbackup/bin/bpbrm bpbrm -restore -mt 0 -mst 1 -k 1 -c LDNWDFSP01 -hostname LDNWDFSP01 -rclnt quaoar -rclnthostname quaoar -browse_clnt LDNWDFSP01 -ru root -firstblk 75541789 -cl UXB-WIN-OS -bt 1409936440 -st 0 -secure 1 -b LDNWDFSP01_1409936440 -cn 1 -rst 1423494090 -to 0 -mud 300 -ct 13 -S quaoar -masterversion 750000 -backup_copy 0 -latest_image -flport 0 -flipc /tmp/vnet-21892423494092666853000000004-6DaGWQ -p /uxbdd2/backup/uxbridge/WIN -rg root -cv -L /usr/openv/netbackup/logs/user_ops/root/logs/jbp-12788423494090883574000000127-kjaG.y.log -clnt_lc_messages en_UK -clnt_lc_time en_UK -clnt_lc_ctype en_UK -clnt_lc_collate en_UK -clnt_lc_numeric en_UK -connect_options 0x01030202 -jobid 67786 -restoreid 67786.001 -job_total 1 15:01:32.684 [21892] <2> start_brm: Received BPCD success message 15:01:32.713 [12142] <2> listen_loop: do_schild = 1 15:01:32.713 [12142] <2> childterm: pid=21902 exit=0, signo=0 core=no 15:01:32.714 [12142] <2> schild: child(ren) not ready to terminate 15:01:33.084 [21892] <2> readline: PID of bpbrm = 21905 15:01:37.788 [21892] <2> vnet_receive_network_socket: [vnet_vnetd.c:1076] hash_str1 NULL 15:01:37.788 [21892] <2> vnet_receive_network_socket: [vnet_vnetd.c:1077] hash_str2 15:01:37.788 [21892] <2> verify_hashes: [vnet_vnetd.c:1613] hash_str1 NULL 15:01:37.788 [21892] <2> verify_hashes: [vnet_vnetd.c:1615] hash_str2 15:01:37.788 [21892] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:808] the socket is 15 0xf 15:01:37.888 [21892] <2> write_files_to_active_sockets: 80 75541789 4334820 0 0 0 4334592 /D/LaptopBackups/NB1787-ABE.carmicht/Outlook/backup_competition_results.pst 15:01:37.889 [21892] <2> write_files_to_active_sockets: EXIT STATUS 0 15:01:45.718 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:02:46.734 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:03:47.751 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:04:46.768 [12142] <2> ConnectionCache::connectAndCache: Acquiring new connection for host quaoar, query type 98 15:04:46.768 [12142] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:04:46.768 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:04:46.768 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:04:46.768 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:04:46.768 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:04:46.768 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:04:46.768 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:04:46.769 [12142] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:04:46.769 [12142] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.235.3.213.63191 TO 10.235.3.213.1556 fd = 10 15:04:46.769 [12142] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63191 TO 10.235.3.213.1556 fd = 10 15:04:46.769 [12142] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpdbm VIA pbx 15:04:46.770 [12142] <2> logconnections: BPDBM CONNECT FROM 10.235.3.213.63191 TO 10.235.3.213.1556 fd = 10 15:04:46.770 [12142] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:04:46.770 [12142] <2> db_end: Need to collect reply 15:04:46.831 [12142] <2> launch: /usr/openv/netbackup/bin/admincmd/bpstsinfo, pid=22028 15:04:46.839 [12142] <2> listen_loop: do_schild = 1 15:04:46.839 [12142] <2> childterm: pid=22028 exit=0, signo=0 core=no 15:04:46.839 [12142] <2> schild: child(ren) not ready to terminate 15:04:48.840 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:05:49.857 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:06:50.874 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:07:51.891 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:08:52.912 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:09:28.811 [21892] <2> process_signal: mpx_main_bprd 0, restore_suspend_signal 0 restore_abort_signal 1 15:09:28.811 [21892] <2> bpcr_send_signal: Ignoring connect_opts = 0x01030202 15:09:28.812 [21892] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:09:28.812 [21892] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:09:28.812 [21892] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:09:28.812 [21892] <2> db_freeEXDB_INFO: ? 15:09:28.812 [21892] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030000 connect_opts2 = 0x01000100 15:09:28.812 [21892] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000000 15:09:28.812 [21892] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0 15:09:28.812 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:09:28.812 [21892] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:09:28.812 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.813 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.813 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.813 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.813 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.813 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.814 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:09:28.814 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 10.235.3.213.63220 TO 10.235.3.213.1556 fd = 11 15:09:28.814 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63220 TO 10.235.3.213.1556 fd = 11 15:09:28.814 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpcd VIA pbx 15:09:28.814 [21892] <2> logconnections: BPCD CONNECT FROM 10.235.3.213.63220 TO 10.235.3.213.1556 fd = 11 15:09:28.814 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:09:28.814 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.815 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.815 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.815 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.815 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:09:28.820 [21892] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.235.3.213.63221 TO 10.235.3.213.1556 fd = 14 15:09:28.820 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63221 TO 10.235.3.213.1556 fd = 14 15:09:28.820 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.235.3.213 10.235.3.213 vnetd VIA pbx 15:09:28.820 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:09:28.821 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-22222423494568820793000000000-0TaWzR 15:09:28.822 [21892] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 cc96004d5fef4cd7f4905fc5b2b1d3d6 15:09:28.823 [21892] <2> bpcr_send_signal: CLIENT_CMD_SOCK from bpcr = 11 15:09:28.823 [21892] <2> bpcr_send_signal: CLIENT_STAT_SOCK from bpcr = 14 15:09:28.824 [21892] <2> mail_msg_and_set_exit_status: entered; status = 150 15:09:28.824 [21892] <2> mail_msg_and_set_exit_status: client_type = -1 15:09:28.824 [21892] <2> mail_msg_and_set_exit_status: Attempting to send mail to root on quaoar 15:09:28.825 [21892] <2> get_long_base: (2) premature end of file (byte 1) 15:09:28.825 [21892] <2> bpcr_disconnect_rqst: bpcr received the following message: 15:09:28.825 [21892] <2> mail_msg_and_set_exit_status: RESTORE EXIT STATUS = 150 15:09:28.825 [21892] <2> set_job_details: jobData (67786) 15:09:28.825 [21892] <2> send_structure_data: Index 9 Field m_nStatus Value <150> 15:09:28.825 [21892] <2> send_structure_data: Index 21 Field m_nState Value <3> 15:09:28.825 [21892] <2> send_structure_data: Index 30 Field m_dtEnded Value <1423494568> 15:09:28.825 [21892] <2> send_structure_data: Index 35 Field m_dtLastInfo Value <1423494568> 15:09:28.825 [21892] <2> append_to_client_log: ../dbmisc.c.3319: about to call ConnectToBPCD connect_opts = 0x01030202 15:09:28.825 [21892] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100 15:09:28.825 [21892] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100 15:09:28.825 [21892] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0 15:09:28.825 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:09:28.825 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.825 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.825 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.826 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.826 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.826 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.827 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:09:28.827 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 10.235.3.213.63222 TO 10.235.3.213.1556 fd = 7 15:09:28.827 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63222 TO 10.235.3.213.1556 fd = 7 15:09:28.827 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpcd VIA pbx 15:09:28.827 [21892] <2> logconnections: BPCD CONNECT FROM 10.235.3.213.63222 TO 10.235.3.213.1556 fd = 7 15:09:28.827 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:09:28.827 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.827 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.827 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.827 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.828 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:09:28.833 [21892] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.235.3.213.63223 TO 10.235.3.213.1556 fd = 8 15:09:28.833 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63223 TO 10.235.3.213.1556 fd = 8 15:09:28.833 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.235.3.213 10.235.3.213 vnetd VIA pbx 15:09:28.833 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:09:28.835 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-22224423494568834294000000000-cUaaAR 15:09:28.835 [21892] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 b7738959e41caa5020eb7944cfb0983f 15:09:28.840 [21892] <2> set_job_details: Tfile (67786): END_OPERATION 1423494568 15:09:28.840 [21892] <2> send_job_file: job ID 67786, ftype = 3 msg len = 25, msg = END_OPERATION 1423494568 15:09:28.854 [21892] <2> job_monitoring_exex: ACK disconnect 15:09:28.854 [21892] <2> job_disconnect: Disconnected 15:09:28.854 [21892] <2> local_bpcr_connect: bpcr.c.278: connect_opts = 0x01030202 connect_opts2 = 0x01000100 15:09:28.854 [21892] <2> local_bpcr_connect: bpcr.c.285: connect_opts = 0x01000100 15:09:28.854 [21892] <2> local_bpcr_connect: bpcr.c.324: daemon_port_type = 0 15:09:28.854 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:09:28.854 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.855 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.855 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.855 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.855 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.855 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.856 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:09:28.856 [21892] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpcd CONNECT FROM 10.235.3.213.63226 TO 10.235.3.213.1556 fd = 6 15:09:28.856 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63226 TO 10.235.3.213.1556 fd = 6 15:09:28.856 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpcd VIA pbx 15:09:28.856 [21892] <2> logconnections: BPCD CONNECT FROM 10.235.3.213.63226 TO 10.235.3.213.1556 fd = 6 15:09:28.856 [21892] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:09:28.856 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.856 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.856 [21892] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:28.856 [21892] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:28.857 [21892] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:09:28.861 [21892] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.235.3.213.63227 TO 10.235.3.213.1556 fd = 7 15:09:28.861 [21892] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63227 TO 10.235.3.213.1556 fd = 7 15:09:28.861 [21892] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.235.3.213 10.235.3.213 vnetd VIA pbx 15:09:28.861 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:09:28.862 [21892] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-22228423494568861853000000000-DUaGAR 15:09:28.863 [21892] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:471] hash_str1 30099a6c83dcef1cb9b696886c6254cb 15:09:28.864 [21892] <2> add_msgs_to_progress_file: CLIENT_CMD_SOCK from bpcr = 6 15:09:28.864 [21892] <2> add_msgs_to_progress_file: CLIENT_STAT_SOCK from bpcr = 7 15:09:28.867 [21892] <2> terminate_restore: EXIT STATUS 150 15:09:28.867 [21892] <2> bprd: /usr/openv/netbackup/bin/userreq_notify restore LDNWDFSP01 root 15:09:31.883 [21892] <2> readline: EXIT STATUS 150 15:09:31.883 [21892] <2> read_bpbrm_stderr: bpbrm exit status = 150 15:09:31.900 [21892] <2> BpConfigHolder::~BpConfigHolder: +++ ENTERING +++ : obj = 100930658 (../BpConf.cpp:40) 15:09:31.900 [21892] <2> BpConfigHolder::free: +++ ENTERING +++ : obj = 100930658 (../BpConf.cpp:60) 15:09:31.900 [21892] <2> BpConfigHolder::free: --- EXITING --- : obj = 100930658 (../BpConf.cpp:60) 15:09:31.900 [21892] <2> BpConfigHolder::~BpConfigHolder: --- EXITING --- : obj = 100930658 (../BpConf.cpp:40) 15:09:31.901 [21892] <2> InsecureEndpointSelectorFactory::~InsecureEndpointSelectorFactory: +++ ENTERING +++ : obj = 1009444d0 (../Endpoint_Selector.cpp:2082) 15:09:31.902 [21892] <2> InsecureEndpointSelectorFactory::~InsecureEndpointSelectorFactory: --- EXITING --- : obj = 1009444d0 (../Endpoint_Selector.cpp:2082) 15:09:31.902 [21892] <2> EndpointSelectorFactory::~EndpointSelectorFactory: +++ ENTERING +++ : obj = 1009443a0 (../Endpoint_Selector.cpp:1987) 15:09:31.902 [21892] <2> EndpointSelectorFactory::~EndpointSelectorFactory: --- EXITING --- : obj = 1009443a0 (../Endpoint_Selector.cpp:1987) 15:09:31.916 [12142] <2> listen_loop: do_schild = 1 15:09:31.916 [12142] <2> childterm: pid=21892 exit=150, signo=0 core=no 15:09:31.917 [12142] <2> schild: wait2() ECHILD 15:09:46.921 [12142] <2> ConnectionCache::connectAndCache: Acquiring new connection for host quaoar, query type 98 15:09:46.921 [12142] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:09:46.921 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:46.921 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:46.921 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:46.921 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:46.921 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:09:46.921 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:09:46.930 [12142] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:09:46.930 [12142] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.235.3.213.63238 TO 10.235.3.213.1556 fd = 10 15:09:46.930 [12142] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63238 TO 10.235.3.213.1556 fd = 10 15:09:46.930 [12142] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpdbm VIA pbx 15:09:46.930 [12142] <2> logconnections: BPDBM CONNECT FROM 10.235.3.213.63238 TO 10.235.3.213.1556 fd = 10 15:09:46.930 [12142] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:09:46.930 [12142] <2> db_end: Need to collect reply 15:09:46.989 [12142] <2> launch: /usr/openv/netbackup/bin/admincmd/bpstsinfo, pid=22250 15:09:46.999 [12142] <2> listen_loop: do_schild = 1 15:09:47.000 [12142] <2> childterm: pid=22250 exit=0, signo=0 core=no 15:09:47.000 [12142] <2> schild: wait2() ECHILD 15:09:53.002 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:10:54.021 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:11:55.039 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:12:56.056 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:13:57.074 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:14:46.088 [12142] <2> ConnectionCache::connectAndCache: Acquiring new connection for host quaoar, query type 98 15:14:46.088 [12142] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:14:46.088 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:14:46.088 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:14:46.088 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:14:46.088 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:14:46.088 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:14:46.088 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:14:46.089 [12142] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:14:46.089 [12142] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.235.3.213.63273 TO 10.235.3.213.1556 fd = 10 15:14:46.089 [12142] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63273 TO 10.235.3.213.1556 fd = 10 15:14:46.090 [12142] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpdbm VIA pbx 15:14:46.090 [12142] <2> logconnections: BPDBM CONNECT FROM 10.235.3.213.63273 TO 10.235.3.213.1556 fd = 10 15:14:46.090 [12142] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:14:46.090 [12142] <2> db_end: Need to collect reply 15:14:46.151 [12142] <2> launch: /usr/openv/netbackup/bin/admincmd/bpstsinfo, pid=22470 15:14:46.161 [12142] <2> listen_loop: do_schild = 1 15:14:46.161 [12142] <2> childterm: pid=22470 exit=0, signo=0 core=no 15:14:46.161 [12142] <2> schild: wait2() ECHILD 15:14:58.164 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:15:59.182 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:17:00.199 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:18:01.217 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:19:02.234 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:19:46.246 [12142] <2> ConnectionCache::connectAndCache: Acquiring new connection for host quaoar, query type 98 15:19:46.247 [12142] <2> vnet_in_resilient_network: [vnet_addrinfo.c:8771] ignoring local host 0 0x0 15:19:46.247 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:19:46.247 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:19:46.247 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:19:46.247 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:19:46.247 [12142] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 15:19:46.247 [12142] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4795] Local [strong] check, using interface ANY 15:19:46.248 [12142] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:19:46.248 [12142] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.235.3.213.63314 TO 10.235.3.213.1556 fd = 10 15:19:46.248 [12142] <2> async_connect: [vnet_connect.c:1433] connect immediate CONNECT FROM 10.235.3.213.63314 TO 10.235.3.213.1556 fd = 10 15:19:46.248 [12142] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO quaoar 10.235.3.213 bpdbm VIA pbx 15:19:46.248 [12142] <2> logconnections: BPDBM CONNECT FROM 10.235.3.213.63314 TO 10.235.3.213.1556 fd = 10 15:19:46.248 [12142] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:19:46.248 [12142] <2> db_end: Need to collect reply 15:19:46.309 [12142] <2> launch: /usr/openv/netbackup/bin/admincmd/bpstsinfo, pid=22726 15:19:46.318 [12142] <2> listen_loop: do_schild = 1 15:19:46.319 [12142] <2> childterm: pid=22726 exit=0, signo=0 core=no 15:19:46.319 [12142] <2> schild: wait2() ECHILD 15:20:03.324 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d 15:21:04.341 [12142] <2> insert_if_not_dup: [vnet_addrinfo.c:5728] ignoring IPv4 APIPA address 2852042317 0xa9feb64d