--- CLI Duplication job --- [root@media01 admincmd]# ./bpduplicate -cn 1 -number_copies 1 -dstunit DESTINATION_STORAGE_UNIT -dp DESTINATION_STORAGE_POOL -backupid CLIENT_1365134403 Duplicate started Thu Oct 3 11:47:02 2013 Activity monitor job id = 1569965 INF - Initiating optimized duplication from @aaabh to @aaabE INF - Duplicating policy CLIENT_OS schedule Full backup id CLIENT_1365134403 copy 1 created on 04/05/2013 00:00:03 on source path @aaabh INF - host media01 backup id CLIENT_1365134403 optimized duplication failed, media write error (84). INF - Duplicate of backupid CLIENT_1365134403 failed, media write error (84). INF - Error processing optimized duplication from @aaabh to @aaabE INF - Status = no images were successfully processed. --- GUI Duplication job --- Oct 3, 2013 11:47:02 AM - begin Duplicate Oct 3, 2013 11:47:04 AM - requesting resource DESTINATION_STORAGE_UNIT Oct 3, 2013 11:47:04 AM - reserving resource @aaabh Oct 3, 2013 11:47:05 AM - resource @aaabh reserved Oct 3, 2013 11:47:05 AM - granted resource MediaID=@aaabE;DiskVolume=destination_Storage;DiskPool=DESTINATION_STORAGE_POOL;Path=destination_Storage;StorageServer=destination_stg_server;MediaServer=media01 Oct 3, 2013 11:47:05 AM - granted resource DESTINATION_STORAGE_UNIT Oct 3, 2013 11:47:06 AM - requesting resource @aaabh Oct 3, 2013 11:47:06 AM - granted resource MediaID=@aaabh;DiskVolume=disk_vol;DiskPool=Disk_pool;Path=disk_vol;StorageServer=SOURCE_STG_SERVER;MediaServer=media01 Oct 3, 2013 11:47:08 AM - Info Duplicate (pid=581690) Initiating optimized duplication from @aaabh to @aaabE Oct 3, 2013 11:47:09 AM - started process bpdm (pid=21289) Oct 3, 2013 11:47:19 AM - begin writing Oct 3, 2013 11:47:20 AM - Critical bpdm (pid=21289) sts_copy_extent failed: error 2060026 invalid server name Oct 3, 2013 11:47:20 AM - Critical bpdm (pid=21289) image copy failed: error 2060026: invalid server name Oct 3, 2013 11:47:21 AM - Error bpdm (pid=21289) cannot copy image from disk, bytesCopied = 18446744073709551615 Oct 3, 2013 11:47:33 AM - Error bpduplicate (pid=581690) host media01 backup id CLIENT_1365134403 optimized duplication failed, media write error (84). Oct 3, 2013 11:47:34 AM - Error bpduplicate (pid=581690) Duplicate of backupid CLIENT_1365134403 failed, media write error (84). Oct 3, 2013 11:47:34 AM - Error bpduplicate (pid=581690) Status = no images were successfully processed. Oct 3, 2013 11:47:34 AM - end Duplicate; elapsed time 0:00:32 media write error (84) --- bpcd --- 11:47:09.136 [21287] <2> bpcd main: offset to GMT 18000 11:47:09.136 [21287] <2> logconnections: BPCD ACCEPT FROM xx.xx.xx.20.38880 TO xx.xx.xx.21.13724 11:47:09.136 [21287] <2> bpcd main: setup_sockopts complete 11:47:09.138 [21287] <2> bpcd peer_hostname: Connection from host master_server (xx.xx.xx.20) port 38880 11:47:09.138 [21287] <2> bpcd valid_server: comparing master_server and master_server 11:47:09.138 [21287] <4> bpcd valid_server: hostname comparison succeeded 11:47:09.138 [21287] <2> bpcd main: output socket port number = 1 11:47:09.284 [21287] <2> bpcd main: Duplicated vnetd socket on stderr 11:47:09.284 [21287] <2> bpcd main: <---- NetBackup 6.5 0 ------------initiated 11:47:09.284 [21287] <2> bpcd main: VERBOSE = 1 11:47:09.284 [21287] <2> bpcd main: Not using VxSS authentication with master_server 11:47:09.491 [21287] <2> bpcd main: BPCD_SET_LOCALE_RQST 11:47:09.492 [21287] <2> set_locale_rqst: setting values: LC_MESSAGES=C, LC_CTYPE=C, LC_TIME=C, LC_COLLATE=C, LC_NUMERIC=C 11:47:09.492 [21287] <2> bpcd main: BPCD_FORK_CMD_RQST 11:47:09.492 [21287] <2> bpcd main: fork cmd = /usr/openv/netbackup/bin/bpdm bpdm -cmd -nosig -everything -cn 1 -c CLIENT -b CLIENT_1365134403 -port 0 0 media01 -jobid 1569965 -shm -dst_cn 2 -p @aaabh -mediasvr media01 -direct_copy 2 -rl 9 -date 2147483647 -storagesvr NEC_HYDRA:destination_stg_server:DESTINATION_STORAGE_POOL:destination_Storage:@aaabE 11:47:09.492 [21287] <2> bpcd main: filter type = -1 11:47:09.492 [21287] <2> bpcd main: backuptime before fork = 0 11:47:09.492 [21287] <2> bpcd exit_bpcd: exit status 0 ----------->exiting 11:47:09.492 [21289] <2> bpcd main: Converting args string into an argv 11:47:09.493 [21289] <2> bpcd main: Done converting args string into an argv 11:47:09.493 [21289] <2> bpcd main: child_args[0] = /usr/openv/netbackup/bin/bpdm 11:47:09.493 [21289] <2> bpcd main: child_args[1] = bpdm 11:47:09.493 [21289] <2> bpcd main: child_args[2] = -cmd 11:47:09.493 [21289] <2> bpcd main: child_args[3] = -nosig 11:47:09.493 [21289] <2> bpcd main: child_args[4] = -everything 11:47:09.493 [21289] <2> bpcd main: child_args[5] = -cn 11:47:09.493 [21289] <2> bpcd main: child_args[6] = 1 11:47:09.493 [21289] <2> bpcd main: child_args[7] = -c 11:47:09.493 [21289] <2> bpcd main: child_args[8] = CLIENT 11:47:09.493 [21289] <2> bpcd main: child_args[9] = -b 11:47:09.493 [21289] <2> bpcd main: child_args[10] = CLIENT_1365134403 11:47:09.493 [21289] <2> bpcd main: child_args[11] = -port 11:47:09.493 [21289] <2> bpcd main: child_args[12] = 0 11:47:09.493 [21289] <2> bpcd main: child_args[13] = 0 11:47:09.493 [21289] <2> bpcd main: child_args[14] = media01 11:47:09.493 [21289] <2> bpcd main: child_args[15] = -jobid 11:47:09.493 [21289] <2> bpcd main: child_args[16] = 1569965 11:47:09.493 [21289] <2> bpcd main: child_args[17] = -shm 11:47:09.493 [21289] <2> bpcd main: child_args[18] = -dst_cn 11:47:09.493 [21289] <2> bpcd main: child_args[19] = 2 11:47:09.493 [21289] <2> bpcd main: child_args[20] = -p 11:47:09.494 [21289] <2> bpcd main: child_args[21] = @aaabh 11:47:09.494 [21289] <2> bpcd main: child_args[22] = -mediasvr 11:47:09.494 [21289] <2> bpcd main: child_args[23] = media01 11:47:09.494 [21289] <2> bpcd main: child_args[24] = -direct_copy 11:47:09.494 [21289] <2> bpcd main: child_args[25] = 2 11:47:09.494 [21289] <2> bpcd main: child_args[26] = -rl 11:47:09.494 [21289] <2> bpcd main: child_args[27] = 9 11:47:09.494 [21289] <2> bpcd main: child_args[28] = -date 11:47:09.494 [21289] <2> bpcd main: child_args[29] = 2147483647 11:47:09.494 [21289] <2> bpcd main: child_args[30] = -storagesvr 11:47:09.494 [21289] <2> bpcd main: child_args[31] = NEC_HYDRA:destination_stg_server:DESTINATION_STORAGE_POOL:destination_Storage:@aaabE 11:47:09.494 [21289] <2> bpcd main: Before execvp of command ---- bpbrm --- No Log --- bptm --- No Log --- bpdm --- 11:47:09.633 [21289] <2> bpdm: INITIATING (VERBOSE = 1): -cmd -nosig -everything -cn 1 -c CLIENT -b CLIENT_1365134403 -port 0 0 media01 -jobid 1569965 -shm -dst_cn 2 -p @aaabh -mediasvr media01 -direct_copy 2 -rl 9 -date 2147483647 -storagesvr NEC_HYDRA:destination_stg_server:DESTINATION_STORAGE_POOL:destination_Storage:@aaabE 11:47:09.693 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:09.693 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpjobd 11:47:09.702 [21289] <2> job_connect: SO_KEEPALIVE set on socket 4 for client master_server 11:47:09.703 [21289] <2> logconnections: BPJOBD CONNECT FROM xx.xx.xx.21.51937 TO xx.xx.xx.20.13724 11:47:09.703 [21289] <2> job_authenticate_connection: ignoring VxSS authentication check for now... 11:47:09.703 [21289] <2> job_connect: Connected to the host master_server contype 10 jobid <1569965> socket <4> 11:47:09.704 [21289] <2> job_connect: Connected on port 51937 11:47:09.761 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:09.761 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:09.913 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.49378 TO xx.xx.xx.20.13724 11:47:10.342 [21289] <4> report_client: VBRC 2 21289 0 CLIENT_1365134403 -1 *NULL* -1 *NULL* 0 1 1 11:47:10.391 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:10.391 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:10.542 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.44359 TO xx.xx.xx.20.13724 11:47:11.004 [21289] <2> get_target_params: opaque string = NEC_HYDRA:destination_stg_server:DESTINATION_STORAGE_POOL:destination_Storage:@aaabE 11:47:11.005 [21289] <2> get_target_params: ststype = NEC_HYDRA, stshost = destination_stg_server, diskgroup = DESTINATION_STORAGE_POOL, lsuname = destination_Storage, mediaid = @aaabE 11:47:11.054 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:11.054 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:11.172 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.47496 TO xx.xx.xx.20.13724 11:47:11.629 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:11.629 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:11.792 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.51839 TO xx.xx.xx.20.13724 11:47:12.263 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:12.263 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:12.422 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.59276 TO xx.xx.xx.20.13724 11:47:12.888 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:12.888 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:13.032 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.58608 TO xx.xx.xx.20.13724 11:47:13.433 [21289] <2> copy_backup: file @aaabh, copy 1, fragment 1 (14606848 Kbytes) being considered for duplicate-optimized 11:47:13.433 [21289] <2> nbjm_media_request: Passing job control to NBJM, type READ/10 11:47:13.433 [21289] <2> nbjm_media_request: old_media_id = , media_id = @aaabh 11:47:13.439 [21289] <2> Orb::setDebugLevelFromVxul: Orb logging configuration level set to 0(Orb.cpp:2330) 11:47:13.443 [21289] <2> LogMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:112) 11:47:13.445 [21289] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:342) 11:47:13.446 [21289] <2> Orb::init: substituting orb name of: Default_CLIENT_Orb(Orb.cpp:530) 11:47:13.446 [21289] <2> Orb::init: initializing ORB with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_CLIENT_Orb'"(Orb.cpp:549) 11:47:13.447 [21289] <2> Orb::init: Created anon service name: NB_21289_586190124(Orb.cpp:610) 11:47:13.447 [21289] <2> Orb::init: endpointvalue is : pbxiop://1556:NB_21289_586190124(Orb.cpp:628) 11:47:13.447 [21289] <2> Orb::init: added args has value of: (Orb.cpp:684) 11:47:13.449 [21289] <2> Orb::init: initializing ORB Default_CLIENT_Orb with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_CLIENT_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBEndpoint pbxiop://1556:NB_21289_586190124 -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:739) 11:47:13.459 [21289] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:342) 11:47:13.459 [21289] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 3600(Orb.cpp:1416) 11:47:13.469 [21289] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:master_server:1556:nbjm/JOBINST(Orb.cpp:1351) 11:47:15.494 [21289] <2> RequestInitialResources: returning 11:47:15.495 [21289] <2> parse_resource_strings: MEDIADB 1 3010790 @aaabh 71 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 11:47:15.495 [21289] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters 11:47:15.495 [21289] <2> parse_resource_strings: VOLUME 1 @aaabh 71 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0 11:47:15.495 [21289] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters 11:47:15.495 [21289] <2> parse_resource_strings: DRIVE 3 *NULL* 0 *NULL* *NULL* 0 0 0 0 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 0 11:47:15.495 [21289] <2> parse_resource_strings: Parsed message type 17, version 3, 23 parameters 11:47:15.495 [21289] <2> parse_resource_strings: STORAGE 1 *NULL* 0 0 0 0 0 0 media01 media01 *NULL* 11:47:15.495 [21289] <2> parse_resource_strings: Parsed message type 18, version 1, 11 parameters 11:47:15.495 [21289] <2> parse_resource_strings: DISKGROUP 0 9 NEC_HYDRA 9 SOURCE_STG_SERVER 16 Disk_pool 11:47:15.495 [21289] <2> parse_resource_strings: Parsed message type 21, version 0, 7 parameters 11:47:15.495 [21289] <2> parse_resource_strings: DISKVOLUME 0 6 @aaabh 11 disk_vol 0 11:47:15.495 [21289] <2> parse_resource_strings: Parsed message type 22, version 0, 6 parameters 11:47:15.495 [21289] <2> parse_resource_strings: DISKMOUNTPOINT 0 11 disk_vol 11:47:15.495 [21289] <2> parse_resource_strings: Parsed message type 23, version 0, 3 parameters 11:47:15.495 [21289] <2> nbjm_media_request: Job control returned to BPTM 11:47:15.943 [21289] <2> SetMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:33) 11:47:15.943 [21289] <2> LogMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:112) 11:47:15.945 [21289] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:342) 11:47:15.946 [21289] <2> Orb::init: initializing ORB with: libstsemm -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'"(Orb.cpp:549) 11:47:15.946 [21289] <2> Orb::init: added args has value of: (Orb.cpp:684) 11:47:15.946 [21289] <2> Orb::init: initializing ORB EMMlib_Orb with: libstsemm -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:739) 11:47:15.949 [21289] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:342) 11:47:15.949 [21289] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 3600(Orb.cpp:1416) 11:47:15.949 [21289] <2> Orb::activate: Non-daemon called activate(Orb.cpp:1146) 11:47:15.949 [21289] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 60(Orb.cpp:1416) 11:47:15.949 [21289] <2> Orb::setOrbTimeoutPolicy: setting ORB request timeout policy: tv = 1800(Orb.cpp:1416) 11:47:15.949 [21289] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:master_server:1556:EMM/EMMServer(Orb.cpp:1351) 11:47:16.010 [21289] <2> Orb::destroyOrb: orb has reference count: 0(Orb.cpp:1571) 11:47:16.433 [21289] <2> SetMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:33) 11:47:16.434 [21289] <2> LogMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:112) 11:47:16.435 [21289] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:342) 11:47:16.436 [21289] <2> Orb::init: initializing ORB with: libstsemm -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'"(Orb.cpp:549) 11:47:16.436 [21289] <2> Orb::init: added args has value of: (Orb.cpp:684) 11:47:16.436 [21289] <2> Orb::init: initializing ORB EMMlib_Orb with: libstsemm -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:739) 11:47:16.438 [21289] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:342) 11:47:16.438 [21289] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 3600(Orb.cpp:1416) 11:47:16.438 [21289] <2> Orb::activate: Non-daemon called activate(Orb.cpp:1146) 11:47:16.438 [21289] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 60(Orb.cpp:1416) 11:47:16.438 [21289] <2> Orb::setOrbTimeoutPolicy: setting ORB request timeout policy: tv = 1800(Orb.cpp:1416) 11:47:16.438 [21289] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:master_server:1556:EMM/EMMServer(Orb.cpp:1351) 11:47:16.531 [21289] <2> Orb::destroyOrb: orb has reference count: 0(Orb.cpp:1571) 11:47:16.805 [21289] <2> bp_sts_get_lsu: entry disk_vol 11:47:16.995 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:16.995 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:17.115 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.58077 TO xx.xx.xx.20.13724 11:47:17.661 [21289] <2> construct_sts_isid: master_server master_server, client CLIENT, backup_time 1365134403, copy_number 2, stream_number 0, fragment_number 1, resume_number 12 11:47:18.727 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:18.727 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:18.752 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.47900 TO xx.xx.xx.20.13724 11:47:19.179 [21289] <4> copy_data: begin copying backup id CLIENT_1365134403 (duplicate-optimized), copy 1, fragment 1 11:47:20.193 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:20.193 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:20.222 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.59038 TO xx.xx.xx.20.13724 11:47:20.642 [21289] <32> bp_sts_copy_extent: sts_copy_extent failed: error 2060026 11:47:20.696 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:20.696 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:20.834 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.57681 TO xx.xx.xx.20.13724 11:47:21.254 [21289] <32> copy_data: image copy failed: error 2060026: 11:47:21.308 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:21.309 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:21.463 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.42196 TO xx.xx.xx.20.13724 11:47:21.888 [21289] <16> copy_data: cannot copy image from disk, bytesCopied = 18446744073709551615 11:47:22.439 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 11:47:22.439 [21289] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm 11:47:22.512 [21289] <2> logconnections: BPDBM CONNECT FROM xx.xx.xx.21.40098 TO xx.xx.xx.20.13724 11:47:33.180 [21289] <2> get_long: (2) premature end of file (byte 1) 11:47:33.180 [21289] <2> job_monitoring_exex: Failed to get frame type 11:47:33.181 [21289] <2> job_disconnect: Disconnected 11:47:33.181 [21289] <2> sanunmount: NOP: MEDIA_DONE 0 1569965 0 @aaabh 71 0 {0E41C76C-1DD2-11B2-B5D4-6468D2240000} 11:47:33.181 [21289] <2> main: NOP: MEDIA_DONE 0 1569965 0 @aaabh 71 0 {0E41C76C-1DD2-11B2-B5D4-6468D2240000} 11:47:33.181 [21289] <2> main: Got bpduplicate acknowledgement status: 0, err: 0 11:47:33.181 [21289] <2> bpdm: EXITING with status 84