Aug 30, 2020 6:08:50 AM - Info nbjm (pid=13924) starting backup job (jobid=54xxxx9) for client XXXXX.XXXXX.com, policy NDMP_XXX_XXXX_002, schedule FULL_WEEKLY
Aug 30, 2020 6:08:50 AM - Info nbjm (pid=13924) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=546069, request id:{E8B0D11F-D2B2-4781-8076-97CECC3BDF94})
Aug 30, 2020 6:08:50 AM - requesting resource  XXXXXX-hcart3-robot-tld-1-XXXXX
Aug 30, 2020 6:08:50 AM - requesting resource  XXXXX.XXX.XXXXcom.NBU_CLIENT.MAXJOBS.XXXXX.XXXX.XXXX.com
Aug 30, 2020 6:08:53 AM - awaiting resource XXXX-hcart3-robot-tld-1-XXXXX. Waiting for resources. 
          Reason: Drives are in use, Media server: XXXXX.XXXX.XX.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: VTL01_NDMP_Datastore, Storage Unit: XXXXX-hcart3-robot-tld-1-XXXXXX, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
Aug 30, 2020 10:05:40 AM - granted resource  XXX.XXXXXX.XXXX.com.NBU_CLIENT.MAXJOBS.XXXXX.XXXX.XX.com
Aug 30, 2020 10:05:40 AM - granted resource  Tape
Aug 30, 2020 10:05:40 AM - granted resource  HPULTRIUM6-SCSI-TLD01-Drive12
Aug 30, 2020 10:05:40 AM - granted resource  XXXX-hcart3-robot-tld-1-XXXXXXX
Aug 30, 2020 10:05:45 AM - estimated 398035039 kbytes needed
Aug 30, 2020 10:05:45 AM - Info nbjm (pid=13924) started backup (backupid=XXXX.XXXX.XX.com_1598762140) job for client XXXX.XXXXX.XXX.com, policy NDMP_XXX_XXXX_002, schedule FULL_WEEKLY on storage unit XXXX-hcart3-robot-tld-1-XXXXXX
Aug 30, 2020 10:05:49 AM - started process bpbrm (pid=9092)
Aug 30, 2020 10:05:54 AM - Info bpbrm (pid=9092) XXXXXXX.XXXX.XXX.com is the host to backup data from
Aug 30, 2020 10:05:59 AM - Info bpbrm (pid=9092) reading file list for client
Aug 30, 2020 10:05:59 AM - connecting
Aug 30, 2020 10:06:03 AM - Info bpbrm (pid=9092) starting ndmpagent on client
Aug 30, 2020 10:06:03 AM - Info ndmpagent (pid=32076) Backup started
Aug 30, 2020 10:06:03 AM - connected; connect time: 0:00:00
Aug 30, 2020 10:06:03 AM - Info ndmpagent (pid=32076) PATH(s) found in file list = 7
Aug 30, 2020 10:06:03 AM - Info bptm (pid=32324) start
Aug 30, 2020 10:06:08 AM - Info ndmpagent (pid=32076) PATH[1 of 7]: /Share1
Aug 30, 2020 10:06:12 AM - Info ndmpagent (pid=32076) PATH[2 of 7]: /Share2
Aug 30, 2020 10:06:17 AM - Info ndmpagent (pid=32076) PATH[3 of 7]: /Share3
Aug 30, 2020 10:06:21 AM - Info ndmpagent (pid=32076) PATH[4 of 7]: /share4
Aug 30, 2020 10:06:22 AM - Info bptm (pid=32324) using 30 data buffers
Aug 30, 2020 10:06:22 AM - Info bptm (pid=32324) using 65536 data buffer size
Aug 30, 2020 10:06:26 AM - Info bptm (pid=32324) start backup
Aug 30, 2020 10:06:26 AM - Info bptm (pid=32324) Waiting for mount of media id A085L6 (copy 1) on server XXXXX.XXXXXX.XXX.com.
Aug 30, 2020 10:06:26 AM - mounting Tape
Aug 30, 2020 10:06:26 AM - Info ndmpagent (pid=32076) PATH[5 of 7]: /Share5
Aug 30, 2020 10:06:31 AM - Info ndmpagent (pid=32076) PATH[6 of 7]: /Share6
Aug 30, 2020 10:06:35 AM - Info ndmpagent (pid=32076) PATH[7 of 7]: /Share7
Aug 30, 2020 10:06:40 AM - Error bptm (pid=32324) cannot open ndmp device c39t0l0, error code 7 (NDMP_IO_ERR)
Aug 30, 2020 10:25:52 AM - Error bptm (pid=32324) error requesting media, TpErrno = Robot operation failed
Aug 30, 2020 10:25:52 AM - Error bptm (pid=32324) cannot open ndmp device c39t0l0, error code 7 (NDMP_IO_ERR)
Aug 30, 2020 10:25:52 AM - Warning bptm (pid=32324) media id Tape load operation reported an error
Aug 30, 2020 10:25:52 AM - current media A085L6 complete, requesting next media Any
Aug 30, 2020 10:32:34 AM - current media -- complete, awaiting next media Any. Waiting for resources. 
          Reason: Drives are in use, Media server: XXXXX.XXXXX.XXXXX.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: VTL01_NDMP_Datastore, Storage Unit: XXXXX-hcart3-robot-tld-1-XXXXX, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
