Hi All, I was backing up jobs via Bacula (2.2.8) this morning and the following occurred...
Unit is a single autochanger with two drives (ATL L500 - 2 Drives - 14 Slots) 02-Feb 10:25 pegasus-dir JobId 4: Start Backup JobId 4, Job=DH. 2008-02-02_08.15.07 02-Feb 10:25 pegasus-dir JobId 4: Using Device "Drive1" 02-Feb 10:25 pegasus-sd JobId 4: 3301 Issuing autochanger "loaded? drive 1" command. 02-Feb 10:25 pegasus-sd JobId 4: 3302 Autochanger "loaded? drive 1", result is Slot 2. 02-Feb 10:25 pegasus-sd JobId 4: 3301 Issuing autochanger "loaded? drive 1" command. 02-Feb 10:25 pegasus-sd JobId 3: Ready to append to end of Volume "BB0012" at file=32. 02-Feb 10:25 pegasus-sd JobId 4: 3302 Autochanger "loaded? drive 1", result is Slot 2. 02-Feb 10:27 pegasus-sd JobId 4: Volume "BB0013" previously written, moving to end of data. 02-Feb 10:28 pegasus-sd JobId 4: Ready to append to end of Volume "BB0013" at file=32. 02-Feb 10:36 pegasus-sd JobId 4: End of Volume "BB0013" at 33:15077 on device "Drive1" (/dev/nst1). Write of 64512 bytes got -1. 02-Feb 10:37 pegasus-sd JobId 4: Re-read of last block succeeded. 02-Feb 10:37 pegasus-sd JobId 4: End of medium on Volume "BB0013" Bytes=33,866,929,152 Blocks=524,970 at 02-Feb-2008 10:37. 02-Feb 10:37 pegasus-dir JobId 4: Using Volume "BB0014" from 'Scratch' pool. 02-Feb 10:37 pegasus-sd JobId 4: 3301 Issuing autochanger "loaded? drive 1" command. 02-Feb 10:37 pegasus-sd JobId 4: 3302 Autochanger "loaded? drive 1", result is Slot 2. 02-Feb 10:37 pegasus-sd JobId 4: 3307 Issuing autochanger "unload slot 2, drive 1" command. 02-Feb 10:37 pegasus-sd JobId 3: End of Volume "BB0012" at 35:7552 on device "Drive0" (/dev/nst0). Write of 64512 bytes got -1. 02-Feb 10:37 pegasus-sd JobId 4: 3304 Issuing autochanger "load slot 3, drive 1" command. 02-Feb 10:38 pegasus-sd JobId 3: Re-read of last block succeeded. 02-Feb 10:38 pegasus-sd JobId 3: End of medium on Volume "BB0012" Bytes=35,253,679,104 Blocks=546,466 at 02-Feb-2008 10:38. 02-Feb 10:39 pegasus-sd JobId 4: 3305 Autochanger "load slot 3, drive 1", status is OK. 02-Feb 10:39 pegasus-sd JobId 4: 3301 Issuing autochanger "loaded? drive 1" command. 02-Feb 10:39 pegasus-sd JobId 4: 3302 Autochanger "loaded? drive 1", result is Slot 3. 02-Feb 10:39 pegasus-sd JobId 3: 3301 Issuing autochanger "loaded? drive 0" command. 02-Feb 10:39 pegasus-sd JobId 3: 3302 Autochanger "loaded? drive 0", result is Slot 1. 02-Feb 10:39 pegasus-sd JobId 3: 3307 Issuing autochanger "unload slot 1, drive 0" command. 02-Feb 10:39 pegasus-sd JobId 4: Warning: Volume "BB0014" not on device "Drive1" (/dev/nst1). 02-Feb 10:39 pegasus-sd JobId 4: Marking Volume "BB0014" in Error in Catalog. 02-Feb 10:39 pegasus-dir JobId 4: Using Volume "BB0015" from 'Scratch' pool. 02-Feb 10:40 pegasus-sd JobId 3: 3304 Issuing autochanger "load slot 3, drive 0" command. 02-Feb 10:45 pegasus-sd JobId 3: Fatal error: 3992 Bad autochanger "load slot 3, drive 0": ERR=Child died from signal 15: Termination. Results=Loading media from Storage Element 3 into drive 0...Source Element Address 258 is Empty Program killed by Bacula watchdog (timeout) Im guessing some sort of race condition appeared ? Regards Matt Brown ------------------------------------------------------------------------- This SF.net email is sponsored by: Microsoft Defy all challenges. Microsoft(R) Visual Studio 2008. http://clk.atdmt.com/MRT/go/vse0120000070mrt/direct/01/ _______________________________________________ Bacula-users mailing list Bacula-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/bacula-users
