When trying to run a backup-job, bacula loads the correct tape, but fail
to use it.
If I manualy load correct tape, and in the console mount the drive
before starting the job, the job runs correct, the changer seams to work
well to, as it unload a tape, and inserting the correct tape. I have
tried to add a "sleep 10" in the load command in the chio-changer
script, without luck.
All tests with the "btape test" ran flawless.
################
#Error message:#
################
30-Nov 21:26 alexandria-dir: Start Backup JobId 33,
Job=PatMailBackup.2005-11-30_21.26.06
30-Nov 20:26 pat: DIR and FD clocks differ by -3598 seconds, FD
automatically adjusting.
30-Nov 21:26 alexandria-sd: 3301 Issuing autochanger "loaded drive 0"
command.
30-Nov 21:26 alexandria-sd: 3302 Autochanger "loaded drive 0", result is
Slot 9.
30-Nov 21:26 alexandria-sd: 3307 Issuing autochanger "unload slot 9,
drive 0" command.
30-Nov 21:27 alexandria-sd: 3304 Issuing autochanger "load slot 1, drive
0" command.
30-Nov 21:27 alexandria-sd: 3305 Autochanger "load slot 1, drive 0",
status is OK.
30-Nov 20:27 pat: PatMailBackup.2005-11-30_21.26.06 Fatal error:
job.c:1662 Comm error with SD. bad response to Append Data. ERR=Resource
temporarily unavailable
30-Nov 21:27 alexandria-dir: PatMailBackup.2005-11-30_21.26.06 Error:
Bacula 1.38.0 (28Oct05): 30-Nov-2005 21:27:54
########################################################################
Here is a log from a successful backup when correct tape is loaded and #
mounted before job is started: #
########################################################################
02-Dec 18:02 alexandria-dir: Start Backup JobId 41,
Job=PatMailBackup.2005-12-02_18.02.33
02-Dec 17:02 pat: DIR and FD clocks differ by -3598 seconds, FD
automatically adjusting.
02-Dec 18:02 alexandria-sd: 3301 Issuing autochanger "loaded drive 0"
command.
02-Dec 18:02 alexandria-sd: 3302 Autochanger "loaded drive 0", result is
Slot 2.
02-Dec 18:02 alexandria-sd: 3301 Issuing autochanger "loaded drive 0"
command.
02-Dec 18:02 alexandria-sd: 3302 Autochanger "loaded drive 0", result is
Slot 2.
02-Dec 18:02 alexandria-sd: Wrote label to prelabeled Volume "000002L2"
on device "TapeLib0-Drive0" (/dev/nsa0)
02-Dec 18:02 alexandria-sd: Spooling data ...
02-Dec 18:05 alexandria-sd: Committing spooled data to Volume.
Despooling 1,314,803,267 bytes ...
02-Dec 18:06 alexandria-sd: Sending spooled attrs to the Director.
Despooling 3,921,248 bytes ...
02-Dec 18:06 alexandria-dir: Bacula 1.38.0 (28Oct05): 02-Dec-2005 18:06:36
JobId: 41
Job: PatMailBackup.2005-12-02_18.02.33
Backup Level: Incremental, since=2005-11-29 13:13:54
Client: "Pat"
i386-unknown-freebsd5.2.1,freebsd,5.2.1-RELEASE
FileSet: "PatFileSet" 2005-11-29 00:05:02
Pool: "IncrementalTape0"
Storage: "Autochanger0"
Scheduled time: 02-Dec-2005 18:02:30
Start time: 02-Dec-2005 18:02:35
End time: 02-Dec-2005 18:06:36
Priority: 10
FD Files Written: 17,923
SD Files Written: 17,923
FD Bytes Written: 1,310,628,325
SD Bytes Written: 1,313,044,041
Rate: 5438.3 KB/s
Software Compression: None
Volume name(s): 000002L2
Volume Session Id: 1
Volume Session Time: 1133546529
Last Volume Bytes: 1,314,558,744
Non-fatal FD errors: 0
SD Errors: 0
FD termination status: OK
SD termination status: OK
Termination: Backup OK
here are my relevant configurations:
###########
#Director:#
###########
Job {
Name = "PatMailBackup"
Client = Pat
Type = Backup
Messages = Standard
Storage = Autochanger0
FileSet="PatFileSet"
Schedule = "NightlyTape"
Priority = 10
Level = Incremental
Pool = IncrementalTape0
Full Backup Pool = FullTape0
SpoolData = yes
}
Storage {
Name = Autochanger0
Device = TapeLib0-Drive0, TapeLib0-Drive1
Address = xxxx.xxxxx.xxx
SDPort = 9103
Password = "iTxZosxNduiE6pcxASWch7CzW6CevcK8eFDqptmXFBwc"
Device = "Autochanger0"
Media Type = LTO-2
Autochanger = yes
}
###############
#Storagedaemon#
###############
Autochanger {
Name = "Autochanger0"
Device = TapeLib0-Drive0, TapeLib0-Drive1
Changer Device = /dev/sh0
Changer Command = "/usr/local/sbin/chio-changer %o %S %a"
}
Device {
Name = "TapeLib0-Drive0"
Media Type = LTO-2
Archive Device = /dev/nsa0 # Normal archive device
Changer Device = /dev/ch0 # Generic SCSI device name
# Changer Command = "/usr/local/sbin/mtx-changer %c %o %S %a %d"
# Changer Command = "/usr/local/sbin/chio-changer %c %o %S %a"
Changer Command = "/usr/local/sbin/chio-changer %o %S %a"
Autochanger = yes
LabelMedia = no
AutomaticMount = yes
AlwaysOpen = yes
############### for testing ##############
Backward Space Record = no
Backward Space File = no
#########################################
Hardware End of Medium = no
Fast Forward Space File = yes
BSF at EOM = yes
Drive Index = 0
TWO EOF = yes
}
TapeLib0-Drive1 is identical to TapeLib0-Drive0, but has never been used.
Everyting compiled from source on FreeBSD 6.0 Release I386
Bacula source: 1.38
./configure \
--sbindir=/usr/local/bin \
--sysconfdir=/usr/local/etc/bacula \
--with-pid-dir=/var/run/bacula \
--with-subsys-dir=/usr/local/bacula/bin \
--with-mysql=yes \
--with-working-dir=/usr/local/bacula/bin/working \
[EMAIL PROTECTED] \
--with-smtp-host=smtp.xxxx.xxxx.xxx \
--with-dir_user=bacula \
--with-fd_user=bacula \
--with-sd_user=bacula \
--enable-readline
Hardware:
alexandria# camcontrol devlist
<SEAGATE ST318453LW 0006> at scbus0 target 0 lun 0 (da0,pass0)
<DELL PV-136T-FC 4194> at scbus2 target 129 lun 0 (pass1)
<DELL PV-136T 3.11> at scbus2 target 129 lun 1 (pass2,ch0)
<IBM ULTRIUM-TD2 37RH> at scbus2 target 129 lun 2 (pass3,sa0)
<IBM ULTRIUM-TD2 37RH> at scbus2 target 129 lun 3 (pass4,sa1)
<SEAGATE ST3146807FC 0006> at scbus2 target 130 lun 0 (pass5,da1)
alexandria#
begin:vcard
fn:Roger Kvam
n:Kvam;Roger
org:Atmel Norway;IT Operations
adr:;;Vestre Rosten 79;Trondheim;Tiller;7075;Norway
email;internet:[EMAIL PROTECTED]
title:System Engineer IT
tel;work:+47 72897561
tel;fax:+47 72884399
tel;cell:+47 92295777
x-mozilla-html:FALSE
url:http://www.atmel.com
version:2.1
end:vcard