14:54:42.148 [7556.7560] <2> vnet_pbxAcceptSocket: Accepted sock[664] from 172.25.1.221:58512 14:54:42.148 [7556.7560] <2> bprd: socket fd from accept() is 664 14:54:42.148 [7556.7560] <2> listen_loop: request complete 14:54:42.226 [7508.11720] <2> logconnections: BPRD ACCEPT FROM 172.25.1.221.58512 TO 172.24.1.8.1556 fd = 664 14:54:42.226 [7508.11720] <2> process_request: setsockopt SO_LINGER on 664 succeeded. 14:54:42.226 [7508.11720] <2> connected_peer: Connection from host ar-gecs21, 172.25.1.221, on non-reserved port 58512 14:54:42.242 [7508.11720] <2> db_valid_master_server: ar-gecs21 is not a valid server 14:54:42.242 [7508.11720] <2> process_request: command C_GET_FEATURES (72) received 14:54:42.257 [7508.11720] <2> process_request: EXIT STATUS 0 14:54:42.273 [7556.7560] <2> vnet_pbxAcceptSocket: Accepted sock[664] from 172.25.1.221:58513 14:54:42.273 [7556.7560] <2> bprd: socket fd from accept() is 664 14:54:42.273 [7556.7560] <2> listen_loop: request complete 14:54:42.367 [12512.5184] <2> logconnections: BPRD ACCEPT FROM 172.25.1.221.58513 TO 172.24.1.8.1556 fd = 664 14:54:42.367 [12512.5184] <2> process_request: setsockopt SO_LINGER on 664 succeeded. 14:54:42.367 [12512.5184] <2> connected_peer: Connection from host ar-gecs21, 172.25.1.221, on non-reserved port 58513 14:54:42.382 [12512.5184] <2> db_valid_master_server: ar-gecs21 is not a valid server 14:54:42.382 [12512.5184] <2> process_request: command C_MEDIA_LIST_BY_FILE_3_2 (67) received 14:54:42.382 [12512.5184] <2> get_image_by_file: client = cfhq002so0232 14:54:42.382 [12512.5184] <2> get_image_by_file: pathname = /c-3373280740-20160430-01 14:54:42.382 [12512.5184] <2> get_image_by_file: starttime = 1461942000 14:54:42.382 [12512.5184] <2> get_image_by_file: endtime = 1462902882 14:54:42.382 [12512.5184] <2> get_image_by_file: client_type = 4 14:54:42.382 [12512.5184] <2> get_image_by_file: policy = *NULL* 14:54:42.382 [12512.5184] <2> get_image_by_file: dbclient_format = 1 14:54:42.382 [12512.5184] <2> ConnectionCache::connectAndCache: Acquiring new connection for host myarsw00403bk01, query type 67 14:54:42.382 [12512.5184] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:42.382 [12512.5184] <2> logconnections: BPDBM CONNECT FROM 172.24.1.8.54639 TO 172.24.1.8.1556 fd = 584 14:54:42.554 [12512.5184] <2> get_image_by_file: Sent to client OVA315 1462027544 1464705944 cfhq002so0232_1462027544 gecap 14:54:42.585 [12512.5184] <2> process_request: EXIT STATUS 0 14:54:42.710 [12512.5184] <2> bprd: C:\Program Files\Veritas\NetBackup\bin\userreq_notify.cmd media_list_by_file cfhq002so0232 unknown 14:54:43.209 [7556.7560] <2> vnet_pbxAcceptSocket: Accepted sock[664] from 172.25.1.221:58514 14:54:43.209 [7556.7560] <2> bprd: socket fd from accept() is 664 14:54:43.209 [7556.7560] <2> listen_loop: request complete 14:54:43.287 [6052.8648] <2> logconnections: BPRD ACCEPT FROM 172.25.1.221.58514 TO 172.24.1.8.1556 fd = 664 14:54:43.287 [6052.8648] <2> process_request: setsockopt SO_LINGER on 664 succeeded. 14:54:43.303 [6052.8648] <2> connected_peer: Connection from host ar-gecs21, 172.25.1.221, on non-reserved port 58514 14:54:43.303 [6052.8648] <2> db_valid_master_server: ar-gecs21 is not a valid server 14:54:43.303 [6052.8648] <2> process_request: command C_BPLIST_4_5 (82) received 14:54:43.318 [6052.8648] <2> process_request: list request = 329199 82 oracle dba cfhq002so0232 ar-gecs21 ar-gecs21 NONE 0 3 999 1462298083 1462902883 4 4 1 1 1 0 4 101 101 4 0 C C C C C 0 2 0 0 0 14:54:43.318 [6052.8648] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1598] in failed file cache ERR=11001 NAME=cfhq002so0232.cordialfinanciera.com.ar SVC=NULL 14:54:43.318 [6052.8648] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1267] vnet_cached_getaddrinfo_and_update() failed 6 0x6 14:54:43.318 [6052.8648] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2864] vnet_cached_getaddrinfo() failed STAT=6 RV=11001 NAME2=cfhq002so0232.cordialfinanciera.com.ar 14:54:43.318 [6052.8648] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1598] in failed file cache ERR=11001 NAME=cfhq002so0232.cfcorp.ad SVC=NULL 14:54:43.318 [6052.8648] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1267] vnet_cached_getaddrinfo_and_update() failed 6 0x6 14:54:43.318 [6052.8648] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2864] vnet_cached_getaddrinfo() failed STAT=6 RV=11001 NAME2=cfhq002so0232.cfcorp.ad 14:54:43.334 [6052.8648] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 14:54:43.334 [6052.8648] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 14:54:43.334 [6052.8648] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 14:54:43.334 [6052.8648] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 14:54:43.334 [6052.8648] <2> read_client: dname=OA_2147483647, offline=0, online_at=2147483647 offline_at=0 14:54:43.334 [6052.8648] <2> get_type_of_client_list_restore: list and restore not specified for ar-gecs21 14:54:43.334 [6052.8648] <2> get_type_of_client_free_browse: Free browse allowed for ar-gecs21 14:54:43.334 [6052.8648] <2> db_freeEXDB_INFO: ? 14:54:43.334 [6052.8648] <2> read_legacy_touch_file: Found D:\GEAplications\Veritas\Netbackup\db\altnames\No.Restrictions; requested from (d:\nb\7.6.1.2\src\nb\dblib\dbreq.c.355). 14:54:43.334 [6052.8648] <2> db_valid_client: -all clients valid- 14:54:43.443 [6052.8648] <2> fileslist: sockfd = 664 14:54:43.443 [6052.8648] <2> fileslist: owner = oracle 14:54:43.443 [6052.8648] <2> fileslist: group = dba 14:54:43.443 [6052.8648] <2> fileslist: client = cfhq002so0232 14:54:43.443 [6052.8648] <2> fileslist: sched_type = 12 14:54:43.443 [6052.8648] <2> fileslist: starttime = 1462298083 14:54:43.443 [6052.8648] <2> fileslist: endtime = 1462902883 14:54:43.443 [6052.8648] <2> fileslist: filepath = /c-3373280740-20160430-01 14:54:43.443 [6052.8648] <2> fileslist: recursion_level = 999 14:54:43.443 [6052.8648] <2> fileslist: timetype = 4 14:54:43.443 [6052.8648] <2> fileslist: rqtimetype = 4 14:54:43.443 [6052.8648] <2> fileslist: user_interface = 1 14:54:43.443 [6052.8648] <2> fileslist: full_listing = 1 14:54:43.443 [6052.8648] <2> fileslist: pc1_listing = 0 14:54:43.443 [6052.8648] <2> fileslist: input_long_listing = 0x1 14:54:43.443 [6052.8648] <2> fileslist: list_seconds = 0 14:54:43.443 [6052.8648] <2> fileslist: list_policy = 0 14:54:43.443 [6052.8648] <2> fileslist: long_listing = 1 14:54:43.443 [6052.8648] <2> fileslist: raw_partition_search = 0 14:54:43.443 [6052.8648] <2> fileslist: client_type = 4 14:54:43.443 [6052.8648] <2> fileslist: policy = NONE 14:54:43.443 [6052.8648] <2> fileslist: keyword = 14:54:43.443 [6052.8648] <2> fileslist: bplist_format = 4 14:54:43.443 [6052.8648] <2> fileslist: true_image = 0 14:54:43.443 [6052.8648] <2> fileslist: list_lc_messages = C 14:54:43.443 [6052.8648] <2> fileslist: list_lc_time = C 14:54:43.443 [6052.8648] <2> fileslist: list_lc_ctype = C 14:54:43.443 [6052.8648] <2> fileslist: list_lc_collate = C 14:54:43.443 [6052.8648] <2> fileslist: list_lc_numeric = C 14:54:43.443 [6052.8648] <2> fileslist: directories_only = 0 14:54:43.443 [6052.8648] <2> fileslist: include_extra_info = 2 14:54:43.443 [6052.8648] <2> fileslist: client_uid = 101 14:54:43.443 [6052.8648] <2> fileslist: client_gid = 101 14:54:43.443 [6052.8648] <2> fileslist: ignore_case = 0 14:54:43.443 [6052.8648] <2> fileslist: list_seconds_pre_4_5 = 0 14:54:43.443 [6052.8648] <2> fileslist: client_flops = 0 14:54:43.443 [6052.8648] <2> fileslist: query.fl_peername = cfhq002so0232 14:54:43.443 [6052.8648] <2> fileslist: myarsw00403bk01 14:54:43.443 [6052.8648] <2> ConnectionCache::connectAndCache: Acquiring new connection for host myarsw00403bk01, query type 79 14:54:43.443 [6052.8648] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:43.443 [6052.8648] <2> logconnections: BPDBM CONNECT FROM 172.24.1.8.54640 TO 172.24.1.8.1556 fd = 608 14:54:43.552 [6052.8648] <2> fileslist: begin db communication 14:54:43.552 [6052.8648] <2> fileslist: criteria sent to db mgr 14:54:43.693 [7556.7560] <2> vnet_pbxAcceptSocket: Accepted sock[664] from 172.25.1.221:58515 14:54:43.693 [7556.7560] <2> bprd: socket fd from accept() is 664 14:54:43.693 [7556.7560] <2> listen_loop: request complete 14:54:43.693 [6052.8648] <2> process_request: EXIT STATUS 0 14:54:43.786 [14292.10788] <2> logconnections: BPRD ACCEPT FROM 172.25.1.221.58515 TO 172.24.1.8.1556 fd = 664 14:54:43.786 [14292.10788] <2> process_request: setsockopt SO_LINGER on 664 succeeded. 14:54:43.786 [14292.10788] <2> connected_peer: Connection from host ar-gecs21, 172.25.1.221, on non-reserved port 58515 14:54:43.802 [14292.10788] <2> db_valid_master_server: ar-gecs21 is not a valid server 14:54:43.802 [14292.10788] <2> process_request: command C_BPRESTORE_6_5_2_2 (126) received 14:54:43.802 [14292.10788] <2> ConnectionCache::connectAndCache: Acquiring new connection for host myarsw00403bk01, query type 84 14:54:43.802 [14292.10788] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:43.802 [14292.10788] <2> logconnections: BPDBM CONNECT FROM 172.24.1.8.54641 TO 172.24.1.8.1556 fd = 584 14:54:43.817 [6052.8648] <2> bprd: C:\Program Files\Veritas\NetBackup\bin\userreq_notify.cmd list cfhq002so0232 oracle 14:54:44.129 [14292.10788] <2> db_end: Need to collect reply 14:54:44.161 [14292.10788] <2> process_request: pre vm_fields 0 0 0 0 0 0 0 0 0 0 NONE -1 0 0 0 0 NONE NONE 14:54:44.161 [14292.10788] <2> process_request: vm_fields 0 NONE -1 0 0 0 0 NONE NONE 14:54:44.161 [14292.10788] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 14:54:44.161 [14292.10788] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 14:54:44.161 [14292.10788] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 14:54:44.161 [14292.10788] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 14:54:44.161 [14292.10788] <2> read_client: dname=OA_2147483647, offline=0, online_at=2147483647 offline_at=0 14:54:44.161 [14292.10788] <2> db_valid_master_server: ar-gecs21 is not a valid server 14:54:44.161 [14292.10788] <2> db_freeEXDB_INFO: ? 14:54:44.176 [14292.10788] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1598] in failed file cache ERR=11001 NAME=cfhq002so0232.cordialfinanciera.com.ar SVC=NULL 14:54:44.176 [14292.10788] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1267] vnet_cached_getaddrinfo_and_update() failed 6 0x6 14:54:44.176 [14292.10788] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2864] vnet_cached_getaddrinfo() failed STAT=6 RV=11001 NAME2=cfhq002so0232.cordialfinanciera.com.ar 14:54:44.176 [14292.10788] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1598] in failed file cache ERR=11001 NAME=cfhq002so0232.cfcorp.ad SVC=NULL 14:54:44.176 [14292.10788] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1267] vnet_cached_getaddrinfo_and_update() failed 6 0x6 14:54:44.176 [14292.10788] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2864] vnet_cached_getaddrinfo() failed STAT=6 RV=11001 NAME2=cfhq002so0232.cfcorp.ad 14:54:44.176 [14292.10788] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 14:54:44.176 [14292.10788] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 14:54:44.176 [14292.10788] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 14:54:44.176 [14292.10788] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 14:54:44.176 [14292.10788] <2> read_client: dname=OA_2147483647, offline=0, online_at=2147483647 offline_at=0 14:54:44.176 [14292.10788] <2> get_type_of_client_list_restore: list and restore not specified for ar-gecs21 14:54:44.192 [14292.10788] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:44.192 [14292.10788] <2> job_connect: SO_KEEPALIVE set on socket 620 for client myarsw00403bk01 14:54:44.192 [14292.10788] <2> logconnections: BPJOBD CONNECT FROM 172.24.1.8.54642 TO 172.24.1.8.1556 fd = 620 14:54:44.192 [14292.10788] <2> job_authenticate_connection: ignoring VxSS authentication check for now... 14:54:44.192 [14292.10788] <2> job_connect: Connected to the host myarsw00403bk01 contype 53 jobid <722179> socket <620> 14:54:44.192 [14292.10788] <2> job_connect: Connected on port 54642 14:54:44.192 [14292.10788] <2> db_freeEXDB_INFO: ? 14:54:44.192 [14292.10788] <2> read_legacy_touch_file: Found D:\GEAplications\Veritas\Netbackup\db\altnames\No.Restrictions; requested from (d:\nb\7.6.1.2\src\nb\dblib\dbreq.c.355). 14:54:44.192 [14292.10788] <2> db_valid_client: -all clients valid- 14:54:44.192 [14292.10788] <2> adjust_clientname: adjust_clientname(ar-gecs21, ar-gecs21, ar-gecs21, ar-gecs21, 0) 14:54:44.192 [14292.10788] <2> ConnectionCache::connectAndCache: Acquiring new connection for host myarsw00403bk01, query type 84 14:54:44.192 [14292.10788] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:44.192 [14292.10788] <2> logconnections: BPDBM CONNECT FROM 172.24.1.8.54643 TO 172.24.1.8.1556 fd = 624 14:54:44.410 [14292.10788] <2> db_end: Need to collect reply 14:54:44.426 [14292.10788] <2> adjust_clientname: adjusted clientname = ar-gecs21, client_bp_conf_name = ar-gecs21 14:54:44.426 [14292.10788] <2> adjust_clientname: adjust_clientname(ar-gecs21, ar-gecs21, ar-gecs21, ar-gecs21, 0) 14:54:44.426 [14292.10788] <2> ConnectionCache::connectAndCache: Acquiring new connection for host myarsw00403bk01, query type 84 14:54:44.426 [14292.10788] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:44.426 [14292.10788] <2> logconnections: BPDBM CONNECT FROM 172.24.1.8.54644 TO 172.24.1.8.1556 fd = 624 14:54:44.660 [14292.10788] <2> db_end: Need to collect reply 14:54:44.675 [14292.10788] <2> adjust_clientname: adjusted clientname = ar-gecs21, client_bp_conf_name = ar-gecs21 14:54:44.675 [14292.10788] <2> process_request: requesting_clnt_hostname = ccname ar-gecs21 14:54:44.675 [14292.10788] <2> restorefiles: mpx_restore_possible = 1 14:54:44.675 [14292.10788] <2> restorefiles: browse_client = cfhq002so0232 14:54:44.675 [14292.10788] <2> restorefiles: requesting_client = ar-gecs21 14:54:44.675 [14292.10788] <2> restorefiles: destination_client = ar-gecs21 14:54:44.675 [14292.10788] <2> restorefiles: requesting_client_hostname = ar-gecs21 14:54:44.675 [14292.10788] <2> restorefiles: destination_client_hostname = ar-gecs21 14:54:44.675 [14292.10788] <2> restorefiles: requesting_user = oracle 14:54:44.675 [14292.10788] <2> restorefiles: requesting_group = dba 14:54:44.675 [14292.10788] <2> restorefiles: progress_file = /usr/openv/netbackup/logs/user_ops/dbext/logs/4667.0.1462816483 14:54:44.675 [14292.10788] <2> restorefiles: rename_file = NONE 14:54:44.675 [14292.10788] <2> restorefiles: policy = NONE 14:54:44.675 [14292.10788] <2> restorefiles: keyword = 14:54:44.675 [14292.10788] <2> restorefiles: sched_type = 12 14:54:44.675 [14292.10788] <2> restorefiles: keep = 0 14:54:44.675 [14292.10788] <2> restorefiles: restore_retries = 0 14:54:44.675 [14292.10788] <2> restorefiles: raw_restore = 0 14:54:44.675 [14292.10788] <2> restorefiles: max_apollo_restore_arg_chars = 9000 14:54:44.675 [14292.10788] <2> restorefiles: rename_linktos = 0 14:54:44.675 [14292.10788] <2> restorefiles: client_type = 4 14:54:44.675 [14292.10788] <2> restorefiles: rest_start_time = 1462816483 14:54:44.675 [14292.10788] <2> restorefiles: cmd_start_date = 1462027544 14:54:44.675 [14292.10788] <2> restorefiles: cmd_end_date = 1462027544 14:54:44.675 [14292.10788] <2> restorefiles: unixtime = 1462816483 14:54:44.675 [14292.10788] <2> restorefiles: timetype = 4 14:54:44.675 [14292.10788] <2> restorefiles: user_interface = 0 14:54:44.675 [14292.10788] <2> restorefiles: monitor_restore_job = 0 14:54:44.675 [14292.10788] <2> restorefiles: restore_jobidstr = 722179 14:54:44.675 [14292.10788] <2> restorefiles: client_locales_available = 1 14:54:44.675 [14292.10788] <2> restorefiles: clnt_lc_messages = C 14:54:44.675 [14292.10788] <2> restorefiles: clnt_lc_time = C 14:54:44.675 [14292.10788] <2> restorefiles: clnt_lc_ctype = C 14:54:44.675 [14292.10788] <2> restorefiles: clnt_lc_collate = C 14:54:44.675 [14292.10788] <2> restorefiles: clnt_lc_numeric = C 14:54:44.675 [14292.10788] <2> restorefiles: cverbose = 0 14:54:44.675 [14292.10788] <2> restorefiles: true_image = 0 14:54:44.675 [14292.10788] <2> restorefiles: restorable_format = 0 14:54:44.675 [14292.10788] <2> restorefiles: bpcd_connect_opts = 16974338 14:54:44.675 [14292.10788] <2> restorefiles: filesystem_only = 0 14:54:44.675 [14292.10788] <2> restorefiles: clntuid = 101 14:54:44.675 [14292.10788] <2> restorefiles: clntgid = 101 14:54:44.675 [14292.10788] <2> restorefiles: acknowledge_status = 0 14:54:44.675 [14292.10788] <2> restorefiles: keep_progress_log_open = 1 14:54:44.675 [14292.10788] <2> restorefiles: clnt_flops = 0 14:54:44.675 [14292.10788] <2> restorefiles: proxy_copy = 0 14:54:44.675 [14292.10788] <2> restorefiles: clnt_v2_1_or_later = 1 14:54:44.675 [14292.10788] <2> restorefiles: commit = 0 14:54:44.675 [14292.10788] <2> restorefiles: dirByteCount = 0 14:54:44.675 [14292.10788] <2> restorefiles: tempDirectory = NULL 14:54:44.675 [14292.10788] <2> restorefiles: mountDatabase = 0 14:54:44.675 [14292.10788] <2> restorefiles: bePathByteCount = 0 14:54:44.675 [14292.10788] <2> restorefiles: beRedirectionPath = NULL 14:54:44.675 [14292.10788] <2> restorefiles: restore_sync_command = 0 14:54:44.675 [14292.10788] <2> restorefiles: use_alt_buf = 0 14:54:44.675 [14292.10788] <2> restorefiles: generate_english_logs = 0 14:54:44.675 [14292.10788] <2> restorefiles: dont_restore_security = 0 14:54:44.675 [14292.10788] <2> restorefiles: redirWebSrvrByteCount = 0 14:54:44.675 [14292.10788] <2> restorefiles: redirWebSrvr = NULL 14:54:44.675 [14292.10788] <2> restorefiles: granular_restore = 0 14:54:44.675 [14292.10788] <2> restorefiles: alt_rest_copy_num = 0 14:54:44.675 [14292.10788] <2> restorefiles: vm_opts = 0 14:54:44.675 [14292.10788] <2> restorefiles: vm_server_string = NONE 14:54:44.675 [14292.10788] <2> restorefiles: priority = -1 14:54:44.675 [14292.10788] <2> restorefiles: disk_media_server = NONE 14:54:44.675 [14292.10788] <2> restorefiles: spsrestoreoptions = 0 14:54:44.675 [14292.10788] <2> restorefiles: vhd_file_name = NONE 14:54:44.675 [14292.10788] <2> restorefiles: vhd_type = 0 14:54:44.675 [14292.10788] <2> restorefiles: vhd_disk_size = 0 14:54:44.675 [14292.10788] <2> restorefiles: vhd_delete_on_failure = 0 14:54:44.675 [14292.10788] <2> restorefiles: vm_db_uri_str_len = 0 14:54:44.675 [14292.10788] <2> restorefiles: optimized_backup = 0 14:54:44.675 [14292.10788] <2> ConnectionCache::connectAndCache: Acquiring new connection for host myarsw00403bk01, query type 76 14:54:44.691 [14292.10788] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:44.691 [14292.10788] <2> logconnections: BPDBM CONNECT FROM 172.24.1.8.54645 TO 172.24.1.8.1556 fd = 628 14:54:44.894 [14292.10788] <2> find_files: file_path = /c-3373280740-20160430-01 14:54:44.894 [14292.10788] <2> find_files: start_date = 1462027544 14:54:44.894 [14292.10788] <2> find_files: end_date = 1462027544 14:54:44.894 [14292.10788] <2> find_files: allow_virtual_browse = 0 14:54:44.894 [14292.10788] <2> find_files: include_no_display = 0 14:54:44.894 [14292.10788] <2> find_files: granular_restore = 0 14:54:44.894 [14292.10788] <2> ConnectionCache::connectAndCache: Acquiring new connection for host myarsw00403bk01, query type 79 14:54:44.909 [14292.10788] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:44.909 [14292.10788] <2> logconnections: BPDBM CONNECT FROM 172.24.1.8.54647 TO 172.24.1.8.1556 fd = 628 14:54:45.034 [14292.10788] <2> send_operation_to_monitor: Sending Operation [31] to activity monitor 14:54:45.128 [14292.10788] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:45.128 [14292.10788] <2> logconnections: BPCD CONNECT FROM 172.24.1.8.54648 TO 172.25.1.221.1556 fd = 644 14:54:45.159 [14292.10788] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:45.159 [14292.10788] <8> do_pbx_service: [vnet_connect.c:2156] via PBX VNETD CONNECT FROM 172.24.1.8.54649 TO 172.25.1.221.1556 fd = 652 14:54:45.159 [14292.10788] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 14:54:45.175 [14292.10788] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string /usr/openv/var/tmp/vnet-04702462816485169551000000273-X8aWlj 14:54:45.221 [14292.10788] <2> bpcr_get_version_rqst: bpcd version: 07610000 14:54:45.331 [14292.10788] <2> find_files: Got CONTINUE from bpdbm 14:54:45.346 [14292.10788] <2> db_end: Need to collect reply 14:54:45.377 [14292.10788] <2> read_legacy_touch_file: Found D:\GEAplications\Veritas\Netbackup\db\config\USE_BACKUP_MEDIA_SERVER_FOR_RESTORE; requested from (../restfiles.c.12023). 14:54:45.409 [14292.10788] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:713) 14:54:45.409 [14292.10788] <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 nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:916) 14:54:45.409 [14292.10788] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:930) 14:54:45.409 [14292.10788] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1562) 14:54:45.409 [14292.10788] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1571) 14:54:45.424 [14292.10788] <2> get_media_server_version: NBU version of myarsw00403bk01 is 761200 14:54:45.455 [14292.10788] <2> restorefiles: User Identity (Name/Domain/Type): Administrator/myarsw00403bk01/7. Restore IMG id list: cfhq002so0232_1462027544 14:54:45.455 [14292.10788] <2> start_mpx_main_bprd: server = myarsw00403bk01 14:54:45.471 [14292.10788] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:45.471 [14292.10788] <2> logconnections: BPCD CONNECT FROM 172.24.1.8.54653 TO 172.24.1.8.1556 fd = 628 14:54:45.471 [14292.10788] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:45.518 [14292.10788] <8> do_pbx_service: [vnet_connect.c:2156] via PBX VNETD CONNECT FROM 172.24.1.8.54654 TO 172.24.1.8.1556 fd = 668 14:54:45.518 [14292.10788] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 14:54:45.518 [14292.10788] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string 54655 14:54:45.580 [14292.10788] <2> bpcr_get_version_rqst: bpcd version: 07610001 14:54:45.580 [14292.10788] <2> start_mpx_main_bprd: MPX_MAIN_CMD_SOCK from bpcr = 628 14:54:45.580 [14292.10788] <2> start_mpx_main_bprd: MPX_MAIN_STAT_SOCK from bpcr = 668 14:54:45.580 [14292.10788] <2> start_mpx_main_bprd: /usr/openv/netbackup/bin/bprd bprd -dontfork -mpxmain 14:54:45.674 [5800.8620] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:45.674 [5800.8620] <2> job_connect: SO_KEEPALIVE set on socket 588 for client myarsw00403bk01 14:54:45.674 [5800.8620] <2> logconnections: BPJOBD CONNECT FROM 172.24.1.8.54657 TO 172.24.1.8.1556 fd = 588 14:54:45.674 [5800.8620] <2> job_authenticate_connection: ignoring VxSS authentication check for now... 14:54:45.674 [5800.8620] <2> job_connect: Connected to the host myarsw00403bk01 contype 53 jobid <0> socket <588> 14:54:45.674 [5800.8620] <2> job_connect: Connected on port 54657 14:54:45.892 [5800.8620] <2> process_msgQ_msg: Got is mpx main up request from pid 14292 14:54:45.908 [14292.10788] <2> restorefiles: Got mpx main is up from MPX main bprd 14:54:45.908 [14292.10788] <2> write_needed_images_to_file: writing needed images to C:\Program Files\Veritas\NetBackup\bin\bprd.d\neededimage.14292 14:54:45.908 [14292.10788] <2> write_needed_images_to_file: need image cfhq002so0232_1462027544 14:54:46.001 [5800.8620] <2> process_msgQ_msg: Got new restore request from pid 14292 14:54:46.001 [5800.8620] <2> read_needed_images_from_file: reading C:\Program Files\Veritas\NetBackup\bin\bprd.d\neededimage.14292 for work to do 14:54:46.001 [5800.8620] <2> read_image_info_from_file: frag 1 file 14 of OVA315 is the first need fragment for cfhq002so0232_1462027544 14:54:46.001 [5800.8620] <2> add_to_tape_list: OVA315 myarsw00403bk01 14:54:46.001 [5800.8620] <2> open_checkpoint_file: open restore job ckpts D:\GEAplications\Veritas\Netbackup\db\jobs\restart\722179.ckpts for a+ 14:54:46.001 [5800.8620] <2> group_tapes_in_frag_lists: Setting brm_pid = 0 for cfhq002so0232_1462027544 restfiles pid 14292 14:54:46.001 [5800.8620] <2> run_restores: Non-MPX image restore 14:54:46.033 [5800.8620] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:713) 14:54:46.033 [5800.8620] <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 nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:916) 14:54:46.033 [5800.8620] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:930) 14:54:46.033 [5800.8620] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1562) 14:54:46.033 [5800.8620] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1571) 14:54:46.048 [5800.8620] <2> get_media_server_version: NBU version of myarsw00403bk01 is 761200 14:54:46.048 [5800.8620] <2> start_mpx_group: media server myarsw00403bk01 version from db = 0x000b9d70 14:54:46.048 [5800.8620] <2> start_mpx_group: /usr/openv/netbackup/bin/bpbrm bpbrm -restore -mpx_restore -S myarsw00403bk01 -mt 2 -to 0 -mud 180 -mediasvr myarsw00403bk01 -masterversion 760000 14:54:46.048 [5800.8620] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:46.048 [5800.8620] <2> logconnections: BPCD CONNECT FROM 172.24.1.8.54661 TO 172.24.1.8.1556 fd = 612 14:54:46.048 [5800.8620] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:46.095 [5800.8620] <8> do_pbx_service: [vnet_connect.c:2156] via PBX VNETD CONNECT FROM 172.24.1.8.54662 TO 172.24.1.8.1556 fd = 628 14:54:46.095 [5800.8620] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 14:54:46.111 [5800.8620] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string 54663 14:54:46.142 [5800.8620] <2> bpcr_get_version_rqst: bpcd version: 07610001 14:54:46.142 [5800.8620] <2> start_mpx_group: bpbrm stdout from bpcr = 612 14:54:46.142 [5800.8620] <2> start_mpx_group: bpbrm stderr from bpcr = 628 14:54:46.235 [5800.8620] <2> read_bpbrm_msg: (pid 0) PID of bpbrm = 13840 14:54:46.235 [5800.8620] <2> start_mpx_group: bpbrm pid = 13840 14:54:46.235 [5800.8620] <2> set_mpx_group_image_brm_pid: Setting brm_pid = 13840 for cfhq002so0232_1462027544 restfiles pid 14292 14:54:46.235 [5800.8620] <2> next_image_using_first_blks: returning backup_id = cfhq002so0232_1462027544, tape = OVA315, fileno = 14, firstblk = 0 14:54:46.235 [5800.8620] <2> start_image_restore: Sending listen msg to restfiles 14292 for image with backup time 1462027544 14:54:46.345 [14292.10788] <2> restorefiles: Received listen message for backup_time 1462027544 backup_id cfhq002so0232_1462027544 14:54:46.345 [14292.10788] <2> restorefiles: File list port = 0, socket = 680 for backup_id cfhq002so0232_1462027544 14:54:46.454 [5800.8620] <2> process_msgQ_msg: Setting portnum = 0 for cfhq002so0232_1462027544 14:54:46.454 [5800.8620] <2> start_image_restore: restfiles pid 14292 brm pid 13840 cfhq002so0232_1462027544 14:54:46.469 [5800.8620] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:46.469 [5800.8620] <2> logconnections: BPCD CONNECT FROM 172.24.1.8.54670 TO 172.25.1.221.1556 fd = 636 14:54:46.501 [5800.8620] <2> vnet_pbxConnect: pbxConnectEx Succeeded 14:54:46.501 [5800.8620] <8> do_pbx_service: [vnet_connect.c:2156] via PBX VNETD CONNECT FROM 172.24.1.8.54671 TO 172.25.1.221.1556 fd = 620 14:54:46.501 [5800.8620] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 14:54:46.516 [5800.8620] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string /usr/openv/var/tmp/vnet-04726462816486514365000000273-YraWoj 14:54:46.563 [5800.8620] <2> bpcr_get_version_rqst: bpcd version: 07610000 14:54:46.641 [5800.8620] <2> start_indep_grp: active_mpx_groups = 1 14:54:46.641 [5800.8620] <2> start_indep_grp: p_active_restore_for_first_grp = NULL 14:54:46.641 [5800.8620] <2> start_indep_grp: p_first_mpx_group_for_restore = NULL 14:54:49.293 [14292.10788] <2> restorefiles: connected on port 0 14:54:49.293 [14292.10788] <2> write_files_to_active_sockets: EXIT STATUS 0