2014-02-28 14:40:49 - Info nbjm(pid=1544) starting backup job (jobid=430915) for client x, policy SAB_x, schedule full  
2014-02-28 14:40:49 - Info nbjm(pid=1544) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=430915, request id:{45AAF350-91F5-43FA-B57D-B72421CE61D8})  
2014-02-28 14:40:49 - requesting resource x-hcart2-robot-tld-1-x
2014-02-28 14:40:49 - requesting resource x.NBU_CLIENT.MAXJOBS.x
2014-02-28 14:40:49 - requesting resource x.NBU_POLICY.MAXJOBS.SAB_x
2014-02-28 14:40:50 - awaiting resource x-hcart2-robot-tld-1-x - No drives are available
2014-02-28 15:12:32 - Info bpbrm(pid=11932) x is the host to backup data from     
2014-02-28 15:12:32 - Info bptm(pid=8508) start            
2014-02-28 15:12:32 - Info bpbrm(pid=11932) reading file list from client        
2014-02-28 15:12:32 - Info bptm(pid=8508) using 64 data buffers         
2014-02-28 15:12:32 - Info bpbrm(pid=11932) starting ndmpagent on client         
2014-02-28 15:12:32 - Info bptm(pid=8508) using 262144 data buffer size        
2014-02-28 15:12:32 - Info ndmpagent(pid=11296) Backup started           
2014-02-28 15:12:32 - granted resource x.NBU_CLIENT.MAXJOBS.x
2014-02-28 15:12:32 - granted resource x.NBU_POLICY.MAXJOBS.SAB_x
2014-02-28 15:12:32 - granted resource IBM085
2014-02-28 15:12:32 - granted resource IBM.ULT3580-TD5.Drive1
2014-02-28 15:12:32 - granted resource x-hcart2-robot-tld-1-x
2014-02-28 15:12:32 - estimated 0 Kbytes needed
2014-02-28 15:12:32 - Info nbjm(pid=1544) started backup (backupid=x_1393596752) job for client x, policy SAB_x, schedule full on storage unit x-hcart2-robot-tld-1-x
2014-02-28 15:12:32 - started process bpbrm (11932)
2014-02-28 15:12:32 - connecting
2014-02-28 15:12:32 - connected; connect time: 00:00:00
2014-02-28 15:12:33 - Info bptm(pid=8508) start backup           
2014-02-28 15:12:33 - Info bptm(pid=8508) Waiting for mount of media id IBM085 (copy 1) on server x. 
2014-02-28 15:12:33 - mounting IBM085
2014-02-28 15:13:10 - Info bptm(pid=8508) media id IBM085 mounted on drive index 4, drivepath c160t0l0, drivename IBM.ULT3580-TD5.Drive1, copy 1
2014-02-28 15:13:10 - mounted; mount time: 00:00:37
2014-02-28 15:13:23 - positioning IBM085 to file 1
2014-02-28 15:13:37 - positioned IBM085; position time: 00:00:14
2014-02-28 15:13:37 - begin writing
2014-02-28 15:13:53 - Info ndmpagent(pid=11296) x: SnapSure file system creation succeeds       
2014-02-28 20:33:02 - Info ndmpagent(pid=11296) x: End of tape reached. Load next tape.     
2014-02-28 20:33:08 - current media IBM085 complete, requesting next resource Any
2014-02-28 20:33:08 - current media -- complete, awaiting next media Any Reason: Drives are in use, Media Server: x, 
	 Robot Number: 0, Robot Type: TLD, Media ID: N/A, Drive Name: N/A, 
	 Volume Pool: x, Storage Unit: x-hcart2-robot-tld-1-x, Drive Scan Host: N/A
	
2014-02-28 20:34:18 - Info bptm(pid=8508) Waiting for mount of media id XO0348 (copy 1) on server x. 
2014-02-28 20:34:18 - granted resource XO0348
2014-02-28 20:34:18 - granted resource IBM.ULT3580-TD5.Drive1
2014-02-28 20:34:18 - granted resource x-hcart2-robot-tld-1-x
2014-02-28 20:34:18 - end writing; write time: 05:20:41
2014-02-28 20:34:18 - mounting XO0348
2014-02-28 20:34:22 - Error bptm(pid=8508) error requesting media, TpErrno = Robot operation failed     
2014-02-28 20:34:22 - Warning bptm(pid=8508) media id XO0348 load operation reported an error     
2014-02-28 20:34:22 - current media XO0348 complete, requesting next resource Any
2014-02-28 20:35:44 - current media -- complete, awaiting next media Any Reason: Drives are in use, Media Server: x, 
	 Robot Number: 0, Robot Type: TLD, Media ID: N/A, Drive Name: N/A, 
	 Volume Pool: x, Storage Unit: x-hcart2-robot-tld-1-x, Drive Scan Host: N/A
	
2014-02-28 20:40:10 - Info bptm(pid=8508) Waiting for mount of media id IBM047 (copy 1) on server x. 
2014-02-28 20:40:10 - granted resource IBM047
2014-02-28 20:40:10 - granted resource IBM.ULT3580-TD5.Drive1
2014-02-28 20:40:10 - granted resource x-hcart2-robot-tld-1-x
2014-02-28 20:40:10 - end writing
2014-02-28 20:40:10 - mounting IBM047
2014-02-28 20:40:22 - Info bptm(pid=8508) media id IBM047 mounted on drive index 4, drivepath c160t0l0, drivename IBM.ULT3580-TD5.Drive1, copy 1
2014-02-28 20:40:22 - Error bptm(pid=8508) io_ioctl_ndmp (MTREW) failed on media id IBM047, drive index 4, return code 7 (NDMP_IO_ERR) (bptm.c.8216)
2014-02-28 20:40:22 - Error bptm(pid=8508) io_ioctl_ndmp (MTREW) failed on media id IBM047, drive index 4, return code 7 (NDMP_IO_ERR) (bptm.c.9611)
2014-02-28 20:40:22 - Error ndmpagent(pid=11296) NDMP backup failed, path = /root_vdm_23/sab_sesol0163srv/sab_sesol0163srv/       
2014-02-28 20:40:22 - Error ndmpagent(pid=11296) x: Medium error          
2014-02-28 20:40:22 - Warning ndmpagent(pid=11296) x: Write failed on archive volume 1      
2014-02-28 20:40:22 - Info ndmpagent(pid=11296) x: server_archive: emctar vol 1, 1020550 files, 0 bytes read, 2096656285696 bytes written
2014-02-28 20:40:22 - Error ndmpagent(pid=11296) x: Backup is aborted.         
2014-02-28 20:40:22 - mounted; mount time: 00:00:12
2014-02-28 20:40:22 - positioning IBM047 to file 1
2014-02-28 20:40:22 - end writing
media position error(86)
2014-02-28 20:40:27 - Info bptm(pid=8508) EXITING with status 86 <----------        
2014-02-28 20:40:27 - Info ndmpagent(pid=0) done. status: 86: media position error