Jean-Louis Martineau <[email protected]> writes:
> On 06/12/2012 11:51 AM, Sven Rudolph wrote:
>> Hello,
>>
>> I got an unexpected error message:
>>
>> FAILURE DUMP SUMMARY:
>> fileserver /data lev 1 partial taper: the requested volume is in drive
>> 0, which this changer instance cannot access
>>
>> My configuration uses drive 1, so the message is correct. But I would
>> expect taper to continue with the next following drive.
>>
>> What I did in order to create the problem: I have some performance
>> problem with tape drive 0, so I took the next tape (which has the
>> oldest data and would be overwritten soon) in order to run some write
>> tests. I moved the tape with mtx to drive 0.
>>
>> And then the nightly amdump tried to find the next tape, found it in
>> the wrong drive, and failed. In my opinion it should completely ignore
>> drives that it is not responsible for, probably by continuing the
>> taperscan algorithm.
>>
>> What is your opinion on this? ;-)
> Sven,
>
> I agree with you.
>
> Can you post the taper debug file? At least the few lines before this error.
I simplified the testing scenario: amcheck already shows the problem. I
set the changers property "use-slots" to "1-64" (corresponding to the
tapes 'Set1-Daily-001' to 'Set1-Daily-064'). So the next tape searched
by the taperscan algorithm ist outside the defined changer. The
following is still amanda 3.3.1:
$ amcheck -s DailySet1
Amanda Tape Server Host Check
-----------------------------
Holding disk /var/spool/amanda/1/1: 255116 MB disk space available, using
254092 MB
Holding disk /var/spool/amanda/2/2: 213256 MB disk space available, using
212232 MB
Holding disk /var/spool/amanda/3/3: 346528 MB disk space available, using
345504 MB
Holding disk /var/spool/amanda/4/4: 403372 MB disk space available, using
402348 MB
Holding disk /var/spool/amanda/5/5: 266740 MB disk space available, using
265716 MB
Searching for label 'Set1-Daily-066': label 'Set1-Daily-066' is in slot 66,
which is not in use-slots (1-64)
ERROR: label 'Set1-Daily-066' is in slot 66, which is not in use-slots (1-64)
Server check took 40.087 seconds
Logfiles:
/var/log/amanda/server/DailySet1# cat amcheck.20120813100356.debug
Mon Aug 13 10:03:56 2012: thd-0x805ae00: amcheck: pid 1061 ruid 0 euid 34
version 3.3.1: start at Mon Aug 13 10:03:56 2012
Mon Aug 13 10:03:56 2012: thd-0x805ae00: amcheck: pid 1061 ruid 0 euid 34
version 3.3.1: rename at Mon Aug 13 10:03:56 2012
Mon Aug 13 10:03:56 2012: thd-0x805ae00: amcheck-server: Spawning
"/usr/lib/amanda/amcheck-device /usr/lib/amanda/amcheck-device DailySet1" in
pipeline
Mon Aug 13 10:04:36 2012: thd-0x805ae00: amcheck-server: userbad: 0
Mon Aug 13 10:04:36 2012: thd-0x805ae00: amcheck-server: confbad: 0
Mon Aug 13 10:04:36 2012: thd-0x805ae00: amcheck-server: tapebad: 1
Mon Aug 13 10:04:36 2012: thd-0x805ae00: amcheck-server: disklow: 0
Mon Aug 13 10:04:36 2012: thd-0x805ae00: amcheck-server: logbad: 0
Mon Aug 13 10:04:36 2012: thd-0x805ae00: amcheck-server: infobad: 0
Mon Aug 13 10:04:36 2012: thd-0x805ae00: amcheck-server: indexbad: 0
Mon Aug 13 10:04:36 2012: thd-0x805ae00: amcheck-server: pgmbad: 0
Mon Aug 13 10:04:36 2012: thd-0x805ae00: amcheck: pid 1061 finish time Mon
Aug 13 10:04:36 2012
and:
/var/log/amanda/server/DailySet1# cat amcheck-device.20120813100356.debug
Mon Aug 13 10:03:56 2012: thd-0x81d2e00: amcheck-device: pid 1063 ruid 34
euid 34 version 3.3.1: start at Mon Aug 13 10:03:56 2012
Mon Aug 13 10:03:56 2012: thd-0x81d2e00: amcheck-device: pid 1063 ruid 34
euid 34 version 3.3.1: rename at Mon Aug 13 10:03:56 2012
Mon Aug 13 10:03:56 2012: thd-0x81d2e00: amcheck-device: chg-robot: using
statefile '/var/lib/amanda/chg-robot-dev-i500-changer'
Mon Aug 13 10:03:56 2012: thd-0x81d2e00: amcheck-device:
Amanda::Taper::Scan::traditional stage 1: search for oldest reusable volume
Mon Aug 13 10:03:56 2012: thd-0x81d2e00: amcheck-device:
Amanda::Taper::Scan::traditional oldest reusable volume is 'Set1-Daily-066'
Mon Aug 13 10:03:56 2012: thd-0x81d2e00: amcheck-device:
Amanda::Taper::Scan::traditional stage 1: searching oldest reusable volume
'Set1-Daily-066'
Mon Aug 13 10:03:56 2012: thd-0x81d2e00: amcheck-device: invoking
/usr/sbin/mtx -f /dev/i500-changer status
Mon Aug 13 10:04:36 2012: thd-0x81d2e00: amcheck-device: i500-changer:
updating state
Mon Aug 13 10:04:36 2012: thd-0x81d2e00: amcheck-device: i500-changer:
loading label 'Set1-Daily-066'
Mon Aug 13 10:04:36 2012: thd-0x81d2e00: amcheck-device: new
Amanda::Changer::Error: type='failed', reason='invalid', message='label
'Set1-Daily-066' is in slot 66, which is not in use-slots (1-64)'
Mon Aug 13 10:04:36 2012: thd-0x81d2e00: amcheck-device:
Amanda::Taper::Scan::traditional result: error=label 'Set1-Daily-066' is in
slot 66, which is not in use-slots (1-64)
Mon Aug 13 10:04:36 2012: thd-0x81d2e00: amcheck-device: pid 1063 finish time
Mon Aug 13 10:04:36 2012
Sven
