Hi Sven,

Send me the log.<datastamp>.* files for the three tapes.

Jean-Louis

On 05/09/2012 11:43 AM, Sven Rudolph wrote:
Hello,

I have a problem with amrecover:

   amrecover>  add cpio.ry
   Added file /cpio.ry
   amrecover>  list
   TAPE Set1-2011-18:1;Set1K-2011-13:15,16;Set1K-2011-14:1,2,3,4,5,6,7,8,9,10 
LEVEL
    0 DATE 2012-01-01-03-08-50
           /cpio.ry
   amrecover>  extract

   Extracting files using tape drive changer on host uxrs74.
   The following tapes are needed: Set1-2011-18 Set1K-2011-13 Set1K-2011-14

   Extracting files using tape drive changer on host uxrs74.
   Load tape Set1-2011-18 now
   Continue [?/Y/n/s/d]? y
   Specified file list matches multiple dumps; cannot continue recovery
   Got no header and data from server, check in amidxtaped.*.debug and 
amandad.*.debug files on server
   amrecover>

Set1-2011-18 is the original tape, the DLE dump resides on this tape
only. Set1K-2011-13 and Set1K-2011-14 are the copies made with
amvault. I had to enable splitting in order to use amvault, so the
dump is split in several 40G-sized parts. And some of these parts are
on one tape and the other on the next tape.

I didn't split in fixed-size parts before, so this might be a cause of
the problem too.

The original tapes were written by Amanda 3.2.3, the vaulting happened
with Amanda 3.3.0. And amrecover is now from Amanda 3.3.1.

Log file amindexd.20120509160105.debug:

   Wed May  9 16:01:05 2012: thd-0x8056e00: amindexd: pid 32483 ruid 34 euid 34 
version 3.3.1: start at Wed May  9 16:01:05 2012
   Wed May  9 16:01:05 2012: thd-0x8056e00: amindexd: version 3.3.1
   Wed May  9 16:01:05 2012: thd-0x8056e00: amindexd:<  220 uxrs74 AMANDA index 
server (3.3.1) ready.
   Wed May  9 16:01:05 2012: thd-0x8056e00: amindexd:>  FEATURES 
ffffffff9efefbffffffffff1f
   Wed May  9 16:01:05 2012: thd-0x8056e00: amindexd:<  200 FEATURES 
ffffffff9efefbffffffffff1f
   Wed May  9 16:01:05 2012: thd-0x8056e00: amindexd:>  DATE 2012-05-09
   Wed May  9 16:01:05 2012: thd-0x8056e00: amindexd:<  200 Working date set to 
2012-05-09.
   Wed May  9 16:01:05 2012: thd-0x8056e00: amindexd:>  SCNF MonthlySet1
   Wed May  9 16:01:05 2012: thd-0x8056e00: amindexd: pid 32483 ruid 34 euid 34 
version 3.3.1: rename at Wed May  9 16:01:05 2012
   Wed May  9 16:01:06 2012: thd-0x8056e00: amindexd:<  200 Config set to 
MonthlySet1.
   Wed May  9 16:01:06 2012: thd-0x8056e00: amindexd:>  HOST uxrs74
   Wed May  9 16:01:06 2012: thd-0x8056e00: amindexd:<  200 Dump host set to 
uxrs74.
   Wed May  9 16:01:12 2012: thd-0x8056e00: amindexd:>  DATE 2012-01-07
   Wed May  9 16:01:12 2012: thd-0x8056e00: amindexd:<  200 Working date set to 
2012-01-07.
   Wed May  9 16:01:15 2012: thd-0x8056e00: amindexd:>  HOST fileserver
   Wed May  9 16:01:15 2012: thd-0x8056e00: amindexd:<  200 Dump host set to 
fileserver.
   Wed May  9 16:01:17 2012: thd-0x8056e00: amindexd:>  LISTDISK
   Wed May  9 16:01:17 2012: thd-0x8056e00: amindexd:<  200- List of disk for 
host fileserver
   Wed May  9 16:01:17 2012: thd-0x8056e00: amindexd:<  200 List of disk for 
host fileserver
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd:>  DISK /data
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: no recovery limit found; 
allowing access
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Apr-17', file=1, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-05-06-03-09-16 0 
Set1-Apr-17 1 1 1
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Mar-15', file=4, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-04-01-03-09-20 0 
Set1-Mar-15 4 1 2
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x8055570, label='Set1-Mar-16', file=1, partnum=2,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-04-01-03-09-20 0 
Set1-Mar-16 1 2 2
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Feb-16', file=2, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-03-11-03-09-18 0 
Set1-Feb-16 2 1 1
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Jan-13', file=5, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-02-05-03-09-17 0 
Set1-Jan-13 5 1 2
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82bfbc0, label='Set1-Jan-14', file=1, partnum=2,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-02-05-03-09-17 0 
Set1-Jan-14 1 2 2
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-2011-18', file=1, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1-2011-18 1 1 1
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-13', file=15, 
partnum=2,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-13 15 2 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-13', file=16, 
partnum=3,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-13 16 3 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-14', file=1, 
partnum=4,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-14 1 4 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-14', file=2, 
partnum=5,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-14 2 5 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-14', file=3, 
partnum=6,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-14 3 6 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-14', file=4, 
partnum=7,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-14 4 7 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-14', file=5, 
partnum=8,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-14 5 8 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-14', file=6, 
partnum=9,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-14 6 9 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-14', file=7, 
partnum=10,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-14 7 10 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-14', file=8, 
partnum=11,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-14 8 11 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-14', file=9, 
partnum=12,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-14 9 12 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82d6e40, label='Set1K-2011-14', file=10, 
partnum=13,  isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2012-01-01-03-08-50 0 
Set1K-2011-14 10 13 13
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Nov-12', file=3, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-12-04-03-09-17 0 
Set1-Nov-12 3 1 2
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82ca6e0, label='Set1-Nov-13', file=1, partnum=2,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-12-04-03-09-17 0 
Set1-Nov-13 1 2 2
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Oct-09', file=2, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-11-06-03-09-13 0 
Set1-Oct-09 2 1 2
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x805bfa8, label='Set1-Oct-10', file=1, partnum=2,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-11-06-03-09-13 0 
Set1-Oct-10 1 2 2
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Sep-09', file=1, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-10-02-03-07-58 0 
Set1-Sep-09 1 1 1
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Aug-17', file=1, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-09-07-15-13-30 0 
Set1-Aug-17 1 1 1
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Aug-07', file=2, partnum=1,  
isafile=0)


   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-09-04-03-08-42 0 
