Bacula-users

Re: [Bacula-users] After upgrade to 3.0.1: Concurrentjobs fail (The number of files mismatch)

2009-05-12 07:32:12
Subject: Re: [Bacula-users] After upgrade to 3.0.1: Concurrentjobs fail (The number of files mismatch)
From: user100 <user100 AT lisec-sw DOT com>
To: Michel Meyers <steltek AT tcnnet DOT com>
Date: Tue, 12 May 2009 13:26:52 +0200
Am 11.05.2009 22:16, schrieb Michel Meyers:
-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

user100 wrote:
  
I would be happy if somebody have an idea... :)

OS: CentOS5.3
Autochanger: Dell Powervault 124T

Worked so far for some years. After upgrade from 2.2.8 to 3.0.1 
concurrent jobs did not work anymore. bacula-sd.conf is still the same. 
I tried with a new set of tapes too. The first job starts and is happy 
with the tape and starts spooling. Before the first job finished the 
second job fails with such a message and marks (the same tape) to Error:

11-May 15:47 swlinbck2-sd JobId 20974: Error: Bacula cannot write on 
tape Volume "000041L3" because:
The number of files mismatch! Volume=1 Catalog=0
11-May 15:47 swlinbck2-sd JobId 20974: Marking Volume "000041L3" in 
Error in Catalog.
    
Not sure if this helps. Back in 2006 somebody had issues with the
drive's firmware and an update fixed it:
http://www.mail-archive.com/bacula-users AT lists.sourceforge DOT net/msg11065.html

Just a shot in the dark really as I can't think of anything better to
try. (Does the btape test work all the way, including fill?)

  

I made a firmware update and tried again. Does not really help. Thanks for the tip anyway (now I have a newer firmware ;)).
Regearding btape. I think it looks good so far (without fill). Do you think the fill-test should show any further? Because the problem occurs already with just one tape and less of data (so not just when the tape changes). On the other side the tapedrive was working well long time before the update.

Following btape test:

Tape block granularity is 1024 bytes.
btape: butil.c:285 Using device: "/dev/nst0" for writing.
12-May 13:06 btape JobId 0: 3301 Issuing autochanger "loaded? drive 0" command.
12-May 13:06 btape JobId 0: 3302 Autochanger "loaded? drive 0", result is Slot 2.
btape: btape.c:383 open device "Drive-0" (/dev/nst0): OK
*test

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

I'm going to write 1000 records and an EOF
then write 1000 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:842 Wrote 1000 blocks of 64412 bytes.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:858 Wrote 1000 blocks of 64412 bytes.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:867 Rewind OK.
1000 blocks re-read correctly.
Got EOF on tape.
1000 blocks re-read correctly.
=== Test Succeeded. End Write, rewind, and re-read test ===


=== Write, rewind, and position test ===

I'm going to write 1000 records and an EOF
then write 1000 records and an EOF, then rewind,
and position to a few blocks and verify that it is correct.

This is an *essential* feature ...

btape: btape.c:954 Wrote 1000 blocks of 64412 bytes.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:970 Wrote 1000 blocks of 64412 bytes.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:979 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:999
Block 1000 re-read correctly.
Reposition to file:block 1:0
Block 1001 re-read correctly.
Reposition to file:block 1:600
Block 1601 re-read correctly.
Reposition to file:block 1:999
Block 2000 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:486 Rewound "Drive-0" (/dev/nst0)
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:383 open device "Drive-0" (/dev/nst0): OK
btape: btape.c:486 Rewound "Drive-0" (/dev/nst0)
btape: btape.c:1108 Now moving to end of medium.
btape: btape.c:537 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:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:486 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.


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

I'm going to write three records and an EOF
then backup over the EOF and re-read the last record.
Bacula does this after writing the last block on the
tape to verify that the block was written correctly.

This is not an *essential* feature ...

btape: btape.c:486 Rewound "Drive-0" (/dev/nst0)
btape: btape.c:721 Wrote first record of 64412 bytes.
btape: btape.c:732 Wrote second record of 64412 bytes.
btape: btape.c:743 Wrote third record of 64412 bytes.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:759 Backspaced over EOF OK.
btape: btape.c:764 Backspace record OK.
btape: btape.c:782
Block re-read correct. Test succeeded!
=== End Write, backup, and re-read test ===



=== Forward space files test ===

This test is essential to Bacula.

I'm going to write five files then test forward spacing

btape: btape.c:486 Rewound "Drive-0" (/dev/nst0)
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:1588 Wrote one record of 64412 bytes.
btape: btape.c:1590 Wrote block to device.
btape: btape.c:516 Wrote 1 EOF to "Drive-0" (/dev/nst0)
btape: btape.c:486 Rewound "Drive-0" (/dev/nst0)
btape: btape.c:1322 Now forward spacing 1 file.
We should be in file 1. I am at file 1. This is correct!
btape: btape.c:1334 Now forward spacing 2 files.
We should be in file 3. I am at file 3. This is correct!
btape: btape.c:486 Rewound "Drive-0" (/dev/nst0)
btape: btape.c:1347 Now forward spacing 4 files.
We should be in file 4. I am at file 4. This is correct!

btape: btape.c:1365 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):


Greetings,
user100
------------------------------------------------------------------------------
The NEW KODAK i700 Series Scanners deliver under ANY circumstances! Your
production scanning environment may not be a perfect world - but thanks to
Kodak, there's a perfect scanner to get the job done! With the NEW KODAK i700
Series Scanner you'll get full speed at 300 dpi even with all image 
processing features enabled. http://p.sf.net/sfu/kodak-com
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users