15:52:42.218 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:52229 15:52:42.218 [26299] <2> bprd: socket fd from accept() is 10 15:52:42.219 [26299] <2> listen_loop: request complete 15:52:42.219 [26979] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.52229 TO 127.0.0.1.1556 fd = 10 15:52:42.220 [26979] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:52:42.220 [26979] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 52229 15:52:42.220 [26979] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:52:42.220 [26979] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 114 0x72 15:52:42.221 [26979] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:52:42.221 [26979] <2> process_request: command C_GET_HOSTINFO (114) received 15:52:42.221 [26979] <2> process_request: 2 arguments, request is from a post 5.1 requestor 15:52:42.261 [26979] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:52:42.261 [26979] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:52:42.261 [26979] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:52:42.261 [26979] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:52:42.261 [26979] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:52:42.261 [26979] <2> db_freeEXDB_INFO: ? 15:52:42.262 [26979] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:52:42.262 [26979] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.46505 TO 10.132.112.22.1556 fd = 5 15:52:42.263 [26979] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:52:42.264 [26979] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.40825 TO 10.132.112.22.1556 fd = 6 15:52:42.265 [26979] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:52:42.305 [26979] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-26981358437962304606000000000-rbQxyb 15:52:42.384 [26979] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 5 15:52:42.384 [26979] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:52:42.404 [26979] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:52:42.404 [26979] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST 15:52:42.404 [26979] <2> bpcr_get_platform_rqst: Server platform length = 17 15:52:42.404 [26979] <2> xlatePlatformName: my version = 750000, client version = 750000, check current list first 15:52:42.404 [26979] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = linuxR_x86_2.6.18 15:52:42.404 [26979] <2> local_getHostInfo: platform of client bfbackup is Linux, RedHat2.6.18 15:52:42.404 [26979] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=22, buffer=UNAME_SYSNAME = Linux 15:52:42.404 [26979] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=39, buffer=UNAME_NODENAME = bfbackup.ipcmedia.com 15:52:42.404 [26979] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=36, buffer=UNAME_RELEASE = 2.6.18-308.24.1.el5 15:52:42.404 [26979] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=52, buffer=UNAME_VERSION = #1 SMP Wed Nov 21 11:42:14 EST 2012 15:52:42.404 [26979] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=23, buffer=UNAME_MACHINE = x86_64 15:52:42.404 [26979] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=3, buffer=END 15:52:42.404 [26979] <2> local_getHostInfo: ../dbmisc.c: 1728, uname -sr=Linux 2.6.18-308.24.1.el5 15:52:42.405 [26979] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:52:42.406 [26979] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 5 15:52:42.406 [26979] <2> getPatchLevel: got client socket 15:52:42.406 [26979] <2> getPatchLevel: file num = 5, file size = 31 15:52:42.406 [26979] <2> bpcr_read_rqst: completed send of file number 5 15:52:42.406 [26979] <2> bpcr_read_rqst: completed send of transfer_count = 16383 15:52:42.406 [26979] <2> bpcr_read_rqst: Before readclientstatus on socket 6 15:52:42.406 [26979] <2> bpcr_read_rqst: Num bytes to be sent by client = 31 15:52:42.406 [26979] <2> getPatchLevel: total chars read = 31 15:52:42.407 [26979] <2> getPatchLevel: level = >7.5.0.4< 15:52:42.448 [26979] <2> process_request: EXIT STATUS 0 15:54:10.115 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:40734 15:54:10.115 [26299] <2> bprd: socket fd from accept() is 10 15:54:10.116 [26299] <2> listen_loop: request complete 15:54:10.116 [27617] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.40734 TO 127.0.0.1.1556 fd = 10 15:54:10.117 [27617] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:10.117 [27617] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 40734 15:54:10.117 [27617] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:10.118 [27617] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 59 0x3b 15:54:10.118 [27617] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:10.118 [27617] <2> process_request: command C_LIKELY_DATE_3_0 (59) received 15:54:10.118 [27617] <2> process_request: likelydate request = 329199 59 bfbackup bfbackup.ipcmedia.com bfbackup NONE 0 12 1342889650 1358441650 en_UK en_UK en_UK en_UK en_UK 0 0 0 0 0 0 0 0 15:54:10.125 [27617] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 83 15:54:10.126 [27617] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:10.126 [27617] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.52508 TO 127.0.0.1.1556 fd = 5 15:54:10.126 [27617] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:54:10.263 [27617] <2> imagelikely: db_getIMAGE() failed: no entity was found 15:54:10.263 [27617] <2> process_request: imagelikely failed - EC status = no entity was found 15:54:10.263 [27617] <2> process_request: EXIT STATUS 227 15:54:10.263 [27617] <2> bprd: /usr/openv/netbackup/bin/userreq_notify image_list bfbackup unknown 15:54:27.854 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:59224 15:54:27.854 [26299] <2> bprd: socket fd from accept() is 10 15:54:27.855 [26299] <2> listen_loop: request complete 15:54:27.856 [27660] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.59224 TO 127.0.0.1.1556 fd = 10 15:54:27.856 [27660] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:27.857 [27660] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 59224 15:54:27.857 [27660] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:27.857 [27660] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 59 0x3b 15:54:27.857 [27660] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:27.857 [27660] <2> process_request: command C_LIKELY_DATE_3_0 (59) received 15:54:27.857 [27660] <2> process_request: likelydate request = 329199 59 subs-live-db-01 bfbackup.ipcmedia.com bfbackup NONE 0 12 1342889667 1358441667 en_UK en_UK en_UK en_UK en_UK 0 0 40 0 0 0 0 0 15:54:27.857 [27660] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1583] in failed file cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:27.857 [27660] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:27.857 [27660] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:27.858 [27660] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:27.858 [27660] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:27.858 [27660] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:27.858 [27660] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:27.858 [27660] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:27.858 [27660] <8> gethostent_force: [vnet_addrinfo.c:2555] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME=subs-live-db-01 ADJUSTEDNAME=subs-live-db-01 15:54:27.858 [27660] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:27.858 [27660] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:27.858 [27660] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:27.858 [27660] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:27.858 [27660] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:27.858 [27660] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:27.863 [27660] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:387] h_errno 1 0x1 15:54:27.863 [27660] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:388] vnet_ipv4_gethostbyname failed subs-live-db-01 15:54:27.863 [27660] <2> vnet_legacy_cached_gethostbyname: vnet_hosts.c.402: 46941778245152: Function failed: 6 0x00000006 15:54:27.869 [27660] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 83 15:54:27.870 [27660] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:27.870 [27660] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.51961 TO 127.0.0.1.1556 fd = 5 15:54:27.870 [27660] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:54:27.985 [27660] <2> db_end: Need to collect reply 15:54:27.985 [27660] <2> process_request: EXIT STATUS 0 15:54:27.985 [27660] <2> bprd: /usr/openv/netbackup/bin/userreq_notify image_list subs-live-db-01 unknown 15:54:28.432 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:60803 15:54:28.432 [26299] <2> bprd: socket fd from accept() is 10 15:54:28.433 [26299] <2> listen_loop: request complete 15:54:28.434 [27667] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.60803 TO 127.0.0.1.1556 fd = 10 15:54:28.434 [27667] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:28.434 [27667] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 60803 15:54:28.435 [27667] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:28.435 [27667] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 163 0xa3 15:54:28.435 [27667] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:28.435 [27667] <2> process_request: command C_BPLIST_7_5 (163) received 15:54:28.435 [27667] <2> process_request: list request = 329199 163 root root subs-live-db-01 bfbackup.ipcmedia.com bfbackup *NULL* NONE 0 3 1 1358266948 1358467199 4 4 0 5 5 0 40 0 0 3 0 en_UK en_UK en_UK en_UK en_UK 0 1 0 0 0 15:54:28.435 [27667] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1583] in failed file cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:28.435 [27667] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:28.435 [27667] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:28.435 [27667] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:28.435 [27667] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:28.435 [27667] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:28.435 [27667] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:28.435 [27667] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:28.435 [27667] <8> gethostent_force: [vnet_addrinfo.c:2555] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME=subs-live-db-01 ADJUSTEDNAME=subs-live-db-01 15:54:28.436 [27667] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:28.436 [27667] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:28.436 [27667] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:28.436 [27667] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:28.436 [27667] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:28.436 [27667] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:28.440 [27667] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:387] h_errno 1 0x1 15:54:28.440 [27667] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:388] vnet_ipv4_gethostbyname failed subs-live-db-01 15:54:28.440 [27667] <2> vnet_legacy_cached_gethostbyname: vnet_hosts.c.402: 46941778245152: Function failed: 6 0x00000006 15:54:28.446 [27667] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:28.446 [27667] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:28.446 [27667] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:28.446 [27667] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:54:28.446 [27667] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:54:28.446 [27667] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:54:28.446 [27667] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:54:28.446 [27667] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:54:28.446 [27667] <2> get_type_of_client_list_restore: list and restore not specified for bfbackup 15:54:28.446 [27667] <2> get_type_of_client_free_browse: Free browse allowed for bfbackup 15:54:28.446 [27667] <2> db_freeEXDB_INFO: ? 15:54:28.446 [27667] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:28.446 [27667] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:28.446 [27667] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2839] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME2=subs-live-db-01 15:54:28.472 [27667] <2> fileslist: sockfd = 10 15:54:28.472 [27667] <2> fileslist: owner = root 15:54:28.472 [27667] <2> fileslist: group = root 15:54:28.472 [27667] <2> fileslist: client = subs-live-db-01 15:54:28.472 [27667] <2> fileslist: sched_type = 12 15:54:28.472 [27667] <2> fileslist: starttime = 1358266948 15:54:28.472 [27667] <2> fileslist: endtime = 1358467199 15:54:28.472 [27667] <2> fileslist: filepath = /home/TIME-INC-CORP/klamb1271/ 15:54:28.472 [27667] <2> fileslist: recursion_level = 1 15:54:28.472 [27667] <2> fileslist: timetype = 4 15:54:28.472 [27667] <2> fileslist: rqtimetype = 4 15:54:28.472 [27667] <2> fileslist: user_interface = 0 15:54:28.472 [27667] <2> fileslist: full_listing = 5 15:54:28.472 [27667] <2> fileslist: full_listing now = 0 15:54:28.472 [27667] <2> fileslist: pc1_listing = 1 15:54:28.472 [27667] <2> fileslist: input_long_listing = 0x5 15:54:28.472 [27667] <2> fileslist: list_seconds = 0 15:54:28.472 [27667] <2> fileslist: list_policy = 1 15:54:28.472 [27667] <2> fileslist: long_listing = 1 15:54:28.472 [27667] <2> fileslist: raw_partition_search = 0 15:54:28.472 [27667] <2> fileslist: client_type = 40 15:54:28.472 [27667] <2> fileslist: policy = NONE 15:54:28.472 [27667] <2> fileslist: keyword = 15:54:28.472 [27667] <2> fileslist: bplist_format = 3 15:54:28.472 [27667] <2> fileslist: true_image = 0 15:54:28.472 [27667] <2> fileslist: list_lc_messages = en_UK 15:54:28.472 [27667] <2> fileslist: list_lc_time = en_UK 15:54:28.472 [27667] <2> fileslist: list_lc_ctype = en_UK 15:54:28.472 [27667] <2> fileslist: list_lc_collate = en_UK 15:54:28.472 [27667] <2> fileslist: list_lc_numeric = en_UK 15:54:28.472 [27667] <2> fileslist: directories_only = 0 15:54:28.472 [27667] <2> fileslist: include_extra_info = 1 15:54:28.472 [27667] <2> fileslist: client_uid = 0 15:54:28.472 [27667] <2> fileslist: client_gid = 0 15:54:28.472 [27667] <2> fileslist: ignore_case = 0 15:54:28.472 [27667] <2> fileslist: list_seconds_pre_4_5 = 0 15:54:28.472 [27667] <2> fileslist: client_flops = 0 15:54:28.473 [27667] <2> fileslist: query.fl_peername = subs-live-db-01 15:54:28.473 [27667] <2> fileslist: bfbackup.ipcmedia.com 15:54:28.473 [27667] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:54:28.473 [27667] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:28.473 [27667] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.42221 TO 127.0.0.1.1556 fd = 5 15:54:28.473 [27667] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:54:28.476 [27667] <2> fileslist: begin db communication 15:54:28.476 [27667] <2> fileslist: criteria sent to db mgr 15:54:28.590 [27667] <2> fileslist: unexpected return value from db_FLISTreceive: no entity was found 227 15:54:28.590 [27667] <2> process_request: fileslist failed - EC status = no entity was found 15:54:28.590 [27667] <2> process_request: EXIT STATUS 227 15:54:28.590 [27667] <2> bprd: /usr/openv/netbackup/bin/userreq_notify list subs-live-db-01 root 15:54:34.270 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:52673 15:54:34.270 [26299] <2> bprd: socket fd from accept() is 10 15:54:34.271 [26299] <2> listen_loop: request complete 15:54:34.271 [27676] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.52673 TO 127.0.0.1.1556 fd = 10 15:54:34.271 [27676] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:34.272 [27676] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 52673 15:54:34.272 [27676] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:34.272 [27676] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 59 0x3b 15:54:34.272 [27676] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:34.272 [27676] <2> process_request: command C_LIKELY_DATE_3_0 (59) received 15:54:34.272 [27676] <2> process_request: likelydate request = 329199 59 subs-live-db-01 bfbackup.ipcmedia.com bfbackup NONE 0 12 1342889674 1358441674 en_UK en_UK en_UK en_UK en_UK 0 0 40 0 0 0 0 0 15:54:34.272 [27676] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1583] in failed file cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:34.272 [27676] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:34.272 [27676] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:34.273 [27676] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:34.273 [27676] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:34.273 [27676] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:34.273 [27676] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:34.273 [27676] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:34.273 [27676] <8> gethostent_force: [vnet_addrinfo.c:2555] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME=subs-live-db-01 ADJUSTEDNAME=subs-live-db-01 15:54:34.273 [27676] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:34.273 [27676] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:34.273 [27676] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:34.273 [27676] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:34.273 [27676] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:34.273 [27676] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:34.277 [27676] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:387] h_errno 1 0x1 15:54:34.277 [27676] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:388] vnet_ipv4_gethostbyname failed subs-live-db-01 15:54:34.277 [27676] <2> vnet_legacy_cached_gethostbyname: vnet_hosts.c.402: 46941778245152: Function failed: 6 0x00000006 15:54:34.284 [27676] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 83 15:54:34.284 [27676] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:34.285 [27676] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.41218 TO 127.0.0.1.1556 fd = 5 15:54:34.285 [27676] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:54:34.389 [27676] <2> db_end: Need to collect reply 15:54:34.389 [27676] <2> process_request: EXIT STATUS 0 15:54:34.390 [27676] <2> bprd: /usr/openv/netbackup/bin/userreq_notify image_list subs-live-db-01 unknown 15:54:37.745 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:38365 15:54:37.746 [26299] <2> bprd: socket fd from accept() is 10 15:54:37.746 [26299] <2> listen_loop: request complete 15:54:37.747 [27684] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.38365 TO 127.0.0.1.1556 fd = 10 15:54:37.747 [27684] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:37.748 [27684] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 38365 15:54:37.748 [27684] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:37.748 [27684] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 81 0x51 15:54:37.748 [27684] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:37.748 [27684] <2> process_request: command C_IMAGE_LIST_4_5 (81) received 15:54:37.748 [27684] <2> process_request: imagelist request = 329199 81 subs-live-db-01 bfbackup.ipcmedia.com bfbackup NONE 0 12 90000 1358467199 en_UK en_UK en_UK en_UK en_UK 8 0 40 0 3 0 0 0 15:54:37.748 [27684] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1583] in failed file cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:37.748 [27684] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:37.748 [27684] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:37.749 [27684] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:37.749 [27684] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:37.749 [27684] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:37.749 [27684] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:37.749 [27684] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:37.749 [27684] <8> gethostent_force: [vnet_addrinfo.c:2555] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME=subs-live-db-01 ADJUSTEDNAME=subs-live-db-01 15:54:37.749 [27684] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:37.749 [27684] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:37.749 [27684] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:37.749 [27684] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:37.749 [27684] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:37.749 [27684] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:37.753 [27684] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:387] h_errno 1 0x1 15:54:37.753 [27684] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:388] vnet_ipv4_gethostbyname failed subs-live-db-01 15:54:37.753 [27684] <2> vnet_legacy_cached_gethostbyname: vnet_hosts.c.402: 46941778245152: Function failed: 6 0x00000006 15:54:37.759 [27684] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:37.759 [27684] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:37.759 [27684] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:37.759 [27684] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 76 15:54:37.760 [27684] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:37.760 [27684] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.36504 TO 127.0.0.1.1556 fd = 5 15:54:37.760 [27684] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:54:37.875 [27684] <2> process_request: EXIT STATUS 0 15:54:37.875 [27684] <2> bprd: /usr/openv/netbackup/bin/userreq_notify image_list subs-live-db-01 unknown 15:54:40.188 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:41583 15:54:40.188 [26299] <2> bprd: socket fd from accept() is 10 15:54:40.188 [26299] <2> listen_loop: request complete 15:54:40.189 [27691] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.41583 TO 127.0.0.1.1556 fd = 10 15:54:40.189 [27691] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:40.190 [27691] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 41583 15:54:40.190 [27691] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:40.190 [27691] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 163 0xa3 15:54:40.190 [27691] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:40.190 [27691] <2> process_request: command C_BPLIST_7_5 (163) received 15:54:40.190 [27691] <2> process_request: list request = 329199 163 root root subs-live-db-01 bfbackup.ipcmedia.com bfbackup *NULL* NONE 0 3 999 1358266948 1358266948 4 4 0 5 5 0 40 0 0 3 0 en_UK en_UK en_UK en_UK en_UK 0 1 0 0 33554432 15:54:40.190 [27691] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1583] in failed file cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:40.190 [27691] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:40.190 [27691] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:40.190 [27691] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:40.191 [27691] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:40.191 [27691] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:40.191 [27691] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:40.191 [27691] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:40.191 [27691] <8> gethostent_force: [vnet_addrinfo.c:2555] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME=subs-live-db-01 ADJUSTEDNAME=subs-live-db-01 15:54:40.191 [27691] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:40.191 [27691] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:40.191 [27691] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:40.191 [27691] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:40.191 [27691] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:40.191 [27691] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:40.195 [27691] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:387] h_errno 1 0x1 15:54:40.195 [27691] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:388] vnet_ipv4_gethostbyname failed subs-live-db-01 15:54:40.195 [27691] <2> vnet_legacy_cached_gethostbyname: vnet_hosts.c.402: 46941778245152: Function failed: 6 0x00000006 15:54:40.201 [27691] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:40.201 [27691] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:40.201 [27691] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:40.202 [27691] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:54:40.202 [27691] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:54:40.202 [27691] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:54:40.202 [27691] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:54:40.202 [27691] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:54:40.202 [27691] <2> get_type_of_client_list_restore: list and restore not specified for bfbackup 15:54:40.202 [27691] <2> get_type_of_client_free_browse: Free browse allowed for bfbackup 15:54:40.202 [27691] <2> db_freeEXDB_INFO: ? 15:54:40.202 [27691] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:40.202 [27691] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:40.202 [27691] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2839] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME2=subs-live-db-01 15:54:40.227 [27691] <2> fileslist: sockfd = 10 15:54:40.228 [27691] <2> fileslist: owner = root 15:54:40.228 [27691] <2> fileslist: group = root 15:54:40.228 [27691] <2> fileslist: client = subs-live-db-01 15:54:40.228 [27691] <2> fileslist: sched_type = 12 15:54:40.228 [27691] <2> fileslist: starttime = 1358266948 15:54:40.228 [27691] <2> fileslist: endtime = 1358266948 15:54:40.228 [27691] <2> fileslist: filepath = / 15:54:40.228 [27691] <2> fileslist: recursion_level = 999 15:54:40.228 [27691] <2> fileslist: timetype = 4 15:54:40.228 [27691] <2> fileslist: rqtimetype = 4 15:54:40.228 [27691] <2> fileslist: user_interface = 0 15:54:40.228 [27691] <2> fileslist: full_listing = 5 15:54:40.228 [27691] <2> fileslist: full_listing now = 0 15:54:40.228 [27691] <2> fileslist: pc1_listing = 1 15:54:40.228 [27691] <2> fileslist: input_long_listing = 0x5 15:54:40.228 [27691] <2> fileslist: list_seconds = 0 15:54:40.228 [27691] <2> fileslist: list_policy = 1 15:54:40.228 [27691] <2> fileslist: long_listing = 1 15:54:40.228 [27691] <2> fileslist: raw_partition_search = 0 15:54:40.228 [27691] <2> fileslist: client_type = 40 15:54:40.228 [27691] <2> fileslist: policy = NONE 15:54:40.228 [27691] <2> fileslist: keyword = 15:54:40.228 [27691] <2> fileslist: bplist_format = 3 15:54:40.228 [27691] <2> fileslist: true_image = 0 15:54:40.228 [27691] <2> fileslist: list_lc_messages = en_UK 15:54:40.228 [27691] <2> fileslist: list_lc_time = en_UK 15:54:40.228 [27691] <2> fileslist: list_lc_ctype = en_UK 15:54:40.228 [27691] <2> fileslist: list_lc_collate = en_UK 15:54:40.228 [27691] <2> fileslist: list_lc_numeric = en_UK 15:54:40.228 [27691] <2> fileslist: directories_only = 0 15:54:40.228 [27691] <2> fileslist: include_extra_info = 1 15:54:40.228 [27691] <2> fileslist: client_uid = 0 15:54:40.228 [27691] <2> fileslist: client_gid = 0 15:54:40.228 [27691] <2> fileslist: ignore_case = 0 15:54:40.228 [27691] <2> fileslist: list_seconds_pre_4_5 = 0 15:54:40.228 [27691] <2> fileslist: client_flops = 33554432 15:54:40.228 [27691] <2> fileslist: query.fl_peername = subs-live-db-01 15:54:40.228 [27691] <2> fileslist: bfbackup.ipcmedia.com 15:54:40.228 [27691] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:54:40.229 [27691] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:40.229 [27691] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.56229 TO 127.0.0.1.1556 fd = 5 15:54:40.229 [27691] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:54:40.232 [27691] <2> fileslist: begin db communication 15:54:40.232 [27691] <2> fileslist: criteria sent to db mgr 15:54:40.710 [27691] <2> process_request: EXIT STATUS 0 15:54:40.710 [27691] <2> bprd: /usr/openv/netbackup/bin/userreq_notify list subs-live-db-01 root 15:54:45.855 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:36285 15:54:45.855 [26299] <2> bprd: socket fd from accept() is 10 15:54:45.856 [26299] <2> listen_loop: request complete 15:54:45.856 [27698] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.36285 TO 127.0.0.1.1556 fd = 10 15:54:45.856 [27698] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:45.857 [27698] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 36285 15:54:45.857 [27698] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:45.857 [27698] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 163 0xa3 15:54:45.857 [27698] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:45.857 [27698] <2> process_request: command C_BPLIST_7_5 (163) received 15:54:45.857 [27698] <2> process_request: list request = 329199 163 root root subs-live-db-01 bfbackup.ipcmedia.com bfbackup *NULL* NONE 0 3 999 1358266948 1358266948 3 4 0 0 0 0 40 0 0 0 0 en_UK en_UK en_UK en_UK en_UK 0 1 0 0 16384 15:54:45.857 [27698] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1583] in failed file cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:45.857 [27698] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:45.857 [27698] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:45.858 [27698] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:45.858 [27698] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:45.858 [27698] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:45.858 [27698] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:45.858 [27698] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:45.858 [27698] <8> gethostent_force: [vnet_addrinfo.c:2555] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME=subs-live-db-01 ADJUSTEDNAME=subs-live-db-01 15:54:45.858 [27698] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:45.858 [27698] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:45.858 [27698] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:45.858 [27698] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:45.858 [27698] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:45.858 [27698] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:45.863 [27698] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:387] h_errno 1 0x1 15:54:45.863 [27698] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:388] vnet_ipv4_gethostbyname failed subs-live-db-01 15:54:45.863 [27698] <2> vnet_legacy_cached_gethostbyname: vnet_hosts.c.402: 46941778245152: Function failed: 6 0x00000006 15:54:45.869 [27698] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:45.869 [27698] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:45.869 [27698] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:45.869 [27698] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:54:45.869 [27698] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:54:45.869 [27698] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:54:45.869 [27698] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:54:45.869 [27698] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:54:45.869 [27698] <2> get_type_of_client_list_restore: list and restore not specified for bfbackup 15:54:45.869 [27698] <2> get_type_of_client_free_browse: Free browse allowed for bfbackup 15:54:45.869 [27698] <2> db_freeEXDB_INFO: ? 15:54:45.869 [27698] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:45.869 [27698] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:45.869 [27698] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2839] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME2=subs-live-db-01 15:54:45.895 [27698] <2> fileslist: sockfd = 10 15:54:45.895 [27698] <2> fileslist: owner = root 15:54:45.895 [27698] <2> fileslist: group = root 15:54:45.895 [27698] <2> fileslist: client = subs-live-db-01 15:54:45.895 [27698] <2> fileslist: sched_type = 12 15:54:45.895 [27698] <2> fileslist: starttime = 1358266948 15:54:45.895 [27698] <2> fileslist: endtime = 1358266948 15:54:45.895 [27698] <2> fileslist: filepath = / 15:54:45.895 [27698] <2> fileslist: recursion_level = 999 15:54:45.895 [27698] <2> fileslist: timetype = 3 15:54:45.895 [27698] <2> fileslist: rqtimetype = 4 15:54:45.895 [27698] <2> fileslist: user_interface = 0 15:54:45.895 [27698] <2> fileslist: full_listing = 0 15:54:45.895 [27698] <2> fileslist: pc1_listing = 0 15:54:45.895 [27698] <2> fileslist: input_long_listing = 0x0 15:54:45.895 [27698] <2> fileslist: list_seconds = 0 15:54:45.895 [27698] <2> fileslist: list_policy = 0 15:54:45.895 [27698] <2> fileslist: long_listing = 0 15:54:45.895 [27698] <2> fileslist: raw_partition_search = 0 15:54:45.895 [27698] <2> fileslist: client_type = 40 15:54:45.895 [27698] <2> fileslist: policy = NONE 15:54:45.895 [27698] <2> fileslist: keyword = 15:54:45.895 [27698] <2> fileslist: bplist_format = 1 15:54:45.895 [27698] <2> fileslist: true_image = 0 15:54:45.895 [27698] <2> fileslist: list_lc_messages = en_UK 15:54:45.895 [27698] <2> fileslist: list_lc_time = en_UK 15:54:45.895 [27698] <2> fileslist: list_lc_ctype = en_UK 15:54:45.895 [27698] <2> fileslist: list_lc_collate = en_UK 15:54:45.895 [27698] <2> fileslist: list_lc_numeric = en_UK 15:54:45.895 [27698] <2> fileslist: directories_only = 0 15:54:45.895 [27698] <2> fileslist: include_extra_info = 1 15:54:45.895 [27698] <2> fileslist: client_uid = 0 15:54:45.895 [27698] <2> fileslist: client_gid = 0 15:54:45.895 [27698] <2> fileslist: ignore_case = 0 15:54:45.895 [27698] <2> fileslist: list_seconds_pre_4_5 = 0 15:54:45.895 [27698] <2> fileslist: client_flops = 16384 15:54:45.895 [27698] <2> fileslist: query.fl_peername = subs-live-db-01 15:54:45.895 [27698] <2> fileslist: bfbackup.ipcmedia.com 15:54:45.895 [27698] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:54:45.896 [27698] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:45.896 [27698] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.53382 TO 127.0.0.1.1556 fd = 5 15:54:45.896 [27698] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:54:45.899 [27698] <2> fileslist: begin db communication 15:54:45.899 [27698] <2> fileslist: criteria sent to db mgr 15:54:46.007 [27698] <2> process_request: EXIT STATUS 0 15:54:46.007 [27698] <2> bprd: /usr/openv/netbackup/bin/userreq_notify list subs-live-db-01 root 15:54:46.549 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:45548 15:54:46.549 [26299] <2> bprd: socket fd from accept() is 10 15:54:46.550 [26299] <2> listen_loop: request complete 15:54:46.550 [27705] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.45548 TO 127.0.0.1.1556 fd = 10 15:54:46.550 [27705] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:46.551 [27705] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 45548 15:54:46.551 [27705] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:46.551 [27705] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 163 0xa3 15:54:46.551 [27705] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:46.551 [27705] <2> process_request: command C_BPLIST_7_5 (163) received 15:54:46.551 [27705] <2> process_request: list request = 329199 163 root root subs-live-db-01 bfbackup.ipcmedia.com bfbackup *NULL* NONE 0 3 999 1358266948 1358266948 3 4 0 0 0 0 40 0 0 0 0 en_UK en_UK en_UK en_UK en_UK 0 1 0 0 16384 15:54:46.551 [27705] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1583] in failed file cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:46.551 [27705] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:46.551 [27705] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:46.552 [27705] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:46.552 [27705] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:46.552 [27705] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:46.552 [27705] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:46.552 [27705] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:46.552 [27705] <8> gethostent_force: [vnet_addrinfo.c:2555] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME=subs-live-db-01 ADJUSTEDNAME=subs-live-db-01 15:54:46.552 [27705] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:46.552 [27705] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:46.552 [27705] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:46.552 [27705] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:46.552 [27705] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:46.552 [27705] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:46.556 [27705] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:387] h_errno 1 0x1 15:54:46.556 [27705] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:388] vnet_ipv4_gethostbyname failed subs-live-db-01 15:54:46.557 [27705] <2> vnet_legacy_cached_gethostbyname: vnet_hosts.c.402: 46941778245152: Function failed: 6 0x00000006 15:54:46.562 [27705] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:46.563 [27705] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:46.563 [27705] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:54:46.563 [27705] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:54:46.563 [27705] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:54:46.563 [27705] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:54:46.563 [27705] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:54:46.563 [27705] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:54:46.563 [27705] <2> get_type_of_client_list_restore: list and restore not specified for bfbackup 15:54:46.563 [27705] <2> get_type_of_client_free_browse: Free browse allowed for bfbackup 15:54:46.563 [27705] <2> db_freeEXDB_INFO: ? 15:54:46.563 [27705] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:54:46.563 [27705] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:54:46.563 [27705] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2839] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME2=subs-live-db-01 15:54:46.589 [27705] <2> fileslist: sockfd = 10 15:54:46.589 [27705] <2> fileslist: owner = root 15:54:46.589 [27705] <2> fileslist: group = root 15:54:46.589 [27705] <2> fileslist: client = subs-live-db-01 15:54:46.589 [27705] <2> fileslist: sched_type = 12 15:54:46.589 [27705] <2> fileslist: starttime = 1358266948 15:54:46.589 [27705] <2> fileslist: endtime = 1358266948 15:54:46.589 [27705] <2> fileslist: filepath = / 15:54:46.589 [27705] <2> fileslist: recursion_level = 999 15:54:46.589 [27705] <2> fileslist: timetype = 3 15:54:46.589 [27705] <2> fileslist: rqtimetype = 4 15:54:46.589 [27705] <2> fileslist: user_interface = 0 15:54:46.589 [27705] <2> fileslist: full_listing = 0 15:54:46.589 [27705] <2> fileslist: pc1_listing = 0 15:54:46.589 [27705] <2> fileslist: input_long_listing = 0x0 15:54:46.589 [27705] <2> fileslist: list_seconds = 0 15:54:46.589 [27705] <2> fileslist: list_policy = 0 15:54:46.589 [27705] <2> fileslist: long_listing = 0 15:54:46.589 [27705] <2> fileslist: raw_partition_search = 0 15:54:46.589 [27705] <2> fileslist: client_type = 40 15:54:46.589 [27705] <2> fileslist: policy = NONE 15:54:46.589 [27705] <2> fileslist: keyword = 15:54:46.589 [27705] <2> fileslist: bplist_format = 1 15:54:46.589 [27705] <2> fileslist: true_image = 0 15:54:46.589 [27705] <2> fileslist: list_lc_messages = en_UK 15:54:46.589 [27705] <2> fileslist: list_lc_time = en_UK 15:54:46.589 [27705] <2> fileslist: list_lc_ctype = en_UK 15:54:46.589 [27705] <2> fileslist: list_lc_collate = en_UK 15:54:46.589 [27705] <2> fileslist: list_lc_numeric = en_UK 15:54:46.589 [27705] <2> fileslist: directories_only = 0 15:54:46.589 [27705] <2> fileslist: include_extra_info = 1 15:54:46.589 [27705] <2> fileslist: client_uid = 0 15:54:46.589 [27705] <2> fileslist: client_gid = 0 15:54:46.589 [27705] <2> fileslist: ignore_case = 0 15:54:46.589 [27705] <2> fileslist: list_seconds_pre_4_5 = 0 15:54:46.589 [27705] <2> fileslist: client_flops = 16384 15:54:46.589 [27705] <2> fileslist: query.fl_peername = subs-live-db-01 15:54:46.589 [27705] <2> fileslist: bfbackup.ipcmedia.com 15:54:46.589 [27705] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:54:46.590 [27705] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:46.590 [27705] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.55000 TO 127.0.0.1.1556 fd = 5 15:54:46.590 [27705] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:54:46.593 [27705] <2> fileslist: begin db communication 15:54:46.593 [27705] <2> fileslist: criteria sent to db mgr 15:54:46.698 [27705] <2> process_request: EXIT STATUS 0 15:54:46.698 [27705] <2> bprd: /usr/openv/netbackup/bin/userreq_notify list subs-live-db-01 root 15:54:49.966 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:49567 15:54:49.966 [26299] <2> bprd: socket fd from accept() is 10 15:54:49.967 [26299] <2> listen_loop: request complete 15:54:49.968 [27713] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.49567 TO 127.0.0.1.1556 fd = 10 15:54:49.968 [27713] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:49.969 [27713] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 49567 15:54:49.969 [27713] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:49.969 [27713] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:54:49.969 [27713] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:49.969 [27713] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:54:50.009 [27713] <2> process_VM_request: Processing VM query request, proxy: _NONE_ bpVMutil_cmd: 9 bpVMutil_flags 0 ESXMount: _NONE_ ESXserver: _NONE_ 15:54:50.054 [27713] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:54:50.055 [27713] <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:825) 15:54:50.058 [27713] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:54:50.059 [27713] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:54:50.059 [27713] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:54:50.074 [27713] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:54:50.074 [27713] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:54:50.074 [27713] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:54:50.074 [27713] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:54:50.074 [27713] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:54:50.075 [27713] <2> db_freeEXDB_INFO: ? 15:54:50.076 [27713] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:50.076 [27713] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.33514 TO 10.132.112.22.1556 fd = 7 15:54:50.077 [27713] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:50.078 [27713] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.60779 TO 10.132.112.22.1556 fd = 8 15:54:50.078 [27713] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:54:50.118 [27713] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-27715358438090118261000000000-A18607 15:54:50.198 [27713] <2> bpcr_get_platform_rqst: Server platform length = 17 15:54:50.198 [27713] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:54:50.199 [27713] <2> bpcr_patch_version_rqst: theRest == > < 15:54:50.199 [27713] <4> createVMwareProxyFile: getHostPlatformInfo() returned host: bfbackup, platform: linuxR_x86_2.6.18, version: 750400 15:54:50.199 [27713] <2> process_request: EXIT STATUS 0 15:54:51.190 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:35743 15:54:51.190 [26299] <2> bprd: socket fd from accept() is 10 15:54:51.191 [26299] <2> listen_loop: request complete 15:54:51.192 [27719] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.35743 TO 127.0.0.1.1556 fd = 10 15:54:51.192 [27719] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:51.192 [27719] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 35743 15:54:51.193 [27719] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:51.193 [27719] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:54:51.193 [27719] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:51.193 [27719] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:54:51.233 [27719] <2> process_VM_request: Processing VM query request, proxy: _NONE_ bpVMutil_cmd: 10 bpVMutil_flags 0 ESXMount: _NONE_ ESXserver: _NONE_ 15:54:51.237 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:50246 15:54:51.237 [26299] <2> bprd: socket fd from accept() is 10 15:54:51.238 [26299] <2> listen_loop: request complete 15:54:51.239 [27722] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.50246 TO 127.0.0.1.1556 fd = 10 15:54:51.239 [27722] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:51.239 [27722] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 50246 15:54:51.240 [27722] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:51.240 [27722] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:54:51.240 [27722] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:51.240 [27722] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:54:51.260 [27719] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:54:51.261 [27719] <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:825) 15:54:51.263 [27719] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:54:51.264 [27719] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:54:51.264 [27719] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:54:51.280 [27722] <2> process_VM_request: Processing VM query request, proxy: lonbfbnbumedia1.corp.ad.timeinc.com bpVMutil_cmd: 16 bpVMutil_flags 1 ESXMount: _NONE_ ESXserver: _NONE_ 15:54:51.285 [27722] <2> process_request: EXIT STATUS 0 15:54:51.314 [27719] <2> process_request: EXIT STATUS 0 15:54:52.278 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:53741 15:54:52.278 [26299] <2> bprd: socket fd from accept() is 10 15:54:52.279 [26299] <2> listen_loop: request complete 15:54:52.280 [27727] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.53741 TO 127.0.0.1.1556 fd = 10 15:54:52.280 [27727] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:52.280 [27727] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 53741 15:54:52.281 [27727] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:52.281 [27727] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:54:52.281 [27727] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:52.281 [27727] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:54:52.321 [27727] <2> process_VM_request: Processing VM query request, proxy: lonbfbnbumedia1.corp.ad.timeinc.com bpVMutil_cmd: 11 bpVMutil_flags 0 ESXMount: esxc1host6.ipcmedia.com ESXserver: lonbfbvc2.corp.ad.timeinc.com 15:54:52.321 [27727] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:54:52.321 [27727] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:54:52.321 [27727] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:54:52.321 [27727] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:54:52.321 [27727] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:54:52.321 [27727] <2> db_freeEXDB_INFO: ? 15:54:52.324 [27727] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:52.324 [27727] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.34935 TO 10.132.117.125.1556 fd = 5 15:54:52.326 [27727] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:52.364 [27727] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.38900 TO 10.132.117.125.1556 fd = 6 15:54:52.364 [27727] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:54:52.567 [27727] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51652 15:54:52.667 [27727] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:54:52.845 [27727] <2> process_request: EXIT STATUS 0 15:54:55.577 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:58904 15:54:55.577 [26299] <2> bprd: socket fd from accept() is 10 15:54:55.578 [26299] <2> listen_loop: request complete 15:54:55.578 [27731] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.58904 TO 127.0.0.1.1556 fd = 10 15:54:55.578 [27731] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:55.579 [27731] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 58904 15:54:55.579 [27731] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:55.579 [27731] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:54:55.579 [27731] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:55.579 [27731] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:54:55.619 [27731] <2> process_VM_request: Processing VM query request, proxy: lonbfbnbumedia1.corp.ad.timeinc.com bpVMutil_cmd: 7 bpVMutil_flags 0 ESXMount: _NONE_ ESXserver: lonbfbvc2.corp.ad.timeinc.com 15:54:55.619 [27731] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:54:55.619 [27731] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:54:55.619 [27731] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:54:55.619 [27731] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:54:55.619 [27731] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:54:55.620 [27731] <2> db_freeEXDB_INFO: ? 15:54:55.622 [27731] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:55.622 [27731] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.58607 TO 10.132.117.125.1556 fd = 5 15:54:55.624 [27731] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:54:55.657 [27731] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.34268 TO 10.132.117.125.1556 fd = 6 15:54:55.657 [27731] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:54:55.854 [27731] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51654 15:54:55.953 [27731] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:54:55.986 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51656 15:54:55.987 [26299] <2> bprd: socket fd from accept() is 10 15:54:55.987 [26299] <2> listen_loop: request complete 15:54:55.988 [27732] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51656 TO 10.132.112.22.1556 fd = 10 15:54:55.988 [27732] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:54:55.988 [27732] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51656 15:54:55.989 [27732] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:54:55.989 [27732] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:54:55.989 [27732] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:54:55.989 [27732] <2> process_request: command C_READ_TEXT_FILE (97) received 15:54:55.989 [27732] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:54:55.989 [27732] <2> read_text_file: Using socket 10 15:54:56.016 [27732] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:54:56.017 [27732] <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:825) 15:54:56.019 [27732] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:54:56.020 [27732] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:54:56.020 [27732] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:54:56.051 [27732] <2> read_text_file: ../bprd.c.17635: file_name = 15:54:56.051 [27732] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:54:56.051 [27732] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:54:56.051 [27732] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:54:56.051 [27732] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:54:56.051 [27732] <2> process_request: EXIT STATUS 0 15:55:00.994 [27731] <2> process_request: EXIT STATUS 0 15:55:08.116 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:49253 15:55:08.116 [26299] <2> bprd: socket fd from accept() is 10 15:55:08.117 [26299] <2> listen_loop: request complete 15:55:08.117 [27736] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.49253 TO 127.0.0.1.1556 fd = 10 15:55:08.117 [27736] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:55:08.118 [27736] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 49253 15:55:08.118 [27736] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:55:08.118 [27736] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:55:08.118 [27736] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:55:08.118 [27736] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:55:08.158 [27736] <2> process_VM_request: Processing VM query request, proxy: lonbfbnbumedia1.corp.ad.timeinc.com bpVMutil_cmd: 11 bpVMutil_flags 0 ESXMount: esxc5host1.ipcmedia.com ESXserver: lonbfbvc2.corp.ad.timeinc.com 15:55:08.159 [27736] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:55:08.159 [27736] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:55:08.159 [27736] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:55:08.159 [27736] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:55:08.159 [27736] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:55:08.159 [27736] <2> db_freeEXDB_INFO: ? 15:55:08.162 [27736] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:55:08.162 [27736] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.35834 TO 10.132.117.125.1556 fd = 5 15:55:08.164 [27736] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:55:08.209 [27736] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.38905 TO 10.132.117.125.1556 fd = 6 15:55:08.209 [27736] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:55:08.412 [27736] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51658 15:55:08.502 [27736] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:55:08.532 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51660 15:55:08.532 [26299] <2> bprd: socket fd from accept() is 10 15:55:08.533 [26299] <2> listen_loop: request complete 15:55:08.533 [27737] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51660 TO 10.132.112.22.1556 fd = 10 15:55:08.534 [27737] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:55:08.534 [27737] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51660 15:55:08.535 [27737] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:55:08.535 [27737] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:55:08.535 [27737] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:55:08.535 [27737] <2> process_request: command C_READ_TEXT_FILE (97) received 15:55:08.535 [27737] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:55:08.535 [27737] <2> read_text_file: Using socket 10 15:55:08.561 [27737] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:55:08.562 [27737] <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:825) 15:55:08.565 [27737] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:55:08.565 [27737] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:55:08.565 [27737] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:55:08.588 [27737] <2> read_text_file: ../bprd.c.17635: file_name = 15:55:08.588 [27737] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:55:08.588 [27737] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:55:08.588 [27737] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:55:08.588 [27737] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:55:08.588 [27737] <2> process_request: EXIT STATUS 0 15:55:21.190 [27736] <2> process_request: EXIT STATUS 0 15:55:27.161 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:57620 15:55:27.161 [26299] <2> bprd: socket fd from accept() is 10 15:55:27.162 [26299] <2> listen_loop: request complete 15:55:27.162 [27775] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.57620 TO 127.0.0.1.1556 fd = 10 15:55:27.162 [27775] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:55:27.163 [27775] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 57620 15:55:27.163 [27775] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:55:27.163 [27775] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:55:27.163 [27775] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:55:27.163 [27775] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:55:27.203 [27775] <2> process_VM_request: Processing VM query request, proxy: lonbfbnbumedia1.corp.ad.timeinc.com bpVMutil_cmd: 19 bpVMutil_flags 0 ESXMount: /DMZ ESXserver: lonbfbvc2.corp.ad.timeinc.com 15:55:27.203 [27775] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:55:27.203 [27775] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:55:27.203 [27775] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:55:27.203 [27775] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:55:27.203 [27775] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:55:27.203 [27775] <2> db_freeEXDB_INFO: ? 15:55:27.206 [27775] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:55:27.206 [27775] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.55760 TO 10.132.117.125.1556 fd = 5 15:55:27.208 [27775] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:55:27.253 [27775] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.48496 TO 10.132.117.125.1556 fd = 6 15:55:27.253 [27775] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:55:27.460 [27775] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51662 15:55:27.549 [27775] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:55:27.577 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51664 15:55:27.577 [26299] <2> bprd: socket fd from accept() is 10 15:55:27.578 [26299] <2> listen_loop: request complete 15:55:27.578 [27776] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51664 TO 10.132.112.22.1556 fd = 10 15:55:27.578 [27776] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:55:27.579 [27776] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51664 15:55:27.580 [27776] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:55:27.580 [27776] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:55:27.580 [27776] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:55:27.580 [27776] <2> process_request: command C_READ_TEXT_FILE (97) received 15:55:27.580 [27776] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:55:27.580 [27776] <2> read_text_file: Using socket 10 15:55:27.606 [27776] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:55:27.607 [27776] <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:825) 15:55:27.610 [27776] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:55:27.610 [27776] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:55:27.610 [27776] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:55:27.633 [27776] <2> read_text_file: ../bprd.c.17635: file_name = 15:55:27.633 [27776] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:55:27.633 [27776] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:55:27.633 [27776] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:55:27.633 [27776] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:55:27.633 [27776] <2> process_request: EXIT STATUS 0 15:55:28.344 [27775] <2> process_request: EXIT STATUS 0 15:55:37.425 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:38147 15:55:37.425 [26299] <2> bprd: socket fd from accept() is 10 15:55:37.426 [26299] <2> listen_loop: request complete 15:55:37.426 [27780] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.38147 TO 127.0.0.1.1556 fd = 10 15:55:37.427 [27780] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:55:37.427 [27780] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 38147 15:55:37.427 [27780] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:55:37.427 [27780] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:55:37.427 [27780] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:55:37.428 [27780] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:55:37.467 [27780] <2> process_VM_request: Processing VM query request, proxy: lonbfbnbumedia1.corp.ad.timeinc.com bpVMutil_cmd: 12 bpVMutil_flags 0 ESXMount: esxc5host1.ipcmedia.com ESXserver: lonbfbvc2.corp.ad.timeinc.com 15:55:37.467 [27780] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:55:37.467 [27780] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:55:37.467 [27780] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:55:37.468 [27780] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:55:37.468 [27780] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:55:37.468 [27780] <2> db_freeEXDB_INFO: ? 15:55:37.470 [27780] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:55:37.471 [27780] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.52643 TO 10.132.117.125.1556 fd = 5 15:55:37.472 [27780] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:55:37.510 [27780] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.34865 TO 10.132.117.125.1556 fd = 6 15:55:37.510 [27780] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:55:37.725 [27780] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51665 15:55:37.824 [27780] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:55:37.929 [27780] <2> process_request: EXIT STATUS 0 15:55:38.890 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:60804 15:55:38.890 [26299] <2> bprd: socket fd from accept() is 10 15:55:38.890 [26299] <2> listen_loop: request complete 15:55:38.891 [27784] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.60804 TO 127.0.0.1.1556 fd = 10 15:55:38.891 [27784] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:55:38.892 [27784] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 60804 15:55:38.892 [27784] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:55:38.892 [27784] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:55:38.892 [27784] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:55:38.892 [27784] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:55:38.932 [27784] <2> process_VM_request: Processing VM query request, proxy: lonbfbnbumedia1.corp.ad.timeinc.com bpVMutil_cmd: 22 bpVMutil_flags 0 ESXMount: esxc5host1.ipcmedia.com ESXserver: lonbfbvc2.corp.ad.timeinc.com 15:55:38.932 [27784] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:55:38.932 [27784] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:55:38.933 [27784] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:55:38.933 [27784] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:55:38.933 [27784] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:55:38.933 [27784] <2> db_freeEXDB_INFO: ? 15:55:38.935 [27784] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:55:38.935 [27784] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.60805 TO 10.132.117.125.1556 fd = 5 15:55:38.937 [27784] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:55:38.974 [27784] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.46234 TO 10.132.117.125.1556 fd = 6 15:55:38.974 [27784] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:55:39.175 [27784] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51667 15:55:39.272 [27784] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:55:39.330 [27784] <2> process_request: EXIT STATUS 0 15:55:57.613 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:49492 15:55:57.613 [26299] <2> bprd: socket fd from accept() is 10 15:55:57.614 [26299] <2> listen_loop: request complete 15:55:57.614 [27789] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.49492 TO 127.0.0.1.1556 fd = 10 15:55:57.614 [27789] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:55:57.615 [27789] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 49492 15:55:57.615 [27789] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:55:57.615 [27789] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:55:57.615 [27789] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:55:57.615 [27789] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:55:57.656 [27789] <2> process_VM_request: Processing VM query request, proxy: lonbfbnbumedia1.corp.ad.timeinc.com bpVMutil_cmd: 13 bpVMutil_flags 0 ESXMount: subs-live-db-01_new ESXserver: lonbfbvc2.corp.ad.timeinc.com 15:55:57.656 [27789] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:55:57.656 [27789] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:55:57.656 [27789] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:55:57.656 [27789] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:55:57.656 [27789] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:55:57.656 [27789] <2> db_freeEXDB_INFO: ? 15:55:57.659 [27789] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:55:57.659 [27789] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.40902 TO 10.132.117.125.1556 fd = 5 15:55:57.661 [27789] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:55:57.705 [27789] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.43239 TO 10.132.117.125.1556 fd = 6 15:55:57.706 [27789] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:55:57.911 [27789] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51669 15:55:58.000 [27789] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:55:58.028 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51671 15:55:58.028 [26299] <2> bprd: socket fd from accept() is 10 15:55:58.029 [26299] <2> listen_loop: request complete 15:55:58.030 [27790] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51671 TO 10.132.112.22.1556 fd = 10 15:55:58.030 [27790] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:55:58.030 [27790] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51671 15:55:58.031 [27790] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:55:58.031 [27790] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:55:58.031 [27790] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:55:58.031 [27790] <2> process_request: command C_READ_TEXT_FILE (97) received 15:55:58.031 [27790] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:55:58.031 [27790] <2> read_text_file: Using socket 10 15:55:58.057 [27790] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:55:58.058 [27790] <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:825) 15:55:58.061 [27790] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:55:58.061 [27790] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:55:58.062 [27790] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:55:58.084 [27790] <2> read_text_file: ../bprd.c.17635: file_name = 15:55:58.084 [27790] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:55:58.084 [27790] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:55:58.084 [27790] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:55:58.084 [27790] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:55:58.084 [27790] <2> process_request: EXIT STATUS 0 15:56:03.319 [27789] <16> bpcr_forward_VM_proxy_rqst: bpcr_forward_VM_proxy_rqst failing with error: no entity was found (227) 15:56:03.319 [27789] <16> forward_bpVMutil_request: bpcr_forward_VM_proxy_rqst failed: no entity was found (227) 15:56:03.319 [27789] <2> put_short: (10) network write() error: Bad file descriptor (9); socket = 5 15:56:03.319 [27789] <2> bpcr_disconnect_rqst: bpcr protocol error - couldn't send request type 15:56:03.319 [27789] <16> forward_bpVMutil_request: bpcr_forward_VM_proxy_rqst failed: no entity was found (227)bpcr_disconnect_rqst failed 15:56:03.320 [27789] <2> process_request: EXIT STATUS 227 15:56:11.845 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:52465 15:56:11.845 [26299] <2> bprd: socket fd from accept() is 10 15:56:11.846 [26299] <2> listen_loop: request complete 15:56:11.846 [27793] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.52465 TO 127.0.0.1.1556 fd = 10 15:56:11.846 [27793] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:56:11.847 [27793] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 52465 15:56:11.847 [27793] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:56:11.847 [27793] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:56:11.847 [27793] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:56:11.848 [27793] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:56:11.887 [27793] <2> process_VM_request: Processing VM query request, proxy: lonbfbnbumedia1.corp.ad.timeinc.com bpVMutil_cmd: 17 bpVMutil_flags 0 ESXMount: esxc5host1.ipcmedia.com ESXserver: lonbfbvc2.corp.ad.timeinc.com 15:56:11.887 [27793] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:56:11.887 [27793] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:56:11.887 [27793] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:56:11.887 [27793] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:56:11.887 [27793] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:56:11.888 [27793] <2> db_freeEXDB_INFO: ? 15:56:11.890 [27793] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:11.890 [27793] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.40142 TO 10.132.117.125.1556 fd = 5 15:56:11.892 [27793] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:11.936 [27793] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.39767 TO 10.132.117.125.1556 fd = 6 15:56:11.936 [27793] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:56:12.138 [27793] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51673 15:56:12.227 [27793] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:56:12.257 [27793] <2> process_request: EXIT STATUS 0 15:56:20.519 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:35301 15:56:20.519 [26299] <2> bprd: socket fd from accept() is 10 15:56:20.520 [26299] <2> listen_loop: request complete 15:56:20.521 [27830] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.35301 TO 127.0.0.1.1556 fd = 10 15:56:20.521 [27830] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:56:20.521 [27830] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 35301 15:56:20.522 [27830] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:56:20.522 [27830] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 136 0x88 15:56:20.522 [27830] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:56:20.522 [27830] <2> process_request: command C_BPRD_VM_PROXY_QUERY (136) received 15:56:20.561 [27830] <2> process_VM_request: Processing VM query request, proxy: lonbfbnbumedia1.corp.ad.timeinc.com bpVMutil_cmd: 4 bpVMutil_flags 0 ESXMount: /usr/openv/netbackup/logs/user_ops/klamb1271/logs/jbp-27001358438179989763000000036-fdNjcu ESXserver: lonbfbvc2.corp.ad.timeinc.com 15:56:20.562 [27830] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:56:20.562 [27830] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:56:20.562 [27830] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:56:20.562 [27830] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:56:20.562 [27830] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:56:20.562 [27830] <2> db_freeEXDB_INFO: ? 15:56:20.564 [27830] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:20.565 [27830] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.48736 TO 10.132.117.125.1556 fd = 5 15:56:20.566 [27830] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:20.613 [27830] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.42138 TO 10.132.117.125.1556 fd = 6 15:56:20.613 [27830] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:56:20.821 [27830] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51675 15:56:20.911 [27830] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:20.911 [27830] <2> logconnections: BPCD CONNECT FROM 127.0.0.1.40372 TO 127.0.0.1.1556 fd = 7 15:56:20.912 [27830] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:20.914 [27830] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 127.0.0.1.43992 TO 127.0.0.1.1556 fd = 8 15:56:20.914 [27830] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:56:20.954 [27830] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-27832358438180953825000000000-6uDtY6 15:56:21.034 [27830] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:56:21.034 [27830] <2> read_validate_file: start reading 15:56:21.035 [27830] <2> bpcr_read_rqst: completed send of file number 5 15:56:21.035 [27830] <2> bpcr_read_rqst: completed send of transfer_count = 4096 15:56:21.035 [27830] <2> bpcr_read_rqst: Before readclientstatus on socket 8 15:56:21.035 [27830] <2> bpcr_read_rqst: Num bytes to be sent by client = 1195 15:56:21.035 [27830] <2> bpcr_VM_validate_requests: open validate_file: /tmp/VMreq-27830358438180561837000000003-nxZkTW 15:56:21.035 [27830] <2> bpcr_VM_validate_requests: Set 'END' of input 15:56:21.067 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51677 15:56:21.067 [26299] <2> bprd: socket fd from accept() is 10 15:56:21.067 [26299] <2> listen_loop: request complete 15:56:21.068 [27833] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51677 TO 10.132.112.22.1556 fd = 10 15:56:21.068 [27833] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:56:21.069 [27833] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51677 15:56:21.070 [27833] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:56:21.070 [27833] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:56:21.070 [27833] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:56:21.070 [27833] <2> process_request: command C_READ_TEXT_FILE (97) received 15:56:21.070 [27833] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:56:21.070 [27833] <2> read_text_file: Using socket 10 15:56:21.096 [27833] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:56:21.097 [27833] <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:825) 15:56:21.099 [27833] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:56:21.100 [27833] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:56:21.100 [27833] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:56:21.123 [27833] <2> read_text_file: ../bprd.c.17635: file_name = 15:56:21.123 [27833] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:56:21.123 [27833] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:56:21.123 [27833] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:56:21.123 [27833] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:56:21.123 [27833] <2> process_request: EXIT STATUS 0 15:56:36.231 [27830] <2> process_request: EXIT STATUS 0 15:56:53.409 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 127.0.0.1:43268 15:56:53.409 [26299] <2> bprd: socket fd from accept() is 10 15:56:53.410 [26299] <2> listen_loop: request complete 15:56:53.410 [27839] <2> logconnections: BPRD ACCEPT FROM 127.0.0.1.43268 TO 127.0.0.1.1556 fd = 10 15:56:53.410 [27839] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:56:53.411 [27839] <2> connected_peer: Connection from host bfbackup.ipcmedia.com, 127.0.0.1, on non-reserved port 43268 15:56:53.411 [27839] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:56:53.411 [27839] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 138 0x8a 15:56:53.411 [27839] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:56:53.411 [27839] <2> process_request: command C_BPRESTORE_7_0 (138) received 15:56:53.411 [27839] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 84 15:56:53.412 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:53.412 [27839] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.37941 TO 127.0.0.1.1556 fd = 5 15:56:53.412 [27839] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:56:53.495 [27839] <2> db_end: Need to collect reply 15:56:53.496 [27839] <2> process_request: pre vm_fields 0 0 0 0 0 0 0 0 0 2560 lonbfbvc2.corp.ad.timeinc.com;lonbfbnbumedia1.corp.ad.timeinc.com;vmtm=san:hotadd:nbd:nbdssl -1 0 0 0 0 NONE NONE NONE 0 0 0 15:56:53.496 [27839] <2> process_request: vm_fields 2560 lonbfbvc2.corp.ad.timeinc.com;lonbfbnbumedia1.corp.ad.timeinc.com;vmtm=san:hotadd:nbd:nbdssl -1 0 0 0 0 NONE NONE NONE 0 0 0 15:56:53.496 [27839] <2> process_request: setting destination client to lonbfbnbumedia1.corp.ad.timeinc.com 15:56:53.496 [27839] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:56:53.496 [27839] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:56:53.496 [27839] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:56:53.496 [27839] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:56:53.496 [27839] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:56:53.497 [27839] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1583] in failed file cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:56:53.497 [27839] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:56:53.497 [27839] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:56:53.497 [27839] <2> db_freeEXDB_INFO: ? 15:56:53.497 [27839] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:56:53.497 [27839] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:56:53.497 [27839] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:56:53.497 [27839] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:56:53.497 [27839] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:56:53.497 [27839] <8> gethostent_force: [vnet_addrinfo.c:2555] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME=subs-live-db-01 ADJUSTEDNAME=subs-live-db-01 15:56:53.497 [27839] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:56:53.497 [27839] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:56:53.497 [27839] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:56:53.497 [27839] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1530] in failed cache ERR=-2 NAME=subs-live-db-01 SVC=NULL 15:56:53.497 [27839] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 15:56:53.497 [27839] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2832] vnet_cached_getaddrinfo() failed STAT=6 RV=-2 NAME1=subs-live-db-01 15:56:53.502 [27839] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:387] h_errno 1 0x1 15:56:53.502 [27839] <8> vnet_legacy_cached_gethostbyname: [vnet_hosts.c:388] vnet_ipv4_gethostbyname failed subs-live-db-01 15:56:53.502 [27839] <2> vnet_legacy_cached_gethostbyname: vnet_hosts.c.402: 46941778245152: Function failed: 6 0x00000006 15:56:53.509 [27839] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:56:53.509 [27839] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:56:53.509 [27839] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:56:53.509 [27839] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:56:53.509 [27839] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:56:53.509 [27839] <2> get_type_of_client_list_restore: list and restore not specified for bfbackup 15:56:53.511 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:53.511 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.53976 TO 10.132.112.22.1556 fd = 5 15:56:53.512 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:53.513 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.36591 TO 10.132.112.22.1556 fd = 6 15:56:53.514 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:56:53.554 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-27842358438213553821000000000-4XVwNF 15:56:53.637 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:53.637 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.53072 TO 10.132.112.22.1556 fd = 5 15:56:53.638 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:53.639 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.48799 TO 10.132.112.22.1556 fd = 6 15:56:53.639 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:56:53.679 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-27844358438213678764000000000-MkBkZO 15:56:53.761 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:53.761 [27839] <2> job_connect: SO_KEEPALIVE set on socket 5 for client bfbackup.ipcmedia.com 15:56:53.761 [27839] <2> logconnections: BPJOBD CONNECT FROM 127.0.0.1.56997 TO 127.0.0.1.1556 fd = 5 15:56:53.761 [27839] <2> job_authenticate_connection: ignoring VxSS authentication check for now... 15:56:53.762 [27839] <2> job_connect: Connected to the host bfbackup.ipcmedia.com contype 53 jobid <88585> socket <5> 15:56:53.762 [27839] <2> job_connect: Connected on port 56997 15:56:53.762 [27839] <2> get_type_of_client_free_browse: Free browse allowed for bfbackup 15:56:53.762 [27839] <2> db_freeEXDB_INFO: ? 15:56:53.762 [27839] <2> adjust_clientname: adjust_clientname(bfbackup.ipcmedia.com, bfbackup.ipcmedia.com, bfbackup, bfbackup, 1) 15:56:53.762 [27839] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 84 15:56:53.763 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:53.763 [27839] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.53287 TO 127.0.0.1.1556 fd = 6 15:56:53.763 [27839] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:56:53.843 [27839] <2> db_end: Need to collect reply 15:56:53.843 [27839] <2> adjust_clientname: adjusted clientname = bfbackup.ipcmedia.com, client_bp_conf_name = bfbackup 15:56:53.845 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:53.845 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.33528 TO 10.132.117.125.1556 fd = 6 15:56:53.847 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:53.888 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.59874 TO 10.132.117.125.1556 fd = 7 15:56:53.888 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:56:54.086 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51681 15:56:54.175 [27839] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:56:54.175 [27839] <2> process_request: requesting_clnt_hostname = ccname bfbackup.ipcmedia.com 15:56:54.175 [27839] <2> restorefiles: mpx_restore_possible = 1 15:56:54.175 [27839] <2> restorefiles: browse_client = subs-live-db-01 15:56:54.175 [27839] <2> restorefiles: requesting_client = bfbackup 15:56:54.175 [27839] <2> restorefiles: destination_client = lonbfbnbumedia1.corp.ad.timeinc.com 15:56:54.175 [27839] <2> restorefiles: requesting_client_hostname = bfbackup.ipcmedia.com 15:56:54.175 [27839] <2> restorefiles: destination_client_hostname = lonbfbnbumedia1.corp.ad.timeinc.com 15:56:54.175 [27839] <2> restorefiles: requesting_user = root 15:56:54.175 [27839] <2> restorefiles: requesting_group = root 15:56:54.175 [27839] <2> restorefiles: progress_file = /usr/openv/netbackup/logs/user_ops/klamb1271/logs/jbp-27001358438212444470000000039-gTF0L0.log 15:56:54.175 [27839] <2> restorefiles: rename_file = /usr/openv/netbackup/logs/user_ops/klamb1271/logs/jbp-27001358438212444470000000039-gTF0L0.chg 15:56:54.175 [27839] <2> restorefiles: policy = NONE 15:56:54.175 [27839] <2> restorefiles: keyword = 15:56:54.175 [27839] <2> restorefiles: sched_type = 12 15:56:54.175 [27839] <2> restorefiles: keep = 1 15:56:54.175 [27839] <2> restorefiles: restore_retries = 0 15:56:54.175 [27839] <2> restorefiles: raw_restore = 0 15:56:54.175 [27839] <2> restorefiles: max_apollo_restore_arg_chars = 9000 15:56:54.175 [27839] <2> restorefiles: rename_linktos = 0 15:56:54.175 [27839] <2> restorefiles: client_type = 40 15:56:54.175 [27839] <2> restorefiles: rest_start_time = 1358438213 15:56:54.175 [27839] <2> restorefiles: cmd_start_date = 1358266948 15:56:54.175 [27839] <2> restorefiles: cmd_end_date = 1358266948 15:56:54.175 [27839] <2> restorefiles: unixtime = 1358438213 15:56:54.175 [27839] <2> restorefiles: timetype = 4 15:56:54.175 [27839] <2> restorefiles: user_interface = 0 15:56:54.175 [27839] <2> restorefiles: monitor_restore_job = 1 15:56:54.175 [27839] <2> restorefiles: restore_jobidstr = 88585 15:56:54.175 [27839] <2> restorefiles: client_locales_available = 1 15:56:54.175 [27839] <2> restorefiles: clnt_lc_messages = en_UK 15:56:54.175 [27839] <2> restorefiles: clnt_lc_time = en_UK 15:56:54.175 [27839] <2> restorefiles: clnt_lc_ctype = en_UK 15:56:54.175 [27839] <2> restorefiles: clnt_lc_collate = en_UK 15:56:54.175 [27839] <2> restorefiles: clnt_lc_numeric = en_UK 15:56:54.175 [27839] <2> restorefiles: cverbose = 0 15:56:54.175 [27839] <2> restorefiles: true_image = 0 15:56:54.175 [27839] <2> restorefiles: restorable_format = 3 15:56:54.175 [27839] <2> restorefiles: bpcd_connect_opts = 16974338 15:56:54.175 [27839] <2> restorefiles: filesystem_only = 0 15:56:54.175 [27839] <2> restorefiles: clntuid = 0 15:56:54.176 [27839] <2> restorefiles: clntgid = 0 15:56:54.176 [27839] <2> restorefiles: acknowledge_status = 0 15:56:54.176 [27839] <2> restorefiles: keep_progress_log_open = 1 15:56:54.176 [27839] <2> restorefiles: clnt_flops = 33554432 15:56:54.176 [27839] <2> restorefiles: proxy_copy = 0 15:56:54.176 [27839] <2> restorefiles: clnt_v2_1_or_later = 1 15:56:54.176 [27839] <2> restorefiles: commit = 0 15:56:54.176 [27839] <2> restorefiles: dirByteCount = 0 15:56:54.176 [27839] <2> restorefiles: tempDirectory = NULL 15:56:54.176 [27839] <2> restorefiles: mountDatabase = 0 15:56:54.176 [27839] <2> restorefiles: bePathByteCount = 0 15:56:54.176 [27839] <2> restorefiles: beRedirectionPath = NULL 15:56:54.176 [27839] <2> restorefiles: restore_sync_command = 0 15:56:54.176 [27839] <2> restorefiles: use_alt_buf = 0 15:56:54.176 [27839] <2> restorefiles: generate_english_logs = 0 15:56:54.176 [27839] <2> restorefiles: dont_restore_security = 0 15:56:54.176 [27839] <2> restorefiles: redirWebSrvrByteCount = 0 15:56:54.176 [27839] <2> restorefiles: redirWebSrvr = NULL 15:56:54.176 [27839] <2> restorefiles: granular_restore = 0 15:56:54.176 [27839] <2> restorefiles: alt_rest_copy_num = 0 15:56:54.176 [27839] <2> restorefiles: vm_opts = 2560 15:56:54.176 [27839] <2> restorefiles: vm_server_string = lonbfbvc2.corp.ad.timeinc.com;lonbfbnbumedia1.corp.ad.timeinc.com;vmtm=san:hotadd:nbd:nbdssl 15:56:54.176 [27839] <2> restorefiles: priority = -1 15:56:54.176 [27839] <2> restorefiles: disk_media_server = NONE 15:56:54.176 [27839] <2> restorefiles: spsrestoreoptions = 0 15:56:54.176 [27839] <2> restorefiles: vhd_file_name = NONE 15:56:54.176 [27839] <2> restorefiles: vhd_type = 0 15:56:54.176 [27839] <2> restorefiles: vhd_disk_size = 0 15:56:54.176 [27839] <2> restorefiles: vhd_delete_on_failure = 0 15:56:54.176 [27839] <2> restorefiles: vm_db_uri_str_len = 0 15:56:54.176 [27839] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 76 15:56:54.176 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:54.177 [27839] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.37540 TO 127.0.0.1.1556 fd = 6 15:56:54.177 [27839] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:56:54.284 [27839] <2> find_files: file_path = / 15:56:54.284 [27839] <2> find_files: start_date = 1358266948 15:56:54.284 [27839] <2> find_files: end_date = 1358266948 15:56:54.284 [27839] <2> find_files: allow_virtual_browse = 0 15:56:54.284 [27839] <2> find_files: include_no_display = 0 15:56:54.284 [27839] <2> find_files: granular_restore = 0 15:56:54.284 [27839] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:56:54.285 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:54.285 [27839] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.42683 TO 127.0.0.1.1556 fd = 6 15:56:54.285 [27839] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:56:54.838 [27839] <2> find_files: Got CONTINUE from bpdbm 15:56:54.838 [27839] <2> db_end: Need to collect reply 15:56:54.842 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:54.842 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.51656 TO 10.132.112.22.1556 fd = 6 15:56:54.843 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:54.844 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.36113 TO 10.132.112.22.1556 fd = 7 15:56:54.844 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:56:54.885 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-27849358438214884905000000000-NNsV68 15:56:54.965 [27839] <2> restorefiles: backup selected for vmware blib restore: subs-live-db-01_1358266948 15:56:54.991 [27839] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:56:54.992 [27839] <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:825) 15:56:54.995 [27839] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:56:54.995 [27839] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:56:54.995 [27839] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:56:55.007 [27839] <2> get_media_server_version: NBU version of bfbackup is 750400 15:56:55.070 [27839] <2> restorefiles: User Identity (Name/Domain/Type): root/bfbackup.ipcmedia.com/7. Restore IMG id list: subs-live-db-01_1358266948 15:56:55.172 [27839] <2> restorefiles: Restoring from image = subs-live-db-01_1358266948 (browse_client = subs-live-db-01, requesting_client = bfbackup, user = root) 15:56:55.172 [27839] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:56:55.173 [27839] <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:825) 15:56:55.173 [27839] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:56:55.173 [27839] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:56:55.173 [27839] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:56:55.182 [27839] <2> get_media_server_version: NBU version of bfbackup is 750400 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: list of FILE_LIST_RECs: 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: 2 files received 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: client_type=40 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: sched_type=0 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: mud=180 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: backup_time=1358266948 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: sp_log=/usr/openv/netbackup/logs/user_ops/klamb1271/logs/jbp-27001358438212444470000000039-gTF0L0.log 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: server=bfbackup 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: backup_id=subs-live-db-01_1358266948 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: class=Digital-VM-Copies 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: sp_ruser=root 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: sp_rgroup=root 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: sp_browse_client=subs-live-db-01 15:56:55.183 [27839] <4> bprd.sfr: dumpargs: sp_requesting_client=bfbackup 15:56:55.183 [27839] <4> bprd.sfr: sfr: using tmproot=/usr/openv/netbackup/SFR_TMPDIR 15:56:55.183 [27839] <4> bprd.sfr: sfr: using vm_opts=2560(a00) 15:56:55.184 [27839] <4> bprd.sfr: sfr: received rename_file=/usr/openv/netbackup/logs/user_ops/klamb1271/logs/jbp-27001358438212444470000000039-gTF0L0.chg 15:56:55.184 [27839] <4> bprd.sfr: sfr: changed rename_file=/usr/openv/netbackup/logs/user_ops/klamb1271/logs/jbp-27001358438212444470000000039-gTF0L0.chg 15:56:55.184 [27839] <4> bprd.sfr: sfr: using rename_file=/usr/openv/netbackup/SFR_TMPDIR/RENAME.27839 15:56:55.184 [27839] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:56:55.184 [27839] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:56:55.184 [27839] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:56:55.184 [27839] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:56:55.184 [27839] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:56:55.184 [27839] <2> db_freeEXDB_INFO: ? 15:56:55.184 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:55.185 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.37388 TO 10.132.112.22.1556 fd = 13 15:56:55.185 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:55.187 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.43604 TO 10.132.112.22.1556 fd = 14 15:56:55.187 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:56:55.227 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-27851358438215226807000000000-jxCdPl 15:56:55.307 [27839] <2> bpcr_read_rqst: completed send of file number 5 15:56:55.307 [27839] <2> bpcr_read_rqst: completed send of transfer_count = 16384 15:56:55.307 [27839] <2> bpcr_read_rqst: Before readclientstatus on socket 14 15:56:55.307 [27839] <2> bpcr_read_rqst: Num bytes to be sent by client = 470 15:56:55.307 [27839] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:56:55.308 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:55.308 [27839] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.60592 TO 127.0.0.1.1556 fd = 13 15:56:55.308 [27839] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:56:55.454 [27839] <2> db_end: Need to collect reply 15:56:55.457 [27839] <4> bprd.sfr: sfr: get_fullvm_params returned last disk 1 and mask 3 15:56:55.457 [27839] <4> bprd.sfr: sfr: opened rename file /usr/openv/netbackup/SFR_TMPDIR/RENAME.27839 15:56:55.457 [27839] <4> bprd.sfr: sfr: using rename_line=change /esxc1_e1msa_13/subs-live-db-01/subs-live-db-01_1.vmdk to /EVA2_DMZ_SUBS1_500GB/subs-live-db-01/subs-live-db-01_1.vmdk 15:56:55.457 [27839] <4> bprd.sfr: sfr: using rename_line=change /esxc1_e1msa_13/subs-live-db-01/subs-live-db-01.vmdk to /EVA2_DMZ_SUBS1_500GB/subs-live-db-01/subs-live-db-01.vmdk 15:56:55.457 [27839] <4> bprd.sfr: sfr: using rename_line=change vmname to subs-live-db-01_new 15:56:55.457 [27839] <4> bprd.sfr: sfr: using rename_line=change esxhost to esxc5host1.ipcmedia.com 15:56:55.457 [27839] <4> bprd.sfr: sfr: using rename_line=change datacenter to /DMZ 15:56:55.457 [27839] <4> bprd.sfr: sfr: using rename_line=change folder to /DMZ/vm 15:56:55.457 [27839] <4> bprd.sfr: sfr: using rename_line=change resourcepool to /DMZ/host/PCI-DMZ/Resources 15:56:55.457 [27839] <4> bprd.sfr: sfr: using rename_line=change datastore to EVA2_DMZ_SUBS1_500GB 15:56:55.457 [27839] <4> bprd.sfr: sfr: Final rename_string = vmname=subs-live-db-01_new;esxhost=esxc5host1.ipcmedia.com;datacenter=/DMZ;datastore=EVA2_DMZ_SUBS1_500GB;resourcepool=/DMZ/host/PCI-DMZ/Resources;folder=/DMZ/vm;diskdatastores=EVA2_DMZ_SUBS1_500GB/EVA2_DMZ_SUBS1_500GB 15:56:55.457 [27839] <4> bprd.sfr: sfr: Final rename_string = vmname=subs-live-db-01_new;esxhost=esxc5host1.ipcmedia.com;datacenter=/DMZ;datastore=EVA2_DMZ_SUBS1_500GB;resourcepool=/DMZ/host/PCI-DMZ/Resources;folder=/DMZ/vm;diskdatastores=EVA2_DMZ_SUBS1_500GB/EVA2_DMZ_SUBS1_500GB 15:56:55.457 [27839] <4> bprd.sfr: sfr: using vm_server_string=lonbfbvc2.corp.ad.timeinc.com;lonbfbnbumedia1.corp.ad.timeinc.com;vmtm=san:hotadd:nbd:nbdssl 15:56:55.457 [27839] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:56:55.457 [27839] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:56:55.457 [27839] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:56:55.457 [27839] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:56:55.457 [27839] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:56:55.457 [27839] <2> db_freeEXDB_INFO: ? 15:56:55.459 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:55.459 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.52094 TO 10.132.117.125.1556 fd = 13 15:56:55.461 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:55.505 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.55892 TO 10.132.117.125.1556 fd = 14 15:56:55.505 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:56:55.709 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51683 15:56:55.798 [27839] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:56:55.798 [27839] <4> bprd.sfr: sfr: final fullvm parameters: server lonbfbvc2.corp.ad.timeinc.com proxy lonbfbnbumedia1.corp.ad.timeinc.com mntpt N/A transport mode san-hotadd-nbd-nbdssl 15:56:55.798 [27839] <4> bprd.sfr: sfr: esxhost esxc1host6.ipcmedia.com vmclient subs-live-db-01 vmdisplayname subs-live-db-01 15:56:55.798 [27839] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:56:55.799 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:55.799 [27839] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.52677 TO 127.0.0.1.1556 fd = 15 15:56:55.799 [27839] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:56:55.942 [27839] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:56:55.943 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:55.943 [27839] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.47746 TO 127.0.0.1.1556 fd = 15 15:56:55.943 [27839] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:56:56.489 [27839] <2> db_end: Need to collect reply 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: partition 1: ri_file_num=44909 ri_file_name=/ 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: partition 2: ri_file_num=89850 ri_file_name=/boot/ 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: partition 3: ri_file_num=136091 ri_file_name=/var/ 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: partition 4: ri_file_num=182363 ri_file_name=/\\.\VMFILE0 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: partition 5: ri_file_num=228636 ri_file_name=/\\.\VMFILE1 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: ending status = 221 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: ri_datarecno= 89841 ri_file_name=/ 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: ri_datarecno= 89944 ri_file_name=/boot/ 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: ri_datarecno= 92538 ri_file_name=/var/ 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: ri_datarecno= 92604 ri_file_name=/\\.\VMFILE0 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: file_based_image set to 1 15:56:56.492 [27839] <4> bprd.sfr: get_rplist: matched ri_datarecno= 92604 ri_file_name = /\\.\VMFILE0 15:56:56.492 [27839] <4> bprd.sfr: sfr: restoring files in partition / 15:56:56.492 [27839] <4> bprd.sfr: sfr: no files to restore in partition / 15:56:56.492 [27839] <4> bprd.sfr: sfr: restoring files in partition /boot/ 15:56:56.492 [27839] <4> bprd.sfr: sfr: no files to restore in partition /boot/ 15:56:56.492 [27839] <4> bprd.sfr: sfr: restoring files in partition /var/ 15:56:56.492 [27839] <4> bprd.sfr: sfr: no files to restore in partition /var/ 15:56:56.492 [27839] <4> bprd.sfr: sfr: restoring files in partition /\\.\VMFILE0 15:56:56.493 [27839] <4> bprd.sfr: get_bxmap_params: path=/\\.\VMFILE1_BITMAP 15:56:56.493 [27839] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:56:56.493 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:56.493 [27839] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.40102 TO 127.0.0.1.1556 fd = 15 15:56:56.493 [27839] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:56:56.597 [27839] <4> bprd.sfr: get_bxmap_params: no pseudo record of type _BITMAP 15:56:56.597 [27839] <2> db_end: Need to collect reply 15:56:56.600 [27839] <2> db_end: no DONE from db_getreply(): no entity was found 15:56:56.600 [27839] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:56:56.601 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:56.601 [27839] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.48510 TO 127.0.0.1.1556 fd = 15 15:56:56.601 [27839] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:56:56.744 [27839] <4> bprd.sfr: get_fsmap_params: non-Auspex image: filenum=46268 recno=92539 size=62 15:56:56.744 [27839] <2> db_end: Need to collect reply 15:56:56.747 [27839] <4> bprd.sfr: sfr: partial: 0 15:56:56.747 [27839] <4> bprd.sfr: sort_blockmap: rpspec->rp_nfiles = 2 15:56:56.747 [27839] <4> bprd.sfr: sort_blockmap: rpspec->rp_fsmapfilnum = 46268 15:56:56.747 [27839] <4> bprd.sfr: sort_blockmap: rpspec->rp_fsmaprecno = 92539 15:56:56.747 [27839] <4> bprd.sfr: sort_blockmap: rpspec->rp_fsmapsiz = 62 15:56:56.747 [27839] <4> bprd.sfr: start_bptm_reader: calling bptm_get 15:56:56.747 [27839] <4> bprd.sfr: bptm_get: ri_file_name = /\\.\VMFILE0 ri_file_num=46270 ri_datarecno=92604 15:56:56.747 [27839] <4> bprd.sfr: bptm_get: rp_nfiles = 2 recno = 92539 rp_fsmapfilnum = 46268 15:56:56.747 [27839] <4> bprd.sfr: bptm_get: rp_fsmapsiz = 62 15:56:56.748 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:56.748 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.33184 TO 10.132.112.22.1556 fd = 15 15:56:56.749 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:56:56.750 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.46264 TO 10.132.112.22.1556 fd = 16 15:56:56.750 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:56:56.790 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-27861358438216789938000000000-Lkh2b6 15:56:56.869 [27839] <4> bprd.sfr: start_bptm_reader: bptmfd = 15 15:56:56.869 [27839] <4> bprd.sfr: start_bptm_reader: statfd = 16 15:56:56.869 [27839] <4> bprd.sfr: open_sort_blockmap_files: bmetag file = /usr/openv/netbackup/SFR_TMPDIR/bmetag.27839 15:56:56.870 [27839] <4> bprd.sfr: open_sort_blockmap_files: bmetagfd = 17 15:56:56.870 [27839] <4> bprd.sfr: open_sort_blockmap_files: filhdr file = /usr/openv/netbackup/SFR_TMPDIR/filhdr.27839 15:56:56.870 [27839] <4> bprd.sfr: open_sort_blockmap_files: filhdrfd = 18 15:56:56.870 [27839] <4> bprd.sfr: sort_blockmap: bmetagfd = 17 filhdrfd = 18 15:56:56.870 [27839] <4> bprd.sfr: sort_blockmap: bptmfd = 15 statfd = 16 15:56:56.870 [27839] <4> bprd.sfr: sort_blockmap: sending mm_msg.x_msg = XFERBLOCK 92539 62 15:56:56.870 [27839] <4> bprd.sfr: find_imghdr: currec = 92538 15:57:05.890 [27839] <4> bprd.sfr: find_imghdr: imghdr->ih_magic = 0x47f923e4 15:57:05.890 [27839] <4> bprd.sfr: find_imghdr: vix_file_based set to 1 15:57:05.890 [27839] <4> bprd.sfr: find_imghdr: change datarecno 89841 to 2665738 15:57:05.890 [27839] <4> bprd.sfr: find_imghdr: change datarecno 89944 to 2665738 15:57:05.890 [27839] <4> bprd.sfr: find_imghdr: change datarecno 92538 to 2665738 15:57:05.890 [27839] <4> bprd.sfr: find_imghdr: change datarecno 92604 to 2665738 15:57:05.890 [27839] <4> bprd.sfr: find_imghdr: change datarecno 2665738 to 2665738 15:57:05.890 [27839] <4> bprd.sfr: get_bxmap_params: path=/\\.\VMFILE1_BITMAP 15:57:05.890 [27839] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 79 15:57:05.891 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:57:05.891 [27839] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.51710 TO 127.0.0.1.1556 fd = 20 15:57:05.891 [27839] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:57:06.001 [27839] <4> bprd.sfr: get_bxmap_params: no pseudo record of type _BITMAP 15:57:06.001 [27839] <2> db_end: Need to collect reply 15:57:06.003 [27839] <2> db_end: no DONE from db_getreply(): no entity was found 15:57:06.004 [27839] <4> bprd.sfr: find_imghdr: change datarecno 2665738 to 92604 15:57:06.004 [27839] <4> bprd.sfr: find_imghdr: change datarecno 2665738 to 92604 15:57:06.004 [27839] <4> bprd.sfr: find_imghdr: change datarecno 2665738 to 92604 15:57:06.004 [27839] <4> bprd.sfr: find_imghdr: change datarecno 2665738 to 92604 15:57:06.004 [27839] <4> bprd.sfr: find_imghdr: change datarecno 2665738 to 92604 15:57:06.005 [27839] <4> bprd.sfr: find_imghdr:cluster size = 512 15:57:06.005 [27839] <4> bprd.sfr: find_imghdr: found image header 15:57:06.005 [27839] <4> bprd.sfr: find_imghdr: currec=92540 15:57:06.005 [27839] <4> bprd.sfr: find_imghdr: flags=b800000 15:57:06.005 [27839] <4> bprd.sfr: find_imghdr: machtype =b fstype=0 15:57:06.005 [27839] <4> bprd.sfr: find_imghdr: byteorder=1 IH_BYTEORDER=1 15:57:06.005 [27839] <4> bprd.sfr: find_imghdr: fiid file size=19456 15:57:06.005 [27839] <4> bprd.sfr: vxms_sort_blockmap: entering ... 15:57:06.005 [27839] <4> bprd.sfr: vxms_setmagic: fh_magic is 7cf01bdc 15:57:06.005 [27839] <4> bprd.sfr: vxms_setmagic: meta_length is 2536 15:57:06.005 [27839] <4> bprd.sfr: vxms_setmagic: fh_filnum is 46269 15:57:06.005 [27839] <4> bprd.sfr: vxms_setmagic: map type=0 15:57:06.007 [27839] <4> bprd.sfr: vxms_sort_blockmap: read to end of map: currec = 92600 15:57:06.007 [27839] <4> bprd.sfr: vxms_sort_blockmap: sending mm_msg.x_msg = EXIT STATUS 0 15:57:06.007 [27839] <4> bprd.sfr: vxms_sort_blockmap: put_string returned 0 15:57:06.007 [27839] <4> bprd.sfr: sfr: sort_blockmap returned 232 15:57:06.008 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:57:06.008 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.35052 TO 10.132.112.22.1556 fd = 6 15:57:06.009 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:57:06.010 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.40764 TO 10.132.112.22.1556 fd = 7 15:57:06.010 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:57:06.050 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-27886358438226050097000000000-2otRLv 15:57:06.130 [27839] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:57:06.130 [27839] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:57:06.130 [27839] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:57:06.130 [27839] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:57:06.130 [27839] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:57:06.130 [27839] <2> db_freeEXDB_INFO: ? 15:57:06.132 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:57:06.132 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.51600 TO 10.132.117.125.1556 fd = 20 15:57:06.134 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:57:06.164 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.37129 TO 10.132.117.125.1556 fd = 21 15:57:06.164 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:57:06.379 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51685 15:57:06.467 [27839] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:57:06.500 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51687 15:57:06.500 [26299] <2> bprd: socket fd from accept() is 10 15:57:06.501 [26299] <2> listen_loop: request complete 15:57:06.501 [27895] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51687 TO 10.132.112.22.1556 fd = 10 15:57:06.502 [27895] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:57:06.502 [27895] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51687 15:57:06.503 [27895] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:57:06.503 [27895] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:57:06.503 [27895] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:57:06.503 [27895] <2> process_request: command C_READ_TEXT_FILE (97) received 15:57:06.503 [27895] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:57:06.503 [27895] <2> read_text_file: Using socket 10 15:57:06.530 [27895] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:57:06.531 [27895] <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:825) 15:57:06.534 [27895] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:57:06.535 [27895] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:57:06.535 [27895] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:57:06.557 [27895] <2> read_text_file: ../bprd.c.17635: file_name = 15:57:06.557 [27895] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:57:06.557 [27895] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:57:06.558 [27895] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:57:06.558 [27895] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:57:06.558 [27895] <2> process_request: EXIT STATUS 0 15:57:11.742 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51689 15:57:11.742 [26299] <2> bprd: socket fd from accept() is 10 15:57:11.743 [26299] <2> listen_loop: request complete 15:57:11.744 [27898] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51689 TO 10.132.112.22.1556 fd = 10 15:57:11.744 [27898] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:57:11.744 [27898] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51689 15:57:11.745 [27898] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:57:11.745 [27898] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:57:11.745 [27898] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:57:11.745 [27898] <2> process_request: command C_READ_TEXT_FILE (97) received 15:57:11.745 [27898] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:57:11.745 [27898] <2> read_text_file: Using socket 10 15:57:11.771 [27898] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:57:11.773 [27898] <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:825) 15:57:11.775 [27898] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:57:11.775 [27898] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:57:11.776 [27898] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:57:11.797 [27898] <2> read_text_file: ../bprd.c.17635: file_name = 15:57:11.797 [27898] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:57:11.797 [27898] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:57:11.798 [27898] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:57:11.798 [27898] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:57:11.798 [27898] <2> process_request: EXIT STATUS 0 15:57:11.802 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51690 15:57:11.803 [26299] <2> bprd: socket fd from accept() is 10 15:57:11.803 [26299] <2> listen_loop: request complete 15:57:11.804 [27899] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51690 TO 10.132.112.22.1556 fd = 10 15:57:11.804 [27899] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:57:11.805 [27899] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51690 15:57:11.806 [27899] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:57:11.806 [27899] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:57:11.806 [27899] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:57:11.806 [27899] <2> process_request: command C_READ_TEXT_FILE (97) received 15:57:11.806 [27899] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:57:11.806 [27899] <2> read_text_file: Using socket 10 15:57:11.832 [27899] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:57:11.833 [27899] <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:825) 15:57:11.835 [27899] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:57:11.836 [27899] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:57:11.836 [27899] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:57:11.858 [27899] <2> read_text_file: ../bprd.c.17635: file_name = 15:57:11.858 [27899] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:57:11.858 [27899] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:57:11.858 [27899] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:57:11.858 [27899] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:57:11.858 [27899] <2> process_request: EXIT STATUS 0 15:57:13.867 [26299] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 98 15:57:13.868 [26299] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:57:13.868 [26299] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.37512 TO 127.0.0.1.1556 fd = 10 15:57:13.868 [26299] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 15:57:13.868 [26299] <2> db_end: Need to collect reply 15:57:27.653 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51692 15:57:27.653 [26299] <2> bprd: socket fd from accept() is 10 15:57:27.654 [26299] <2> listen_loop: request complete 15:57:27.655 [27945] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51692 TO 10.132.112.22.1556 fd = 10 15:57:27.655 [27945] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:57:27.655 [27945] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51692 15:57:27.656 [27945] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:57:27.656 [27945] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:57:27.656 [27945] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:57:27.656 [27945] <2> process_request: command C_READ_TEXT_FILE (97) received 15:57:27.656 [27945] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:57:27.656 [27945] <2> read_text_file: Using socket 10 15:57:27.683 [27945] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:57:27.684 [27945] <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:825) 15:57:27.687 [27945] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:57:27.687 [27945] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:57:27.687 [27945] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:57:27.710 [27945] <2> read_text_file: ../bprd.c.17635: file_name = 15:57:27.710 [27945] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:57:27.710 [27945] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:57:27.710 [27945] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:57:27.710 [27945] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:57:27.710 [27945] <2> process_request: EXIT STATUS 0 15:57:32.133 [27839] <2> bpcr_disconnect_rqst: bpcr received the following message: 15:57:32.134 [27839] <4> bprd.sfr: start_bptm_writer: portnum=0 15:57:32.134 [27839] <4> bprd.sfr: start_bptm_writer: ipc=/tmp/vnet-27839358438252134147000000004-TWj0lf 15:57:32.134 [27839] <4> bprd.sfr: start_bptm_writer: socket=20 15:57:32.134 [27839] <2> start_brm: backup restore server = bfbackup 15:57:32.135 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:57:32.135 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.36247 TO 10.132.112.22.1556 fd = 21 15:57:32.136 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:57:32.137 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.36112 TO 10.132.112.22.1556 fd = 22 15:57:32.137 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:57:32.178 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-27947358438252177851000000000-B4LiSx 15:57:32.257 [27839] <2> start_brm: BPBRM_CMD_SOCK from bpcr = 21 15:57:32.257 [27839] <2> start_brm: BPBRM_STAT_SOCK from bpcr = 22 15:57:32.257 [27839] <2> start_brm: /usr/openv/netbackup/bin/bpbrm bpbrm -restore -mt 2 -mst 1 -k 1 -c lonbfbnbumedia1.corp.ad.timeinc.com -rclnt bfbackup -ru root -cl Digital-VM-Copies -bt 1358266948 -st 0 -secure 1 -b subs-live-db-01_1358266948 -cn 1 -rst 1358438213 -to 0 -mud 180 -ct 40 -ifrfr -S bfbackup.ipcmedia.com -masterversion 750000 -m B0208A -latest_image -vm_opts 2560 -rg root -L /usr/openv/netbackup/logs/user_ops/klamb1271/logs/jbp-27001358438212444470000000039-gTF0L0.log -R /usr/openv/netbackup/logs/user_ops/klamb1271/logs/jbp-27001358438212444470000000039-gTF0L0.chg -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 88585 -restoreid 88585.001 -job_total 1 -flport 0 -flipc /tmp/vnet-27839358438252134147000000004-TWj0lf -partno 3 -firstblk 92604 15:57:36.080 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51705 15:57:36.080 [26299] <2> bprd: socket fd from accept() is 10 15:57:36.081 [26299] <2> listen_loop: request complete 15:57:36.082 [27952] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51705 TO 10.132.112.22.1556 fd = 10 15:57:36.082 [27952] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:57:36.082 [27952] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51705 15:57:36.083 [27952] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:57:36.083 [27952] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:57:36.083 [27952] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:57:36.083 [27952] <2> process_request: command C_READ_TEXT_FILE (97) received 15:57:36.083 [27952] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:57:36.083 [27952] <2> read_text_file: Using socket 10 15:57:36.110 [27952] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:57:36.111 [27952] <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:825) 15:57:36.113 [27952] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:57:36.114 [27952] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:57:36.114 [27952] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:57:36.136 [27952] <2> read_text_file: ../bprd.c.17635: file_name = 15:57:36.136 [27952] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:57:36.136 [27952] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:57:36.136 [27952] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:57:36.136 [27952] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:57:36.136 [27952] <2> process_request: EXIT STATUS 0 15:57:36.609 [27839] <4> bprd.sfr: start_bptm_writer: returning file_list_socket 24 15:57:36.609 [27839] <4> bprd.sfr: sfr: vix_directed=1, hyperv_directed=0, vm_opts=a00 15:57:36.658 [27839] <4> bprd.sfr: restore_regfiles: processsed 0 zero length files 15:57:36.658 [27839] <4> bprd.sfr: restore_regfiles: starting hard link files 15:57:36.658 [27839] <4> bprd.sfr: restore_regfiles: processed 0 hard link files 15:57:36.658 [27839] <4> bprd.sfr: sfr: flags 0 partial 0 15:57:36.658 [27839] <4> bprd.sfr: bptm_end: sending exit status 0 to bptm 15:57:41.657 [27839] <4> check_bpbrm_status: bpbrm exit status = 24 15:57:41.659 [27839] <8> bprd.sfr: check_bpbrm_status: bpbrm failed: socket write failed 15:57:41.659 [27839] <4> bprd.sfr: sfr: closing bptmfd=24 15:57:41.659 [27839] <4> bprd.sfr: sfr: restoring files in partition /\\.\VMFILE1 15:57:41.659 [27839] <4> bprd.sfr: sfr: no files to restore in partition /\\.\VMFILE1 15:57:41.659 [27839] <4> bprd.sfr: sfr: Reached done, error: 0 15:57:41.659 [27839] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 15:57:41.659 [27839] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 15:57:41.659 [27839] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 15:57:41.659 [27839] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 15:57:41.659 [27839] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 15:57:41.660 [27839] <2> db_freeEXDB_INFO: ? 15:57:41.661 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:57:41.661 [27839] <2> logconnections: BPCD CONNECT FROM 10.132.112.22.42995 TO 10.132.117.125.1556 fd = 17 15:57:41.663 [27839] <2> vnet_pbxConnect: pbxConnectEx Succeeded 15:57:41.699 [27839] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.132.112.22.54967 TO 10.132.117.125.1556 fd = 18 15:57:41.699 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 15:57:41.900 [27839] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51721 15:57:41.993 [27839] <2> bpcr_get_version_rqst: bpcd version: 07500004 15:57:42.020 [26299] <2> vnet_pbxAcceptSocket: Accepted sock[10] from 10.132.117.125:51723 15:57:42.020 [26299] <2> bprd: socket fd from accept() is 10 15:57:42.021 [26299] <2> listen_loop: request complete 15:57:42.022 [27979] <2> logconnections: BPRD ACCEPT FROM 10.132.117.125.51723 TO 10.132.112.22.1556 fd = 10 15:57:42.022 [27979] <2> process_request: setsockopt SO_LINGER on 10 succeeded. 15:57:42.023 [27979] <2> connected_peer: Connection from host lonbfbnbumedia1.corp.ad.timeinc.com, 10.132.117.125, on non-reserved port 51723 15:57:42.024 [27979] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:495] VxSS magic 329199 0x505ef 15:57:42.024 [27979] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:496] remote_vxss 97 0x61 15:57:42.024 [27979] <8> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:538] Ignoring VxSS authentication 2 0x2 15:57:42.024 [27979] <2> process_request: command C_READ_TEXT_FILE (97) received 15:57:42.024 [27979] <2> read_text_file: from client lonbfbnbumedia1.corp.ad.timeinc.com: FileID=5 15:57:42.024 [27979] <2> read_text_file: Using socket 10 15:57:42.050 [27979] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 15:57:42.051 [27979] <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:825) 15:57:42.054 [27979] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 15:57:42.054 [27979] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 15:57:42.055 [27979] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 15:57:42.076 [27979] <2> read_text_file: ../bprd.c.17635: file_name = 15:57:42.076 [27979] <2> read_text_file: ../bprd.c.17645: vtext_open() v status = 0 15:57:42.076 [27979] <2> read_text_file: ../bprd.c.17648: vtext_open() n status = 0 15:57:42.077 [27979] <2> read_text_file: ../bprd.c.17682: vtext_close() v status = 0 15:57:42.077 [27979] <2> read_text_file: ../bprd.c.17685: vtext_close() n status = 0 15:57:42.077 [27979] <2> process_request: EXIT STATUS 0