Aug 31, 2020 8:42:35 AM - Info bptm (pid=32324) Waiting for mount of media id A085L6 (copy 1) on server XXXXX.XXXX.xxx.com.
Aug 31, 2020 8:42:35 AM - granted resource  Tape
Aug 31, 2020 8:42:35 AM - granted resource  HPULTRIUM6-SCSI-TLD01-Drive02
Aug 31, 2020 8:42:35 AM - granted resource  XXXXXX-hcart3-robot-tld-1-XXXXXX
Aug 31, 2020 8:42:35 AM - mounting Tape
Aug 31, 2020 8:56:52 AM - Error bptm (pid=32324) error requesting media, TpErrno = Robot operation failed
Aug 31, 2020 8:56:52 AM - Warning bptm (pid=32324) media id Tape load operation reported an error
Aug 31, 2020 8:56:52 AM - current media Tape complete, requesting next media Any
Aug 31, 2020 8:57:31 AM - Info bptm (pid=32324) Waiting for mount of media id Tape(copy 1) on server XXX.XXXX.XXX.com.
Aug 31, 2020 8:57:31 AM - granted resource  Tape
Aug 31, 2020 8:57:31 AM - granted resource  HPULTRIUM6-SCSI-TLD01-Drive02
Aug 31, 2020 8:57:31 AM - granted resource  XXXX-hcart3-robot-tld-1-XXXXX
Aug 31, 2020 8:57:31 AM - end writing
Aug 31, 2020 8:57:31 AM - mounting Tape
Aug 31, 2020 9:07:48 AM - Info bptm (pid=32324) media id Tape mounted on drive index 2, drivepath c40t0l0, drivename HPULTRIUM6-SCSI-TLD01-Drive02, copy 1
Aug 31, 2020 9:07:48 AM - Error bptm (pid=32324) incorrect media found in drive index 2, expected TapeID, found TapeID, FREEZING TapeID
Aug 31, 2020 9:07:48 AM - mounted Tape; mount time: 0:10:17
Aug 31, 2020 9:07:55 AM - current media A01BL6 complete, requesting next media Any
Aug 31, 2020 9:08:24 AM - Info bptm (pid=32324) Waiting for mount of media id Tape (copy 1) on server XXXX.XXXX.XX.com.
Aug 31, 2020 9:08:24 AM - granted resource  Tape
Aug 31, 2020 9:08:24 AM - granted resource  HPULTRIUM6-SCSI-TLD01-Drive02
Aug 31, 2020 9:08:24 AM - granted resource  XXXXX-hcart3-robot-tld-1-XXXX
Aug 31, 2020 9:08:24 AM - end writing
Aug 31, 2020 9:08:24 AM - mounting Tape
Aug 31, 2020 9:22:43 AM - Error bptm (pid=32324) error requesting media, TpErrno = Robot operation failed
Aug 31, 2020 9:22:43 AM - Warning bptm (pid=32324) media id Tape load operation reported an error
Aug 31, 2020 9:22:43 AM - current media TapeID complete, requesting next media Any
Aug 31, 2020 9:23:27 AM - current media -- complete, awaiting next media Any. Waiting for resources. 
          Reason: Drives are in use, Media server: XXXX.XXX.XXX.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: VTL01_NDMP_Datastore, Storage Unit: XXXXXX-hcart3-robot-tld-1-XXXXX, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
