Re: [Bacula-users] [Bacula-devel] bug in autolabel code in 5.0.0?


John Drescher wrote:
> On Tue, Feb 2, 2010 at 11:16 AM, Bob Hetzel <beh AT case DOT edu> wrote:
>>
>> John Drescher wrote:
>>> On Tue, Feb 2, 2010 at 10:58 AM, Bob Hetzel <beh AT case DOT edu> wrote:
>>>> Greetings,
>>>>
>>>> I've just upgraded to 5.0.0 and have run into this odd issue.  Bacula
>>>> seems
>>>> to be stuck waiting for a labeled volume, even after it attempts to label
>>>> a
>>>> new volume.  The bconsole messages seem to reflect this loop...
>>>>
>>>> *mess
>>>> 02-Feb 10:32 claustrum-sd JobId 3583: Error: block.c:1012 Read error on
>>>> fd=9 at file:blk 0:0 on device "IBMLTO4-0" (/dev/nst0). ERR=Device or
>>>> resource busy.
>>>> 02-Feb 10:32 claustrum-sd JobId 3583: Labeled new Volume "LTO429L4" on
>>>> device "IBMLTO4-0" (/dev/nst0).
>>>> 02-Feb 10:33 claustrum-sd JobId 3583: Error: block.c:1012 Read error on
>>>> fd=9 at file:blk 0:0 on device "IBMLTO4-0" (/dev/nst0). ERR=Device or
>>>> resource busy.
>>>> 02-Feb 10:33 claustrum-sd JobId 3583: Labeled new Volume "LTO429L4" on
>>>> device "IBMLTO4-0" (/dev/nst0).
>>>> 02-Feb 10:33 claustrum-sd JobId 3583: Error: block.c:1012 Read error on
>>>> fd=9 at file:blk 0:0 on device "IBMLTO4-0" (/dev/nst0). ERR=Device or
>>>> resource busy.
>>>> 02-Feb 10:33 claustrum-sd JobId 3583: Labeled new Volume "LTO429L4" on
>>>> device "IBMLTO4-0" (/dev/nst0).
>>>>
>>>> The tape in question, LTO429L4 is new and never used before.  Here's the
>>>> list volumes output for it
>>>>
>>>> |      48 | LTO429L4   | Append    |       1 |                 0 |
>>>>  0
>>>> |   10,368,000 |       1 |   39 |         1 | LTO       | 0000-00-00
>>>> 00:00:00 |
>>>> +-
>>>>
>>>> *status storage
>>>> Automatically selected Storage: Dell-ML6000
>>>> Connecting to Storage daemon Dell-ML6000 at claustrum:9103
>>>>
>>>> claustrum-sd Version: 5.0.0 (26 January 2010) x86_64-unknown-linux-gnu
>>>> ubuntu 9.10
>>>> Daemon started 02-Feb-10 09:36, 0 Jobs run since started.
>>>>  Heap: heap=2,592,768 smbytes=2,274,219 max_bytes=2,306,226 bufs=168
>>>> max_bufs=174
>>>> Sizes: boffset_t=8 size_t=8 int32_t=4 int64_t=8
>>>>
>>>> Running Jobs:
>>>> Writing: Incremental Backup job pituitary JobId=3583 Volume="LTO429L4"
>>>>    pool="Westwing" device="IBMLTO4-0" (/dev/nst0)
>>>>    spooling=0 despooling=0 despool_wait=0
>>>>    Files=0 Bytes=0 Bytes/sec=0
>>>>    FDReadSeqNo=6 in_msg=6 out_msg=4 fd=5
>>>> Writing: Incremental Backup job cortex JobId=3584 Volume="LTO428L4"
>>>>    pool="Westwing" device="IBMLTO4-1" (/dev/nst1)
>>>>    spooling=0 despooling=0 despool_wait=0
>>>>    Files=0 Bytes=0 Bytes/sec=0
>>>>    FDReadSeqNo=6 in_msg=6 out_msg=4 fd=7
>>>> ====
>>>>
>>>> Jobs waiting to reserve a drive:
>>>> ====
>>>>
>>>> Terminated Jobs:
>>>> <content deleted>
>>>> ====
>>>>
>>>> Device status:
>>>> Autochanger "Dell-ML6000" with devices:
>>>>   "IBMLTO4-0" (/dev/nst0)
>>>>   "IBMLTO4-1" (/dev/nst1)
>>>> Device "IBMLTO4-0" (/dev/nst0) open but no Bacula volume is currently
>>>> mounted.
>>>>    Device is being initialized.
>>>>    Slot 39 is loaded in drive 0.
>>>>    Total Bytes Read=0 Blocks Read=0 Bytes/block=0
>>>>    Positioned at File=0 Block=0
>>>> Device "IBMLTO4-1" (/dev/nst1) open but no Bacula volume is currently
>>>> mounted.
>>>>    Device is being initialized.
>>>>    Slot 38 is loaded in drive 1.
>>>>    Total Bytes Read=0 Blocks Read=0 Bytes/block=0
>>>>    Positioned at File=0 Block=0
>>>> ====
>>>>
>>>> Used Volume status:

