Bacula-users

[Bacula-users] btape test append ejects the cartridge from tape

2011-09-15 04:35:10
Subject: [Bacula-users] btape test append ejects the cartridge from tape
From: Pierre Bernhardt <pierre AT starcumulus.owl DOT de>
To: bacula-users AT lists.sourceforge DOT net
Date: Thu, 15 Sep 2011 10:32:04 +0200
Hello,

I've installed new backups system on same hardware. The old system
was lenny with an bacula 2.x release working well with this drive.

After installing I've tried to test all the tape drives. For my DLT-8000
tape drive in my Library I have no problem registred and the test has
been ended successfully.

But for my SuperDLT1 internal tape drive if I start the test the cartrige
will ejects at Append files test and then I got messages the test has
been failed with an, tata, "No medium found" error.

I googled about the problem and found one thread from 2007, but there is
no real solution shown nor an answer of an hint to disable "Offline on umount"
to no, which is allready performed by me.


root@backup:/etc/bacula# btape -v -d 99 -c bacula-sd.conf 
/dev/tape/by-path/xen-pci-0-pci-0000\:00\:01.0-scsi-0\:0\:1\:0-nst
Tape block granularity is 1024 bytes.
btape: stored_conf.c:698-0 Inserting director res: backup-mon
btape: stored_conf.c:698-0 Inserting device res: SDLT-1
btape: stored_conf.c:698-0 Inserting device res: DEC TZ89
btape: stored_conf.c:698-0 Inserting device res: QUANTUM DLT8000
btape: butil.c:284 Using device: 
"/dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst" for writing.
btape: btape.c:476 open device "SDLT-1" 
(/dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst): OK
*test

=== Write, rewind, and re-read test ===

I'm going to write 10000 records and an EOF
then write 10000 records and an EOF, then rewind,
and re-read the data to verify that it is correct.

This is an *essential* feature ...

btape: btape.c:1148 Wrote 10000 blocks of 64412 bytes.
btape: btape.c:608 Wrote 1 EOF to "SDLT-1" 
(/dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst)
btape: btape.c:1164 Wrote 10000 blocks of 64412 bytes.
btape: btape.c:608 Wrote 1 EOF to "SDLT-1" 
(/dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst)
btape: btape.c:1206 Rewind OK.
10000 blocks re-read correctly.
Got EOF on tape.
10000 blocks re-read correctly.
=== Test Succeeded. End Write, rewind, and re-read test ===

btape: btape.c:1274 Block position test
btape: btape.c:1286 Rewind OK.
Reposition to file:block 0:4
Block 5 re-read correctly.
Reposition to file:block 0:200
Block 201 re-read correctly.
Reposition to file:block 0:9999
Block 10000 re-read correctly.
Reposition to file:block 1:0
Block 10001 re-read correctly.
Reposition to file:block 1:600
Block 10601 re-read correctly.
Reposition to file:block 1:9999
Block 20000 re-read correctly.
=== Test Succeeded. End Write, rewind, and re-read test ===


=== Append files test ===

This test is essential to Bacula.

I'm going to write one record  in file 0,
                   two records in file 1,
             and three records in file 2

btape: btape.c:578 Rewound "SDLT-1" 
(/dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst)
btape: btape.c:1905 Wrote one record of 64412 bytes.
btape: btape.c:1907 Wrote block to device.
btape: btape.c:608 Wrote 1 EOF to "SDLT-1" 
(/dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst)
btape: btape.c:1905 Wrote one record of 64412 bytes.
btape: btape.c:1907 Wrote block to device.
btape: btape.c:1905 Wrote one record of 64412 bytes.
btape: btape.c:1907 Wrote block to device.
btape: btape.c:608 Wrote 1 EOF to "SDLT-1" 
(/dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst)
btape: btape.c:1905 Wrote one record of 64412 bytes.
btape: btape.c:1907 Wrote block to device.
btape: btape.c:1905 Wrote one record of 64412 bytes.
btape: btape.c:1907 Wrote block to device.
btape: btape.c:1905 Wrote one record of 64412 bytes.
btape: btape.c:1907 Wrote block to device.
btape: btape.c:608 Wrote 1 EOF to "SDLT-1" 
(/dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst)
15-Sep 09:48 btape: Fatal Error at btape.c:472 because:
dev open failed: dev.c:491 Unable to open device "SDLT-1" 
(/dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst): ERR=No medium 
found


