From: <bac...@li...> - 2008-09-21 07:46:42
|
The following issue requires your FEEDBACK. ====================================================================== 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: feedback ====================================================================== Date Submitted: 09-21-2008 08:06 BST Last Modified: 09-21-2008 08:46 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 *** ====================================================================== ---------------------------------------------------------------------- kern - 09-21-08 08:46 ---------------------------------------------------------------------- 1. Have you applied all the 2.4.2 patches? If so, please indicate. If not, please indicate, then apply all the patches and try again. 2. Please complete all the fields in the bug report that you have left blank. 3. Please provide a listing of the Pool where you say there were volumes available. 4. Please provide an "llist" of the scratch volume in question. Issue History Date Modified Username Field Change ====================================================================== 09-21-08 08:06 jesperkrogh New Issue 09-21-08 08:46 kern Note Added: 0003652 09-21-08 08:46 kern Status new => feedback ====================================================================== |