10:09:37.418 [9160.8460] <4> bpbrm main: logging will continue into new log at midnight
10:09:37.418 [9160.8460] <2> bpbrm main: INITIATING (VERBOSE = 5): version NetBackup 7.6.1 2015052215
10:09:37.418 [9160.8460] <2> logparams: -backup -S rf0020500095562.rfoc.srf -c rf0020500095523.rfoc.srf -ct 13 -ru root -cl TESTE_DEDUP06 -sched Full -bt 1448539770 -dt 0 -st 0 -b rf0020500095523.rfoc.srf_1448539770 -mediasvr rf0020500095523.rfoc.srf -jobid 219313 -jobgrpid 219313 -masterversion 760000 -maxfrag 51200 -bpstart_time 1448540076 -reqid -1448470144 -mt 0 -to 0 -stunit dedup.stu.nbudedup06 -rl 0 -rp 604800 -eari 0 -p PureDiskVolume -mst 6 -flags 2 -storagesvr rf0020500095523.rfoc.srf -sts_type PureDisk -use_ofb -use_otm -WOFB_enabled 1 -WOFB_fim 1 -nbu -jm -secure 1 -kl 28 -rg other -fso -ost_nocd -fscp -connect_options 16974338 
10:09:37.418 [9160.8460] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:802] the socket is  548 0x224
10:09:37.434 [9160.8460] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:802] the socket is  504 0x1f8
10:09:37.465 [9160.8460] <2> bprd_connect: Ignoring reserved port request.
10:09:37.465 [9160.8460] <2> bprd_connect: Ignoring local_name rf0020500095523.rfoc.srf.
10:09:37.465 [9160.8460] <2> bprd_connect: Ignoring owner_name SYSTEM.
10:09:37.465 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:37.465 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:37.465 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:37.465 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:37.465 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:37.465 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:37.465 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:37.480 [9160.8460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:09:37.480 [9160.8460] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bprd CONNECT FROM 10.61.12.147.51140 TO 10.61.12.186.1556 fd = 544
10:09:37.480 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51140 TO 10.61.12.186.1556 fd = 544
10:09:37.480 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bprd VIA pbx
10:09:37.480 [9160.8460] <2> logconnections: BPRD CONNECT FROM 10.61.12.147.51140 TO 10.61.12.186.1556 fd = 544
10:09:37.496 [9160.8460] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:939] Ignoring VxSS authentication 2 0x2
10:09:38.822 [9160.8460] <2> brm_update_local_resiliency: changed = 0
10:09:38.822 [9160.8460] <2> bpbrm main: max_entries_per_add = 5000
10:09:38.822 [9160.8460] <2> verify_client: ../bpbrm.c.42193: *connect_opts: 16974338 16974338 0x01030202
10:09:38.822 [9160.8460] <2> ConnectionCache::connectAndCache: Acquiring new connection for host rf0020500095562.rfoc.srf, query type 223
10:09:38.822 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:38.822 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:38.822 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:38.822 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:38.822 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:38.822 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:38.838 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:38.838 [9160.8460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:09:38.838 [9160.8460] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpdbm CONNECT FROM 10.61.12.147.51141 TO 10.61.12.186.1556 fd = 544
10:09:38.838 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51141 TO 10.61.12.186.1556 fd = 544
10:09:38.838 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bpdbm VIA pbx
10:09:38.838 [9160.8460] <2> logconnections: BPDBM CONNECT FROM 10.61.12.147.51141 TO 10.61.12.186.1556 fd = 544
10:09:38.838 [9160.8460] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:939] Ignoring VxSS authentication 2 0x2
10:09:39.493 [9160.8460] <2> db_CLIENTsend: reset client protocol version from 0 to 8
10:09:39.493 [9160.8460] <2> db_CLIENTsend: prec->offline=0
10:09:39.493 [9160.8460] <2> db_CLIENTsend: prec->online_at=0
10:09:39.493 [9160.8460] <2> db_CLIENTsend: prec->offline_at=0
10:09:39.493 [9160.8460] <2> db_CLIENTsend: sending 65 bytes
10:09:39.820 [9160.8460] <2> db_CLIENTreceive: clientrec_protocol_version=8
10:09:39.820 [9160.8460] <2> db_CLIENTreceive: num_fi_info=0
10:09:39.820 [9160.8460] <2> db_CLIENTreceive: num_exdb_info=0
10:09:39.820 [9160.8460] <2> db_CLIENTreceive: offline=0
10:09:39.820 [9160.8460] <2> db_CLIENTreceive: online_at=2147483647
10:09:39.820 [9160.8460] <2> db_CLIENTreceive: offline_at=0
10:09:39.820 [9160.8460] <2> db_end: Need to collect reply
10:09:39.820 [9160.8460] <2> isDONE: reply DONE 0 <?xml version="1.0" encoding="utf-8"?><StatusMsgDoc><StatusMsg NBUStatus="0" Severity="0" VXULoid="271" VXULtid="0"><MsgString>the&#32;requested&#32;operation&#32;was&#32;successfully&#32;completed</MsgString></StatusMsg></StatusMsgDoc>
10:09:39.820 [9160.8460] <2> isDONE: reply DONE 0 
10:09:39.820 [9160.8460] <2> verify_client: ../bpbrm.c.42340: OST Proxy is Disabled: 0 0 0x00000000
10:09:39.820 [9160.8460] <2> verify_client: ../bpbrm.c.42385: Client Direct is Disabled:: 0 0 0x00000000
10:09:39.820 [9160.8460] <2> db_freeSEC_INFO: pinfo is NULL
10:09:39.820 [9160.8460] <2> db_freeEXDB_INFO: ?
10:09:39.820 [9160.8460] <2> db_freeEXDB_INFO: plist is NULL
10:09:39.820 [9160.8460] <2> verify_client: ../bpbrm.c.42547: *connect_opts: 16908289 16908289 0x01020001
10:09:39.820 [9160.8460] <2> ConnectionCache::connectAndCache: Acquiring new connection for host rf0020500095562.rfoc.srf, query type 223
10:09:39.820 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:39.820 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:39.820 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:39.820 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:39.820 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:39.820 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:39.820 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:39.836 [9160.8460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:09:39.836 [9160.8460] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpdbm CONNECT FROM 10.61.12.147.51142 TO 10.61.12.186.1556 fd = 552
10:09:39.836 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51142 TO 10.61.12.186.1556 fd = 552
10:09:39.836 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bpdbm VIA pbx
10:09:39.836 [9160.8460] <2> logconnections: BPDBM CONNECT FROM 10.61.12.147.51142 TO 10.61.12.186.1556 fd = 552
10:09:39.836 [9160.8460] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:939] Ignoring VxSS authentication 2 0x2
10:09:40.382 [9160.8460] <2> db_CLIENTsend: reset client protocol version from 0 to 8
10:09:40.382 [9160.8460] <2> db_CLIENTsend: prec->offline=0
10:09:40.382 [9160.8460] <2> db_CLIENTsend: prec->online_at=0
10:09:40.382 [9160.8460] <2> db_CLIENTsend: prec->offline_at=0
10:09:40.382 [9160.8460] <2> db_CLIENTsend: sending 65 bytes
10:09:40.632 [9160.8460] <2> db_CLIENTreceive: clientrec_protocol_version=8
10:09:40.632 [9160.8460] <2> db_CLIENTreceive: num_fi_info=0
10:09:40.632 [9160.8460] <2> db_CLIENTreceive: num_exdb_info=0
10:09:40.632 [9160.8460] <2> db_CLIENTreceive: offline=0
10:09:40.632 [9160.8460] <2> db_CLIENTreceive: online_at=2147483647
10:09:40.632 [9160.8460] <2> db_CLIENTreceive: offline_at=0
10:09:40.632 [9160.8460] <2> db_end: Need to collect reply
10:09:40.632 [9160.8460] <2> isDONE: reply DONE 0 <?xml version="1.0" encoding="utf-8"?><StatusMsgDoc><StatusMsg NBUStatus="0" Severity="0" VXULoid="271" VXULtid="0"><MsgString>the&#32;requested&#32;operation&#32;was&#32;successfully&#32;completed</MsgString></StatusMsg></StatusMsgDoc>
10:09:40.632 [9160.8460] <2> isDONE: reply DONE 0 
10:09:40.632 [9160.8460] <2> db_freeSEC_INFO: pinfo is NULL
10:09:40.632 [9160.8460] <2> db_freeEXDB_INFO: ?
10:09:40.632 [9160.8460] <2> db_freeEXDB_INFO: plist is NULL
10:09:40.632 [9160.8460] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01030202 connect_opts2 = 0x01020001
10:09:40.632 [9160.8460] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01020001
10:09:40.632 [9160.8460] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 2
10:09:40.632 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:40.632 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4717] Local [strong] check, using interface  ANY
10:09:40.647 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:40.647 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51143 TO 10.61.12.147.13782 fd = 552
10:09:40.647 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095523.rfoc.srf 10.61.12.147 bpcd
10:09:40.647 [9160.8460] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.51143 TO 10.61.12.147.13782 fd = 552
10:09:40.897 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:40.897 [9160.8460] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 552
10:09:40.897 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:40.928 [9160.8460] <2> local_getHostInfo: hostname comparison failed
10:09:40.928 [9160.8460] <2> local_getHostInfo: hostname comparison failed
10:09:40.928 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:40.928 [9160.8460] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST
10:09:40.928 [9160.8460] <2> bpcr_get_platform_rqst: Server platform length = 7
10:09:40.928 [9160.8460] <2> xlatePlatformName: my version = 760000, client version = 760000, check current list first
10:09:40.928 [9160.8460] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64
10:09:40.928 [9160.8460] <2> local_getHostInfo: platform of client rf0020500095523.rfoc.srf is PC-x64, WindowsXP
10:09:40.928 [9160.8460] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=28, buffer=UNAME_SYSNAME = Windows2008 
10:09:40.928 [9160.8460] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=33, buffer=UNAME_NODENAME = RF0020500095523 
10:09:40.928 [9160.8460] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=18, buffer=UNAME_RELEASE = 6 
10:09:40.928 [9160.8460] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=18, buffer=UNAME_VERSION = 1 
10:09:40.928 [9160.8460] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=52, buffer=UNAME_MACHINE = Unknown - Unknown -  Revision x2f02 
10:09:40.928 [9160.8460] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=3, buffer=END
10:09:40.928 [9160.8460] <2> local_getHostInfo: d:\nb\7.6.1.2\src\nb\dblib\dbmisc.c: 1740, uname -sr=Windows2008 6 