Aug 31, 2020 10:20:17 PM - Info bptm (pid=32324) Waiting for mount of media id Tape (copy 1) on server XXXX.XXXX.XXX.com.
Aug 31, 2020 10:20:17 PM - granted resource  Tape
Aug 31, 2020 10:20:17 PM - granted resource  HPULTRIUM6-SCSI-TLD01-Drive08
Aug 31, 2020 10:20:17 PM - granted resource  XXXXXXX-hcart3-robot-tld-1-XXXXXXX
Aug 31, 2020 10:20:17 PM - mounting A01PL6
Aug 31, 2020 10:20:30 PM - Info bptm (pid=32324) media id TapeID mounted on drive index 5, drivepath c43t0l0, drivename HPULTRIUM6-SCSI-TLD01-Drive08, copy 1
Aug 31, 2020 10:20:30 PM - mounted TapeID; mount time: 0:00:13
Aug 31, 2020 10:20:30 PM - positioning TapeID to file 1
Aug 31, 2020 10:20:32 PM - positioned TapeID; position time: 0:00:02
Aug 31, 2020 10:20:32 PM - begin writing
Aug 31, 2020 10:20:33 PM - Info ndmpagent (pid=32076) NDMP Local - tape host and data host match
Aug 31, 2020 10:20:38 PM - Info ndmpagent (pid=32076) NDMP server.xxxx.xxx.com: SnapSure file system creation succeeds
Aug 31, 2020 10:20:42 PM - Info ndmpagent (pid=32076) NDMP server.xxxx.xxx.com: server_archive: emctar vol 1, 152 files, 0 bytes read, 201742633 bytes written
Aug 31, 2020 10:20:47 PM - Info ndmpagent (pid=32076) NDMP backup successfully completed, path = /share1/
Aug 31, 2020 10:21:00 PM - Info ndmpagent (pid=32076) NDMP server.xxxx.xxx.com: SnapSure file system creation succeeds
Aug 31, 2020 10:23:13 PM - Info ndmpagent (pid=32076) NDMP server.xxxx.xxx.com: server_archive: emctar vol 1, 9576 files, 0 bytes read, 29027999175 bytes written
Aug 31, 2020 10:23:18 PM - Info ndmpagent (pid=32076) NDMP backup successfully completed, path = /share2/
Aug 31, 2020 10:23:30 PM - Info ndmpagent (pid=32076) NDMP server.xxxx.xxx.com: SnapSure file system creation succeeds
Aug 31, 2020 10:42:51 PM - Info ndmpagent (pid=32076) NDMP server.xxxx.xxx.com: server_archive: emctar vol 1, 137141 files, 0 bytes read, 254996400439 bytes written
Aug 31, 2020 10:42:56 PM - Info ndmpagent (pid=32076) NDMP backup successfully completed, path = /share3/
Aug 31, 2020 10:43:09 PM - Info ndmpagent (pid=32076) NDMP server.xxxx.xxx.com: SnapSure file system creation succeeds
Aug 31, 2020 10:47:11 PM - Info ndmpagent (pid=32076) NDMP server.xxxx.xxx.com: server_archive: emctar vol 1, 2701 files, 0 bytes read, 55743748119 bytes written
Aug 31, 2020 10:47:16 PM - Info ndmpagent (pid=32076) NDMP backup successfully completed, path = /share4/
Aug 31, 2020 10:47:30 PM - Info ndmpagent (pid=32076) NDMP server.xxxx.xxx.com: SnapSure file system creation succeeds
Aug 31, 2020 10:47:36 PM - Info ndmpagent (pid=32076) NDMP server.xxxx.xxx.com: server_archive: emctar vol 1, 1323 files, 0 bytes read, 1156900889 bytes written
Aug 31, 2020 10:47:40 PM - Info ndmpagent (pid=32076) NDMP backup successfully completed, path = /Share5
Aug 31, 2020 10:47:50 PM - Error ndmpagent (pid=32076) MOVER_HALTED unexpected reason = 2 (NDMP_MOVER_HALT_ABORTED)
Aug 31, 2020 10:47:55 PM - Error ndmpagent (pid=32076) ndmp_data_start_backup failed, status = 9 (NDMP_ILLEGAL_ARGS_ERR)
Aug 31, 2020 10:48:00 PM - Error ndmpagent (pid=32076) NDMP backup failed, path = /Share6
Aug 31, 2020 10:48:11 PM - Info bptm (pid=32324) EXITING with status 99 <----------
Aug 31, 2020 10:48:11 PM - Info ndmpagent (pid=0) done. status: 99: NDMP backup failure
Aug 31, 2020 10:48:11 PM - end writing; write time: 0:27:39
NDMP backup failure  (99)