Bacula-users

Re: [Bacula-users] btape autochanger errors (Device /dev/nst0 - not ready, retrying... - ERR=Child died from signal 9: Kill, unblockable)

2013-04-15 14:10:51
Subject: Re: [Bacula-users] btape autochanger errors (Device /dev/nst0 - not ready, retrying... - ERR=Child died from signal 9: Kill, unblockable)
From: LDC - Gustavo El Khoury <gustavo AT ldc.usb DOT ve>
To: Marco Carcano- Statusnobilis SA <marco.carcano AT statusnobilis DOT com>
Date: Mon, 15 Apr 2013 13:37:03 -0430
It seems the mtx-changer script is crashing or something... Mmmm... About the 
ready messages, i've seen some people modify the mtx script to increase the 
time it waits for a retry, but there doesn't seem to ve any errors after 
waiting...
Question: is Bacula trying to load an already loaded tape or something? In the 
example it tries to load the tape in slot 1. What's in the drive and in slot 1 
at the moment?

LDC - Gustavo El Khoury

On 15/04/2013, at 12:02 p.m., Marco Carcano- Statusnobilis SA <marco.carcano AT 
statusnobilis DOT com> wrote:

> Hello everybody
> 
> I hope that someone can help me:
> 
> I'm trying to setup an HP MSL 2024 to work with bacula 5.0.0 (26 January 
> 2010) - I installed it with yum using CentOS 6 base repository
> 
> I tested the autochanger with the commands:
> 
> /usr/libexec/bacula/mtx-changer /dev/sg3 listall 0 /dev/nst0 and got the 
> following output:
> 
> D:0:F:1:PT7789L3
> S:1:E
> S:2:F:PT7784L3
> S:3:E
> S:4:F:CLN002L1
> S:5:F:PT7783L3
> S:6:F:PT7788L3
> S:7:E
> S:8:F:CLN001L1
> S:9:F:PT7782L3
> S:10:F:PT7787L3
> S:11:F:PT7785L3
> S:12:E
> S:13:E
> S:14:E
> S:15:E
> S:16:E
> S:17:E
> S:18:E
> S:19:E
> S:20:E
> S:21:E
> S:22:E
> S:23:E
> S:24:E
> 
> tried to move tapes into the drive
> 
> /usr/libexec/bacula/mtx-changer /dev/sg3 loaded 0  /dev/nst0 0
> 1
> 
> and back to the slot
> /usr/libexec/bacula/mtx-changer /dev/sg3 unload 1 /dev/nst0 0
> Unloading drive 0 into Storage Element 1...done
> 
> this all seems to work all right, but If I try btape:
> 
> the tape seems to be ok (I have previously loaded it with mtx-changer), 
> but the autochanger test fails with the error:
> 
> 3301 Issuing autochanger "loaded" command.
> 3991 Bad autochanger command: /usr/libexec/bacula/mtx-changer /dev/sg3 
> loaded 1 /dev/nst0 0
> 3991 result="": ERR=Child died from signal 9: Kill, unblockable
> You must correct this error or the Autochanger will not work.
> 
> I enabled debugging in mtx-changer.conf and noticed a lot of not ready 
> messages like these:
> 
> 20130415-17:17:17 Doing mtx -f /dev/sg3 load 1 0
> 20130415-17:18:11 Device /dev/nst0 - not ready, retrying...
> 20130415-17:18:12 Device /dev/nst0 - not ready, retrying...
> 20130415-17:18:13 Device /dev/nst0 - not ready, retrying...
> 20130415-17:18:14 Device /dev/nst0 - not ready, retrying...
> 20130415-17:18:15 Device /dev/nst0 - not ready, retrying...
> .......
> 0130415-17:23:11 Device /dev/nst0 - not ready, retrying...
> 20130415-17:23:12 Device /dev/nst0 - not ready, retrying...
> 20130415-17:23:13 Device /dev/nst0 - not ready, retrying...
> 20130415-17:23:14 Device /dev/nst0 - not ready, retrying...
> 20130415-17:23:15 Device /dev/nst0 - not ready, retrying...
> 20130415-17:47:01 Parms: /dev/sg3 loaded 0 /dev/nst0 0
> 20130415-17:47:01 Doing mtx -f /dev/sg3 0 -- to find what is loaded
> 20130415-17:48:49 Parms: /dev/sg3 loaded 0 /dev/nst0 0
> 
> how can I manage? may someone help please?
> 
> the only "strange" thing I did is that as I have not yet LTO4 RW barcode 
> labels I used LTO3 RW ones, but may this be the cause of not ready messages?
> 
> kind regards
> 
> Marco Carcano
> 
> 
> 
> 
> # btape -c /etc/bacula/bacula-sd.conf /dev/nst0
> Tape block granularity is 1024 bytes.
> btape: butil.c:285 Using device: "/dev/nst0" for writing.
> 15-Apr 17:48 btape JobId 0: 3301 Issuing autochanger "loaded? drive 0" 
> command.
> 15-Apr 17:48 btape JobId 0: 3302 Autochanger "loaded? drive 0", result 
> is Slot 1.
> btape: btape.c:476 open device "Drive-0" (/dev/nst0): 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 "Drive-0" (/dev/nst0)
> 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 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 "Drive-0" (/dev/nst0)
> 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 "Drive-0" (/dev/nst0)
> 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:578 Rewound "Drive-0" (/dev/nst0)
> btape: btape.c:1418 Now moving to end of medium.
> btape: btape.c:629 Moved to end of medium.
> We should be in file 3. I am at file 3. This is correct!
> 
> Now the important part, I am going to attempt to append to the tape.
> 
> 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 "Drive-0" (/dev/nst0)
> btape: btape.c:608 Wrote 1 EOF to "Drive-0" (/dev/nst0)
> btape: btape.c:578 Rewound "Drive-0" (/dev/nst0)
> Done appending, there should be no I/O errors
> 
> Doing Bacula scan of blocks:
> 1 block of 64448 bytes in file 1
> End of File mark.
> 2 blocks of 64448 bytes in file 2
> End of File mark.
> 3 blocks of 64448 bytes in file 3
> End of File mark.
> 1 block of 64448 bytes in file 4
> End of File mark.
> Total files=4, blocks=7, bytes = 451,136
> End scanning the tape.
> We should be in file 4. I am at file 4. This is correct!
> 
> The above Bacula scan should have output identical to what follows.
> Please double check it ...
> === Sample correct output ===
> 1 block of 64448 bytes in file 1
> End of File mark.
> 2 blocks of 64448 bytes in file 2
> End of File mark.
> 3 blocks of 64448 bytes in file 3
> End of File mark.
> 1 block of 64448 bytes in file 4
> End of File mark.
> Total files=4, blocks=7, bytes = 451,136
> === End sample correct output ===
> 
> If the above scan output is not identical to the
> sample output, you MUST correct the problem
> or Bacula will not be able to write multiple Jobs to
> the tape.
> 
> Skipping read backwards test because BSR turned off.
> 
> 
> === Forward space files test ===
> 
> This test is essential to Bacula.
> 
> I'm going to write five files then test forward spacing
> 
> btape: btape.c:578 Rewound "Drive-0" (/dev/nst0)
> 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 "Drive-0" (/dev/nst0)
> 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 "Drive-0" (/dev/nst0)
> 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 "Drive-0" (/dev/nst0)
> 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 "Drive-0" (/dev/nst0)
> 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 "Drive-0" (/dev/nst0)
> btape: btape.c:608 Wrote 1 EOF to "Drive-0" (/dev/nst0)
> btape: btape.c:578 Rewound "Drive-0" (/dev/nst0)
> btape: btape.c:1632 Now forward spacing 1 file.
> We should be in file 1. I am at file 1. This is correct!
> btape: btape.c:1644 Now forward spacing 2 files.
> We should be in file 3. I am at file 3. This is correct!
> btape: btape.c:578 Rewound "Drive-0" (/dev/nst0)
> btape: btape.c:1657 Now forward spacing 4 files.
> We should be in file 4. I am at file 4. This is correct!
> 
> btape: btape.c:1675 Now forward spacing 1 more file.
> We should be in file 5. I am at file 5. This is correct!
> 
> === End Forward space files test ===
> 
> 
> Ah, I see you have an autochanger configured.
> To test the autochanger you must have a blank tape
>  that I can write on in Slot 1.
> 
> Do you wish to continue with the Autochanger test? (y/n): y
> 
> 
> === Autochanger test ===
> 
> 3301 Issuing autochanger "loaded" command.
> 3991 Bad autochanger command: /usr/libexec/bacula/mtx-changer /dev/sg3 
> loaded 1 /dev/nst0 0
> 3991 result="": ERR=Child died from signal 9: Kill, unblockable
> You must correct this error or the Autochanger will not work.
> 
> === End Forward space files test ===
> 
> 
> Ah, I see you have an autochanger configured.
> To test the autochanger you must have a blank tape
>  that I can write on in Slot 1.
> 
> Do you wish to continue with the Autochanger test? (y/n): y
> 
> 
> === Autochanger test ===
> 
> 3301 Issuing autochanger "loaded" command.
> 3991 Bad autochanger command: /usr/libexec/bacula/mtx-changer /dev/sg3 
> loaded 1 /dev/nst0 0
> 3991 result="": ERR=Child died from signal 9: Kill, unblockable
> You must correct this error or the Autochanger will not work.
> 
> 
> ------------------------------------------------------------------------------
> Precog is a next-generation analytics platform capable of advanced
> analytics on semi-structured data. The platform includes APIs for building
> apps and a phenomenal toolset for data science. Developers can use
> our toolset for easy data analysis & visualization. Get a free account!
> http://www2.precog.com/precogplatform/slashdotnewsletter
> _______________________________________________
> Bacula-users mailing list
> Bacula-users AT lists.sourceforge DOT net
> https://lists.sourceforge.net/lists/listinfo/bacula-users

------------------------------------------------------------------------------
Precog is a next-generation analytics platform capable of advanced
analytics on semi-structured data. The platform includes APIs for building
apps and a phenomenal toolset for data science. Developers can use
our toolset for easy data analysis & visualization. Get a free account!
http://www2.precog.com/precogplatform/slashdotnewsletter
_______________________________________________
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>