Append test failed. Attempting again.
Setting "Hardware End of Medium = no
    and "Fast Forward Space File = no
and retrying append test.



=== Append files test ===

This test is essential to Bacula.

I'm going to write one record  in file 0,
                   two records in file 1,
             and three records in file 2

15-Sep 09:48 btape: ABORTING due to ERROR in dev.c:782
dev.c:781 Bad call to rewind. Device "SDLT-1" 
(/dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst) not open
Bacula interrupted by signal 11: Segmentation violation
Kaboom! btape, btape got signal 11 - Segmentation violation. Attempting 
traceback.
Kaboom! exepath=/etc/bacula
Calling: /etc/bacula/btraceback /etc/bacula/btape 11319 /tmp
execv: /etc/bacula/btraceback failed: ERR=No such file or directory
It looks like the traceback worked ...
Dumping: /tmp/btape.11319.bactrace
btape: lockmgr.c:928 lockmgr disabled

The tape drive shows no error with tapeinfo:

root@backup:/etc/bacula# tapeinfo -f /dev/sg3
Product Type: Tape Drive
Vendor ID: 'COMPAQ  '
Product ID: 'SuperDLT1       '
Revision: '2D2D'
Attached Changer API: No
SerialNumber: 'PKC22H0229  '
MinBlock: 4
MaxBlock: 16777212
SCSI ID: 1
SCSI LUN: 0
Ready: no

I think this is an file which is created from the time of the btape failed:

root@backup:/etc/bacula# cat /tmp/btape.11319.bactrace
Attempt to dump current JCRs
JCR=0x243e728 JobId=0 name=btape JobStatus=T
        use_count=1
        JobType=U JobLevel=F
        sched_time=15-Sep-2011 09:38 start_time=01-Jan-1970 01:00
        end_time=01-Jan-1970 01:00 wait_time=01-Jan-1970 01:00
        db=(nil) db_batch=(nil) batch_started=0
Attempt to dump plugins. Hook count=0

In /var/log/messages I found multiple times this message but it shows only
that the cartridge has been ejected, too:

Sep 15 09:48:51 backup kernel: [71749.811866] st2: Sense Key : Not Ready 
[current]
Sep 15 09:48:51 backup kernel: [71749.811903] st2: Add. Sense: Medium not 
present
...

I can go to the drive and insert the cartrige again, but If I restart the test,
the problem will be again fail near the same time at the same test.

Here the part from my bacula-sd.conf:

Device {
  Name = "SDLT-1"
  Drive Index = 0
  Media Type = "SDLT-1"
  Archive Device = /dev/tape/by-path/xen-pci-0-pci-0000:00:01.0-scsi-0:0:1:0-nst
  AutomaticMount = yes;               # when device opened, read it
  AlwaysOpen = yes;
  RemovableMedia = yes;
  RandomAccess = no;
  AutoChanger = no
  # Enable the Alert command only if you have the mtx package loaded
  Alert Command = "sh -c 'tapeinfo -f %c |grep TapeAlert|cat'"
  Spool Directory = "/tmp"
  Offline On Unmount = No
  Requires Mount = Yes
  Mount Command = "mt -f %a load"
  Unmount Command = "mt -f %a eject"
  Maximum Spool Size = 536870912
}

Remember, with an older release of bacula (from Etch on os Lenny) I didn't had 
the problem.
OS is now Squeeze with Bacula from 5.0.2 from Squeeze, too.
Help is welcome. Thank you.

MfG...
Pierre


------------------------------------------------------------------------------
Doing More with Less: The Next Generation Virtual Desktop 
What are the key obstacles that have prevented many mid-market businesses
from deploying virtual desktops?   How do next-generation virtual desktops
provide companies an easier-to-deploy, easier-to-manage and more affordable
virtual desktop model.http://www.accelacomm.com/jaw/sfnl/114/51426474/
_______________________________________________
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>