10:09:40.928 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:40.928 [9160.8460] <2> bpcr_get_cipher_strings_rqst: version_crypt line = <HARDWARE Windows x64>
10:09:40.959 [9160.8460] <2> bpcr_get_cipher_strings_rqst: cipher = <AES-128-CFB>
10:09:40.959 [9160.8460] <2> bpcr_get_cipher_strings_rqst: cipher = <BF-CFB>
10:09:40.959 [9160.8460] <2> bpcr_get_cipher_strings_rqst: cipher = <DES-EDE-CFB>
10:09:40.959 [9160.8460] <2> bpcr_get_cipher_strings_rqst: cipher = <AES-256-CFB>
10:09:40.959 [9160.8460] <2> getCipherList: *pCipherList = <AES-128-CFB,BF-CFB,DES-EDE-CFB,AES-256-CFB>
10:09:40.959 [9160.8460] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 552
10:09:40.959 [9160.8460] <2> getPatchLevel: got client socket
10:09:40.959 [9160.8460] <2> getPatchLevel: file num = 3, file size = 113
10:09:40.959 [9160.8460] <2> bpcr_read_rqst: completed send of file number 3
10:09:40.959 [9160.8460] <2> bpcr_read_rqst: completed send of transfer_count = 16383
10:09:40.959 [9160.8460] <2> bpcr_read_rqst: Before readclientstatus on socket 560
10:09:40.959 [9160.8460] <2> bpcr_read_rqst: Num bytes to be sent by client = 113
10:09:40.959 [9160.8460] <2> getPatchLevel: total chars read = 113
10:09:40.959 [9160.8460] <2> getPatchLevel: level = >7.6.1.2<
10:09:40.959 [9160.8460] <2> bpbrm main: Client NBU version: 760000
10:09:40.975 [9160.8460] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 9160
10:09:40.975 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:40.975 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:40.975 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:40.975 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:40.975 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:40.975 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:40.975 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:40.975 [9160.8460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:09:40.975 [9160.8460] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpjobd CONNECT FROM 10.61.12.147.51145 TO 10.61.12.186.1556 fd = 584
10:09:40.975 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51145 TO 10.61.12.186.1556 fd = 584
10:09:40.975 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bpjobd VIA pbx
10:09:40.975 [9160.8460] <2> job_connect: SO_KEEPALIVE set on socket 584 for client rf0020500095562.rfoc.srf
10:09:40.975 [9160.8460] <2> logconnections: BPJOBD CONNECT FROM 10.61.12.147.51145 TO 10.61.12.186.1556 fd = 584
10:09:40.975 [9160.8460] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
10:09:41.006 [9160.8460] <2> job_connect: Connected to the host rf0020500095562.rfoc.srf contype 53 jobid <219313> socket <584>
10:09:41.006 [9160.8460] <2> job_connect: Connected on port 51145
10:09:41.006 [9160.8460] <2> set_job_details: Tfile (219313): LOG 1448539780 4 bpbrm 9160 rf0020500095523.rfoc.srf is the host to backup data from

10:09:41.006 [9160.8460] <2> send_job_file: job ID 219313, ftype = 3 msg len = 85, msg = LOG 1448539780 4 bpbrm 9160 rf0020500095523.rfoc.srf is the host to backup data from

10:09:41.006 [9160.8460] <2> set_job_details: Tfile (219313): LOG 1448539781 4 bpbrm 9160 reading file list for client

10:09:41.006 [9160.8460] <2> send_job_file: job ID 219313, ftype = 3 msg len = 57, msg = LOG 1448539781 4 bpbrm 9160 reading file list for client

10:09:41.006 [9160.8460] <2> bpbrm main: File read = "C:\CAMEL_TOOL"
10:09:41.006 [9160.8460] <2> bpbrm main: File read = "CONTINUE"
10:09:41.006 [9160.8460] <2> bpbrm main: send_3_0_client_params = 1
10:09:41.006 [9160.8460] <2> bpbrm main: backup_cmd = /usr/openv/netbackup/bin/bpbkar bpbkar32 -r 604800 -ru root -dt 0 -to 0 -bpstart_time 1448540076 -clnt rf0020500095523.rfoc.srf -class TESTE_DEDUP06 -sched Full -st FULL -bpstart_to 300 -bpend_to 300 -read_to 32767 -blks_per_buffer 512 -use_otm -fso -b rf0020500095523.rfoc.srf_1448539770 -kl 28 -WOFB_enabled -WOFB_fim 1 -WOFB_usage 0 -WOFB_error 0 -ct 13
10:09:41.006 [9160.8460] <2> bpbrm start_bpcd_stat: ../bpbrm.c.23188: bpcd_client: rf0020500095523.rfoc.srf
10:09:41.006 [9160.8460] <2> bpbrm start_bpcd_stat: ../bpbrm.c.23191: bpcd_client_hostname: rf0020500095523.rfoc.srf
10:09:41.006 [9160.8460] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01020001 connect_opts2 = 0x01020001
10:09:41.006 [9160.8460] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01020001
10:09:41.006 [9160.8460] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 2
10:09:41.006 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:41.006 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4717] Local [strong] check, using interface  ANY
10:09:41.006 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:41.006 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51146 TO 10.61.12.147.13782 fd = 592
10:09:41.006 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095523.rfoc.srf 10.61.12.147 bpcd
10:09:41.006 [9160.8460] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.51146 TO 10.61.12.147.13782 fd = 592
10:09:41.334 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:41.334 [9160.8460] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 592
10:09:41.334 [9160.8460] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 600
10:09:41.334 [9160.8460] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01020001 connect_opts2 = 0x01020001
10:09:41.334 [9160.8460] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01020001
10:09:41.334 [9160.8460] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 2
10:09:41.334 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:41.334 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4717] Local [strong] check, using interface  ANY
10:09:41.334 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:41.349 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51148 TO 10.61.12.147.13782 fd = 596
10:09:41.349 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095523.rfoc.srf 10.61.12.147 bpcd
10:09:41.349 [9160.8460] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.51148 TO 10.61.12.147.13782 fd = 596
10:09:41.630 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:41.630 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:41.646 [9160.8460] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:587)
10:09:41.646 [9160.8460] <2> BpConfigHolder::BpConfigHolder: +++ ENTERING +++ : obj = 000000000130E7E8 (../BpConf.cpp:34)
10:09:41.646 [9160.8460] <2> BpConfigHolder::reload: +++ ENTERING +++ : obj = 000000000130E7E8 (../BpConf.cpp:46)
10:09:41.646 [9160.8460] <2> BpConfigHolder::reload: --- EXITING --- : obj = 000000000130E7E8 (../BpConf.cpp:46)
10:09:41.646 [9160.8460] <2> BpConfigHolder::BpConfigHolder: --- EXITING --- : obj = 000000000130E7E8 (../BpConf.cpp:34)
10:09:41.646 [9160.8460] <2> EndpointSelectorFactory::EndpointSelectorFactory: +++ ENTERING +++ : obj = 000000000133EF10 (../Endpoint_Selector.cpp:1967)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::EndpointSelector_R2: +++ ENTERING +++ : obj = 00000000012A2D50 (../Endpoint_Selector.cpp:364)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf LastUpd=1448539715 bp.conf LastLook=0(Endpoint_Selector.cpp:405)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:409)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::updateConfMembers: epsr2: tardy lookup log time value set to 5 seconds(Endpoint_Selector.cpp:447)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::updateConfMembers: epsr2: USE_VXSS = PROHIBITED(Endpoint_Selector.cpp:458)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::EndpointSelector_R2: --- EXITING --- : obj = 00000000012A2D50 (../Endpoint_Selector.cpp:364)
10:09:41.646 [9160.8460] <2> EndpointSelectorFactory::EndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0(Endpoint_Selector.cpp:2014)
10:09:41.646 [9160.8460] <2> EndpointSelectorFactory::EndpointSelectorFactory: --- EXITING --- : obj = 000000000133EF10 (../Endpoint_Selector.cpp:1967)
10:09:41.646 [9160.8460] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: +++ ENTERING +++ : obj = 000000000133EFB0 (../Endpoint_Selector.cpp:2060)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::EndpointSelector_R2: +++ ENTERING +++ : obj = 00000000012A2630 (../Endpoint_Selector.cpp:364)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf LastUpd=1448539715 bp.conf LastLook=0(Endpoint_Selector.cpp:405)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:409)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::updateConfMembers: epsr2: tardy lookup log time value set to 5 seconds(Endpoint_Selector.cpp:447)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::updateConfMembers: epsr2: USE_VXSS = PROHIBITED(Endpoint_Selector.cpp:458)
10:09:41.646 [9160.8460] <2> EndpointSelector_R2::EndpointSelector_R2: --- EXITING --- : obj = 00000000012A2630 (../Endpoint_Selector.cpp:364)
10:09:41.646 [9160.8460] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0 [Ignoring BPCONF server list](Endpoint_Selector.cpp:2108)
10:09:41.646 [9160.8460] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: --- EXITING --- : obj = 000000000133EFB0 (../Endpoint_Selector.cpp:2060)
10:09:41.646 [9160.8460] <2> checkService: found service: 000000006E0F3748(Orb.cpp:563)
10:09:41.661 [9160.8460] <2> checkService: found service: 000000006E0F3720(Orb.cpp:563)
10:09:41.661 [9160.8460] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:481)
10:09:41.661 [9160.8460] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:533)
10:09:41.661 [9160.8460] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:713)
10:09:41.661 [9160.8460] <2> Orb::init: substituting orb name of: Default_CLIENT_Orb(Orb.cpp:747)
10:09:41.661 [9160.8460] <2> Orb::init: initializing ORB with: Unknown -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 Default_CLIENT_Orb'"(Orb.cpp:766)
10:09:41.661 [9160.8460] <2> Orb::init: added args has value of: (Orb.cpp:861)
10:09:41.661 [9160.8460] <2> Orb::init: initializing ORB Default_CLIENT_Orb with: Unknown -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 Default_CLIENT_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:916)
10:09:41.661 [9160.8460] <2> NBEvaluatorFactory::init: +++ ENTERING +++ : obj = 000000000133EDD0 (../SecureEvaluator.cpp:509)
10:09:41.661 [9160.8460] <2> NBEvaluatorFactory::init: --- EXITING --- : obj = 000000000133EDD0 (../SecureEvaluator.cpp:509)
10:09:41.661 [9160.8460] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 00000000012AF480 (../NBOrbInitializer.cpp:45)
10:09:41.661 [9160.8460] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 00000000012AF480 (../NBOrbInitializer.cpp:45)
10:09:41.661 [9160.8460] <2> ExternalResolver::set: Resolved host_name localhost[127.0.0.1](ExternalResolver.cpp:73)
10:09:41.661 [9160.8460] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 00000000012AF480 (../NBOrbInitializer.cpp:53)
10:09:41.661 [9160.8460] <2> NBOrbInitializer::post_init: Adding interceptors(NBOrbInitializer.cpp:74)
10:09:41.661 [9160.8460] <2> NBIORInterceptor::NBIORInterceptor: Interceptor Name: _Default_CLIENT_Orb(NBIORInterceptor.cpp:47)
10:09:41.661 [9160.8460] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 00000000012AF480 (../NBOrbInitializer.cpp:53)
10:09:41.661 [9160.8460] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:930)
10:09:41.661 [9160.8460] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 7200(Orb.cpp:1585)
10:09:41.661 [9160.8460] <2> Orb::activate: +++ ENTERING +++ : obj = 0000000001366660 (../Orb.cpp:1346)
10:09:41.661 [9160.8460] <2> Orb::activate: Client Orb called activate; Ignored(Orb.cpp:1351)
10:09:41.661 [9160.8460] <2> Orb::activate: --- EXITING --- : obj = 0000000001366660 (../Orb.cpp:1346)
10:09:41.661 [9160.8460] <2> Orb::connectToObject: Connection attempt #1 mW:5 rW:0 stopOnShutdown 0 m_shutdownInitiated 0(Orb.cpp:1236)
10:09:41.661 [9160.8460] <2> Orb::createInsecureObjectRef: +++ ENTERING +++ : obj = 0000000001366660 (../Orb.cpp:1513)
10:09:41.661 [9160.8460] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:rf0020500095562.rfoc.srf:1556:NBDSMFSM/DiskService.DiskServiceMgr(Orb.cpp:1521)
10:09:41.661 [9160.8460] <2> Orb::createInsecureObjectRef: --- EXITING --- : obj = 0000000001366660 (../Orb.cpp:1513)
10:09:41.661 [9160.8460] <2> Orb::connectToObjectOnce: attempt to create insecure connection(Orb.cpp:1098)
10:09:41.661 [9160.8460] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:535)
10:09:41.661 [9160.8460] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 1 endpoints(Endpoint_Selector.cpp:651)
10:09:41.661 [9160.8460] <2> VxssContext::useVxss: epsr2: Setting up useVxss=2(Endpoint_Selector.cpp:157)
10:09:41.661 [9160.8460] <2> VxssContext::begin: epsr2: Setup context, passes. vxss=2 passes=1(Endpoint_Selector.cpp:187)
10:09:41.661 [9160.8460] <2> VxssContext::end: epsr2: all done? passes=1 curridx=0(Endpoint_Selector.cpp:193)
10:09:41.661 [9160.8460] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: pass: 0 secure_mode:2/0(Endpoint_Selector.cpp:815)
10:09:41.661 [9160.8460] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: PBXIOP endpoint host: rf0020500095562.rfoc.srf(Endpoint_Selector.cpp:1511)
10:09:41.677 [9160.8460] <2> ConnectList::search: host 10.61.12.186 not found in cache(ConnectList.cpp:118)
10:09:41.677 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:41.677 [9160.8460] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.61.12.186: ANY(Endpoint_Selector.cpp:1757)
10:09:41.677 [9160.8460] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname rf0020500095562.rfoc.srf has 1 candidate addresses(Endpoint_Selector.cpp:1691)
10:09:41.677 [9160.8460] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Endpoint host rf0020500095562.rfoc.srf is not checked against server list(Endpoint_Selector.cpp:1634)
10:09:41.677 [9160.8460] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Found 1 usable IP addresses for host rf0020500095562.rfoc.srf:[10.61.12.186] (Endpoint_Selector.cpp:1643)
10:09:41.677 [9160.8460] <2> ConnectList::search: host 10.61.12.186 not found in cache(ConnectList.cpp:118)
10:09:41.677 [9160.8460] <2> ExternalResolver::set: Resolved host_name 10.61.12.186[10.61.12.186](ExternalResolver.cpp:73)
10:09:41.677 [9160.8460] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Target: 10.61.12.186, source: ANY(Endpoint_Selector.cpp:1017)
10:09:41.677 [9160.8460] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=1 useVxss=2(Endpoint_Selector.cpp:243)
10:09:41.755 [9160.8460] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.61.12.186 SUCCESS(Endpoint_Selector.cpp:1226)
10:09:41.755 [9160.8460] <2> ConnectList::update: added host 10.61.12.186 (1 total) as GOOD(ConnectList.cpp:216)
10:09:41.755 [9160.8460] <2> VxssContext::end: epsr2: all done? passes=1 curridx=1(Endpoint_Selector.cpp:193)
10:09:41.770 [9160.8460] <2> EndpointSelector_R2::forward: epsr2: Adding 1 forward profiles(Endpoint_Selector.cpp:579)
10:09:41.770 [9160.8460] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:535)
10:09:41.770 [9160.8460] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:651)
10:09:41.770 [9160.8460] <2> ConnectList::search: host 10.61.12.186 found GOOD(ConnectList.cpp:91)
10:09:41.770 [9160.8460] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.61.12.186, source: ANY(Endpoint_Selector.cpp:713)
10:09:41.770 [9160.8460] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243)
10:09:41.770 [9160.8460] <2> ExternalResolver::set: Resolved host_name 10.61.12.186[10.61.12.186](ExternalResolver.cpp:73)
10:09:41.770 [9160.8460] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.61.12.186 SUCCESS(Endpoint_Selector.cpp:1226)
10:09:41.770 [9160.8460] <2> ConnectList::update: updated host 10.61.12.186 as GOOD, used 0 of 3 retries(ConnectList.cpp:180)
10:09:41.770 [9160.8460] <2> Orb::connectToObjectOnce: connection created(Orb.cpp:1116)
10:09:41.770 [9160.8460] <2> FrameworkRegistrar::init: +++ ENTERING +++ : obj = 0000000000213C30 (../FrameworkRegistrar.cpp:131)
10:09:41.770 [9160.8460] <2> FrameworkRegistrar::init: --- EXITING --- : obj = 0000000000213C30 (../FrameworkRegistrar.cpp:131)
10:09:41.770 [9160.8460] <2> DiskServiceRegistrar::init: +++ ENTERING +++ : obj = 0000000000213C80 (../DiskServiceRegistrar.cpp:144)
10:09:41.770 [9160.8460] <2> DiskServiceRegistrar::init: --- EXITING --- : obj = 0000000000213C80 (../DiskServiceRegistrar.cpp:144)
10:09:41.770 [9160.8460] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:535)
10:09:41.770 [9160.8460] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:651)
10:09:41.770 [9160.8460] <2> ConnectList::search: host 10.61.12.186 found GOOD(ConnectList.cpp:91)
10:09:41.770 [9160.8460] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.61.12.186, source: ANY(Endpoint_Selector.cpp:713)
10:09:41.770 [9160.8460] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243)
10:09:41.770 [9160.8460] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.61.12.186 SUCCESS(Endpoint_Selector.cpp:1226)
10:09:41.770 [9160.8460] <2> ConnectList::update: updated host 10.61.12.186 as GOOD, used 0 of 3 retries(ConnectList.cpp:180)
10:09:41.786 [9160.8460] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:535)
10:09:41.786 [9160.8460] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(Endpoint_Selector.cpp:651)
10:09:41.786 [9160.8460] <2> ConnectList::search: host 10.61.12.186 found GOOD(ConnectList.cpp:91)
10:09:41.786 [9160.8460] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.61.12.186, source: ANY(Endpoint_Selector.cpp:713)
10:09:41.786 [9160.8460] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243)
10:09:41.786 [9160.8460] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.61.12.186 SUCCESS(Endpoint_Selector.cpp:1226)
10:09:41.786 [9160.8460] <2> ConnectList::update: updated host 10.61.12.186 as GOOD, used 0 of 3 retries(ConnectList.cpp:180)
10:09:41.848 [9160.8460] <4> MmDeviceMappings::GetRobotAttributes
	: <../../lib/MmDeviceMappings.cpp:976> search robot list (length=439) for OST PureDisk, type 8

