From: <bac...@li...> - 2008-09-21 07:26:22
|
The following issue has been SUBMITTED. ====================================================================== http://bugs.bacula.org/view.php?id=1160 ====================================================================== Reported By: jesperkrogh Assigned To: ====================================================================== Project: bacula Issue ID: 1160 Category: Director Reproducibility: always Severity: major Priority: normal Status: new ====================================================================== Date Submitted: 09-21-2008 08:06 BST Last Modified: 09-21-2008 08:06 BST ====================================================================== Summary: Fail to select inChanger volume when needed. Description: This night a large backup failed .. Logtrace here: 21-Sep 02:34 bacula-sd JobId 18514: End of medium on Volume "001737L3" Bytes=654,427,339,776 Blocks=10,144,272 at 21-Sep-2008 02:34. 21-Sep 02:34 bacula-sd JobId 18514: 3307 Issuing autochanger "unload slot 19, drive 0" command. 21-Sep 02:55 bacula-dir JobId 18514: There are no more Jobs associated with Volume "000773L3". Marking it purged. 21-Sep 02:55 bacula-dir JobId 18514: New Pool is: Scratch 21-Sep 02:55 bacula-dir JobId 18514: All records pruned from Volume "000773L3"; marking it "Purged" 21-Sep 02:55 bacula-dir JobId 18514: Recycled volume "000773L3" 21-Sep 02:55 bacula-dir JobId 18514: Using Volume "000773L3" from 'Scratch' pool. 21-Sep 02:55 bacula-sd JobId 18514: Invalid slot=0 defined in catalog for Volume "000773L3" on "LTO3-0" (/dev/nst0). Manual load may be required. 21-Sep 02:55 bacula-sd JobId 18514: 3301 Issuing autochanger "loaded? drive 0" command. 21-Sep 02:55 bacula-sd JobId 18514: 3302 Autochanger "loaded? drive 0", result: nothing loaded. 21-Sep 02:55 bacula-sd JobId 18514: 3301 Issuing autochanger "loaded? drive 0" command. 21-Sep 02:55 tes/second It correctly purges an old volume, putting it into the Scratch pool, but it selects that same volume to continue with.. no matter that there was 2 other volumes that had the "inChanger" flag set. It then failes to continue. When I set the "enabled" flag on the volume to 0 it then gave this message: 21-Sep 06:25 bacula-sd JobId 18514: Invalid slot=0 defined in catalog for Volume "000773L3" on "LTO3-0" (/dev/nst0). Manual load may be required. 21-Sep 06:25 bacula-sd JobId 18514: 3301 Issuing autochanger "loaded? drive 0" command. 21-Sep 06:25 bacula-sd JobId 18514: 3302 Autochanger "loaded? drive 0", result: nothing loaded. 21-Sep 06:25 bacula-sd JobId 18514: 3301 Issuing autochanger "loaded? drive 0" command. 21-Sep 06:25 bacula-sd JobId 18514: 3302 Autochanger "loaded? drive 0", result: nothing loaded. 21-Sep 06:25 bacula-sd JobId 18514: Fatal error: Too many errors trying to mount device "LTO3-0" (/dev/nst0). 21-Sep 06:25 bacula-sd JobId 18514: Fatal error: spool.c:296 Fatal append error on device "LTO3-0" (/dev/nst0): ERR=askdir.c:570 Cannot request another volume: no volume name given. 21-Sep 06:25 bacula-sd JobId 18514: Despooling elapsed time = 03:51:15, Transfer rate = 576.5 K bytes/second 21-Sep 06:25 bacula-sd JobId 18514: Job write elapsed time = 32:09:54, Transfer rate = 13.04 M bytes/second 21-Sep 06:25 atlas-fd JobId 18514: Fatal error: backup.c:892 Network send error to SD. ERR=Connection reset by peer 21-Sep 06:26 bacula-dir JobId 18514: Error: Bacula bacula-dir 2.4.2 (26Jul08): 21-Sep-2008 06:26:04 Build OS: x86_64-pc-linux-gnu debian lenny/sid JobId: 18514 Job: Atlas_Daily_X3I.2008-09-19_22.01.34 Backup Level: Full Client: "atlas-fd" 2.2.6 (10Nov07) x86_64-pc-linux-gnu,debian,4.0 FileSet: "Atlas Set X3I" 2007-02-05 17:24:23 Pool: "Full-Pool" (From Job FullPool override) Storage: "LTO-4" (From Job resource) Scheduled time: 19-Sep-2008 22:01:00 Start time: 19-Sep-2008 22:05:30 End time: 21-Sep-2008 06:26:04 Elapsed time: 1 day 8 hours 20 mins 34 secs Priority: 10 FD Files Written: 4,732,435 SD Files Written: 4,732,435 FD Bytes Written: 1,509,668,719,895 (1.509 TB) SD Bytes Written: 1,510,434,721,776 (1.510 TB) Rate: 12965.9 KB/s Software Compression: None VSS: no Storage Encryption: no Volume name(s): 000839L3|000805L3|001728L3|001737L3 Volume Session Id: 138 Volume Session Time: 1221372824 Last Volume Bytes: 0 (0 B) Non-fatal FD errors: 0 SD Errors: 0 FD termination status: Error SD termination status: Error Termination: *** Backup Error *** ====================================================================== Issue History Date Modified Username Field Change ====================================================================== 09-21-08 08:06 jesperkrogh New Issue ====================================================================== |