Greetings..

 

Fairly new to Bacula, and am using a fully disk based backup approach.  I have a virtual autochanger set up (using a custom autochanger script) and have ten virtual drives set up.  Each drive has maximum concurrent jobs set to 1, so that Bacula will reach for a new volume rather than interleave data.  Things seem to be working fairly well, except that Bacula seems to be making strange decisions as to which volumes to use for which jobs, and I don’t understand why.  It appears to be deciding to use a volume that it knows is in use on another drive.  See the log output below:

 

01-Mar 01:30 zztop.ajboggs.net-dir JobId 31: Start Backup JobId 31, Job=backup-fullset-rush.ajboggs.net.2010-03-01_01.30.00_42

01-Mar 01:30 zztop.ajboggs.net-dir JobId 31: Using Device "changer0_drive0"

01-Mar 01:30 zztop.ajboggs.net-sd JobId 31: 3307 Issuing autochanger "unload slot 4, drive 0" command.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: Warning: Volume "bvol_OnSite01-009" wanted on "changer0_drive0" (/bacula/changer0/drives/drive0) is in use by device "changer0_drive2" (/bacula/changer0/drives/drive2)

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: 3301 Issuing autochanger "loaded? drive 0" command.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: 3302 Autochanger "loaded? drive 0", result: nothing loaded.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: Warning: Volume "bvol_OnSite01-009" not on device "changer0_drive0" (/bacula/changer0/drives/drive0).

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: Marking Volume "bvol_OnSite01-009" in Error in Catalog.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: 3301 Issuing autochanger "loaded? drive 0" command.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: 3302 Autochanger "loaded? drive 0", result: nothing loaded.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: Warning: Volume "bvol_OnSite01-009" not on device "changer0_drive0" (/bacula/changer0/drives/drive0).

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: Marking Volume "bvol_OnSite01-009" in Error in Catalog.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: Warning: mount.c:227 Open device "changer0_drive0" (/bacula/changer0/drives/drive0) Volume "bvol_OnSite01-009" failed: ERR=dev.c:549 Could not open: /bacula/changer0/drives/drive0, ERR=No such file or directory

 

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: 3301 Issuing autochanger "loaded? drive 0" command.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: 3302 Autochanger "loaded? drive 0", result: nothing loaded.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: 3304 Issuing autochanger "load slot 5, drive 0" command.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: 3305 Autochanger "load slot 5, drive 0", status is OK.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: Volume "bvol_OnSite01-005" previously written, moving to end of data.

01-Mar 01:31 zztop.ajboggs.net-sd JobId 31: Ready to append to end of Volume "bvol_OnSite01-005" size=133733782

 

A couple things about the log output doesn’t make any sense to me – first, why is Bacula deciding to use a volume which is in use in another drive, and second, why is it complaining that the volume is not mounted on the drive that it’s chosen for this job when it never asked the autochanger to unload it from the drive it was in, and then load it into the drive it wanted?

 

Any pointers would be appreciated..

 

Thanks,

 

Joe