10:09:41.848 [9160.8460] <4> MmDeviceMappings::GetRobotAttributes
	: <../../lib/MmDeviceMappings.cpp:1229> found match: "PureDisk" OST PureDisk
10:09:41.848 [9160.8460] <2> nb_accl_sts_support_check: accelerator support code is 1
10:09:41.848 [9160.8460] <2> set_job_details: Tfile (219313): LOG 1448539781 4 bpbrm 9160 accelerator enabled

10:09:41.848 [9160.8460] <2> send_job_file: job ID 219313, ftype = 3 msg len = 48, msg = LOG 1448539781 4 bpbrm 9160 accelerator enabled

10:09:41.848 [9160.8460] <2> nb_accl_validate: accelerator enabled
10:09:41.848 [9160.8460] <4> nb_accl_get_last_backup_byname: track_dir_win=\rf0020500095562.rfoc.srf\rf0020500095523.rfoc.srf\rf0020500095523.rfoc.srf\TESTE_DEDUP06, track_dir_lnx=/rf0020500095562.rfoc.srf/rf0020500095523.rfoc.srf/rf0020500095523.rfoc.srf/TESTE_DEDUP06, IN_SOCK=-1, OUT_SOCK=-1,real_client rf0020500095523.rfoc.srf,client_param rf0020500095523.rfoc.srf,client_hostname_param rf0020500095523.rfoc.srf .
10:09:41.848 [9160.8460] <2> bpbrm start_bpcd_stat: ../bpbrm.c.23188: bpcd_client: rf0020500095523.rfoc.srf
10:09:41.848 [9160.8460] <2> bpbrm start_bpcd_stat: ../bpbrm.c.23191: bpcd_client_hostname: rf0020500095523.rfoc.srf
10:09:41.864 [9160.8460] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01020001 connect_opts2 = 0x01020001
10:09:41.864 [9160.8460] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01020001
10:09:41.864 [9160.8460] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 2
10:09:41.864 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:41.864 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4717] Local [strong] check, using interface  ANY
10:09:41.864 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:41.864 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51153 TO 10.61.12.147.13782 fd = 840
10:09:41.864 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095523.rfoc.srf 10.61.12.147 bpcd
10:09:41.864 [9160.8460] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.51153 TO 10.61.12.147.13782 fd = 840
10:09:42.363 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:42.363 [9160.8460] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 840
10:09:42.363 [9160.8460] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 848
10:09:42.363 [9160.8460] <4> nb_accl_get_last_backup_byname: get backup ids from client failed.
10:09:42.363 [9160.8460] <4> nb_accl_get_last_backup_byname: get backup ids from client failed.
10:09:42.363 [9160.8460] <2> set_job_details: Tfile (219313): LOG 1448539782 4 bpbrm 9160 There is no complete backup image match with track journal, a regular full backup will be performed.

