On Thu, Nov 22, 2001 at 20:35:31 +0100, Dietmar Goldbeck wrote:
> This is very strange, amandad received only estimate request
> for souterrain2 sda3, the other filesystems are missing.
>
> The startung times within amandad and sendsize debug don't match
> exactly. Perhaps amandad did a retry. The bad thing about a retry
> would be that debugging information gets overwritten.
> Do you have some kind of firewalling between the two machines?
There is no firewalling between the machines. Souterrain2 is the machine
with the tape unit. Strange enough, things have been running fine for a
year and a half, and now since a week or two this error occurs.
> I am sorry, i don't have a good idea.
> 2 suggestions:
>
> - try a amdump run with _only_ the failing filesystem in the disklist
> and mail debugging output to the list.
Okay, I have commented out all but /dev/hda6 on souterrain. Now the
backup finishes in a minute and the files are shown below.
Thanks a lot for any further suggestions!
Erik van der Meulen
----
- amandad.debug
amandad: debug 1 pid 21669 ruid 34 euid 34 start time Fri Nov 23
13:32:55 2001
amandad: version 2.4.2
amandad: build: VERSION="Amanda-2.4.2"
amandad: BUILT_DATE="Wed Feb 14 21:15:01 MST 2001"
amandad: BUILT_MACH="Linux rover 2.4.0-test7 #1 Fri Sep 1
21:47:30 MDT 2000 i686 unknown"
amandad: CC="gcc"
amandad: paths: bindir="/usr/sbin" sbindir="/usr/sbin"
amandad: libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
amandad: AMANDA_TMPDIR="/tmp/amanda"
AMANDA_DBGDIR="/tmp/amanda"
amandad: CONFIG_DIR="/etc/amanda" DEV_PREFIX="/dev/"
amandad: RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
amandad: RESTORE="/sbin/restore"
SAMBA_CLIENT="/usr/bin/smbclient"
amandad: GNUTAR="/bin/tar" COMPRESS_PATH="/bin/gzip"
amandad: UNCOMPRESS_PATH="/bin/gzip" MAILER="/usr/bin/Mail"
amandad: listed_incr_dir="/var/lib/amanda/gnutar-lists"
amandad: defs: DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
amandad: DEFAULT_TAPE_SERVER="localhost"
amandad: DEFAULT_TAPE_DEVICE="/dev/null" HAVE_MMAP HAVE_SYSVSHM
amandad: LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
BSD_SECURITY
amandad: USE_AMANDAHOSTS CLIENT_LOGIN="backup" FORCE_USERID
HAVE_GZIP
amandad: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
amandad: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
got packet:
--------
Amanda 2.4 REQ HANDLE 000-60BD0608 SEQ 1006518651
SECURITY USER backup
SERVICE sendbackup
OPTIONS hostname=souterrain2;
GNUTAR sda3 1 2001:11:14:0:54:43 OPTIONS
|;bsd-auth;compress-fast;index;
--------
sending ack:
----
Amanda 2.4 ACK HANDLE 000-60BD0608 SEQ 1006518651
----
bsd security: remote host souterrain2.avondel.nl user backup local
user backup
amandahosts security check passed
amandad: running service "/usr/lib/amanda/sendbackup"
amandad: sending REP packet:
----
Amanda 2.4 REP HANDLE 000-60BD0608 SEQ 1006518651
CONNECT DATA 2176 MESG 2177 INDEX 2178
OPTIONS ;compress-fast;bsd-auth;index;
----
amandad: got packet:
----
Amanda 2.4 ACK HANDLE 000-60BD0608 SEQ 1006518651
----
amandad: pid 21669 finish time Fri Nov 23 13:32:55 2001
- sendsize.debug
sendsize: debug 1 pid 21666 ruid 34 euid 34 start time Fri Nov 23
13:30:51 2001
/usr/lib/amanda/sendsize: version 2.4.2
calculating for amname 'sda3', dirname '/home'
sendsize: getting size via gnutar for sda3 level 0
sendsize: running "/usr/lib/amanda/runtar --create --directory /home
--listed-incremental
/var/lib/amanda/gnutar-lists/souterrain2sda3_0.new --sparse
--one-file-system --ignore-failed-read --totals --file /dev/null "
sendsize: spawning "/usr/lib/amanda/runtar" in pipeline
sendsize: argument list: "/bin/tar" "--create" "--directory" "/home"
"--listed-incremental"
"/var/lib/amanda/gnutar-lists/souterrain2sda3_0.new" "--sparse"
"--one-file-system" "--ignore-failed-read" "--totals" "--file"
"/dev/null" "."
Total bytes written: 1466562560 (1.4GB, 1.4GB/s)
.....
sendsize: getting size via gnutar for sda3 level 1
sendsize: running "/usr/lib/amanda/runtar --create --directory /home
--listed-incremental
/var/lib/amanda/gnutar-lists/souterrain2sda3_1.new --sparse
--one-file-system --ignore-failed-read --totals --file /dev/null "
sendsize: spawning "/usr/lib/amanda/runtar" in pipeline
sendsize: argument list: "/bin/tar" "--create" "--directory" "/home"
"--listed-incremental"
"/var/lib/amanda/gnutar-lists/souterrain2sda3_1.new" "--sparse"
"--one-file-system" "--ignore-failed-read" "--totals" "--file"
"/dev/null" "."
Total bytes written: 16209920 (15MB, 15MB/s)
.....
sendsize: pid 21666 finish time Fri Nov 23 13:30:53 2001
- amdump.1
amdump: start at Fri Nov 23 14:49:43 CET 2001
planner: pid 21759 executable /usr/lib/amanda/planner version 2.4.2
planner: build: VERSION="Amanda-2.4.2"
planner: BUILT_DATE="Wed Feb 14 21:15:01 MST 2001"
planner: BUILT_MACH="Linux rover 2.4.0-test7 #1 Fri Sep 1 21:47:30 MDT 2000
i686 unknown"
planner: CC="gcc"
planner: paths: bindir="/usr/sbin" sbindir="/usr/sbin"
planner: libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
planner: AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
planner: CONFIG_DIR="/etc/amanda" DEV_PREFIX="/dev/"
planner: RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
planner: RESTORE="/sbin/restore" SAMBA_CLIENT="/usr/bin/smbclient"
planner: GNUTAR="/bin/tar" COMPRESS_PATH="/bin/gzip"
planner: UNCOMPRESS_PATH="/bin/gzip" MAILER="/usr/bin/Mail"
planner: listed_incr_dir="/var/lib/amanda/gnutar-lists"
planner: defs: DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
planner: DEFAULT_TAPE_SERVER="localhost"
planner: DEFAULT_TAPE_DEVICE="/dev/null" HAVE_MMAP HAVE_SYSVSHM
planner: LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE BSD_SECURITY
planner: USE_AMANDAHOSTS CLIENT_LOGIN="backup" FORCE_USERID HAVE_GZIP
planner: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
planner: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
READING CONF FILES...
startup took 0.007 secs
SETTING UP FOR ESTIMATES...
setting up estimates for souterrain:hda6
driver: pid 21760 executable /usr/lib/amanda/driver version 2.4.2
driver: send-cmd time 0.010 to taper: START-TAPER 20011123
driver: started dumper0 pid 21762
driver: started dumper1 pid 21763
driver: started dumper2 pid 21764
driver: started dumper3 pid 21765
setup_estimate: souterrain:hda6: command 0, options:
last_level 1 next_level0 10 level_days 2
getting estimates 0 (70080) 1 (11280) -1 (-1)
setting up estimates took 0.031 secs
GETTING ESTIMATES...
taper: pid 21761 executable taper version 2.4.2
dumper: pid 21762 executable dumper version 2.4.2, using port 761
dumper: pid 21765 executable dumper version 2.4.2, using port 764
dumper: pid 21764 executable dumper version 2.4.2, using port 763
dumper: pid 21763 executable dumper version 2.4.2, using port 762
taper: read label `AVONDEL03' date `20011114'
taper: wrote label `AVONDEL03' date `20011123'
got result for host souterrain disk hda6: 0 -> -1K, 1 -> -1K, -1 -> -1K
getting estimates took 82.213 secs
FAILED QUEUE:
0: souterrain hda6
DONE QUEUE: empty
ANALYZING ESTIMATES...
planner: FAILED souterrain hda6 0 [disk hda6 offline on souterrain?]
INITIAL SCHEDULE (size 264):
DELAYING DUMPS IF NEEDED, total_size 264, tape length 2048000 mark 100
delay: Total size now 264.
PROMOTING DUMPS IF NEEDED, total_lev0 0, balanced_size 0...
analysis took 0.000 secs
GENERATING SCHEDULE:
--------
--------
driver: adding holding disk 0 dir /dump/amanda size 296960
reserving 296960 out of 296960 for degraded-mode dumps
driver: start time 82.232 inparallel 4 bandwidth 600 diskspace 296960 dir OBSOLETE
datestamp 20011123 driver: drain-ends tapeq LFFO big-dumpers 1
driver: result time 82.233 from taper: TAPER-OK
driver: state time 82.233 free kps: 600 space: 296960 taper: idle idle-dumpers: 4 qlen
tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: not-idle
driver: interface-state time 82.233 if : free 600
driver: hdisk-state time 82.233 hdisk 0: free 296960 dumpers 0
driver: QUITTING time 82.233 telling children to quit
driver: send-cmd time 82.233 to dumper0: QUIT
driver: send-cmd time 82.233 to dumper1: QUIT
driver: send-cmd time 82.233 to dumper2: QUIT
driver: send-cmd time 82.233 to dumper3: QUIT
driver: send-cmd time 82.233 to taper: QUIT
taper: DONE [idle wait: 53.013 secs]
taper: writing end marker. [AVONDEL03 OK kb 0 fm 0]
driver: FINISHED time 103.537
amdump: end at Fri Nov 23 14:51:27 CET 2001
