Hi all,
I'm using 5.0 on centos 6 with 10 hot-swap sata drives in vchanger. It's
been running fine for quite some time, here's what I got this morning:
The "volumes" got written to vchanger_0007_0011 timestamped 11:13 pm
last night.
Then @ 02-Sep 23:13 the log goes
... shell command: run BeforeJob
"/usr/libexec/bacula/make_catalog_backup bacula bacula"
... Start Backup JobId 5430, Job=BackupCatalog.2014-09-02_23.10.00_37
... Using Device "Vchanger-Drive"
... Issuing autochanger "unload slot 340, drive 0" command.
... 3304 Issuing autochanger "load slot 293, drive 0" command.
... 3305 Autochanger "load slot 293, drive 0", status is OK.
... Volume "vchanger_0007_0011" previously written, moving to end of data.
... Ready to append to end of Volume "vchanger_0007_0011" size=47130067587
... Spooling data ...
... Job write elapsed time = 00:00:18, Transfer rate = 441.7 M Bytes/second
... Committing spooled data to Volume "vchanger_0007_0011". Despooling
7,958,134,476 bytes ...
... User defined maximum volume capacity 49,807,360,000 exceeded on
device "Vchanger-Drive" (/var/spool/bacula/vchanger/0/drive0).
... End of medium on Volume "vchanger_0007_0011" Bytes=49,807,315,584
Blocks=772,093 at 02-Sep-2014 23:13.
... 3304 Issuing autochanger "load slot 293, drive 0" command.
... 3305 Autochanger "load slot 293, drive 0", status is OK.
... Warning: Director wanted Volume "vchanger_0008_0011".
Current Volume "vchanger_0007_0011" not acceptable because:
1998 Volume "vchanger_0007_0011" status is Full, volume has
recycling disabled.
... 3307 Issuing autochanger "unload slot 293, drive 0" command.
... 3301 Issuing autochanger "loaded? drive 0" command.
... 3302 Autochanger "loaded? drive 0", result: nothing loaded.
... 3304 Issuing autochanger "load slot 293, drive 0" command.
... 3305 Autochanger "load slot 293, drive 0", status is OK.
... Warning: Director wanted Volume "vchanger_0008_0011".
Current Volume "vchanger_0007_0011" not acceptable because:
1998 Volume "vchanger_0007_0011" status is Full, volume has
recycling disabled.
and so on until I got to work and killed bacula-dir. Somewhere in the
middle it sent out the "intervention needed" e-mail asking me to "label
a new one" -- there are labeled volumes in magazines from vchanger_0001
to vchanger_0010, with 47 volumes per magazine. I.e. volumes from
vchanger_0007_0012 to vchanger_0010_0047 are all labeled, empty,
on-line, and with "append" status.
So aside from the bug, why does director want vchanger_0008_0011 instead
of vchanger_0007_0012? If there's no guarantee all of the job will go to
volumes in the same magazine, then vchanger is not quite so useful.
Looking at the media, I now have full volumes down to vchanger_0007_0011
(incl), then empty ones to vchanger_0008_0011, that one's 70% full, then
empty ones to vchanger_0009_0011 @ 20% full (touched last night), then
empty ones to vchanger_0010_0011 @ 50% full (also touched last night),
then empties to vchanger_001_0047, full, written in May 2013, and
finally empties to vchanger_0011_0011 @ 25% full, last written last night.
Anybody knows wtf is going on?
