On Thu, Nov 22, 2001 at 07:34:37 +0100, Dietmar Goldbeck wrote:
> On Wed, Nov 21, 2001 at 10:33:47PM +0100, Erik van der Meulen wrote:
> >
> > Dear Amanda people - I have been using this fine piece of software for
> > quite a while now withou any problems. Wonderful!
> >
> > However, since a few days I get the following "FAILURE AND STRANGE DUMP
> > SUMMARY" quoted below. To me it seems that Amanda is unable to connect
> > to /dev/hda6 on my "souterrain" machine. This seems odd (at least to me)
> > because on the machine the volume seems to work well. It is a /var
> > partition that spools my mail and a lot more.
> >
> > Does anybody have any suggestions where things might have messed
> > up? I cannot seem to find it myself.
> >
>
> Please provide some more information:
>
> - Amanda Version
> - is souterrain backed up with GNUtar or dump?
> - Please attach the amandad and sendsize debug file (typical
> /tmp/amanda/amandad...)
>
> - Please attach the amdump file from your log directory.
Thanks a lot. The information you have requested is inserted below.
I hope this helps.
- Amanda version 2.4.2 (on Debian/GNU Linux 2.2, kernel 2.2.17)
- Souterrain is backed up using:
define dumptype erik-test-comp {
program "GNUTAR"
comment "Eriks Test w Compression"
options index compress-fast
priority medium
}
- amandad.debug:
amandad: debug 1 pid 20419 ruid 34 euid 34 start time Thu Nov 22
00:46:53 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 1006386301
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 1006386301
----
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 1006386301
CONNECT DATA 2141 MESG 2142 INDEX 2143
OPTIONS ;compress-fast;bsd-auth;index;
----
amandad: got packet:
----
Amanda 2.4 ACK HANDLE 000-60BD0608 SEQ 1006386301
----
amandad: pid 20419 finish time Thu Nov 22 00:46:53 2001
- sendsize.debug:
sendsize: debug 1 pid 20415 ruid 34 euid 34 start time Thu Nov 22
00:45:01 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: 1465006080 (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: 14653440 (14MB, 14MB/s)
.....
sendsize: pid 20415 finish time Thu Nov 22 00:45:04 2001
- amdump.1:
amdump: start at Thu Nov 22 00:45:01 CET 2001
planner: pid 20406 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 souterrain2:sda3
setup_estimate: souterrain2:sda3: command 0, options:
last_level 1 next_level0 20 level_days 5
getting estimates 0 (1425470) 1 (14310) -1 (-1)
setting up estimates for souterrain:hda1
driver: pid 20407 executable /usr/lib/amanda/driver version 2.4.2
driver: send-cmd time 0.033 to taper: START-TAPER 20011122
driver: started dumper0 pid 20409
driver: started dumper1 pid 20410
driver: started dumper2 pid 20411
driver: started dumper3 pid 20412
setup_estimate: souterrain:hda1: command 0, options:
last_level 1 next_level0 26 level_days 1
getting estimates 0 (42130) 1 (140) -1 (-1)
setting up estimates for souterrain:hda3
setup_estimate: souterrain:hda3: command 0, options:
last_level 3 next_level0 19 level_days 3
getting estimates 0 (848960) 3 (416190) 4 (0)
setting up estimates for souterrain:hda6
taper: pid 20408 executable taper version 2.4.2
setup_estimate: souterrain:hda6: command 0, options:
last_level 1 next_level0 12 level_days 2
getting estimates 0 (70080) 1 (11280) -1 (-1)
setting up estimates took 0.118 secs
GETTING ESTIMATES...
dumper: pid 20409 executable dumper version 2.4.2, using port 566
dumper: pid 20410 executable dumper version 2.4.2, using port 567
dumper: pid 20412 executable dumper version 2.4.2, using port 570
dumper: pid 20411 executable dumper version 2.4.2, using port 569
got result for host souterrain2 disk sda3: 0 -> 1430670K, 1 -> 14310K, -1 -> -1K
taper: read label `AVONDEL08' date `20011109'
taper: wrote label `AVONDEL08' date `20011122'
got result for host souterrain disk hda6: 0 -> -1K, 1 -> -1K, -1 -> -1K
got result for host souterrain disk hda3: 0 -> 848040K, 3 -> 417510K, 4 -> 206150K
got result for host souterrain disk hda1: 0 -> 42130K, 1 -> 140K, -1 -> -1K
getting estimates took 111.254 secs
FAILED QUEUE:
0: souterrain hda6
DONE QUEUE:
0: souterrain2 sda3
1: souterrain hda3
2: souterrain hda1
ANALYZING ESTIMATES...
pondering souterrain2:sda3... next_level0 20 last_level 1 (not due for a full dump,
picking an incr level)
pick: size 14310 level 1 days 5 (thresh 20480K, 1 days)
curr level 1 size 8434 total size 8830 total_lev0 0 balanced-lev0size 44245
pondering souterrain:hda3... next_level0 19 last_level 3 (not due for a full dump,
picking an incr level)
pick: size 417510 level 3 days 3 (thresh 327680K, 1 days)
pick: next size 206150... not bumped
curr level 3 size 224746 total size 233708 total_lev0 0 balanced-lev0size 61276
pondering souterrain:hda1... next_level0 26 last_level 1 (not due for a full dump,
picking an incr level)
pick: size 140 level 1 days 1 (thresh 20480K, 1 days)
curr level 1 size 30 total size 233870 total_lev0 0 balanced-lev0size 62260
planner: FAILED souterrain hda6 0 [disk hda6 offline on souterrain?]
INITIAL SCHEDULE (size 233870):
souterrain hda3 pri 1 lev 3 size 224746
souterrain2 sda3 pri 1 lev 1 size 8434
souterrain hda1 pri 1 lev 1 size 30
DELAYING DUMPS IF NEEDED, total_size 233870, tape length 2048000 mark 100
delay: Total size now 233870.
PROMOTING DUMPS IF NEEDED, total_lev0 0, balanced_size 62260...
promote: checking up to 26 days ahead
promote: checking 1 day now
promote: checking 2 days now
promote: checking 3 days now
promote: checking 4 days now
promote: checking 5 days now
promote: checking 6 days now
promote: checking 7 days now
promote: checking 8 days now
promote: checking 9 days now
promote: checking 10 days now
promote: checking 11 days now
promote: checking 12 days now
promote: checking 13 days now
promote: checking 14 days now
promote: checking 15 days now
promote: checking 16 days now
promote: checking 17 days now
promote: checking 18 days now
promote: checking 19 days now
promote: souterrain:hda3 too big: new size 476876 total 476876, bal size 62260
thresh 3113
promote: checking 20 days now
promote: souterrain2:sda3 too big: new size 1238871 total 1238871, bal size 62260
thresh 3113
promote: checking 21 days now
promote: checking 22 days now
promote: checking 23 days now
promote: checking 24 days now
promote: checking 25 days now
promote: checking 26 days now
promote: moving souterrain:hda1 up, total_lev0 27552, total_size 261392
promote: checking up to 26 days ahead
promote: checking 1 day now
promote: checking 2 days now
promote: checking 3 days now
promote: checking 4 days now
promote: checking 5 days now
promote: checking 6 days now
promote: checking 7 days now
promote: checking 8 days now
promote: checking 9 days now
promote: checking 10 days now
promote: checking 11 days now
promote: checking 12 days now
promote: checking 13 days now
promote: checking 14 days now
promote: checking 15 days now
promote: checking 16 days now
promote: checking 17 days now
promote: checking 18 days now
promote: checking 19 days now
promote: souterrain:hda3 too big: new size 476876 total 504428, bal size 62260
thresh 3113
promote: checking 20 days now
promote: souterrain2:sda3 too big: new size 1238871 total 1266423, bal size 62260
thresh 3113
promote: checking 21 days now
promote: checking 22 days now
promote: checking 23 days now
promote: checking 24 days now
promote: checking 25 days now
promote: checking 26 days now
analysis took 0.002 secs
GENERATING SCHEDULE:
--------
souterrain hda3 1 3 2001:11:15:23:46:59 224746 2702
souterrain2 sda3 1 1 2001:11:14:0:54:43 8434 16
souterrain hda1 1 0 1970:1:1:0:0:0 27552 313 1 2001:11:19:23:46:42 30 3
--------
driver: adding holding disk 0 dir /dump/amanda size 296960
reserving 296960 out of 296960 for degraded-mode dumps
driver: start time 111.358 inparallel 4 bandwidth 600 diskspace 296960 dir OBSOLETE
datestamp 20011122 driver: drain-ends tapeq LFFO big-dumpers 1
driver: result time 111.359 from taper: TAPER-OK
driver: send-cmd time 111.359 to dumper0: FILE-DUMP 00-00001
/dump/amanda/20011122/souterrain2.sda3.1 souterrain2 sda3 1 2001:11:14:0:54:43
1073741824 GNUTAR 8512 |;bsd-auth;compress-fast;index;
driver: state time 111.359 free kps: 73 space: 288448 taper: idle idle-dumpers: 3 qlen
tapeq: 0 runq: 2 roomq: 0 wakeup: 86400 driver-idle: no-bandwidth
driver: interface-state time 111.359 if : free 73
driver: hdisk-state time 111.359 hdisk 0: free 288448 dumpers 1
driver: result time 127.298 from dumper0: RQ-MORE-DISK 00-00001
driver: send-cmd time 127.298 to dumper0: CONTINUE
/dump/amanda/20011122/souterrain2.sda3.1 1073741824 480
driver: state time 127.298 free kps: 73 space: 287968 taper: idle idle-dumpers: 3 qlen
tapeq: 0 runq: 2 roomq: 0 wakeup: 86400 driver-idle: no-bandwidth
driver: interface-state time 127.298 if : free 73
driver: hdisk-state time 127.298 hdisk 0: free 287968 dumpers 1
driver: result time 127.671 from dumper0: DONE 00-00001 14310 8736 16 [sec 16.214 kb
8736 kps 538.8 orig-kb 14310]
driver: finished-cmd time 127.675 dumper0 dumped souterrain2:sda3
driver: send-cmd time 127.675 to taper: FILE-WRITE 00-00002
/dump/amanda/20011122/souterrain2.sda3.1 souterrain2 sda3 1 20011122
driver: send-cmd time 127.675 to dumper0: FILE-DUMP 01-00003
/dump/amanda/20011122/souterrain.hda1.0 souterrain hda1 0 1970:1:1:0:0:0 1073741824
GNUTAR 27616 |;bsd-auth;compress-fast;index;
driver: state time 127.675 free kps: 512 space: 260576 taper: writing idle-dumpers: 3
qlen tapeq: 0 runq: 1 roomq: 0 wakeup: 15 driver-idle: start-wait
driver: interface-state time 127.675 if : free 512
driver: hdisk-state time 127.675 hdisk 0: free 260576 dumpers 1
driver: state time 142.672 free kps: 512 space: 260576 taper: writing idle-dumpers: 3
qlen tapeq: 0 runq: 1 roomq: 0 wakeup: 86400 driver-idle: client-constrained
driver: interface-state time 142.672 if : free 512
driver: hdisk-state time 142.672 hdisk 0: free 260576 dumpers 1
taper: reader-side: got label AVONDEL08 filenum 1
driver: result time 241.564 from taper: DONE 00-00002 AVONDEL08 1 [sec 113.884 kb 8768
kps 77.0 {wr: writers 274 rdwait 0.000 wrwait 92.056 filemark 21.813}]
driver: finished-cmd time 241.574 taper wrote souterrain2:sda3
driver: state time 241.575 free kps: 512 space: 269344 taper: idle idle-dumpers: 3
qlen tapeq: 0 runq: 1 roomq: 0 wakeup: 86400 driver-idle: client-constrained
driver: interface-state time 241.575 if : free 512
driver: hdisk-state time 241.575 hdisk 0: free 269344 dumpers 1
driver: result time 367.867 from dumper0: DONE 01-00003 42130 27552 240 [sec 239.739
kb 27552 kps 114.9 orig-kb 42130]
driver: finished-cmd time 367.879 dumper0 dumped souterrain:hda1
driver: send-cmd time 367.879 to taper: FILE-WRITE 00-00004
/dump/amanda/20011122/souterrain.hda1.0 souterrain hda1 0 20011122
driver: send-cmd time 367.880 to dumper0: FILE-DUMP 01-00005
/dump/amanda/20011122/souterrain.hda3.3 souterrain hda3 3 2001:11:15:23:46:59
1073741824 GNUTAR 224832 |;bsd-auth;compress-fast;index;
driver: state time 367.880 free kps: 517 space: 44544 taper: writing idle-dumpers: 3
qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: not-idle
driver: interface-state time 367.880 if : free 517
driver: hdisk-state time 367.880 hdisk 0: free 44544 dumpers 1
taper: reader-side: got label AVONDEL08 filenum 2
driver: result time 578.070 from taper: DONE 00-00004 AVONDEL08 2 [sec 210.188 kb
27584 kps 131.2 {wr: writers 862 rdwait 0.000 wrwait 189.912 filemark 20.236}]
driver: finished-cmd time 578.093 taper wrote souterrain:hda1
driver: state time 578.094 free kps: 517 space: 72128 taper: idle idle-dumpers: 3 qlen
tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: not-idle
driver: interface-state time 578.094 if : free 517
driver: hdisk-state time 578.094 hdisk 0: free 72128 dumpers 1
driver: result time 2541.466 from dumper0: RQ-MORE-DISK 01-00005
driver: send-cmd time 2541.478 to dumper0: CONTINUE
/dump/amanda/20011122/souterrain.hda3.3 1073741824 11296
driver: state time 2541.478 free kps: 517 space: 60832 taper: idle idle-dumpers: 3
qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: not-idle
driver: interface-state time 2541.478 if : free 517
driver: hdisk-state time 2541.478 hdisk 0: free 60832 dumpers 1
driver: result time 2544.071 from dumper0: DONE 01-00005 417530 225024 2175 [sec
2175.250 kb 225024 kps 103.4 orig-kb 417530]
driver: finished-cmd time 2544.161 dumper0 dumped souterrain:hda3
driver: send-cmd time 2544.161 to taper: FILE-WRITE 00-00006
/dump/amanda/20011122/souterrain.hda3.3 souterrain hda3 3 20011122
driver: state time 2544.161 free kps: 600 space: 71904 taper: writing idle-dumpers: 4
qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: not-idle
driver: interface-state time 2544.161 if : free 600
driver: hdisk-state time 2544.161 hdisk 0: free 71904 dumpers 0
taper: reader-side: got label AVONDEL08 filenum 3
driver: result time 4052.566 from taper: DONE 00-00006 AVONDEL08 3 [sec 1508.403 kb
225056 kps 149.2 {wr: writers 7033 rdwait 0.000 wrwait 1504.069 filemark 4.105}]
driver: finished-cmd time 4052.856 taper wrote souterrain:hda3
driver: state time 4052.857 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 4052.857 if : free 600
driver: hdisk-state time 4052.857 hdisk 0: free 296960 dumpers 0
driver: QUITTING time 4052.857 telling children to quit
driver: send-cmd time 4052.857 to dumper0: QUIT
driver: send-cmd time 4052.859 to dumper1: QUIT
driver: send-cmd time 4052.860 to dumper2: QUIT
driver: send-cmd time 4052.861 to dumper3: QUIT
driver: send-cmd time 4052.862 to taper: QUIT
taper: DONE [idle wait: 2191.671 secs]
taper: writing end marker. [AVONDEL08 OK kb 261408 fm 3]
driver: FINISHED time 4058.820
amdump: end at Thu Nov 22 01:52:40 CET 2001
--
Erik van der Meulen <[EMAIL PROTECTED]>
