11:50:53.611 [3876.3724] <4> bpbrm main: logging will continue into new log at midnight 11:50:53.611 [3876.3724] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.7.2 2016011118 11:50:53.611 [3876.3724] <2> logparams: -backup -S capbkup0001 -c CAPQMM0001 -ct 40 -ru root -cl sgnt-bscap-qmm-prd-win-vm-os -sched Daily-incr -bt 1558756243 -dt 114948 -st 1 -b CAPQMM0001_1558756243 -mediasvr capbkup0001 -jobid 194158 -jobgrpid 194157 -masterversion 771000 -maxfrag 524288 -bpstart_time 1558756553 -reqid -1558682609 -mt 0 -to 0 -stunit FTSDP1-stu -rl 0 -rp 604800 -eari 0 -p _PhysicalLSU -mst 6 -flags 0 -storagesvr CS800STRSVR1_capbkup0002-bk -sts_type FTS -block_incr -prev_bli_incr 1558641605 -last_bli_full 1558173608 -num_related_classes 1 -use_ofb -use_otm -fi -fim NONE -vm_type 2 -vm_proxy capbkup0001 -nbu -vm_proxy capbkup0001 -jm -secure 1 -kl 40 -rg root -fso -ifrfr -fscp -connect_options 16974338 11:50:53.611 [3876.3724] <2> ReadConfigReg: Unexpected data type (11) in registry, Entry (VXMS_VERBOSE) ignored. 11:50:53.611 [3876.3724] <2> ReadConfigReg: Unexpected data type (11) in registry, Entry (VDDK_VERBOSE) ignored. 11:50:53.611 [3876.3724] <2> bpbrm main: DBG - Snapshot method [4] - NONE 11:50:53.611 [3876.3724] <2> bpbrm main: max_entries_per_add = 95000 11:50:53.611 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 81 11:50:53.627 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59540 TO 10.225.53.20.13721 fd = 572 11:50:58.690 [3876.3724] <2> db_end: Need to collect reply 11:50:58.721 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 89 11:50:58.737 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59543 TO 10.225.53.20.13721 fd = 572 11:51:03.299 [3876.3724] <2> db_end: Need to collect reply 11:51:03.799 [3876.3724] <2> logconnections: BPCD CONNECT FROM 10.225.53.20.59544 TO 10.225.53.20.13782 fd = 572 11:51:08.364 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:08.364 [3876.3724] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 572 11:51:08.364 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:08.395 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:08.395 [3876.3724] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST 11:51:08.395 [3876.3724] <2> bpcr_get_platform_rqst: Server platform length = 7 11:51:08.395 [3876.3724] <2> xlatePlatformName: my version = 770000, client version = 770000, check current list first 11:51:08.395 [3876.3724] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64 11:51:08.395 [3876.3724] <2> local_getHostInfo: platform of client capbkup0001 is PC-x64, WindowsXP 11:51:08.395 [3876.3724] <2> bpcr_get_uname_rqst: bpcr.c: 3461 count=28, buffer=UNAME_SYSNAME = Windows2012 11:51:08.395 [3876.3724] <2> bpcr_get_uname_rqst: bpcr.c: 3461 count=29, buffer=UNAME_NODENAME = CAPBKUP0001 11:51:08.395 [3876.3724] <2> bpcr_get_uname_rqst: bpcr.c: 3461 count=18, buffer=UNAME_RELEASE = 6 11:51:08.395 [3876.3724] <2> bpcr_get_uname_rqst: bpcr.c: 3461 count=18, buffer=UNAME_VERSION = 2 11:51:08.395 [3876.3724] <2> bpcr_get_uname_rqst: bpcr.c: 3461 count=52, buffer=UNAME_MACHINE = Unknown - Unknown - Revision x3f02 11:51:08.395 [3876.3724] <2> bpcr_get_uname_rqst: bpcr.c: 3461 count=3, buffer=END 11:51:08.395 [3876.3724] <2> local_getHostInfo: d:\nb\7.7.2\src\nb\dblib\dbmisc.c: 1744, uname -sr=Windows2012 6 11:51:08.395 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:08.395 [3876.3724] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 572 11:51:08.395 [3876.3724] <2> getPatchLevel: got client socket 11:51:08.395 [3876.3724] <2> getPatchLevel: file num = 5, file size = 111 11:51:08.395 [3876.3724] <2> bpcr_read_rqst: completed send of file number 5 11:51:08.395 [3876.3724] <2> bpcr_read_rqst: completed send of transfer_count = 16383 11:51:08.395 [3876.3724] <2> bpcr_read_rqst: Before readclientstatus on socket 612 11:51:08.395 [3876.3724] <2> bpcr_read_rqst: Num bytes to be sent by client = 111 11:51:08.395 [3876.3724] <2> getPatchLevel: total chars read = 111 11:51:08.395 [3876.3724] <2> getPatchLevel: level = >7.7.2< 11:51:08.395 [3876.3724] <2> verify_client: d:\nb\7.7.2\src\nb\brm\bpbrm.c.42482: db_getCLIENT failed for CLIENT_HOSTNAME: capbkup0001 11:51:08.395 [3876.3724] <2> verify_client: d:\nb\7.7.2\src\nb\brm\bpbrm.c.42649: db_getCLIENT failed: 227 227 0x000000e3 11:51:08.395 [3876.3724] <2> local_getHostInfo: found TSS cached host capbkup0001 type 67 11:51:08.395 [3876.3724] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 3876 11:51:08.411 [3876.3724] <2> job_connect: SO_KEEPALIVE set on socket 644 for client capbkup0001 11:51:08.411 [3876.3724] <2> logconnections: BPJOBD CONNECT FROM 10.225.53.20.59546 TO 10.225.53.20.13723 fd = 644 11:51:08.411 [3876.3724] <2> job_authenticate_connection: VxSS authentication check for now... 11:51:12.898 [3876.3724] <2> job_connect: Connected to the host capbkup0001 contype 53 jobid <194158> socket <644> 11:51:12.898 [3876.3724] <2> job_connect: Connected on port 59546 11:51:12.898 [3876.3724] <2> bpbrm main: send_3_0_client_params = 1 11:51:12.898 [3876.3724] <2> bpbrm main: backup_cmd = /usr/openv/netbackup/bin/bpbkar bpbkar32 -r 604800 -ru root -dt 114948 -to 0 -bpstart_time 1558756553 -clnt CAPQMM0001 -class sgnt-bscap-qmm-prd-win-vm-os -sched Daily-incr -st INCR -bpstart_to 300 -bpend_to 300 -read_to 3600 -blks_per_buffer 512 -use_otm -fso -ifr -pid 3876 -mediasvr capbkup0001 -bt 1558756243 -t 1 -b CAPQMM0001_1558756243 -kl 40 -fi -S capbkup0001 -fim NONE -ct 40 11:51:12.898 [3876.3724] <2> setup_accelerator: performing regular backup, client:, hostname: 11:51:12.898 [3876.3724] <2> logconnections: BPCD CONNECT FROM 10.225.53.20.59548 TO 10.225.53.20.13782 fd = 648 11:51:17.508 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:17.508 [3876.3724] <2> ddns_check: Entering 11:51:17.508 [3876.3724] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 648 11:51:17.508 [3876.3724] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 656 11:51:17.508 [3876.3724] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:824) 11:51:17.508 [3876.3724] <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:1031) 11:51:17.524 [3876.3724] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1045) 11:51:22.930 [3876.3724] <4> MmDeviceMappings::GetRobotAttributes 11:51:22.930 [3876.3724] <4> MmDeviceMappings::GetRobotAttributes 11:51:22.930 [3876.3724] <2> nb_accl_sts_support_check: accelerator support code is 1 11:51:22.930 [3876.3724] <2> nb_accl_validate: accelerator enabled 11:51:22.930 [3876.3724] <2> setup_accelerator: get last backup id, client:, hostname:, backup_client:, backup_client_hostname:, media server:, media_server_hostname: 11:51:22.945 [3876.3724] <2> logconnections: BPCD CONNECT FROM 10.225.53.20.59553 TO 10.225.53.20.13782 fd = 900 11:51:27.495 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:27.495 [3876.3724] <2> ddns_check: Entering 11:51:27.495 [3876.3724] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 900 11:51:27.495 [3876.3724] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 912 11:51:27.495 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts load_plugins() 19/05/25 11:51:27: opening plugin directory D:\Program Files\Veritas\NetBackup\bin\ 11:51:27.495 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:27: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspibasicdisk.dll 11:51:27.495 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:27: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspicp.dll 11:51:27.495 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:27: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspinbostpxy.dll 11:51:27.495 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:27: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspinearstore.dll 11:51:27.495 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:27: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspiproxy.dll 11:51:27.495 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:27: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspishmem.dll 11:51:27.495 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts load_plugins() 19/05/25 11:51:27: opening plugin directory D:\Program Files\Veritas\NetBackup\bin\\ost-plugins 11:51:27.495 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:27: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiamazon.dll 11:51:27.495 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:27: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiatt.dll 11:51:28.167 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiazure.dll 11:51:28.167 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiemc_celerra.dll 11:51:28.167 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiencrypt.dll 11:51:28.167 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiFTS.dll 11:51:28.167 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspigateway.dll 11:51:28.167 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspimetering.dll 11:51:28.183 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspipd.dll 11:51:28.183 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspirackspace.dll 11:51:28.183 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspisoapwrapper.dll 11:51:28.183 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspithrottling.dll 11:51:28.198 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: libsts openp() 19/05/25 11:51:28: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspizapi.dll 11:51:28.198 [3876.3724] <4> 194158:bpbrm:3876:capbkup0001: libsts setupp() 19/05/25 11:51:28: FTS:CS800STRSVR1_capbkup0002-bk claimed by D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiFTS.dll 11:51:28.198 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ENTER pgn_get_server_prop_byname: serverName=FTS:CS800STRSVR1_capbkup0002-bk 11:51:28.198 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Server Name FTS:CS800STRSVR1_capbkup0002-bk. 11:51:28.198 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ostCreateServerHandle:338 Establishing new XCOMM-MI connection to host capbkup0002-bk:10002 11:51:28.198 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ostCreateServerHandle:346 Successfully created XCOMM-MI connection to host capbkup0002-bk:10002 11:51:28.198 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Using pgn_min_version = 9 pgn_max_version = 11 pgn_operating_version = 0x6000b, protocol=6 11:51:28.198 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Got server operating_version = 11, protocol = 6, server_version = 6, ostIOPath = ACCENT 11:51:28.198 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: EXIT pgn_get_server_prop_byname: (OK:0) serverName=FTS:CS800STRSVR1_capbkup0002-bk 11:51:28.198 [3876.3724] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:824) 11:51:28.198 [3876.3724] <2> Orb::init: initializing ORB EMMlib_Orb with: [libstsemm] [-ORBSvcConfDirective] [-ORBDottedDecimalAddresses 0] [-ORBSvcConfDirective] [static Resource_Factory '-ORBNativeCharCodeSet UTF-8'] [-ORBSvcConfDirective] [static PBXIOP_Factory '-enable_keepalive'] [-ORBSvcConfDirective] [static EndpointSelectorFactory ''] [-ORBSvcConfDirective] [static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'] [-ORBSvcConfDirective] [static Resource_Factory '-ORBProtocolFactory IIOP_Factory'] [-ORBDefaultInitRef] [] [-ORBSvcConfDirective] [static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'] [-ORBSvcConfDirective] [static Resource_Factory '-ORBConnectionCacheMax 1024'] [-ORBSvcConf] [nul] [-ORBSvcConfDirective] [static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'](Orb.cpp:1031) 11:51:28.198 [3876.3724] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1045) 11:51:28.198 [3876.3724] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1396) 11:51:28.198 [3876.3724] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1405) 11:51:32.714 [3876.3724] <4> 194158:bpbrm:3876:capbkup0001: libsts setupp() 19/05/25 11:51:32: FTS:CS800STRSVR1_capbkup0002-bk claimed by D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiFTS.dll 11:51:32.714 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ENTER pgn_open_server: sts_server_name=FTS:CS800STRSVR1_capbkup0002-bk, source_sh=0000000000000000 11:51:32.714 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Server Name FTS:CS800STRSVR1_capbkup0002-bk. 11:51:32.714 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ostCreateServerHandle:338 Establishing new XCOMM-MI connection to host capbkup0002-bk:10002 11:51:32.730 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ostCreateServerHandle:346 Successfully created XCOMM-MI connection to host capbkup0002-bk:10002 11:51:32.730 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Using pgn_min_version = 9 pgn_max_version = 11 pgn_operating_version = 0x6000b, protocol=6 11:51:32.730 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Got server operating_version = 11, protocol = 6, server_version = 6, ostIOPath = ACCENT 11:51:32.730 [3876.3724] <4> 194158:bpbrm:3876:capbkup0001: Admin alerts enabled: ver=6 11:51:32.730 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: certificate length = 16 11:51:32.730 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Authenticating username = nbuost1 11:51:32.730 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Authentication is successful. 11:51:32.730 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: EXIT pgn_open_server: (OK:0) sts_server_name=FTS:CS800STRSVR1_capbkup0002-bk, sh=0000000003370080 11:51:32.730 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 132 11:51:32.745 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59564 TO 10.225.53.20.13721 fd = 1320 11:51:37.761 [3876.3724] <2> nb_accl_get_backup_in_one_dir: got image information using cached connection, status:<0> 11:51:37.761 [3876.3724] <2> nb_accl_get_backup_in_one_dir: client:, client:, expiration:<1559246523>, copies:<1>, fragments:<2> 11:51:37.761 [3876.3724] <4> nb_accl_get_backup_in_one_dir: media_desc=1;FTS;CS800STRSVR1_capbkup0002-bk;FTSDP1;_PhysicalLSU;0, stu=CS800STRSVR1_capbkup0002-bk 11:51:37.761 [3876.3724] <2> nb_accl_image_exist_on_sts: image_name is (CAPQMM0001_1558641723_C1_TIR), lsuname is (_PhysicalLSU) 11:51:37.761 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ENTER pgn_open_image: lsu=_PhysicalLSU, sh=0000000003370080, basename=CAPQMM0001_1558641723_C1_TIR, date=1558641723, mode=1 11:51:37.761 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ENTER pgn_get_image_prop_byname: lsu=_PhysicalLSU, sh=0000000003370080, basename=CAPQMM0001_1558641723_C1_TIR, date=1558641723 11:51:37.761 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: EXIT pgn_get_image_prop_byname: (OK:0) lsu=_PhysicalLSU, sh=0000000003370080, basename=CAPQMM0001_1558641723_C1_TIR, date=1558641723 11:51:37.761 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Requesting OST_OPEN_IMAGE_V3 with ACCENT transport, request_flags = OST_IMG_FLAG_HYBRID | OST_IMG_FLAG_ENCRYPTION | OST_IMG_FLAG_DM_ENCRYPTION | OST_IMG_FLAG_DM_FALLBACK and stsmode = STS_O_READ 11:51:37.761 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: OST_OPEN_IMAGE_V3 reply flags = UNKNOWN (0x1e00) 11:51:37.761 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Dropping back to non Accent mode 11:51:37.761 [3876.3724] <4> 194158:bpbrm:3876:capbkup0001: Reads using DM transport. 11:51:37.761 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: pgn_open_image:1822 Opening DM session to host capbkup0002-bk, encryption_required=0 11:51:37.761 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ostDmSession:342 Establishing new connection to host capbkup0002-bk:3095 11:51:37.792 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ostDmSession:362 Successfully created connection to host capbkup0002-bk:3095 11:51:37.792 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: open image:CS800STRSVR1_capbkup0002-bk/_PhysicalLSU/CAPQMM0001_1558641723_C1_TIR_1558641723.img in read mode, io_path=2 11:51:37.792 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: call dm_open(CS800STRSVR1_capbkup0002-bk/_PhysicalLSU/CAPQMM0001_1558641723_C1_TIR_1558641723.img) 11:51:37.792 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Successfully opened image file CS800STRSVR1_capbkup0002-bk/_PhysicalLSU/CAPQMM0001_1558641723_C1_TIR_1558641723.img 11:51:37.792 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: EXIT pgn_open_image: (OK:0) lsu=_PhysicalLSU, sh=0000000003370080, basename=CAPQMM0001_1558641723_C1_TIR, date=1558641723, mode=1, imageHandle=0000000003370AD0 11:51:37.792 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ENTER pgn_close_image: ih=0000000003370AD0, completeFlag=0x0 11:51:37.792 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: pgn_close_image: CAPQMM0001_1558641723_C1_TIR_1558641723.img 11:51:37.823 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: EXIT pgn_close_image: (OK:0) ih=0000000000000000 11:51:37.823 [3876.3724] <2> nb_accl_get_backup_in_one_dir: got image information using cached connection, status:<0> 11:51:37.823 [3876.3724] <2> nb_accl_get_backup_in_one_dir: client:, client:, expiration:<1560592952>, copies:<1>, fragments:<2> 11:51:37.823 [3876.3724] <4> nb_accl_get_backup_in_one_dir: media_desc=1;FTS;CS800STRSVR1_capbkup0002-bk;FTSDP1;_PhysicalLSU;0, stu=CS800STRSVR1_capbkup0002-bk 11:51:37.823 [3876.3724] <2> nb_accl_image_exist_on_sts: image_name is (CAPQMM0001_1558173752_C1_TIR), lsuname is (_PhysicalLSU) 11:51:37.823 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ENTER pgn_open_image: lsu=_PhysicalLSU, sh=0000000003370080, basename=CAPQMM0001_1558173752_C1_TIR, date=1558173752, mode=1 11:51:37.823 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ENTER pgn_get_image_prop_byname: lsu=_PhysicalLSU, sh=0000000003370080, basename=CAPQMM0001_1558173752_C1_TIR, date=1558173752 11:51:37.839 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: EXIT pgn_get_image_prop_byname: (OK:0) lsu=_PhysicalLSU, sh=0000000003370080, basename=CAPQMM0001_1558173752_C1_TIR, date=1558173752 11:51:37.839 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Requesting OST_OPEN_IMAGE_V3 with ACCENT transport, request_flags = OST_IMG_FLAG_HYBRID | OST_IMG_FLAG_ENCRYPTION | OST_IMG_FLAG_DM_ENCRYPTION | OST_IMG_FLAG_DM_FALLBACK and stsmode = STS_O_READ 11:51:37.839 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: OST_OPEN_IMAGE_V3 reply flags = UNKNOWN (0x1e00) 11:51:37.839 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Dropping back to non Accent mode 11:51:37.839 [3876.3724] <4> 194158:bpbrm:3876:capbkup0001: Reads using DM transport. 11:51:37.839 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: pgn_open_image:1822 Opening DM session to host capbkup0002-bk, encryption_required=0 11:51:37.839 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ostDmSession:336 connection already established to host capbkup0002-bk 11:51:37.839 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: open image:CS800STRSVR1_capbkup0002-bk/_PhysicalLSU/CAPQMM0001_1558173752_C1_TIR_1558173752.img in read mode, io_path=2 11:51:37.839 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: call dm_open(CS800STRSVR1_capbkup0002-bk/_PhysicalLSU/CAPQMM0001_1558173752_C1_TIR_1558173752.img) 11:51:37.855 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Successfully opened image file CS800STRSVR1_capbkup0002-bk/_PhysicalLSU/CAPQMM0001_1558173752_C1_TIR_1558173752.img 11:51:37.855 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: EXIT pgn_open_image: (OK:0) lsu=_PhysicalLSU, sh=0000000003370080, basename=CAPQMM0001_1558173752_C1_TIR, date=1558173752, mode=1, imageHandle=0000000003370AD0 11:51:37.855 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ENTER pgn_close_image: ih=0000000003370AD0, completeFlag=0x0 11:51:37.855 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: pgn_close_image: CAPQMM0001_1558173752_C1_TIR_1558173752.img 11:51:37.870 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: EXIT pgn_close_image: (OK:0) ih=0000000000000000 11:51:37.870 [3876.3724] <4> nb_accl_get_backup_in_one_dir: CAPQMM0001_1558641723 is added, max_files_per_cache=50000 11:51:37.870 [3876.3724] <4> nb_accl_get_backup_in_one_dir: CAPQMM0001_1558173752 is added 11:51:37.870 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: ENTER pgn_close_server: sh=0000000003370080 11:51:37.870 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: Sending CLOSE_SERVER. 11:51:37.870 [3876.3724] <2> 194158:bpbrm:3876:capbkup0001: EXIT pgn_close_server: (OK:0) 11:51:37.870 [3876.3724] <4> nb_accl_get_last_backup_byname: get available backup ids from client success. backup_ids=CAPQMM0001_1558641723 11:51:37.870 [3876.3724] <4> nb_accl_get_last_backup_byname: END: client CAPQMM0001, client_hostname CAPQMM0001, last_backup_ids CAPQMM0001_1558641723,CAPQMM0001_1558173752,. 11:51:37.870 [3876.3724] <4> nb_accl_get_last_backup: client=CAPQMM0001, last_backup_ids is 'CAPQMM0001_1558641723,CAPQMM0001_1558173752' 11:51:37.886 [3876.3724] <2> logconnections: BPCD CONNECT FROM 10.225.53.20.59566 TO 10.225.53.20.13782 fd = 912 11:51:42.439 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:42.439 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:42.455 [3876.3724] <2> logconnections: BPCD CONNECT FROM 10.225.53.20.59570 TO 10.225.53.20.13782 fd = 900 11:51:47.002 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:47.002 [3876.3724] <2> ddns_check: Entering 11:51:47.002 [3876.3724] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 900 11:51:47.002 [3876.3724] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 1304 11:51:47.002 [3876.3724] <4> bpcr_put_client_backup_id: bpcd got status :ok 11:51:47.002 [3876.3724] <2> nb_accl_set_verified_last_backup_ids: set verified last backup ids : bid@sgnt-bscap-qmm-prd-win-vm-os_CAPQMM0001_1558756243 11:51:47.002 [3876.3724] <2> setup_accelerator: previous_backup_ids: 11:51:47.017 [3876.3724] <2> db_end: Need to collect reply 11:51:47.064 [3876.3724] <2> logconnections: BPCD CONNECT FROM 10.225.53.20.59572 TO 10.225.53.20.13782 fd = 1264 11:51:51.658 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:51.658 [3876.3724] <2> ddns_check: Entering 11:51:51.658 [3876.3724] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 1264 11:51:51.658 [3876.3724] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 1316 11:51:51.658 [3876.3724] <2> bpbrm main: Going to execute cmd on host 11:51:51.658 [3876.3724] <2> bpbrm main: received bpcd success message 11:51:52.299 [3876.3724] <2> get_readline_type: Returning BPBRM_READ_INFORM_WHEN_DONE 11:51:52.299 [3876.3724] <2> bpbrm main: client_pid=9876 11:51:52.299 [3876.3724] <2> bpbrm main: from client CAPQMM0001: read client start message 11:51:52.299 [3876.3724] <2> bpbrm main: save last backup ids to media server 11:51:52.299 [3876.3724] <2> logconnections: BPCD CONNECT FROM 10.225.53.20.59574 TO 10.225.53.20.13782 fd = 916 11:51:56.908 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:51:56.908 [3876.3724] <2> ddns_check: Entering 11:51:56.908 [3876.3724] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 916 11:51:56.908 [3876.3724] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 1276 11:51:56.908 [3876.3724] <4> bpcr_put_client_backup_id: bpcd got status :ok 11:51:56.908 [3876.3724] <2> bpbrm spawn_child: "D:\Program Files\Veritas\NetBackup\bin\bptm.exe" -w -pid 3876 -c CAPQMM0001 -dpath _PhysicalLSU -stunit FTSDP1-stu -cl sgnt-bscap-qmm-prd-win-vm-os -bt 1558756243 -b CAPQMM0001_1558756243 -st 1 -cj 1 -reqid -1558682609 -vm 2 -jm -brm -hostname CAPQMM0001 -ru root -rclnt CAPQMM0001 -rclnthostname CAPQMM0001 -rl 0 -rp 604800 -sl Daily-incr -ct 40 -maxfrag 524288 -eari 0 -mediasvr capbkup0001 -ifr -nonrsvdports -connect_options 0x01020001 -jobid 194158 -jobgrpid 194157 -masterversion 771000 -fscp -storagesvr CS800STRSVR1_capbkup0002-bk -bidlist bid@sgnt-bscap-qmm-prd-win-vm-os_CAPQMM0001_1558756243 -bpbrm_shm_id Global\NetBackup_BPBRM_SHM_Path_74044828_3876_3724 -blks_per_buffer 512 -shm 11:51:56.908 [3876.3724] <2> bpbrm create_mm_terminate: created terminate event pid 2908 11:51:56.908 [3876.3724] <2> bpbrm create_mm_suspend: created suspend event pid 2908 11:51:56.908 [3876.3724] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <1264> 11:51:56.908 [3876.3724] <2> get_cached_client_version: Using cached version. CLNT=capbkup0001 V=124911616 Fetched=Sat May 25 11:51:56 2019 11:51:56.908 [3876.3724] <2> write_file_names: buffering file name 'FIS_VM_BI_ATTRS_FILE=\online_util\fi_cntl\bpfis.fim.CAPQMM0001_1558756130.1.0.NBU_DATA.xml' for output 11:51:56.908 [3876.3724] <2> write_file_names: buffering file name 'BACKUP \\.\D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.CAPQMM0001_1558756130.1.0.NBU_DATA.xml USING \\.\C:\ OPTIONS:FITYPE=MIRROR,FSTYPE=NTFS' for output 11:51:56.908 [3876.3724] <2> write_file_names: successfully wrote buffer to COMM_SOCK 11:51:56.908 [3876.3724] <2> bpbrm main: wrote CONTINUE on COMM_SOCK 11:51:56.908 [3876.3724] <2> bpbrm main: Value of PFI = 0 11:51:56.908 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - BACKUP 5/25/2019 11:51:52 AM CAPQMM0001 sgnt-bscap-qmm-prd-win-vm-os Daily-incr INCR 5/24/2019 2:56:04 AM 11:52:25.376 [3876.3724] <2> bpbrm main: ESTIMATE -1 -1 CAPQMM0001_1558756243 11:52:25.423 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Backing up vCenter server 172.31.6.14, ESX host capvmh0006.apac.bridgestone.com, BIOS UUID 420d7820-c33b-8757-fc80-465ead4cdeb4, Instance UUID 500d5147-c167-bdde-94fe-83d4818560c7, Display Name CAPQMM0001, Hostname capqmm0001 11:52:39.098 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Snapshot mapping type: ON 11:52:39.098 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Snapshot BLIB type: ON 11:52:47.634 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Found 4 virtual volume(s) 11:52:48.985 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog C: 11:52:48.985 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog C: 11:52:49.016 [3876.3724] <2> bpbrm main: start to write catalog 11:52:49.016 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:52:49.031 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59636 TO 10.225.53.20.13721 fd = 1348 11:52:53.597 [3876.3724] <2> db_end: Need to collect reply 11:52:54.065 [3876.3724] <2> bpbrm main: end write catalog 11:52:54.081 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog C: 11:52:54.081 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog C: 11:52:54.081 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog C: 11:52:54.081 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog D: 11:52:54.081 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog D: 11:52:54.081 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog D: 11:52:54.081 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog D: 11:52:54.081 [3876.3724] <2> bpbrm main: start to write catalog 11:52:54.081 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:52:54.097 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59646 TO 10.225.53.20.13721 fd = 1348 11:52:58.647 [3876.3724] <2> db_end: Need to collect reply 11:52:59.147 [3876.3724] <2> bpbrm main: end write catalog 11:52:59.147 [3876.3724] <2> bpbrm main: start to write catalog 11:52:59.147 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:52:59.179 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59660 TO 10.225.53.20.13721 fd = 1348 11:53:03.726 [3876.3724] <2> db_end: Need to collect reply 11:53:04.194 [3876.3724] <2> bpbrm main: end write catalog 11:53:04.194 [3876.3724] <2> bpbrm mm_sig: received ready signal from media manager 11:53:04.194 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog D: 11:53:04.194 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog E: 11:53:04.194 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog E: 11:53:04.194 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog E: 11:53:04.194 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog E: 11:53:04.194 [3876.3724] <2> bpbrm main: start to write catalog 11:53:04.194 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:53:04.210 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59664 TO 10.225.53.20.13721 fd = 1348 11:53:08.804 [3876.3724] <2> db_end: Need to collect reply 11:53:09.272 [3876.3724] <2> bpbrm main: end write catalog 11:53:09.272 [3876.3724] <2> bpbrm main: start to write catalog 11:53:09.272 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:53:09.288 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59665 TO 10.225.53.20.13721 fd = 1348 11:53:13.882 [3876.3724] <2> db_end: Need to collect reply 11:53:14.335 [3876.3724] <2> bpbrm main: end write catalog 11:53:14.335 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog E: 11:53:14.335 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog Volume{cc172791-b248-11e5-80b4-806e6f6e6963} 11:53:14.335 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog VOLUME{CC172791-B248-11E5-80B4-806E6F6E6963} 11:53:14.335 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog VOLUME{CC172791-B248-11E5-80B4-806E6F6E6963} 11:53:14.335 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog VOLUME{CC172791-B248-11E5-80B4-806E6F6E6963} 11:53:14.335 [3876.3724] <2> bpbrm main: start to write catalog 11:53:14.335 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:53:14.351 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59667 TO 10.225.53.20.13721 fd = 1348 11:53:18.947 [3876.3724] <2> db_end: Need to collect reply 11:53:19.400 [3876.3724] <2> bpbrm main: end write catalog 11:53:19.400 [3876.3724] <2> bpbrm main: start to write catalog 11:53:19.400 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:53:19.416 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59669 TO 10.225.53.20.13721 fd = 1348 11:53:23.997 [3876.3724] <2> db_end: Need to collect reply 11:53:24.450 [3876.3724] <2> bpbrm main: end write catalog 11:53:24.450 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog VOLUME{CC172791-B248-11E5-80B4-806E6F6E6963} 11:53:24.450 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Virtual machine backup host: capbkup0001 11:53:24.450 [3876.3724] <2> bpbrm main: start to write catalog 11:53:24.450 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:53:24.466 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59671 TO 10.225.53.20.13721 fd = 1348 11:53:29.049 [3876.3724] <2> db_end: Need to collect reply 11:53:29.502 [3876.3724] <2> bpbrm main: end write catalog 11:53:29.502 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Transport Type = nbd 11:53:29.502 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog VIRTUAL_FILE 11:53:29.502 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog VIRTUAL_FILE 11:53:29.502 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog VMFILE0_1558756243 11:55:09.594 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog VMFILE1_1558756243 11:55:11.047 [3876.3724] <2> bpbrm main: from client CAPQMM0001: INF - Files System Name to catalog VMFILE2_1558756243 11:55:13.143 [3876.3724] <2> bpbrm main: start to write catalog 11:55:13.143 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:55:13.159 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59692 TO 10.225.53.20.13721 fd = 1848 11:55:17.710 [3876.3724] <2> db_end: Need to collect reply 11:55:18.163 [3876.3724] <2> bpbrm main: end write catalog 11:55:18.163 [3876.3724] <2> bpbrm main: start to write catalog 11:55:18.163 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:55:18.179 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59694 TO 10.225.53.20.13721 fd = 1848 11:55:22.761 [3876.3724] <2> db_end: Need to collect reply 11:55:23.245 [3876.3724] <2> bpbrm main: end write catalog 11:55:23.245 [3876.3724] <2> bpbrm main: start to write catalog 11:55:23.245 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:55:23.261 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59695 TO 10.225.53.20.13721 fd = 1848 11:55:27.886 [3876.3724] <2> db_end: Need to collect reply 11:55:28.339 [3876.3724] <2> bpbrm main: end write catalog 11:55:28.339 [3876.3724] <2> bpbrm send_event_optimization_to_monitor: read accl optimization (rate = 99.2) as: 99 11:55:28.339 [3876.3724] <2> get_cached_client_version: Using cached version. CLNT=capbkup0001 V=124911616 Fetched=Sat May 25 11:55:28 2019 11:55:28.339 [3876.3724] <2> bpbrm send_event_optimization_to_monitor: OPTIMIZATION_RATE = 99 11:55:28.339 [3876.3724] <2> persist_accelerator_bytes_transferred: read accl bytes_transferred as 484348928 and computed kilobytes_data_transferred as: 472997 11:55:28.339 [3876.3724] <2> persist_accelerator_bytes_transferred: KILOBYTES_DATA_TRANSFERRED = 472997 11:55:28.339 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 89 11:55:28.355 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59699 TO 10.225.53.20.13721 fd = 1848 11:55:32.902 [3876.3724] <2> db_end: Need to collect reply 11:55:33.355 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 78 11:55:33.370 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59708 TO 10.225.53.20.13721 fd = 1848 11:55:37.966 [3876.3724] <2> db_end: Need to collect reply 11:55:38.420 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 62 11:55:38.435 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59712 TO 10.225.53.20.13721 fd = 1848 11:55:43.029 [3876.3724] <2> db_end: Need to collect reply 11:55:43.482 [3876.3724] <2> bpbrm wait_for_child: start 11:56:35.382 [3876.3724] <2> bpbrm wait_for_child: child exit_status = 0 11:56:35.398 [3876.3724] <2> bpbrm wait_for_child: child exit normal 11:56:35.398 [3876.3724] <2> bpbrm main: validating image for client CAPQMM0001 11:56:35.398 [3876.3724] <2> ConnectionCache::connectAndCache: Acquiring new connection for host capbkup0001, query type 74 11:56:35.414 [3876.3724] <2> logconnections: BPDBM CONNECT FROM 10.225.53.20.59729 TO 10.225.53.20.13721 fd = 916 11:56:40.008 [3876.3724] <2> db_end: Need to collect reply 11:56:40.508 [3876.3724] <2> inform_client_of_status: INF - Server status = 0 11:56:42.211 [3876.3724] <2> inform_client_of_status: COMM_SOCK == INVALID_SOCKET, 0 11:56:42.211 [3876.3724] <2> bpbrm Exit: attempting to send mail to root on capbkup0001 11:56:42.226 [3876.3724] <2> logconnections: BPCD CONNECT FROM 10.225.53.20.59733 TO 10.225.53.20.13782 fd = 1316 11:56:46.804 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:56:46.804 [3876.3724] <2> bpbrm Exit: OUT_SOCK from bpcr = 1316 11:56:46.804 [3876.3724] <2> bpbrm Exit: IN_SOCK from bpcr = 916 11:56:46.804 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:56:46.804 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:56:46.820 [3876.3724] <2> bpcr_get_version_rqst: bpcd version: 07720000 11:56:46.851 [3876.3724] <2> bpbrm Exit: client snapshot EXIT STATUS 0: the requested operation was successfully completed 11:56:46.867 [3876.3724] <2> job_monitoring_exex: ACK disconnect 11:56:46.867 [3876.3724] <2> job_disconnect: Disconnected