10:09:42.363 [9160.8460] <2> send_job_file: job ID 219313, ftype = 3 msg len = 129, msg = LOG 1448539782 4 bpbrm 9160 There is no complete backup image match with track journal, a regular full backup will be performed.

10:09:42.363 [9160.8460] <2> ConnectionCache::connectAndCache: Acquiring new connection for host rf0020500095562.rfoc.srf, query type 200
10:09:42.363 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:42.363 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:42.363 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:42.379 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:42.379 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:42.379 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:42.379 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:42.394 [9160.8460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:09:42.394 [9160.8460] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpdbm CONNECT FROM 10.61.12.147.51155 TO 10.61.12.186.1556 fd = 848
10:09:42.394 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51155 TO 10.61.12.186.1556 fd = 848
10:09:42.394 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bpdbm VIA pbx
10:09:42.394 [9160.8460] <2> logconnections: BPDBM CONNECT FROM 10.61.12.147.51155 TO 10.61.12.186.1556 fd = 848
10:09:42.394 [9160.8460] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:939] Ignoring VxSS authentication 2 0x2
10:09:42.738 [9160.8460] <2> db_CLASSmarshal: protocol version: 760000
10:09:42.738 [9160.8460] <4> TZ: Send Timezone offset=7200
10:09:42.769 [9160.8460] <4> TZ: Recv Timezone offset=7200-local=7200
10:09:42.769 [9160.8460] <4> db_CLASSunmarshal: Remote timezone offset = 7200
10:09:42.769 [9160.8460] <2> db_end: Need to collect reply
10:09:42.800 [9160.8460] <2> isDONE: reply DONE 0 <?xml version="1.0" encoding="utf-8"?><StatusMsgDoc><StatusMsg NBUStatus="0" Severity="0" VXULoid="271" VXULtid="0"><MsgString>the&#32;requested&#32;operation&#32;was&#32;successfully&#32;completed</MsgString></StatusMsg></StatusMsgDoc>
10:09:42.800 [9160.8460] <2> isDONE: reply DONE 0 
10:09:42.800 [9160.8460] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01020001 connect_opts2 = 0x01020001
10:09:42.800 [9160.8460] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01020001
10:09:42.800 [9160.8460] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 2
10:09:42.800 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:42.800 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4717] Local [strong] check, using interface  ANY
10:09:42.800 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:42.800 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51156 TO 10.61.12.147.13782 fd = 840
10:09:42.800 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095523.rfoc.srf 10.61.12.147 bpcd
10:09:42.800 [9160.8460] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.51156 TO 10.61.12.147.13782 fd = 840
10:09:43.377 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:43.377 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:43.377 [9160.8460] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
10:09:43.377 [9160.8460] <2> ConnectionCache::connectAndCache: Acquiring new connection for host rf0020500095562.rfoc.srf, query type 89
10:09:43.377 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:43.377 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:43.377 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:43.377 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:43.377 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:43.377 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
10:09:43.377 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:43.393 [9160.8460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:09:43.393 [9160.8460] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpdbm CONNECT FROM 10.61.12.147.51158 TO 10.61.12.186.1556 fd = 852
10:09:43.393 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51158 TO 10.61.12.186.1556 fd = 852
10:09:43.393 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bpdbm VIA pbx
10:09:43.393 [9160.8460] <2> logconnections: BPDBM CONNECT FROM 10.61.12.147.51158 TO 10.61.12.186.1556 fd = 852
10:09:43.393 [9160.8460] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:939] Ignoring VxSS authentication 2 0x2
10:09:43.767 [9160.8460] <2> db_end: Need to collect reply
10:09:44.969 [9160.8460] <2> isDONE: reply DONE 0 <?xml version="1.0" encoding="utf-8"?><StatusMsgDoc><StatusMsg NBUStatus="0" Severity="0" VXULoid="271" VXULtid="0"><MsgString>the&#32;requested&#32;operation&#32;was&#32;successfully&#32;completed</MsgString></StatusMsg></StatusMsgDoc>
10:09:44.969 [9160.8460] <2> isDONE: reply DONE 0 
10:09:44.969 [9160.8460] <2> bpbrm start_bpcd_stat: ../bpbrm.c.23188: bpcd_client: rf0020500095523.rfoc.srf
10:09:44.969 [9160.8460] <2> bpbrm start_bpcd_stat: ../bpbrm.c.23191: bpcd_client_hostname: rf0020500095523.rfoc.srf
10:09:44.969 [9160.8460] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01020001 connect_opts2 = 0x01020001
10:09:44.969 [9160.8460] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01020001
10:09:44.969 [9160.8460] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 2
10:09:44.969 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:44.969 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4717] Local [strong] check, using interface  ANY
10:09:44.969 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:44.984 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51159 TO 10.61.12.147.13782 fd = 852
10:09:44.984 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095523.rfoc.srf 10.61.12.147 bpcd
10:09:44.984 [9160.8460] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.51159 TO 10.61.12.147.13782 fd = 852
10:09:45.265 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:45.265 [9160.8460] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 852
10:09:45.265 [9160.8460] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 844
10:09:45.265 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:45.265 [9160.8460] <2> bpcr_get_platform_rqst: Server platform length = 7
10:09:45.281 [9160.8460] <2> bpcr_check_for_use_ofb_support: bpcd platform win_x64
10:09:45.281 [9160.8460] <2> set_job_details: Tfile (219313): LOG 1448539785 4 bpbrm 9160 starting bpbkar32 on client

