Bacula-users

[Bacula-users] read error

2008-05-14 19:25:41
Subject: [Bacula-users] read error
From: Mark Nienberg <gmane AT tippingmar DOT com>
To: bacula-users AT lists.sourceforge DOT net
Date: Wed, 14 May 2008 16:25:22 -0700
I continue to be plagued with the problem shown below.  About 80% of the time 
it 
works perfectly.  The rest of the time I have to use bconsole to unmount, then 
mount 
the tape again.  The second read always works and the backup continues.  Of 
course it 
happens in the middle of the night when the changer switches tapes and so I 
have to 
intervene the next day.

The good news is this has nothing to do with the tape change.  The mtx-changer 
script 
is waiting until the mt command returns a status of ONLINE.  It does so 
successfully, 
and I can see it in the mtx-changer log. This means the drive was able to read 
the 
system area of the tape and is happy with it.  I don't know why then bacula has 
trouble reading the data portion.

I'm thinking about writing a script that cron could run every hour to see if 
the 
device is blocked even if the correct tape is loaded.  If so, it could do a 
unmount/mount.  Or maybe I could add some command to the changer script to read 
some 
data off the tape after it loads or something?  Or maybe bacula could be 
improved to 
try harder (whatever that means) to resolve the issue on its own?

Bacula 2.2.8 with a VXA320 drive and packetloader changer.

Thanks for any ideas.

Mark

10-May 07:24 khyber-dir JobId 2043: All records pruned from Volume "A0000006"; 
marking it "Purged"
10-May 07:24 khyber-dir JobId 2043: Recycled volume "A0000006"
10-May 07:24 khyber-sd JobId 2043: 3307 Issuing autochanger "unload slot 3, 
drive 0" 
command.
10-May 07:25 khyber-sd JobId 2043: 3304 Issuing autochanger "load slot 5, drive 
0" 
command.
10-May 07:26 khyber-sd JobId 2043: 3305 Autochanger "load slot 5, drive 0", 
status is OK.
10-May 07:26 khyber-sd JobId 2043: 3301 Issuing autochanger "loaded? drive 0" 
command.
10-May 07:26 khyber-sd JobId 2043: 3302 Autochanger "loaded? drive 0", result 
is Slot 5.

10-May 07:26 khyber-sd JobId 2043: Error: block.c:995 Read error on fd=7 at 
file:blk 
0:0 on device "VXA3drive" (/dev/nst0). ERR=Input/output error.
10-May 07:26 khyber-sd JobId 2043: Error: block.c:995 Read error on fd=7 at 
file:blk 
0:0 on device "VXA3drive" (/dev/nst0). ERR=Input/output error.
10-May 07:26 khyber-sd JobId 2043: Error: block.c:995 Read error on fd=7 at 
file:blk 
0:0 on device "VXA3drive" (/dev/nst0). ERR=Input/output error.
10-May 07:26 khyber-sd JobId 2043: Error: block.c:995 Read error on fd=7 at 
file:blk 
0:0 on device "VXA3drive" (/dev/nst0). ERR=Input/output error.
10-May 07:26 khyber-sd JobId 2043: Error: block.c:995 Read error on fd=7 at 
file:blk 
0:0 on device "VXA3drive" (/dev/nst0). ERR=Input/output error.

10-May 07:26 khyber-sd JobId 2043: Please mount Volume "A0000006" or label a 
new one for:
     Job:          gecko.2008-05-10_01.05.59
     Storage:      "VXA3drive" (/dev/nst0)
     Pool:         WeekBar
     Media type:   VXA3

--snip--

10-May 09:22 khyber-sd JobId 2043: 3301 Issuing autochanger "loaded? drive 0" 
command.
10-May 09:22 khyber-sd JobId 2043: 3302 Autochanger "loaded? drive 0", result 
is Slot 5.
10-May 09:22 khyber-sd JobId 2043: Recycled volume "A0000006" on device 
"VXA3drive" 
(/dev/nst0), all previous data lost.
10-May 09:22 khyber-sd JobId 2043: New volume "A0000006" mounted on device 
"VXA3drive" (/dev/nst0) at 10-May-2008 09:22.
10-May 10:11 khyber-sd JobId 2043: Job write elapsed time = 07:00:09, Transfer 
rate = 
8.237 M bytes/second


-------------------------------------------------------------------------
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 AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users

<Prev in Thread] Current Thread [Next in Thread>