Set1-Aug-07 2 1 1
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Jul-06', file=3, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-08-07-03-08-41 0 
Set1-Jul-06 3 1 2
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=0x82b95c8, label='Set1-Jul-07', file=1, partnum=2,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-08-07-03-08-41 0 
Set1-Jul-07 1 2 2
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-Jun-07', file=2, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-07-03-03-09-16 0 
Set1-Jun-07 2 1 1
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-May-07', file=2, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-06-05-03-09-17 0 
Set1-May-07 2 1 1
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: 
append_to_tapelist(tapelist=(nil), label='Set1-2010-09', file=1, partnum=1,  
isafile=0)
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: - 2011-01-02-03-09-16 0 
Set1-2010-09 1 1 1
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd:<  200 Disk set to /data.
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd:>  OISD /
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: Spawning "/bin/gzip /bin/gzip 
-dc /var/lib/amanda/MonthlySet1/index/fileserver/_data/20120101030850_0.gz" in 
pipeline
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: Spawning "/usr/bin/sort 
/usr/bin/sort" in pipeline
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd: f 
/var/lib/amanda/MonthlySet1/index/fileserver/_data/20120101030850_0
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd:<  200 "/" is a valid 
directory
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd:>  OLSD /
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd:<  200- Opaque list of /
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd:<  200  Opaque list of /
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd:>  DLE
   Wed May  9 16:01:22 2012: thd-0x8056e00: amindexd:<  200 
"<dle>\n<program>GNUTAR</program>\n<disk>/data</disk>\n<auth>bsd</auth>\n<record>NO</record>\n<index>YES</index>\n<datapath>AMANDA</datapath>\n</dle>\n"


amidxtaped.20120509160219.debug is quite boring:

   Wed May  9 16:02:19 2012: thd-0x88bee00: amidxtaped: pid 32500 ruid 34 euid 
34 version 3.3.1: start at Wed May  9 16:02:19 2012
   Wed May  9 16:02:19 2012: thd-0x88bee00: amidxtaped: CTL<<  
FEATURES=ffffffff9efefbffffffffff1f
   Wed May  9 16:02:19 2012: thd-0x88bee00: amidxtaped: CTL<<  
CONFIG=MonthlySet1
   Wed May  9 16:02:20 2012: thd-0x88bee00: amidxtaped: CTL<<  
LABEL=Set1-2011-18:1;Set1K-2011-13:15,16;Set1K-2011-14:1,2,3,4,5,6,7,8,9,10
   Wed May  9 16:02:20 2012: thd-0x88bee00: amidxtaped: CTL<<  FSF=1
   Wed May  9 16:02:20 2012: thd-0x88bee00: amidxtaped: CTL<<  HEADER
   Wed May  9 16:02:20 2012: thd-0x88bee00: amidxtaped: CTL<<  DEVICE=changer
   Wed May  9 16:02:20 2012: thd-0x88bee00: amidxtaped: CTL<<  HOST=^fileserver$
   Wed May  9 16:02:20 2012: thd-0x88bee00: amidxtaped: CTL<<  DISK=^/data$
   Wed May  9 16:02:20 2012: thd-0x88bee00: amidxtaped: CTL<<  
DATESTAMP=20120101030850
   Wed May  9 16:02:20 2012: thd-0x88bee00: amidxtaped: CTL<<  END
   Wed May  9 16:02:20 2012: thd-0x88bee00: amidxtaped: pid 32500 ruid 34 euid 
34 version 3.3.1: rename at Wed May  9 16:02:20 2012
   Wed May  9 16:02:20 2012: thd-0x88bee00: amidxtaped: chg-robot: using 
statefile '/var/lib/amanda/chg-robot-dev-i500-changer'
   Warning: no log files found for tape ...
   ... more such warnings ...
   Wed May  9 16:02:31 2012: thd-0x88bee00: amidxtaped: CTL>>  MESSAGE 
Specified file list matches multiple dumps; cannot continue recovery
   Wed May  9 16:02:31 2012: thd-0x88bee00: amidxtaped: exiting with 0
   Wed May  9 16:02:31 2012: thd-0x88bee00: amidxtaped: pid 32500 finish time 
Wed May  9 16:02:31 2012


Internet search pointed me to this:
<http://archives.zmanda.com/amanda-archives/viewtopic.php?t=6270&sid=00e0bd4b4a37001ee0e475b5e5da4fd3>

But the patch shown there should already be in 3.3.x? (And the github
link to the last patch shown there doesn't work (anymore).)

Any ideas? Or should I provide more info?

Thanks,

        Sven



Reply via email to