10:09:45.281 [9160.8460] <2> send_job_file: job ID 219313, ftype = 3 msg len = 56, msg = LOG 1448539785 4 bpbrm 9160 starting bpbkar32 on client

10:09:45.281 [9160.8460] <2> bpbrm main: received bpcd success message
10:09:47.667 [9160.8460] <2> get_readline_type: Returning BPBRM_READ_INFORM_WHEN_DONE
10:09:47.667 [9160.8460] <2> bpbrm main: client_pid=9260
10:09:47.667 [9160.8460] <2> set_job_details: Tfile (219313): LOG 1448539787 4 bpbkar32 9260 Backup started

10:09:47.667 [9160.8460] <2> send_job_file: job ID 219313, ftype = 3 msg len = 46, msg = LOG 1448539787 4 bpbkar32 9260 Backup started

10:09:47.667 [9160.8460] <2> bpbrm main: from client rf0020500095523.rfoc.srf: read client start message
10:09:47.667 [9160.8460] <2> bpbrm spawn_child: "C:\Program Files\Veritas\NetBackup\bin\bptm.exe" -w -pid 9160 -c rf0020500095523.rfoc.srf -dpath PureDiskVolume -stunit dedup.stu.nbudedup06 -cl TESTE_DEDUP06 -bt 1448539770 -b rf0020500095523.rfoc.srf_1448539770 -st 0 -cj 1 -reqid -1448470144 -jm -brm -hostname rf0020500095523.rfoc.srf -ru root -rclnt rf0020500095523.rfoc.srf -rclnthostname rf0020500095523.rfoc.srf -rl 0 -rp 604800 -sl Full -ct 13 -maxfrag 51200 -eari 0 -v -mediasvr rf0020500095523.rfoc.srf -nonrsvdports -connect_options 0x01020001 -jobid 219313 -jobgrpid 219313 -masterversion 760000 -fscp -storagesvr rf0020500095523.rfoc.srf -bpbrm_shm_id Global\NetBackup_BPBRM_SHM_Path_66467267_9160_8460 -blks_per_buffer 512 -shm 
10:09:47.683 [9160.8460] <2> bpbrm create_mm_terminate: created terminate event pid 8412
10:09:47.683 [9160.8460] <2> bpbrm create_mm_suspend: created suspend event pid 8412
10:09:47.683 [9160.8460] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <852>
10:09:47.683 [9160.8460] <2> write_file_names: buffering file name 'C:\CAMEL_TOOL' for output
10:09:47.699 [9160.8460] <2> write_file_names: successfully wrote buffer to COMM_SOCK
10:09:47.699 [9160.8460] <2> bpbrm main: wrote CONTINUE on COMM_SOCK
10:09:47.699 [9160.8460] <2> bpbrm main: Value of PFI = 0
10:09:47.699 [9160.8460] <2> set_job_details: Tfile (219313): LOG 1448539787 4 bpbkar32 9260 change time comparison:<enabled>

10:09:47.699 [9160.8460] <2> send_job_file: job ID 219313, ftype = 3 msg len = 64, msg = LOG 1448539787 4 bpbkar32 9260 change time comparison:<enabled>

10:09:47.699 [9160.8460] <2> set_job_details: Tfile (219313): LOG 1448539787 4 bpbkar32 9260 accelerator enabled backup, archive bit processing:<disabled>

10:09:47.699 [9160.8460] <2> send_job_file: job ID 219313, ftype = 3 msg len = 93, msg = LOG 1448539787 4 bpbkar32 9260 accelerator enabled backup, archive bit processing:<disabled>

10:09:47.699 [9160.8460] <2> bpbrm main: from client rf0020500095523.rfoc.srf: INF - BACKUP 26/11/2015 10:09:47 rf0020500095523.rfoc.srf TESTE_DEDUP06 Full FULL 
10:09:48.042 [9160.8460] <2> set_job_details: Tfile (219313): LOG 1448539788 4 bpbkar32 9260 will attempt to use change journal data for <C:\CAMEL_TOOL>

10:09:48.042 [9160.8460] <2> send_job_file: job ID 219313, ftype = 3 msg len = 91, msg = LOG 1448539788 4 bpbkar32 9260 will attempt to use change journal data for <C:\CAMEL_TOOL>

10:09:53.050 [9160.8460] <2> bpbrm readline: sending keepalive
10:09:58.057 [9160.8460] <2> bpbrm check_for_terminate: exit_status = 83
10:09:58.057 [9160.8460] <2> bpbrm check_for_terminate: child exit abnormal - status = 83
10:09:58.057 [9160.8460] <2> bpbrm kill_child_process_Ex: start
10:09:58.057 [9160.8460] <2> inform_client_of_status: INF - Server status = 83
10:09:58.057 [9160.8460] <2> bpbrm Exit: attempting to send mail to root on rf0020500095523.rfoc.srf
10:09:58.057 [9160.8460] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01020001 connect_opts2 = 0x01020001
10:09:58.057 [9160.8460] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01020001
10:09:58.057 [9160.8460] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 2
10:09:58.057 [9160.8460] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
10:09:58.057 [9160.8460] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4717] Local [strong] check, using interface  ANY
10:09:58.057 [9160.8460] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
10:09:58.057 [9160.8460] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.51207 TO 10.61.12.147.13782 fd = 852
10:09:58.057 [9160.8460] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095523.rfoc.srf 10.61.12.147 bpcd
10:09:58.057 [9160.8460] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.51207 TO 10.61.12.147.13782 fd = 852
10:09:58.354 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:58.354 [9160.8460] <2> bpbrm Exit: OUT_SOCK from bpcr = 852
10:09:58.354 [9160.8460] <2> bpbrm Exit: IN_SOCK from bpcr = 876
10:09:58.354 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:58.354 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:58.401 [9160.8460] <2> bpcr_get_version_rqst: bpcd version: 07610001
10:09:58.448 [9160.8460] <2> set_job_details: Tfile (219313): LOG 1448539798 4 bpbkar32 9260 done. status: 83: media open error

10:09:58.448 [9160.8460] <2> send_job_file: job ID 219313, ftype = 3 msg len = 66, msg = LOG 1448539798 4 bpbkar32 9260 done. status: 83: media open error

