Bacula-users

[Bacula-users] btape "fill" fails ... what can I do ?

2012-10-09 08:36:06
Subject: [Bacula-users] btape "fill" fails ... what can I do ?
From: Durand Toto <gnewbee AT gmail DOT com>
To: bacula-users <bacula-users AT lists.sourceforge DOT net>
Date: Tue, 9 Oct 2012 13:33:36 +0100
Hi all,


I have an issue when changing between tapes, which provides the following message:

>         Error: Re-read last block at EOT failed. ERR=block.c:1029 Read zero
> bytes at 818:0 on device "LTO5" (/dev/nst0).


I tried btape
*test : all tests succeeded
*autochanger : all tests succeeded
* fill :
I get a labelling error
           Error: 08-Oct 20:16 btape JobId 0: Wrote label to prelabeled Volume "TestVolume2" on device "LTO5" (/dev/nst0)
Something that makes no sense
           Error: 08-Oct 20:18 btape JobId 0: Ready to read from volume "TestVolume1" on device "LTO5" (/dev/nst0).
but more importantly I still get
           Error: block.c:1029 Read zero bytes at 0:0 on device "LTO5" (/dev/nst0).

However, the test seems happy:
           The last block on the second tape matches. Test succeeded.


Could you please suggest some troubleshooting ideas ?


Best,

Gnewbee

P.S.:
end of full btape fill output:
20:12:34 Flush block, write EOF
Wrote block=23430000, file,blk=282,45179 VolBytes=1,511,516,095,488 rate=120.5 MB/s
Wrote block=23435000, file,blk=282,50179 VolBytes=1,511,838,655,488 rate=120.5 MB/s
Wrote block=23440000, file,blk=282,55179 VolBytes=1,512,161,215,488 rate=120.5 MB/s
Wrote block=23445000, file,blk=282,60179 VolBytes=1,512,483,775,488 rate=120.5 MB/s
Wrote block=23450000, file,blk=282,65179 VolBytes=1,512,806,335,488 rate=120.5 MB/s
Wrote block=23455000, file,blk=282,70179 VolBytes=1,513,128,895,488 rate=120.5 MB/s
Wrote block=23460000, file,blk=282,75179 VolBytes=1,513,451,455,488 rate=120.5 MB/s
Wrote block=23465000, file,blk=282,80179 VolBytes=1,513,774,015,488 rate=120.6 MB/s
Wrote block=23470000, file,blk=283,1959 VolBytes=1,514,096,575,488 rate=120.5 MB/s
Wrote block=23475000, file,blk=283,6959 VolBytes=1,514,419,135,488 rate=120.5 MB/s
Wrote block=23480000, file,blk=283,11959 VolBytes=1,514,741,695,488 rate=120.5 MB/s
Wrote block=23485000, file,blk=283,16959 VolBytes=1,515,064,255,488 rate=120.5 MB/s
Wrote block=23490000, file,blk=283,21959 VolBytes=1,515,386,815,488 rate=120.5 MB/s
Wrote block=23495000, file,blk=283,26959 VolBytes=1,515,709,375,488 rate=120.5 MB/s
Wrote block=23500000, file,blk=283,31959 VolBytes=1,516,031,935,488 rate=120.5 MB/s
Wrote block=23505000, file,blk=283,36959 VolBytes=1,516,354,495,488 rate=120.5 MB/s
Wrote block=23510000, file,blk=283,41959 VolBytes=1,516,677,055,488 rate=120.5 MB/s
Wrote block=23515000, file,blk=283,46959 VolBytes=1,516,999,615,488 rate=120.5 MB/s
Wrote block=23520000, file,blk=283,51959 VolBytes=1,517,322,175,488 rate=120.5 MB/s
Wrote block=23525000, file,blk=283,56959 VolBytes=1,517,644,735,488 rate=120.5 MB/s
Wrote block=23530000, file,blk=283,61959 VolBytes=1,517,967,295,488 rate=120.5 MB/s
Wrote block=23535000, file,blk=283,66959 VolBytes=1,518,289,855,488 rate=120.5 MB/s
Wrote block=23540000, file,blk=283,71959 VolBytes=1,518,612,415,488 rate=120.6 MB/s
Wrote block=23545000, file,blk=283,76959 VolBytes=1,518,934,975,488 rate=120.6 MB/s
Wrote block=23550000, file,blk=283,81959 VolBytes=1,519,257,535,488 rate=120.6 MB/s
08-Oct 20:13 btape JobId 0: End of Volume "TestVolume1" at 283:82917 on device "LTO5" (/dev/nst0). Write of 64512 bytes got -1.
08-Oct 20:13 btape JobId 0: Re-read of last block succeeded.
btape: btape.c:2701 Last block at: 283:82916 this_dev_block_num=82917
btape: btape.c:2736 End of tape 283:0. Volume Bytes=1,519,319,337,984. Write rate = 120.5 MB/s
08-Oct 20:13 btape JobId 0: End of medium on Volume "TestVolume1" Bytes=1,519,319,337,984 Blocks=23,550,957 at 08-Oct-2012 20:13.
08-Oct 20:13 btape JobId 0: 3307 Issuing autochanger "unload slot 1, drive 0" command.
08-Oct 20:16 btape JobId 0: 3301 Issuing autochanger "loaded? drive 0" command.
08-Oct 20:16 btape JobId 0: 3302 Autochanger "loaded? drive 0", result: nothing loaded.
08-Oct 20:16 btape JobId 0: 3304 Issuing autochanger "load slot 2, drive 0" command.
08-Oct 20:16 btape JobId 0: 3305 Autochanger "load slot 2, drive 0", status is OK.
Wrote Volume label for volume "TestVolume2".
08-Oct 20:16 btape JobId 0: Error: 08-Oct 20:16 btape JobId 0: Wrote label to prelabeled Volume "TestVolume2" on device "LTO5" (/dev/nst0)
08-Oct 20:16 btape JobId 0: New volume "TestVolume2" mounted on device "LTO5" (/dev/nst0) at 08-Oct-2012 20:16.
btape: btape.c:2311 Wrote 1000 blocks on second tape. Done.
Done writing 0 records ...
Wrote End of Session label.
btape: btape.c:2380 Wrote state file last_block_num1=82916 last_block_num2=1001
btape: btape.c:2398

