Hi Dustin > -----Original Message----- > From: djmit...@gmail.com [mailto:djmit...@gmail.com] On > Behalf Of Dustin J. Mitchell > Sent: Wednesday, September 22, 2010 4:58 PM > To: Titl Erich > Cc: amanda-users@amanda.org > Subject: Re: amrecover bug? > > On Wed, Sep 22, 2010 at 3:17 AM, Titl Erich <erich.t...@ruf.ch> wrote: > > A few weeks ago I reported in the forum a problem with > amrecover and > > compressed dump files. Meanwhile I changed to uncompressed backup, > > still no luck and, unfortunately not much replies either. So please > > bear with me when I repeat this here. > > No worries about the repetition - this mailing list is the > more actively-monitored spot. > > My first guess is that Amanda is not seeking to the required > file appropriately. Can you let us know what OS you're > using, what type of tape, and also send along the amrecover > debug log file from the client and the amidxtaped debug log > file (with matching datestamp) from the server?
OS Linux Debian Lenny
Tape is an overland REO 9000 virtual tape library iSCSI connected
>
> Have a look at the FSF_AFTER_FILEMARK property in
> amanda-devices(7), as that's my first guess for what's going wrong.
OK, looking at my config this is not defined so false at the current OS, now
I set this to true
subversion:/backup/amanda# amrecover
AMRECOVER Version 3.1.2. Contacting server on amanda.ruf.ch ...
220 amanda AMANDA index server (3.1.2) ready.
Setting restore date to today (2010-09-22)
200 Working date set to 2010-09-22.
200 Config set to DailySet1.
501 Host subversion is not in your disklist.
Trying host subversion ...
501 Host subversion is not in your disklist.
Trying host subversion.ruf.ch ...
200 Dump host set to subversion.ruf.ch.
Use the setdisk command to choose dump disk to recover
amrecover> listdisk
200- List of disk for host subversion.ruf.ch
201- /
201- /home
201- /var
201- /usr
201- /data
200 List of disk for host subversion.ruf.ch
amrecover> setdisk /data
200 Disk set to /data.
amrecover> ls
2010-09-22-01-00-02 svn/
2010-09-22-01-00-02 lost+found/
2010-09-22-01-00-02 amanda/
2010-09-22-01-00-02 .
amrecover> add svn
Added dir /svn/ at date 2010-09-21-08-23-12
Added dir /svn/ at date 2010-09-22-01-00-02
amrecover> extract
Extracting files using tape drive changer on host amanda.ruf.ch.
The following tapes are needed: amanda-0004
amanda-0005
Extracting files using tape drive changer on host amanda.ruf.ch.
Load tape amanda-0004 now
Continue [?/Y/n/s/d]?
tar: ./svn: Not found in archive
tar: Error exit delayed from previous errors
Extractor child exited with status 2
Extracting files using tape drive changer on host amanda.ruf.ch.
Load tape amanda-0005 now
Continue [?/Y/n/s/d]?
tar: ./svn: Not found in archive
tar: Error exit delayed from previous errors
Extractor child exited with status 2
amrecover>
I infer from this that tar found a reasonable archive, but was unable to
extract the svn directory.
Herre is some debugging from the client
Wed Sep 22 17:14:28 2010: amrecover: user command: 'ls'
Wed Sep 22 17:14:34 2010: amrecover: user command: 'add svn'
Wed Sep 22 17:14:34 2010: amrecover: add_glob (svn) -> ^svn$
Wed Sep 22 17:14:34 2010: amrecover: add_file: Looking for "svn[/]*$"
Wed Sep 22 17:14:34 2010: amrecover: add_file: Converted path="svn[/]*$" to
path_on_disk="/svn[/]*$"
Wed Sep 22 17:14:34 2010: amrecover: add_file: Pondering ditem->path=/svn/
Wed Sep 22 17:14:34 2010: amrecover: sending: ORLD /svn^M
Wed Sep 22 17:14:34 2010: amrecover: add_file: (Successful) Added dir /svn/
at date 2010-09-21-08-23-12
Wed Sep 22 17:14:35 2010: amrecover: add_file: (Successful) Added dir /svn/
at date 2010-09-22-01-00-02
Wed Sep 22 17:14:35 2010: amrecover: add_file: Pondering
ditem->path=/lost+found/
Wed Sep 22 17:14:35 2010: amrecover: add_file: Pondering
ditem->path=/amanda/
Wed Sep 22 17:14:35 2010: amrecover: add_file: Pondering ditem->path=/.
Wed Sep 22 17:14:46 2010: amrecover: user command: 'extract'
Wed Sep 22 17:14:46 2010: amrecover: append_to_tapelist(tapelist=(nil),
label='amanda-0004', file=-1, partnum=-1, isafile=0)
Wed Sep 22 17:14:46 2010: amrecover: append_to_tapelist(tapelist=0xde0910,
label='amanda-0004', file=13, partnum=-1, isafile=0)
Wed Sep 22 17:14:46 2010: amrecover: append_to_tapelist(tapelist=(nil),
label='amanda-0005', file=-1, partnum=-1, isafile=0)
Wed Sep 22 17:14:46 2010: amrecover: append_to_tapelist(tapelist=0xde0910,
label='amanda-0005', file=6, partnum=-1, isafile=0)
Wed Sep 22 17:14:46 2010: amrecover: append_to_tapelist(tapelist=(nil),
label='amanda-0004', file=-1, partnum=-1, isafile=0)
Wed Sep 22 17:14:46 2010: amrecover: append_to_tapelist(tapelist=0xde0910,
label='amanda-0004', file=13, partnum=-1, isafile=0)
Wed Sep 22 17:14:46 2010: amrecover: Requesting tape amanda-0004 from user
Wed Sep 22 17:15:17 2010: amrecover: User prompt: 'Continue [?/Y/n/s/d]? ';
response: ''
Wed Sep 22 17:15:17 2010: amrecover: security_getdriver(name=bsd) returns
0x7f79352e3540
Wed Sep 22 17:15:17 2010: amrecover: security_handleinit(handle=0xde0a40,
driver=0x7f79352e3540 (BSD))
Wed Sep 22 17:15:17 2010: amrecover: dgram_send_addr(addr=0xde0a80,
dgram=0x7f79352eea48)
Wed Sep 22 17:15:17 2010: amrecover: (sockaddr_in *)0xde0a80 = { 2, 10080,
172.29.4.88 }
Wed Sep 22 17:15:17 2010: amrecover: dgram_send_addr: 0x7f79352eea48->socket
= 3
Wed Sep 22 17:15:17 2010: amrecover: dgram_recv(dgram=0x7f79352eea48,
timeout=0, fromaddr=0x7f79352fea40)
Wed Sep 22 17:15:17 2010: amrecover: (sockaddr_in *)0x7f79352fea40 = { 2,
10080, 172.29.4.88 }
Wed Sep 22 17:15:18 2010: amrecover: dgram_recv(dgram=0x7f79352eea48,
timeout=0, fromaddr=0x7f79352fea40)
Wed Sep 22 17:15:18 2010: amrecover: (sockaddr_in *)0x7f79352fea40 = { 2,
10080, 172.29.4.88 }
Wed Sep 22 17:15:18 2010: amrecover: dgram_send_addr(addr=0xde0a80,
dgram=0x7f79352eea48)
Wed Sep 22 17:15:18 2010: amrecover: (sockaddr_in *)0xde0a80 = { 2, 10080,
172.29.4.88 }
Wed Sep 22 17:15:18 2010: amrecover: dgram_send_addr: 0x7f79352eea48->socket
= 3
Wed Sep 22 17:15:18 2010: amrecover: security_streaminit(stream=0xde2600,
driver=0x7f79352e3540 (BSD))
Wed Sep 22 17:15:18 2010: amrecover: make_socket opening socket with family
2
Wed Sep 22 17:15:18 2010: amrecover: connect_port: Try port 11000:
available - Success
Wed Sep 22 17:15:18 2010: amrecover: connected to 172.29.4.88.11017
Wed Sep 22 17:15:18 2010: amrecover: our side is 0.0.0.0.11000
Wed Sep 22 17:15:18 2010: amrecover: try_socksize: send buffer size is 65536
Wed Sep 22 17:15:18 2010: amrecover: try_socksize: receive buffer size is
65536
Wed Sep 22 17:15:18 2010: amrecover: amidxtaped_streams[0].fd = 0xde2600
Wed Sep 22 17:15:18 2010: amrecover: security_streaminit(stream=0xdea660,
driver=0x7f79352e3540 (BSD))
Wed Sep 22 17:15:18 2010: amrecover: make_socket opening socket with family
2
Wed Sep 22 17:15:18 2010: amrecover: connect_port: Try port 11000:
available - Success
Wed Sep 22 17:15:18 2010: amrecover: connected to 172.29.4.88.11018
Wed Sep 22 17:15:18 2010: amrecover: our side is 0.0.0.0.11000
Wed Sep 22 17:15:18 2010: amrecover: try_socksize: send buffer size is 65536
Wed Sep 22 17:15:18 2010: amrecover: try_socksize: receive buffer size is
65536
Wed Sep 22 17:15:18 2010: amrecover: amidxtaped_streams[1].fd = 0xdea660
Wed Sep 22 17:15:18 2010: amrecover: security_close(handle=0xde0a40,
driver=0x7f79352e3540 (BSD))
Wed Sep 22 17:15:18 2010: amrecover: send_to_tape_server:
FEATURES=ffffffff9efefbffffffffff01
Wed Sep 22 17:15:18 2010: amrecover: send_to_tape_server: CONFIG=DailySet1
Wed Sep 22 17:15:18 2010: amrecover: send_to_tape_server:
LABEL=amanda-0004:13
Wed Sep 22 17:15:18 2010: amrecover: send_to_tape_server: FSF=13
Wed Sep 22 17:15:18 2010: amrecover: send_to_tape_server: HEADER
Wed Sep 22 17:15:18 2010: amrecover: send_to_tape_server: DEVICE=changer
Wed Sep 22 17:15:18 2010: amrecover: send_to_tape_server:
HOST=^subversion.ruf.ch$
Wed Sep 22 17:15:18 2010: amrecover: send_to_tape_server: DISK=^/data$
Wed Sep 22 17:15:18 2010: amrecover: send_to_tape_server:
DATESTAMP=20100921082312
Wed Sep 22 17:15:18 2010: amrecover: send_to_tape_server: END
Wed Sep 22 17:15:19 2010: amrecover: send_to_tape_server: AVAIL-DATAPATH
AMANDA
Wed Sep 22 17:15:19 2010: amrecover: get amidxtaped line: USE-DATAPATH
AMANDA
Wed Sep 22 17:15:19 2010: amrecover: Using AMANDA data-path
Wed Sep 22 17:15:19 2010: amrecover: send_to_tape_server: DATAPATH-OK
Wed Sep 22 17:15:19 2010: amrecover: Exec'ing /bin/tar with arguments:
Wed Sep 22 17:15:19 2010: amrecover: tar
Wed Sep 22 17:15:19 2010: amrecover: --numeric-owner
Wed Sep 22 17:15:19 2010: amrecover: -xpGvf
Wed Sep 22 17:15:19 2010: amrecover: -
Wed Sep 22 17:15:19 2010: amrecover: ./svn
Wed Sep 22 17:40:37 2010: amrecover: security_stream_close(0xdea660)
Wed Sep 22 17:40:37 2010: amrecover: security_stream_close(0xde2600)
Wed Sep 22 17:40:37 2010: amrecover: append_to_tapelist(tapelist=(nil),
label='amanda-0005', file=-1, partnum=-1, isafile=0)
Wed Sep 22 17:40:37 2010: amrecover: append_to_tapelist(tapelist=0xde1210,
label='amanda-0005', file=6, partnum=-1, isafile=0)
Wed Sep 22 17:40:37 2010: amrecover: Requesting tape amanda-0005 from user
Wed Sep 22 17:41:28 2010: amrecover: User prompt: 'Continue [?/Y/n/s/d]? ';
response: ''
Wed Sep 22 17:41:28 2010: amrecover: security_getdriver(name=bsd) returns
0x7f79352e3540
Wed Sep 22 17:41:28 2010: amrecover: security_handleinit(handle=0xde0890,
driver=0x7f79352e3540 (BSD))
Wed Sep 22 17:41:28 2010: amrecover: dgram_send_addr(addr=0xde08d0,
dgram=0x7f79352eea48)
Wed Sep 22 17:41:28 2010: amrecover: (sockaddr_in *)0xde08d0 = { 2, 10080,
172.29.4.88 }
Wed Sep 22 17:41:28 2010: amrecover: dgram_send_addr: 0x7f79352eea48->socket
= 3
Wed Sep 22 17:41:28 2010: amrecover: dgram_recv(dgram=0x7f79352eea48,
timeout=0, fromaddr=0x7f79352fea40)
Wed Sep 22 17:41:28 2010: amrecover: (sockaddr_in *)0x7f79352fea40 = { 2,
10080, 172.29.4.88 }
Wed Sep 22 17:41:28 2010: amrecover: dgram_recv(dgram=0x7f79352eea48,
timeout=0, fromaddr=0x7f79352fea40)
Wed Sep 22 17:41:28 2010: amrecover: (sockaddr_in *)0x7f79352fea40 = { 2,
10080, 172.29.4.88 }
Wed Sep 22 17:41:28 2010: amrecover: dgram_send_addr: 0x7f79352eea48->socket
= 3
Wed Sep 22 17:41:28 2010: amrecover: dgram_recv(dgram=0x7f79352eea48,
timeout=0, fromaddr=0x7f79352fea40)
Wed Sep 22 17:41:28 2010: amrecover: (sockaddr_in *)0x7f79352fea40 = { 2,
10080, 172.29.4.88 }
Wed Sep 22 17:41:28 2010: amrecover: dgram_recv(dgram=0x7f79352eea48,
timeout=0, fromaddr=0x7f79352fea40)
Wed Sep 22 17:41:28 2010: amrecover: (sockaddr_in *)0x7f79352fea40 = { 2,
10080, 172.29.4.88 }
Wed Sep 22 17:41:28 2010: amrecover: dgram_send_addr(addr=0xde08d0,
dgram=0x7f79352eea48)
Wed Sep 22 17:41:28 2010: amrecover: (sockaddr_in *)0xde08d0 = { 2, 10080,
172.29.4.88 }
Wed Sep 22 17:41:28 2010: amrecover: dgram_send_addr: 0x7f79352eea48->socket
= 3
Wed Sep 22 17:41:28 2010: amrecover: security_streaminit(stream=0xde22d0,
driver=0x7f79352e3540 (BSD))
Wed Sep 22 17:41:28 2010: amrecover: make_socket opening socket with family
2
Wed Sep 22 17:41:28 2010: amrecover: connect_port: Try port 11000:
available - Success
Wed Sep 22 17:41:28 2010: amrecover: connected to 172.29.4.88.11029
Wed Sep 22 17:41:28 2010: amrecover: our side is 0.0.0.0.11000
Wed Sep 22 17:41:28 2010: amrecover: try_socksize: send buffer size is 65536
Wed Sep 22 17:41:28 2010: amrecover: try_socksize: receive buffer size is
65536
Wed Sep 22 17:41:28 2010: amrecover: amidxtaped_streams[0].fd = 0xde22d0
Wed Sep 22 17:41:28 2010: amrecover: security_streaminit(stream=0xdea330,
driver=0x7f79352e3540 (BSD))
Wed Sep 22 17:41:28 2010: amrecover: make_socket opening socket with family
2
Wed Sep 22 17:41:28 2010: amrecover: connect_port: Try port 11000:
available - Success
Wed Sep 22 17:41:28 2010: amrecover: connected to 172.29.4.88.11030
Wed Sep 22 17:41:28 2010: amrecover: our side is 0.0.0.0.11000
Wed Sep 22 17:41:28 2010: amrecover: try_socksize: send buffer size is 65536
Wed Sep 22 17:41:28 2010: amrecover: try_socksize: receive buffer size is
65536
Wed Sep 22 17:41:28 2010: amrecover: amidxtaped_streams[1].fd = 0xdea330
Wed Sep 22 17:41:28 2010: amrecover: security_close(handle=0xde0890,
driver=0x7f79352e3540 (BSD))
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server:
FEATURES=ffffffff9efefbffffffffff01
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server: CONFIG=DailySet1
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server:
LABEL=amanda-0005:6
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server: FSF=6
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server: HEADER
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server: DEVICE=changer
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server:
HOST=^subversion.ruf.ch$
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server: DISK=^/data$
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server:
DATESTAMP=20100922010002
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server: END
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server: AVAIL-DATAPATH
AMANDA
Wed Sep 22 17:41:28 2010: amrecover: get amidxtaped line: USE-DATAPATH
AMANDA
Wed Sep 22 17:41:28 2010: amrecover: Using AMANDA data-path
Wed Sep 22 17:41:28 2010: amrecover: send_to_tape_server: DATAPATH-OK
Wed Sep 22 17:41:28 2010: amrecover: Exec'ing /bin/tar with arguments:
Wed Sep 22 17:41:28 2010: amrecover: tar
Wed Sep 22 17:41:28 2010: amrecover: --numeric-owner
Wed Sep 22 17:41:28 2010: amrecover: -xpGvf
Wed Sep 22 17:41:28 2010: amrecover: -
Wed Sep 22 17:41:28 2010: amrecover: ./svn
Wed Sep 22 17:41:30 2010: amrecover: security_stream_close(0xdea330)
Wed Sep 22 17:41:30 2010: amrecover: security_stream_close(0xde22d0)
Wed Sep 22 17:42:03 2010: amrecover: user command: 'quit'
Wed Sep 22 17:42:03 2010: amrecover: sending: QUIT^M
Wed Sep 22 17:42:03 2010: amrecover: security_stream_close(0xdbcff0)
Wed Sep 22 17:42:03 2010: amrecover: pid 9705 finish time Wed Sep 22
17:42:03 2010
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Here is the server part
Wed Sep 22 17:15:18 2010: amidxtaped: pid 27023 ruid 1002 euid 1002 version
3.1.2: start at Wed Sep 22 17:15:18 2010
Wed Sep 22 17:15:18 2010: amidxtaped: CTL <<
FEATURES=ffffffff9efefbffffffffff01
Wed Sep 22 17:15:18 2010: amidxtaped: CTL << CONFIG=DailySet1
Wed Sep 22 17:15:18 2010: amidxtaped: CTL << LABEL=amanda-0004:13
Wed Sep 22 17:15:18 2010: amidxtaped: CTL << FSF=13
Wed Sep 22 17:15:18 2010: amidxtaped: CTL << HEADER
Wed Sep 22 17:15:18 2010: amidxtaped: CTL << DEVICE=changer
Wed Sep 22 17:15:18 2010: amidxtaped: CTL << HOST=^subversion.ruf.ch$
Wed Sep 22 17:15:18 2010: amidxtaped: CTL << DISK=^/data$
Wed Sep 22 17:15:18 2010: amidxtaped: CTL << DATESTAMP=20100921082312
Wed Sep 22 17:15:18 2010: amidxtaped: CTL << END
Wed Sep 22 17:15:18 2010: amidxtaped: pid 27023 ruid 1002 euid 1002 version
3.1.2: rename at Wed Sep 22 17:15:18 2010
Wed Sep 22 17:15:18 2010: amidxtaped: chg-robot: using statefile
'/var/amanda/chg-robot-dev-sg0'
Wed Sep 22 17:15:18 2010: amidxtaped: find_volume labeled 'amanda-0004'
Wed Sep 22 17:15:18 2010: amidxtaped: invoking /usr/sbin/mtx -f /dev/sg0
status
Wed Sep 22 17:15:18 2010: amidxtaped: OVERLAND-REO: updating state
Wed Sep 22 17:15:18 2010: amidxtaped: parse_inventory: load slot 4 with
label 'amanda-0004'
Wed Sep 22 17:15:18 2010: amidxtaped: OVERLAND-REO: too early for another
'status' invocation
Wed Sep 22 17:15:18 2010: amidxtaped: OVERLAND-REO: loading slot '4'
Wed Sep 22 17:15:18 2010: amidxtaped: OVERLAND-REO: using drive 0
Wed Sep 22 17:15:18 2010: amidxtaped: OVERLAND-REO: unloading drive 0
Wed Sep 22 17:15:18 2010: amidxtaped: invoking /usr/sbin/mtx -f /dev/sg0
unload 5 0
Wed Sep 22 17:15:18 2010: amidxtaped: OVERLAND-REO: unload complete
Wed Sep 22 17:15:18 2010: amidxtaped: invoking /usr/sbin/mtx -f /dev/sg0
load 4 0
Wed Sep 22 17:15:18 2010: amidxtaped: OVERLAND-REO: polling 'tape:/dev/nst0'
to see if it's ready
Wed Sep 22 17:15:18 2010: amidxtaped: ignoring spurious
Amanda::Recovery::Scan abort call
Wed Sep 22 17:15:19 2010: amidxtaped: recovering from split dumpfile: date
20100921082312 host subversion.ruf.ch disk /data part 1/UNKNOWN lev 1 comp N
program /bin/tar
Wed Sep 22 17:15:19 2010: amidxtaped: Building type FILE header of
32768-32768 bytes with name='subversion.ruf.ch' disk='/data' dumplevel=1 and
blocksize=0
Wed Sep 22 17:15:19 2010: amidxtaped: CTL << AVAIL-DATAPATH AMANDA
Wed Sep 22 17:15:19 2010: amidxtaped: CTL >> USE-DATAPATH AMANDA
Wed Sep 22 17:15:19 2010: amidxtaped: CTL << DATAPATH-OK
Wed Sep 22 17:15:19 2010: amidxtaped: Starting <x...@0x284bbf0
(<xfersourcerecov...@0x288c000> -> <xferdes...@0x288c810>)>
Wed Sep 22 17:15:19 2010: amidxtaped: Final linkage:
<xfersourcerecov...@0x288c000> -(PULL_BUFFER)-> <xferelementg...@0x28890e0>
-(WRITEFD)-> <xferdes...@0x288c810>
Wed Sep 22 17:15:19 2010: amidxtaped: started xfer; datapath=amanda
Wed Sep 22 17:40:37 2010: amidxtaped: Device tape:/dev/nst0 error = 'EOF'
Wed Sep 22 17:40:37 2010: amidxtaped: recovery complete
Wed Sep 22 17:40:37 2010: amidxtaped: invoking /usr/sbin/mtx -f /dev/sg0
status
Wed Sep 22 17:40:37 2010: amidxtaped: OVERLAND-REO: updating state
Wed Sep 22 17:40:37 2010: amidxtaped: exiting with 0
Wed Sep 22 17:40:37 2010: amidxtaped: pid 27023 finish time Wed Sep 22
17:40:37 2010
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
And here the next tape
Wed Sep 22 17:41:28 2010: amidxtaped: pid 28068 ruid 1002 euid 1002 version
3.1.2: start at Wed Sep 22 17:41:28 2010
Wed Sep 22 17:41:28 2010: amidxtaped: CTL <<
FEATURES=ffffffff9efefbffffffffff01
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << CONFIG=DailySet1
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << LABEL=amanda-0005:6
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << FSF=6
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << HEADER
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << DEVICE=changer
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << HOST=^subversion.ruf.ch$
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << DISK=^/data$
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << DATESTAMP=20100922010002
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << END
Wed Sep 22 17:41:28 2010: amidxtaped: pid 28068 ruid 1002 euid 1002 version
3.1.2: rename at Wed Sep 22 17:41:28 2010
Wed Sep 22 17:41:28 2010: amidxtaped: chg-robot: using statefile
'/var/amanda/chg-robot-dev-sg0'
Wed Sep 22 17:41:28 2010: amidxtaped: find_volume labeled 'amanda-0005'
Wed Sep 22 17:41:28 2010: amidxtaped: invoking /usr/sbin/mtx -f /dev/sg0
status
Wed Sep 22 17:41:28 2010: amidxtaped: OVERLAND-REO: updating state
Wed Sep 22 17:41:28 2010: amidxtaped: parse_inventory: load slot 5 with
label 'amanda-0005'
Wed Sep 22 17:41:28 2010: amidxtaped: OVERLAND-REO: too early for another
'status' invocation
Wed Sep 22 17:41:28 2010: amidxtaped: OVERLAND-REO: loading slot '5'
Wed Sep 22 17:41:28 2010: amidxtaped: OVERLAND-REO: using drive 0
Wed Sep 22 17:41:28 2010: amidxtaped: OVERLAND-REO: unloading drive 0
Wed Sep 22 17:41:28 2010: amidxtaped: invoking /usr/sbin/mtx -f /dev/sg0
unload 4 0
Wed Sep 22 17:41:28 2010: amidxtaped: OVERLAND-REO: unload complete
Wed Sep 22 17:41:28 2010: amidxtaped: invoking /usr/sbin/mtx -f /dev/sg0
load 5 0
Wed Sep 22 17:41:28 2010: amidxtaped: OVERLAND-REO: polling 'tape:/dev/nst0'
to see if it's ready
Wed Sep 22 17:41:28 2010: amidxtaped: ignoring spurious
Amanda::Recovery::Scan abort call
Wed Sep 22 17:41:28 2010: amidxtaped: recovering from split dumpfile: date
20100922010002 host subversion.ruf.ch disk /data part 1/UNKNOWN lev 2 comp N
program /bin/tar
Wed Sep 22 17:41:28 2010: amidxtaped: Building type FILE header of
32768-32768 bytes with name='subversion.ruf.ch' disk='/data' dumplevel=2 and
blocksize=0
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << AVAIL-DATAPATH AMANDA
Wed Sep 22 17:41:28 2010: amidxtaped: CTL >> USE-DATAPATH AMANDA
Wed Sep 22 17:41:28 2010: amidxtaped: CTL << DATAPATH-OK
Wed Sep 22 17:41:28 2010: amidxtaped: Starting <x...@0x2c9b120
(<xfersourcerecov...@0x2cdb800> -> <xferdes...@0x2cdc010>)>
Wed Sep 22 17:41:28 2010: amidxtaped: Final linkage:
<xfersourcerecov...@0x2cdb800> -(PULL_BUFFER)-> <xferelementg...@0x2cd78e0>
-(WRITEFD)-> <xferdes...@0x2cdc010>
Wed Sep 22 17:41:28 2010: amidxtaped: started xfer; datapath=amanda
Wed Sep 22 17:41:30 2010: amidxtaped: Device tape:/dev/nst0 error = 'EOF'
Wed Sep 22 17:41:30 2010: amidxtaped: recovery complete
Wed Sep 22 17:41:30 2010: amidxtaped: invoking /usr/sbin/mtx -f /dev/sg0
status
Wed Sep 22 17:41:30 2010: amidxtaped: OVERLAND-REO: updating state
Wed Sep 22 17:41:30 2010: amidxtaped: exiting with 0
Wed Sep 22 17:41:30 2010: amidxtaped: pid 28068 finish time Wed Sep 22
17:41:30 2010
The tape error puzzles me
Here is the changer definition
define changer OVERLAND-REO {
tpchanger "chg-robot:/dev/sg0" # your changer device file
property "tape-device" "0=tape:/dev/nst0"
property "FSF_AFTER_FILEMARK" "TRUE"
}
tapedev "OVERLAND-REO"
Thanks
Erich
smime.p7s
Description: S/MIME cryptographic signature
