21-May 23:10 tibor-dir JobId 4209: shell command: run BeforeJob "/etc/bacula/make_catalog_backup bacula bacula"
21-May 23:18 tibor-dir JobId 4209: Start Backup JobId 4209, Job=BackupCatalog.2009-05-21_23.10.00_21
21-May 23:18 tibor-dir JobId 4209: Using Device "NEO-LTO-1"
21-May 23:18 tibor-sd JobId 4209: Volume "100092L2" previously written, moving to end of data.
21-May 23:19 tibor-sd JobId 4209: Error: Unable to position to end of data on device "NEO-LTO-1" (/dev/nst1): ERR=dev.c:946 ioctl MTIOCGET error on "NEO-LTO-1" (/dev/nst1). ERR=Input/output error.

21-May 23:19 tibor-sd JobId 4209: Marking Volume "100092L2" in Error in Catalog.
21-May 23:19 tibor-sd JobId 4209: 3307 Issuing autochanger "unload slot 3, drive 1" command.
21-May 23:19 tibor-sd JobId 4209: 3995 Bad autochanger "unload slot 3, drive 1": ERR=Child exited with code 1
Results=Unloading drive 1 into Storage Element 3...mtx: Request Sense: Long Report=yes
mtx: Request Sense: Valid Residual=no
mtx: Request Sense: Error Code=70 (Current)
mtx: Request Sense: Sense Key=Illegal Request
mtx: Request Sense: FileMark=no
mtx: Request Sense: EOM=no
mtx: Request Sense: ILI=no
mtx: Request Sense: Additional Sense Code = 53
mtx: Request Sense: Additional Sense Qualifier = 02
mtx: Request Sense: BPV=no
mtx: Request Sense: Error in CDB=no
mtx: Request Sense: SKSV=no
MOVE MEDIUM from Element Address 481 to 34 Failed

21-May 23:19 tibor-sd JobId 4209: 3301 Issuing autochanger "loaded? drive 1" command.
21-May 23:19 tibor-sd JobId 4209: 3302 Autochanger "loaded? drive 1", result is Slot 3.
21-May 23:19 tibor-sd JobId 4209: 3307 Issuing autochanger "unload slot 3, drive 1" command.
21-May 23:19 tibor-sd JobId 4209: 3995 Bad autochanger "unload slot 3, drive 1": ERR=Child exited with code 1
Results=Unloading drive 1 into Storage Element 3...mtx: Request Sense: Long Report=yes
mtx: Request Sense: Valid Residual=no
mtx: Request Sense: Error Code=70 (Current)
mtx: Request Sense: Sense Key=Illegal Request
mtx: Request Sense: FileMark=no
mtx: Request Sense: EOM=no
mtx: Request Sense: ILI=no
mtx: Request Sense: Additional Sense Code = 53
mtx: Request Sense: Additional Sense Qualifier = 02
mtx: Request Sense: BPV=no
mtx: Request Sense: Error in CDB=no
mtx: Request Sense: SKSV=no
MOVE MEDIUM from Element Address 481 to 34 Failed

21-May 23:19 tibor-sd JobId 4209: Please mount Volume "000152L2" or label a new one for:
    Job:          BackupCatalog.2009-05-21_23.10.00_21
    Storage:      "NEO-LTO-1" (/dev/nst1)
    Pool:         Weekly-Tape-WH
    Media type:   LTO-2
22-May 00:19 tibor-sd JobId 4209: Please mount Volume "000152L2" or label a new one for:
    Job:          BackupCatalog.2009-05-21_23.10.00_21
    Storage:      "NEO-LTO-1" (/dev/nst1)
    Pool:         Weekly-Tape-WH
    Media type:   LTO-2
22-May 02:19 tibor-sd JobId 4209: Please mount Volume "000152L2" or label a new one for:
    Job:          BackupCatalog.2009-05-21_23.10.00_21
    Storage:      "NEO-LTO-1" (/dev/nst1)
    Pool:         Weekly-Tape-WH
    Media type:   LTO-2
22-May 06:19 tibor-sd JobId 4209: Please mount Volume "000152L2" or label a new one for:
    Job:          BackupCatalog.2009-05-21_23.10.00_21
    Storage:      "NEO-LTO-1" (/dev/nst1)
    Pool:         Weekly-Tape-WH
    Media type:   LTO-2
22-May 10:25 tibor-sd JobId 4209: 3301 Issuing autochanger "loaded? drive 1" command.
22-May 10:25 tibor-sd JobId 4209: 3302 Autochanger "loaded? drive 1", result is Slot 3.
22-May 10:26 tibor-sd JobId 4209: 3307 Issuing autochanger "unload slot 3, drive 1" command.
22-May 10:27 tibor-sd JobId 4209: 3301 Issuing autochanger "loaded? drive 1" command.
22-May 10:27 tibor-sd JobId 4209: 3302 Autochanger "loaded? drive 1", result: nothing loaded.
22-May 10:27 tibor-sd JobId 4209: 3304 Issuing autochanger "load slot 1, drive 1" command.
22-May 10:27 tibor-sd JobId 4209: 3305 Autochanger "load slot 1, drive 1", status is OK.
22-May 10:27 tibor-sd JobId 4209: Volume "000152L2" previously written, moving to end of data.
22-May 10:28 tibor-sd JobId 4209: Ready to append to end of Volume "000152L2" at file=61.
22-May 10:28 tibor-sd JobId 4209: Spooling data ...
22-May 10:31 tibor-sd JobId 4209: Job write elapsed time = 00:02:34, Transfer rate = 76.40 M bytes/second
22-May 10:31 tibor-sd JobId 4209: Committing spooled data to Volume "000152L2". Despooling 11,777,035,227 bytes ...
22-May 10:35 tibor-sd JobId 4209: Despooling elapsed time = 00:03:48, Transfer rate = 51.65 M bytes/second