20:16:42 Done filling tapes at 0:1003. Now beginning re-read of first tape ...
btape: btape.c:2476 Enter do_unfill
08-Oct 20:16 btape JobId 0: 3307 Issuing autochanger "unload slot 2, drive 0" command.
08-Oct 20:17 btape JobId 0: 3304 Issuing autochanger "load slot 1, drive 0" command.
08-Oct 20:18 btape JobId 0: 3305 Autochanger "load slot 1, drive 0", status is OK.
08-Oct 20:18 btape JobId 0: Error: 08-Oct 20:18 btape JobId 0: Ready to read from volume "TestVolume1" on device "LTO5" (/dev/nst0).
Rewinding.
Reading the first 10000 records from 0:0.
10000 records read now at 1:5084
Reposition from 1:5084 to 283:82916
Reading block 82916.

The last block of the first tape matches.

08-Oct 20:19 btape JobId 0: 3307 Issuing autochanger "unload slot 1, drive 0" command.
08-Oct 20:22 btape JobId 0: 3304 Issuing autochanger "load slot 2, drive 0" command.
08-Oct 20:22 btape JobId 0: 3305 Autochanger "load slot 2, drive 0", status is OK.
08-Oct 20:22 btape JobId 0: Error: block.c:1029 Read zero bytes at 0:0 on device "LTO5" (/dev/nst0).
08-Oct 20:22 btape JobId 0: Ready to read from volume "TestVolume2" on device "LTO5" (/dev/nst0).
Reposition from 0:0 to 0:1
Reading block 1.

The first block on the second tape matches.

Reposition from 0:2 to 0:1001
Reading block 1001.

The last block on the second tape matches. Test succeeded.



------------------------------------------------------------------------------
Don't let slow site performance ruin your business. Deploy New Relic APM
Deploy New Relic app performance management and know exactly
what is happening inside your Ruby, Python, PHP, Java, and .NET app
Try New Relic at no cost today and get our sweet Data Nerd shirt too!
http://p.sf.net/sfu/newrelic-dev2dev
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users