I've turned the debug level up to 100, told bacula to mount slot=39 into 
drive 0 and got the following in the SD's trace file:

claustrum-sd: dircmd.c:213-0 Message channel init completed.
claustrum-sd: bnet.c:669-0 who=client host=127.0.1.1 port=36643
claustrum-sd: cram-md5.c:73-0 send: auth cram-md5 
<819442589.1265155107@claustrum-sd> ssl=0
claustrum-sd: cram-md5.c:133-0 cram-get received: auth cram-md5 
<1976517466.1265155107@claustrum-dir> ssl=0
claustrum-sd: cram-md5.c:152-0 sending resp to challenge: 
F7cy0QYVSVRL10owCD/D/C
claustrum-sd: dircmd.c:213-0 Message channel init completed.
claustrum-sd: dircmd.c:599-0 Try changer device IBMLTO4-0
claustrum-sd: dircmd.c:615-0 Found changer device IBMLTO4-0
claustrum-sd: dircmd.c:628-0 Found device IBMLTO4-0
claustrum-sd: dircmd.c:660-0 mount cmd blocked=0 must_unload=0
claustrum-sd: autochanger.c:133-0 autoload: slot=39 InChgr=1 Vol=
claustrum-sd: autochanger.c:273-0 Run program=/etc/bacula/mtx-changer 
/dev/changer loaded 39 /dev/nst0 0
claustrum-sd: autochanger.c:275-0 run_prog: /etc/bacula/mtx-changer 
/dev/changer loaded 39 /dev/nst0 0 stat=0 result=0
claustrum-sd: autochanger.c:190-0 Doing changer load slot 39 "IBMLTO4-0" 
(/dev/nst0)
claustrum-sd: dev.c:1919-0 close_dev "IBMLTO4-0" (/dev/nst0)
claustrum-sd: dev.c:1926-0 device "IBMLTO4-0" (/dev/nst0) already closed vol=
claustrum-sd: autochanger.c:202-0 load slot 39, drive 0, status is OK.
claustrum-sd: autochanger.c:215-0 load slot 39 status=0
claustrum-sd: autochanger.c:221-0 After changer, status=0
claustrum-sd: dev.c:361-0 open dev: type=2 dev_name="IBMLTO4-0" (/dev/nst0) 
vol= mode=OPEN_READ_ONLY
claustrum-sd: dev.c:411-0 Open dev: device is tape
claustrum-sd: dev.c:426-0 Try open "IBMLTO4-0" (/dev/nst0) mode=OPEN_READ_ONLY
claustrum-sd: dev.c:448-0 Rewind after open
claustrum-sd: dev.c:2581-0 In set_os_device_parameters
claustrum-sd: dev.c:2603-0 MTSETDRVBUFFER
claustrum-sd: dev.c:500-0 open dev: tape 7 opened
claustrum-sd: dev.c:374-0 preserve=0x0 fd=7
claustrum-sd: label.c:81-0 Enter read_volume_label res=0 device="IBMLTO4-0" 
(/dev/nst0) vol= dev_Vol=*NULL*
claustrum-sd: block.c:999-0 ===== read retry=1 stat=-1 errno=16: ERR=Device 
or resource busy
claustrum-sd: block.c:999-0 ===== read retry=2 stat=-1 errno=16: ERR=Device 
or resource busy
claustrum-sd: block.c:999-0 ===== read retry=3 stat=-1 errno=16: ERR=Device 
or resource busy
claustrum-sd: label.c:163-0 No volume label - bailing out



------------------------------------------------------------------------------
The Planet: dedicated and managed hosting, cloud storage, colocation
Stay online with enterprise data centers and the best network in the business
Choose flexible plans and management services without long-term contracts
Personal 24x7 support from experience hosting pros just a phone call away.
http://p.sf.net/sfu/theplanet-com
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users