Hi Dustin Just before you drop the ball completely, have you or someone else had time to look at the debug files
> -----Original Message----- > From: owner-amanda-us...@amanda.org > [mailto:owner-amanda-us...@amanda.org] On Behalf Of Titl Erich > Sent: Wednesday, September 22, 2010 6:23 PM > To: amanda-users@amanda.org > Subject: RE: amrecover bug? > > 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