10:09:58.448 [9160.8460] <2> bpbrm Exit: client backup EXIT STATUS 83: media open error
10:09:58.463 [9160.8460] <2> vnet_close_socket_safely: [vnet.c:1101] safe close 0 0x0
10:09:58.713 [9160.8460] <2> job_monitoring_exex: ACK disconnect
10:09:58.713 [9160.8460] <2> job_disconnect: Disconnected
10:09:58.713 [9160.8460] <2> BpConfigHolder::~BpConfigHolder: +++ ENTERING +++ : obj = 000000000130E7E8 (../BpConf.cpp:40)
10:09:58.713 [9160.8460] <2> BpConfigHolder::free: +++ ENTERING +++ : obj = 000000000130E7E8 (../BpConf.cpp:61)
10:09:58.713 [9160.8460] <2> BpConfigHolder::free: --- EXITING --- : obj = 000000000130E7E8 (../BpConf.cpp:61)
10:09:58.713 [9160.8460] <2> BpConfigHolder::~BpConfigHolder: --- EXITING --- : obj = 000000000130E7E8 (../BpConf.cpp:40)
10:09:58.728 [9160.8460] <2> NBIORInterceptor::destroy: Destroying Interceptor: _Default_CLIENT_Orb(NBIORInterceptor.cpp:61)
10:09:58.728 [9160.8460] <2> InsecureEndpointSelectorFactory::~InsecureEndpointSelectorFactory: +++ ENTERING +++ : obj = 000000000133EFB0 (../Endpoint_Selector.cpp:2114)
10:09:58.728 [9160.8460] <2> InsecureEndpointSelectorFactory::~InsecureEndpointSelectorFactory: --- EXITING --- : obj = 000000000133EFB0 (../Endpoint_Selector.cpp:2114)
10:09:58.728 [9160.8460] <2> EndpointSelectorFactory::~EndpointSelectorFactory: +++ ENTERING +++ : obj = 000000000133EF10 (../Endpoint_Selector.cpp:2020)
10:09:58.728 [9160.8460] <2> EndpointSelectorFactory::~EndpointSelectorFactory: --- EXITING --- : obj = 000000000133EF10 (../Endpoint_Selector.cpp:2020)
11:00:13.803 [7716.7956] <4> bpbrm main: logging will continue into new log at midnight
11:00:13.834 [7716.7956] <2> bpbrm main: INITIATING (VERBOSE = 5): version NetBackup 7.6.1 2015052215
11:00:13.834 [7716.7956] <2> logparams: -backup -S rf0020500095562.rfoc.srf -c RF0020500096393.rfoc.srf -ct 13 -ru SQLServer -cl MSSQL_ARIS -sched Application -bt 1448542806 -dt 0 -st 2 -b RF0020500096393.rfoc.srf_1448542806 -mediasvr rf0020500095523.rfoc.srf -jobid 219315 -jobgrpid 219315 -masterversion 760000 -maxfrag 51200 -bpstart_time 1448543111 -reqid -1448470236 -mt 0 -to 0 -stunit dedup.stu.nbudedup07 -rl 1 -rp 1209600 -eari 0 -p PureDiskVolume -mst 6 -flags 2 -storagesvr rf0020500095525.rfoc.srf -sts_type PureDisk -use_ofb -use_otm -WOFB_enabled 1 -nbu -jm -secure 1 -kl 28 -rg SQLServer -fso -keyword altiris -connect_options 16974338 
11:00:13.834 [7716.7956] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:802] the socket is  508 0x1fc
11:00:13.834 [7716.7956] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:802] the socket is  504 0x1f8
11:00:13.850 [7716.7956] <2> bprd_connect: Ignoring reserved port request.
11:00:13.850 [7716.7956] <2> bprd_connect: Ignoring local_name rf0020500095523.rfoc.srf.
11:00:13.850 [7716.7956] <2> bprd_connect: Ignoring owner_name SYSTEM.
11:00:13.850 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:13.850 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:13.850 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:13.850 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:13.850 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:13.850 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:13.850 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:13.865 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:13.865 [7716.7956] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bprd CONNECT FROM 10.61.12.147.53276 TO 10.61.12.186.1556 fd = 572
11:00:13.865 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53276 TO 10.61.12.186.1556 fd = 572
11:00:13.865 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bprd VIA pbx
11:00:13.865 [7716.7956] <2> logconnections: BPRD CONNECT FROM 10.61.12.147.53276 TO 10.61.12.186.1556 fd = 572
11:00:13.865 [7716.7956] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:939] Ignoring VxSS authentication 2 0x2
11:00:14.396 [7716.7956] <2> brm_update_local_resiliency: changed = 0
11:00:14.396 [7716.7956] <2> bpbrm main: max_entries_per_add = 5000
11:00:14.396 [7716.7956] <2> verify_client: ../bpbrm.c.42193: *connect_opts: 16974338 16974338 0x01030202
11:00:14.411 [7716.7956] <2> ConnectionCache::connectAndCache: Acquiring new connection for host rf0020500095562.rfoc.srf, query type 223
11:00:14.411 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:14.411 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:14.411 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:14.411 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:14.411 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:14.411 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:14.411 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:14.427 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:14.427 [7716.7956] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpdbm CONNECT FROM 10.61.12.147.53277 TO 10.61.12.186.1556 fd = 572
11:00:14.427 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53277 TO 10.61.12.186.1556 fd = 572
11:00:14.427 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bpdbm VIA pbx
11:00:14.427 [7716.7956] <2> logconnections: BPDBM CONNECT FROM 10.61.12.147.53277 TO 10.61.12.186.1556 fd = 572
11:00:14.427 [7716.7956] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:939] Ignoring VxSS authentication 2 0x2
11:00:14.692 [7716.7956] <2> db_CLIENTsend: reset client protocol version from 0 to 8
11:00:14.708 [7716.7956] <2> db_CLIENTsend: prec->offline=0
11:00:14.708 [7716.7956] <2> db_CLIENTsend: prec->online_at=0
11:00:14.708 [7716.7956] <2> db_CLIENTsend: prec->offline_at=0
11:00:14.708 [7716.7956] <2> db_CLIENTsend: sending 65 bytes
11:00:14.942 [7716.7956] <2> isDONE: reply DONE 227 <?xml version="1.0" encoding="utf-8"?><StatusMsgDoc><StatusMsg NBUStatus="227" Severity="2" VXULoid="271" VXULtid="227"><MsgString>no&#32;entity&#32;was&#32;found</MsgString></StatusMsg></StatusMsgDoc>
11:00:14.973 [7716.7956] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227
11:00:14.973 [7716.7956] <2> ConnectionCache::connectAndCache: Acquiring new connection for host rf0020500095562.rfoc.srf, query type 223
11:00:14.973 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:14.973 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:14.973 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:14.973 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:14.973 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:14.973 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:14.973 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:14.973 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:14.973 [7716.7956] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpdbm CONNECT FROM 10.61.12.147.53278 TO 10.61.12.186.1556 fd = 576
11:00:14.973 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53278 TO 10.61.12.186.1556 fd = 576
11:00:14.973 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bpdbm VIA pbx
11:00:14.973 [7716.7956] <2> logconnections: BPDBM CONNECT FROM 10.61.12.147.53278 TO 10.61.12.186.1556 fd = 576
11:00:14.973 [7716.7956] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:939] Ignoring VxSS authentication 2 0x2
11:00:15.207 [7716.7956] <2> db_CLIENTsend: reset client protocol version from 0 to 8
11:00:15.207 [7716.7956] <2> db_CLIENTsend: prec->offline=0
11:00:15.207 [7716.7956] <2> db_CLIENTsend: prec->online_at=0
11:00:15.207 [7716.7956] <2> db_CLIENTsend: prec->offline_at=0
11:00:15.207 [7716.7956] <2> db_CLIENTsend: sending 56 bytes
11:00:15.441 [7716.7956] <2> isDONE: reply DONE 227 <?xml version="1.0" encoding="utf-8"?><StatusMsgDoc><StatusMsg NBUStatus="227" Severity="2" VXULoid="271" VXULtid="227"><MsgString>no&#32;entity&#32;was&#32;found</MsgString></StatusMsg></StatusMsgDoc>
11:00:15.457 [7716.7956] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227
11:00:15.457 [7716.7956] <2> verify_client: ../bpbrm.c.42259: db_getCLIENT failed for CLIENT: RF0020500096393.rfoc.srf
11:00:15.457 [7716.7956] <2> verify_client: ../bpbrm.c.42429: db_getCLIENT failed: 227 227 0x000000e3
11:00:15.457 [7716.7956] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1645] found via getaddrinfo NAME=RF0020500096393.rfoc.srf SVC=NULL
11:00:15.472 [7716.7956] <2> bpbrm start_bpcd_stat: ../bpbrm.c.23188: bpcd_client: RF0020500096393.rfoc.srf
11:00:15.472 [7716.7956] <2> bpbrm start_bpcd_stat: ../bpbrm.c.23191: bpcd_client_hostname: RF0020500096393.rfoc.srf
11:00:15.488 [7716.7956] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01000100 connect_opts2 = 0x01000100
11:00:15.488 [7716.7956] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01000100
11:00:15.488 [7716.7956] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 0
11:00:15.488 [7716.7956] <2> get_sig_pbx_port: [vnet_addrinfo.c:4323] RegOpenKeyEx failed STAT=2 REGISTRY=Software\VERITAS\VxPBX
11:00:15.488 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:856] using IANA SVC=veritas_pbx PORT=1556
11:00:15.488 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:882] adjusted service name 1556
11:00:15.503 [7716.7956] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1645] found via getaddrinfo NAME=RF0020500096393.rfoc.srf SVC=veritas_pbx
11:00:15.503 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:15.503 [7716.7956] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1645] found via getaddrinfo NAME=10.61.15.249 SVC=NULL
11:00:15.519 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:15.535 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:878] using SVC=vnetd PORT=13724
11:00:15.535 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:882] adjusted service name 13724
11:00:15.535 [7716.7956] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1645] found via getaddrinfo NAME=RF0020500096393.rfoc.srf SVC=vnetd
11:00:15.535 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:15.535 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:15.535 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:878] using SVC=bpcd PORT=13782
11:00:15.535 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:882] adjusted service name 13782
11:00:15.535 [7716.7956] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1645] found via getaddrinfo NAME=RF0020500096393.rfoc.srf SVC=bpcd
11:00:15.535 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:15.535 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:15.535 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:15.581 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:15.581 [7716.7956] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpcd CONNECT FROM 10.61.12.147.53279 TO 10.61.15.249.1556 fd = 576
11:00:15.581 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53279 TO 10.61.15.249.1556 fd = 576
11:00:15.581 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO RF0020500096393.rfoc.srf 10.61.15.249 bpcd VIA pbx
11:00:15.581 [7716.7956] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.53279 TO 10.61.15.249.1556 fd = 576
11:00:15.581 [7716.7956] <2> get_sig_pbx_port: [vnet_addrinfo.c:4323] RegOpenKeyEx failed STAT=2 REGISTRY=Software\VERITAS\VxPBX
11:00:15.581 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:856] using IANA SVC=veritas_pbx PORT=1556
11:00:15.581 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:882] adjusted service name 1556
11:00:15.581 [7716.7956] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1645] found via getaddrinfo NAME=10.61.15.249 SVC=veritas_pbx
11:00:15.597 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:15.597 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:15.597 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:878] using SVC=vnetd PORT=13724
11:00:15.597 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:882] adjusted service name 13724
11:00:15.597 [7716.7956] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1645] found via getaddrinfo NAME=10.61.15.249 SVC=vnetd
11:00:15.613 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:15.613 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:15.613 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:15.628 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:15.737 [7716.7956] <8> do_pbx_service: [vnet_connect.c:2156] via PBX VNETD CONNECT FROM 10.61.12.147.53280 TO 10.61.15.249.1556 fd = 572
11:00:15.737 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53280 TO 10.61.15.249.1556 fd = 572
11:00:15.737 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.61.15.249 10.61.15.249 vnetd VIA pbx
11:00:15.737 [7716.7956] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
11:00:15.956 [7716.7956] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string 49966
11:00:16.159 [7716.7956] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:468] hash_str1 392665b04efe4488464f4a1f88a1f9a1
11:00:17.126 [7716.7956] <2> bpcr_get_version_rqst: bpcd version: 07600000
11:00:17.343 [7716.7956] <2> bpcr_get_hostname_rqst: Server hostname length = 24
11:00:17.343 [7716.7956] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 576
11:00:17.343 [7716.7956] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 572
11:00:17.359 [7716.7956] <2> verify_client: ../bpbrm.c.42520: hostname: rf0020500095523.rfoc.srf
11:00:17.359 [7716.7956] <2> verify_client: ../bpbrm.c.42521: is_on: 0 0 0x00000000
11:00:17.546 [7716.7956] <2> bpcr_get_version_rqst: bpcd version: 07600000
11:00:17.765 [7716.7956] <2> verify_client: ../bpbrm.c.42524: bpcr_update_resiliency : 0 0 0x00000000
11:00:17.936 [7716.7956] <2> verify_client: ../bpbrm.c.42547: *connect_opts: 16843008 16843008 0x01010100
11:00:17.936 [7716.7956] <2> ConnectionCache::connectAndCache: Acquiring new connection for host rf0020500095562.rfoc.srf, query type 223
11:00:17.936 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:17.936 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:17.936 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:17.936 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:17.936 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:17.936 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:17.936 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:17.952 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:17.952 [7716.7956] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpdbm CONNECT FROM 10.61.12.147.53296 TO 10.61.12.186.1556 fd = 572
11:00:17.952 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53296 TO 10.61.12.186.1556 fd = 572
11:00:17.952 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bpdbm VIA pbx
11:00:17.952 [7716.7956] <2> logconnections: BPDBM CONNECT FROM 10.61.12.147.53296 TO 10.61.12.186.1556 fd = 572
11:00:17.952 [7716.7956] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:939] Ignoring VxSS authentication 2 0x2
11:00:18.155 [7716.7956] <2> db_CLIENTsend: reset client protocol version from 0 to 8
11:00:18.155 [7716.7956] <2> db_CLIENTsend: prec->offline=0
11:00:18.155 [7716.7956] <2> db_CLIENTsend: prec->online_at=0
11:00:18.155 [7716.7956] <2> db_CLIENTsend: prec->offline_at=0
11:00:18.155 [7716.7956] <2> db_CLIENTsend: sending 65 bytes
11:00:18.389 [7716.7956] <2> isDONE: reply DONE 227 <?xml version="1.0" encoding="utf-8"?><StatusMsgDoc><StatusMsg NBUStatus="227" Severity="2" VXULoid="271" VXULtid="227"><MsgString>no&#32;entity&#32;was&#32;found</MsgString></StatusMsg></StatusMsgDoc>
11:00:18.389 [7716.7956] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227
11:00:18.389 [7716.7956] <2> ConnectToBPCD: db_getCLIENT(RF0020500096393.rfoc.srf) failed: 227
11:00:18.389 [7716.7956] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01000100 connect_opts2 = 0x01000100
11:00:18.389 [7716.7956] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01000100
11:00:18.389 [7716.7956] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 0
11:00:18.389 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:18.389 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:18.389 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:18.389 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:18.389 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:18.389 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:18.389 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:18.404 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:18.404 [7716.7956] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpcd CONNECT FROM 10.61.12.147.53299 TO 10.61.15.249.1556 fd = 576
11:00:18.404 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53299 TO 10.61.15.249.1556 fd = 576
11:00:18.404 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO RF0020500096393.rfoc.srf 10.61.15.249 bpcd VIA pbx
11:00:18.404 [7716.7956] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.53299 TO 10.61.15.249.1556 fd = 576
11:00:18.404 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:18.404 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:18.404 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:18.404 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:18.404 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:18.420 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:18.529 [7716.7956] <8> do_pbx_service: [vnet_connect.c:2156] via PBX VNETD CONNECT FROM 10.61.12.147.53300 TO 10.61.15.249.1556 fd = 580
11:00:18.529 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53300 TO 10.61.15.249.1556 fd = 580
11:00:18.529 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.61.15.249 10.61.15.249 vnetd VIA pbx
11:00:18.529 [7716.7956] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
11:00:18.732 [7716.7956] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string 49968
11:00:18.935 [7716.7956] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:468] hash_str1 5a4b6f70cfe3f55f29db8e71ae91b99c
11:00:19.777 [7716.7956] <2> bpcr_get_version_rqst: bpcd version: 07600000
11:00:19.777 [7716.7956] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 576
11:00:19.980 [7716.7956] <2> bpcr_get_version_rqst: bpcd version: 07600000
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.417 [7716.7956] <2> local_getHostInfo: hostname comparison failed
11:00:20.619 [7716.7956] <2> bpcr_get_version_rqst: bpcd version: 07600000
11:00:20.619 [7716.7956] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST
11:00:21.025 [7716.7956] <2> bpcr_get_platform_rqst: Server platform length = 7
11:00:21.025 [7716.7956] <2> xlatePlatformName: my version = 760000, client version = 760000, check current list first
11:00:21.025 [7716.7956] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64
11:00:21.025 [7716.7956] <2> local_getHostInfo: platform of client RF0020500096393.rfoc.srf is PC-x64, WindowsXP
11:00:21.243 [7716.7956] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=28, buffer=UNAME_SYSNAME = Windows2008 
11:00:21.243 [7716.7956] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=33, buffer=UNAME_NODENAME = RF0020500096393 
11:00:21.243 [7716.7956] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=18, buffer=UNAME_RELEASE = 6 
11:00:21.243 [7716.7956] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=18, buffer=UNAME_VERSION = 1 
11:00:21.243 [7716.7956] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=52, buffer=UNAME_MACHINE = Unknown - Unknown -  Revision x2f02 
11:00:21.243 [7716.7956] <2> bpcr_get_uname_rqst: bpcr.c: 3678 count=3, buffer=END
11:00:21.243 [7716.7956] <2> local_getHostInfo: d:\nb\7.6.1.2\src\nb\dblib\dbmisc.c: 1740, uname -sr=Windows2008 6 

