I'm having a problem with a task that seems simple enough. I want to
recover some files that were backed up from a NetApp filer (with a recent
ONTAP version), but this fails.
Environment: One backup server running Solaris 7, Networker 7.1.1,
SnapImage 1.6 for Solaris. Tried doing this with the binaries that are for
7.0 and without them - same result. My guess is that these binaries are
only for 7.0 since they appear to be older than the ones that shipped with
7.1.1.
In brief, this is what I do: I run the "recover -c
netappfiler.customer.domain" command on the backup server, changetime to
the time that I want to recover from, browse to the specified folder, run
"add folderthatiwanttorecover", and then type "recover". This fails, at
least its not 100% successful.
I've also tried running nsrck -L 6, rebooted the machine, ran a complete
inventory, ran nsrjb -HEv etc. ndmp version on the filer is set to 4,
however I did try changing it to 3 when doing the recovery, with the same
result.
This is the (shortened) output that I get on the client:
[root@britt:/] recover -c netappfiler.customer.domain
recover: Current working directory is /
recover> changetime
current save time is Sat Mar 20 09:40:00 2004
<return> leaves save time unchanged
new save time: 02/23/04
time changed to Mon Feb 23 23:59:59 2004
recover> cd vol/vol0/users/RB34AHN
recover> add profile
/vol/vol0/users/RB34AHN
/vol/vol0/users/RB34AHN/profile
/vol/vol0/users/RB34AHN/profile/Cookies
/vol/vol0/users/RB34AHN/profile/Favoriter
/vol/vol0/users/RB34AHN/profile/Favoriter/Kanaler
[snip]
/vol/vol0/users/RB34AHN/profile/Tidigare/MSHist012001100320011004
/vol/vol0/users/RB34AHN/profile/Tidigare/MSHist012001100520011006
548 file(s) marked for recovery
recover> recover
recover: Total estimated disk space needed for recover is 38 MB.
Recovering 548 files into their original locations
Volumes needed (all on-line):
SL009064 at Aki
SL000166 at Aki
SL000016 at Aki
QB000089B at Aki
SL000123 at Aki
Requesting 548 file(s), this may take a while...
Direct Retrieval.
Direct Retrieval.
Connecting to the NDMP data server successfully.
Open the tape device : /dev/rmt/6cbn
NDMP Service Log: RESTORE: RESTORE IS DONE
******************************************************************
------ E R R O R -------
Tape server halted: The restore is aborted by operator.
******************************************************************
Close the tape device.
OK
Connecting to the NDMP data server successfully.
Open the tape device : /dev/rmt/6cbn
NDMP Service Log: RESTORE: Sat Mar 20 09:53:07 2004 : We have read 548088
KB from the backup.
[snip]
<< NOTE: It seems like its trying to scan the entire tape now, and it
takes a very long time >>
NDMP Service Log: RESTORE: Sat Mar 20 11:34:06 2004 : We have read
263386196 KB from the backup.
Tape server paused: waiting to position the tape
Close the tape device.
Open the tape device : /dev/rmt/6cbn
NDMP Service Log: RESTORE: Sat Mar 20 11:40:41 2004 : We have read
269609566 KB from the backup.
NDMP Service Log: RESTORE: RESTORE IS DONE
******************************************************************
------ E R R O R -------
Tape server halted: The restore is aborted by operator.
******************************************************************
Close the tape device.
OK
recover>
OK, this looks rather unexplainable at first, but during this session, the
following messages appear in the daemon.log on the backup server:
(output from two different sessions)
03/20/04 09:40:01 nsrd: britt.qbranch.se:root browsing
03/20/04 09:40:34 nsrd: britt.qbranch.se:/ browsing
03/20/04 09:40:34 nsrd: britt.qbranch.se:/ browsing
03/20/04 09:40:34 nsrd: media waiting event: waiting for 8mm AIT-2 tape
SL000123 (NS0215) on britt.qbranch.se
03/20/04 09:40:36 nsrmmd #7: Start nsrmmd #7, with PID 10655, at HOST
britt.qbranch.se
03/20/04 09:40:38 nsrd: media info: loading volume SL000123 into
rd=britt.qbranch.se:/dev/rmt/6cbn
03/20/04 09:40:46 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn Verify label
operation in progress
03/20/04 09:40:54 nsrmmd #7: ndmp tape mtio (1) failed, I/O error
03/20/04 09:41:14 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn is now enabled
read/write
03/20/04 09:41:14 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn Mount operation
in progress
03/20/04 09:41:25 nsrd: media event cleared: confirmed mount of SL000123
on rd=britt.qbranch.se:/dev/rmt/6cbn
03/20/04 09:43:18 ansrd: Cannot bind socket to connection port in
configured port range on system britt.qbranch.se.
[snip]
03/20/04 09:44:33 ansrd: Cannot bind socket to connection port in
configured port range on system britt.qbranch.se.
03/20/04 09:44:59 nsrd: netappfiler.customer.domain:/vol/vol0 (2/19/04)
done
reading
03/20/04 09:44:59 nsrd: media waiting event: waiting for 8mm AIT-2 tape
SL009064 (NS0433) on britt.qbranch.se
03/20/04 09:45:02 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn Eject operation
in progress
03/20/04 09:46:43 nsrd: media info: loading volume SL009064 into
rd=britt.qbranch.se:/dev/rmt/6cbn
03/20/04 09:46:54 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn Verify label
operation in progress
03/20/04 09:47:01 nsrmmd #1: ndmp tape mtio (1) failed, I/O error
03/20/04 09:47:21 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn is now enabled
read/write
03/20/04 09:47:21 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn Mount operation
in progress
03/20/04 09:47:32 nsrd: media event cleared: confirmed mount of SL009064
on rd=britt.qbranch.se:/dev/rmt/6cbn
And from another session:
03/20/04 23:39:05 nsrd: britt.qbranch.se:/ browsing
03/20/04 23:39:05 nsrd: britt.qbranch.se:/ browsing
03/20/04 23:39:05 nsrd: media waiting event: waiting for 8mm AIT-2 tape
SL000123 (NS0215) on britt.qbranch.se
03/20/04 23:39:07 nsrmmd #7: Start nsrmmd #7, with PID 984, at HOST
britt.qbranch.se
03/20/04 23:39:18 nsrd: media info: loading volume SL000123 into
rd=britt.qbranch.se:/dev/rmt/6cbn
03/20/04 23:39:29 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn Verify label
operation in progress
03/20/04 23:39:37 nsrmmd #7: ndmp tape mtio (1) failed, I/O error
03/20/04 23:39:57 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn is now enabled
read/write
03/20/04 23:39:57 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn Mount operation
in progress
03/20/04 23:40:08 nsrd: media event cleared: confirmed mount of SL000123
on rd=britt.qbranch.se:/dev/rmt/6cbn
03/20/04 23:41:11 ansrd: Cannot bind socket to connection port in
configured port range on system britt.qbranch.se.
[snip]
03/20/04 23:43:03 ansrd: Cannot bind socket to connection port in
configured port range on system britt.qbranch.se.
03/20/04 23:43:42 nsrd: netappfiler.customer.domain:/vol/vol0 (2/19/04)
done
reading
03/20/04 23:43:42 nsrd: britt.qbranch.se:/ done browsing
03/20/04 23:43:42 nsrd: media waiting event: waiting for 8mm AIT-2 tape
SL009064 (NS0433) on britt.qbranch.se
03/20/04 23:43:44 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn Eject operation
in progress
03/20/04 23:45:28 nsrd: media info: loading volume SL009064 into
rd=britt.qbranch.se:/dev/rmt/6cbn
03/20/04 23:45:38 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn Verify label
operation in progress
03/20/04 23:45:47 nsrmmd #1: ndmp tape mtio (1) failed, I/O error
03/20/04 23:46:06 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn is now enabled
read/write
03/20/04 23:46:06 nsrd: rd=britt.qbranch.se:/dev/rmt/6cbn Mount operation
in progress
03/20/04 23:46:18 nsrd: media event cleared: confirmed mount of SL009064
on rd=britt.qbranch.se:/dev/rmt/6cbn
03/20/04 23:46:51 nsrd: netappfiler.customer.domain:/vol/vol0 (2/08/04)
done
reading
03/20/04 23:46:51 nsrd: britt.qbranch.se:/ done browsing
03/20/04 23:50:57 nsrd: britt.qbranch.se:root done browsing
I don't really understand what's happening here, why those ansrd messages
pop up and why I get those I/O errors.
Can anyone shed some light to what could be the cause to a seemingly
simple task?
//Oscar
--
Note: To sign off this list, send a "signoff networker" command via email
to listserv AT listmail.temple DOT edu or visit the list's Web site at
http://listmail.temple.edu/archives/networker.html where you can
also view and post messages to the list.
=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=
|
