Job details

Job id
29954
Time Event
2017-06-05 17:12:04 bacula-dir JobId 29954: Start Backup JobId 29954, Job=BackMysql01.2017-06-04_23.05.00_25
2017-06-05 17:12:04 bacula-dir JobId 29954: Using Device "ULT3580-TD3" to write.
2017-06-05 17:12:04 bacula-sd JobId 29954: Spooling data ...
2017-06-05 17:20:31 bacula-sd JobId 29954: Committing spooled data to Volume "L30046L3". Despooling 4,203,941,893 bytes ...
2017-06-05 17:20:36 bacula-sd JobId 29954: End of Volume "L30046L3" at 598:639 on device "ULT3580-TD3" (/dev/nst1). Write of 262144 bytes got -1.
2017-06-05 17:20:40 bacula-sd JobId 29954: Re-read of last block succeeded.
2017-06-05 17:20:40 bacula-sd JobId 29954: End of medium on Volume "L30046L3" Bytes=560,027,673,600 Blocks=2,136,382 at 05-Jun-2017 17:20.
2017-06-05 17:20:40 bacula-sd JobId 29954: 3307 Issuing autochanger "unload slot 291, drive 1" command.
2017-06-05 17:20:50 bacula-sd JobId 29954: 3995 Bad autochanger "unload slot 291, drive 1": ERR=Child exited with code 1
Results=mtx: Request Sense: Long Report=yes
mtx: Request Sense: Valid Residual=no
mtx: Request Sense: Error Code=70 (Current)
mtx: Request Sense: Sense Key=Unit Attention
mtx: Request Sense: FileMark=no
mtx: Request Sense: EOM=no
mtx: Request Sense: ILI=no
mtx: Request Sense: Additional Sense Code = 28
mtx: Request Sense: Additional Sense Qualifier = 01
mtx: Request Sense: BPV=no
mtx: Request Sense: Error in CDB=no
mtx: Request Sense: SKSV=no
Mode sense (0x1A) for Page 0x1D failed
illegal argument '291' to 'unload' command

2017-06-05 17:20:53 bacula-sd JobId 29954: 3307 Issuing autochanger "unload slot 291, drive 1" command.
2017-06-05 17:21:27 bacula-sd JobId 29954: 3304 Issuing autochanger "load slot 292, drive 1" command.
2017-06-05 17:21:41 bacula-sd JobId 29954: 3305 Autochanger "load slot 292, drive 1", status is OK.
2017-06-05 17:21:44 bacula-sd JobId 29954: Wrote label to prelabeled Volume "L30047L3" on tape device "ULT3580-TD3" (/dev/nst1)
2017-06-05 17:21:44 bacula-sd JobId 29954: New volume "L30047L3" mounted on device "ULT3580-TD3" (/dev/nst1) at 05-Jun-2017 17:21.
2017-06-05 17:23:07 bacula-sd JobId 29954: Despooling elapsed time = 00:01:32, Transfer rate = 45.69 M Bytes/second
2017-06-05 17:23:08 bacula-sd JobId 29954: Elapsed time=00:10:00, Transfer rate=6.999 M Bytes/second
2017-06-05 17:23:10 bacula-sd JobId 29954: Sending spooled attrs to the Director. Despooling 13,050,676 bytes ...
2017-06-05 17:23:18 bacula-dir JobId 29954: Bacula bacula-dir 7.0.5 (28Jul14):
Build OS: x86_64-pc-linux-gnu ubuntu 16.04
JobId: 29954
Job: BackMysql01.2017-06-04_23.05.00_25
Backup Level: Full
Client: "mysql01-fd" 5.2.6 (21Feb12) x86_64-pc-linux-gnu,debian,7.0
FileSet: "FullServices" 2013-08-09 09:40:30
Pool: "Full" (From Job FullPool override)
Catalog: "MyCatalog" (From Client resource)
Storage: "TSM3500-LTO3" (From Job resource)
Scheduled time: 04-Jun-2017 23:05:00
Start time: 05-Jun-2017 17:12:04
End time: 05-Jun-2017 17:23:18
Elapsed time: 11 mins 14 secs
Priority: 10
FD Files Written: 64,920
SD Files Written: 64,920
FD Bytes Written: 4,192,360,239 (4.192 GB)
SD Bytes Written: 4,199,892,715 (4.199 GB)
Rate: 6220.1 KB/s
Software Compression: 61.8% 2.6:1
VSS: no
Encryption: no
Accurate: no
Volume name(s): L30046L3|L30047L3
Volume Session Id: 519
Volume Session Time: 1494578088
Last Volume Bytes: 4,036,241,408 (4.036 GB)
Non-fatal FD errors: 0
SD Errors: 0
FD termination status: OK
SD termination status: OK
Termination: Backup OK

2017-06-05 17:23:18 bacula-dir JobId 29954: Begin pruning Jobs older than 1 month 15 days .
2017-06-05 17:23:38 bacula-dir JobId 29954: Pruned 2 Jobs for client mysql01-fd from catalog.
2017-06-05 17:23:38 bacula-dir JobId 29954: Begin pruning Files.
2017-06-05 17:23:38 bacula-dir JobId 29954: No Files found to prune.
2017-06-05 17:23:38 bacula-dir JobId 29954: End auto prune.