11:00:21.462 [7716.7956] <2> bpcr_get_version_rqst: bpcd version: 07600000
11:00:21.665 [7716.7956] <2> bpcr_get_cipher_strings_rqst: version_crypt line = <HARDWARE Windows x64>
11:00:21.867 [7716.7956] <2> bpcr_get_cipher_strings_rqst: cipher = <AES-128-CFB>
11:00:21.867 [7716.7956] <2> bpcr_get_cipher_strings_rqst: cipher = <BF-CFB>
11:00:21.867 [7716.7956] <2> bpcr_get_cipher_strings_rqst: cipher = <DES-EDE-CFB>
11:00:21.867 [7716.7956] <2> bpcr_get_cipher_strings_rqst: cipher = <AES-256-CFB>
11:00:21.883 [7716.7956] <2> getCipherList: *pCipherList = <AES-128-CFB,BF-CFB,DES-EDE-CFB,AES-256-CFB>
11:00:21.883 [7716.7956] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 576
11:00:22.086 [7716.7956] <2> getPatchLevel: got client socket
11:00:22.086 [7716.7956] <2> getPatchLevel: file num = 3, file size = 109
11:00:22.086 [7716.7956] <2> bpcr_read_rqst: completed send of file number 3
11:00:22.086 [7716.7956] <2> bpcr_read_rqst: completed send of transfer_count = 16383
11:00:22.086 [7716.7956] <2> bpcr_read_rqst: Before readclientstatus on socket 580
11:00:22.289 [7716.7956] <2> bpcr_read_rqst: Num bytes to be sent by client = 109
11:00:22.289 [7716.7956] <2> getPatchLevel: total chars read = 109
11:00:22.289 [7716.7956] <2> getPatchLevel: level = >7.6.0.1<
11:00:22.710 [7716.7956] <2> bpbrm main: Client NBU version: 760000
11:00:22.959 [7716.7956] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 7716
11:00:22.991 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:22.991 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:22.991 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:22.991 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:22.991 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:878] using SVC=bpjobd PORT=13723
11:00:22.991 [7716.7956] <2> retry_getaddrinfo: [vnet_addrinfo.c:882] adjusted service name 13723
11:00:22.991 [7716.7956] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1645] found via getaddrinfo NAME=rf0020500095562.rfoc.srf SVC=bpjobd
11:00:22.991 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:22.991 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:22.991 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:23.022 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:23.022 [7716.7956] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpjobd CONNECT FROM 10.61.12.147.53306 TO 10.61.12.186.1556 fd = 608
11:00:23.022 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53306 TO 10.61.12.186.1556 fd = 608
11:00:23.022 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bpjobd VIA pbx
11:00:23.022 [7716.7956] <2> job_connect: SO_KEEPALIVE set on socket 608 for client rf0020500095562.rfoc.srf
11:00:23.022 [7716.7956] <2> logconnections: BPJOBD CONNECT FROM 10.61.12.147.53306 TO 10.61.12.186.1556 fd = 608
11:00:23.037 [7716.7956] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
11:00:23.037 [7716.7956] <2> job_connect: Connected to the host rf0020500095562.rfoc.srf contype 53 jobid <219315> socket <608>
11:00:23.037 [7716.7956] <2> job_connect: Connected on port 53306
11:00:23.037 [7716.7956] <2> set_job_details: Tfile (219315): LOG 1448542822 4 bpbrm 7716 RF0020500096393.rfoc.srf is the host to backup data from

11:00:23.037 [7716.7956] <2> send_job_file: job ID 219315, ftype = 3 msg len = 85, msg = LOG 1448542822 4 bpbrm 7716 RF0020500096393.rfoc.srf is the host to backup data from

11:00:23.037 [7716.7956] <2> set_job_details: Tfile (219315): LOG 1448542823 4 bpbrm 7716 reading file list for client

11:00:23.037 [7716.7956] <2> send_job_file: job ID 219315, ftype = 3 msg len = 57, msg = LOG 1448542823 4 bpbrm 7716 reading file list for client

11:00:23.037 [7716.7956] <2> bpbrm main: File read = "/F/Backup/User Databases/LOG"
11:00:23.037 [7716.7956] <2> bpbrm main: File read = "/C/Windows/system32/-w"
11:00:23.037 [7716.7956] <2> bpbrm main: File read = "CONTINUE"
11:00:23.037 [7716.7956] <2> bpbrm main: send_3_0_client_params = 1
11:00:23.037 [7716.7956] <2> bpbrm main: backup_cmd = /usr/openv/netbackup/bin/bpbkar bpbkar32 -r 1209600 -ru SQLServer -dt 0 -to 0 -bpstart_time 1448543111 -clnt RF0020500096393.rfoc.srf -class MSSQL_ARIS -sched Application -st UBAK -bpstart_to 300 -bpend_to 300 -read_to 32767 -rg SQLServer -blks_per_buffer 512 -use_otm -fso -U -IEL -b RF0020500096393.rfoc.srf_1448542806 -kl 28 -WOFB_enabled -WOFB_fim 0 -WOFB_usage 0 -WOFB_error 0 -ct 13
11:00:23.037 [7716.7956] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01000100 connect_opts2 = 0x01000100
11:00:23.037 [7716.7956] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01000100
11:00:23.037 [7716.7956] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 0
11:00:23.037 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:23.037 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:23.037 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:23.053 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:23.053 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:23.053 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:23.053 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:23.069 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:23.069 [7716.7956] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpcd CONNECT FROM 10.61.12.147.53307 TO 10.61.15.249.1556 fd = 620
11:00:23.069 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53307 TO 10.61.15.249.1556 fd = 620
11:00:23.069 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO RF0020500096393.rfoc.srf 10.61.15.249 bpcd VIA pbx
11:00:23.069 [7716.7956] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.53307 TO 10.61.15.249.1556 fd = 620
11:00:23.069 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:23.069 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:23.069 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:23.069 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:23.069 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:23.084 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:23.162 [7716.7956] <8> do_pbx_service: [vnet_connect.c:2156] via PBX VNETD CONNECT FROM 10.61.12.147.53308 TO 10.61.15.249.1556 fd = 624
11:00:23.162 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53308 TO 10.61.15.249.1556 fd = 624
11:00:23.162 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.61.15.249 10.61.15.249 vnetd VIA pbx
11:00:23.162 [7716.7956] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
11:00:23.381 [7716.7956] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string 49970
11:00:23.583 [7716.7956] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:468] hash_str1 3100ccaaac380d8aeea1335922ffa136
11:00:24.426 [7716.7956] <2> bpcr_get_version_rqst: bpcd version: 07600000
11:00:24.644 [7716.7956] <2> bpcr_get_version_rqst: bpcd version: 07600000
11:00:24.847 [7716.7956] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
11:00:25.019 [7716.7956] <2> ConnectionCache::connectAndCache: Acquiring new connection for host rf0020500095562.rfoc.srf, query type 89
11:00:25.019 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:25.034 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:25.034 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:25.034 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:25.034 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:25.034 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:25.034 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:25.034 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:25.034 [7716.7956] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpdbm CONNECT FROM 10.61.12.147.53309 TO 10.61.12.186.1556 fd = 624
11:00:25.034 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53309 TO 10.61.12.186.1556 fd = 624
11:00:25.034 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO rf0020500095562.rfoc.srf 10.61.12.186 bpdbm VIA pbx
11:00:25.034 [7716.7956] <2> logconnections: BPDBM CONNECT FROM 10.61.12.147.53309 TO 10.61.12.186.1556 fd = 624
11:00:25.034 [7716.7956] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:939] Ignoring VxSS authentication 2 0x2
11:00:25.299 [7716.7956] <2> db_end: Need to collect reply
11:00:25.440 [7716.7956] <2> isDONE: reply DONE 0 <?xml version="1.0" encoding="utf-8"?><StatusMsgDoc><StatusMsg NBUStatus="0" Severity="0" VXULoid="271" VXULtid="0"><MsgString>the&#32;requested&#32;operation&#32;was&#32;successfully&#32;completed</MsgString></StatusMsg></StatusMsgDoc>
11:00:25.455 [7716.7956] <2> isDONE: reply DONE 0 
11:00:25.455 [7716.7956] <2> bpbrm start_bpcd_stat: ../bpbrm.c.23188: bpcd_client: RF0020500096393.rfoc.srf
11:00:25.455 [7716.7956] <2> bpbrm start_bpcd_stat: ../bpbrm.c.23191: bpcd_client_hostname: RF0020500096393.rfoc.srf
11:00:25.455 [7716.7956] <2> local_bpcr_connect: bpcr.c.333: connect_opts = 0x01010100 connect_opts2 = 0x01000100
11:00:25.455 [7716.7956] <2> local_bpcr_connect: bpcr.c.340: connect_opts = 0x01010100
11:00:25.455 [7716.7956] <2> local_bpcr_connect: bpcr.c.379: daemon_port_type = 1
11:00:25.455 [7716.7956] <2> conf_update_time: ../../libvlibs/nbconf_glue.cpp.389: RegOpenKeyExA failed: 19 19 0x00000013
11:00:25.455 [7716.7956] <2> getLocalHostConfigImage: Unable to read configuration from SOFTWARE\Veritas\NetBackup\CurrentVersion\Config
11:00:25.455 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:25.455 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:25.455 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:25.455 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:25.455 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:25.455 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:25.455 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:25.471 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:25.471 [7716.7956] <2> do_pbx_service: [vnet_connect.c:2164] via PBX bpcd CONNECT FROM 10.61.12.147.53310 TO 10.61.15.249.1556 fd = 620
11:00:25.471 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53310 TO 10.61.15.249.1556 fd = 620
11:00:25.471 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO RF0020500096393.rfoc.srf 10.61.15.249 bpcd VIA pbx
11:00:25.471 [7716.7956] <2> logconnections: BPCD CONNECT FROM 10.61.12.147.53310 TO 10.61.15.249.1556 fd = 620
11:00:25.471 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:25.471 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:25.471 [7716.7956] <2> vnet_sortaddrs: [vnet_addrinfo.c:3852] sorted addrs: 1 0x1
11:00:25.471 [7716.7956] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4750] using interface  ANY
11:00:25.471 [7716.7956] <2> async_connect: [vnet_connect.c:1499] connect in progress 1 0x1
11:00:25.487 [7716.7956] <2> vnet_pbxConnect: pbxConnectEx Succeeded
11:00:25.580 [7716.7956] <8> do_pbx_service: [vnet_connect.c:2156] via PBX VNETD CONNECT FROM 10.61.12.147.53311 TO 10.61.15.249.1556 fd = 624
11:00:25.580 [7716.7956] <2> async_connect: [vnet_connect.c:1691] connect async CONNECT FROM 10.61.12.147.53311 TO 10.61.15.249.1556 fd = 624
11:00:25.580 [7716.7956] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO 10.61.15.249 10.61.15.249 vnetd VIA pbx
11:00:25.580 [7716.7956] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
11:00:25.783 [7716.7956] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string 49972
11:00:25.986 [7716.7956] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:468] hash_str1 249c022622e188875d73acfedc5ccf7c
11:00:26.797 [7716.7956] <2> bpcr_get_version_rqst: bpcd version: 07600000