ports/106685: amanda-client-2.5.1p2, 1 backup failures (both dump and tar)

Andrew Bogecho andrewb at cs.mcgill.ca
Wed Dec 13 20:40:16 UTC 2006


>Number:         106685
>Category:       ports
>Synopsis:       amanda-client-2.5.1p2,1 backup failures (both dump and tar)
>Confidential:   no
>Severity:       non-critical
>Priority:       low
>Responsible:    freebsd-ports-bugs
>State:          open
>Quarter:        
>Keywords:       
>Date-Required:
>Class:          sw-bug
>Submitter-Id:   current-users
>Arrival-Date:   Wed Dec 13 20:40:14 GMT 2006
>Closed-Date:
>Last-Modified:
>Originator:     Andrew Bogecho
>Release:        FreeBSD 6.2-RC1
>Organization:
McGill University
>Environment:
FreeBSD host.CS.McGill.CA 6.2-RC1 FreeBSD 6.2-RC1 #2: Sat Dec  9 12:58:10 EST 2006     root at host.CS.McGill.CA:/usr/obj/usr/src/sys/KERNEL  i386
>Description:
I have had no luck with amanda on this new machine. It seems that the estimates do work:

planner: time 5849.901: got result for host host disk /home/Crypto/Labo: 0 -> 48051390K, 1 -> 48051390K, 2 -> 48051400K
planner: time 5849.901: got result for host host disk /dev/mirror/gm0s1a: 0 -> 266992K, 1 -> 267006K, 2 -> 267011K

dmesg on the client shows:

pid 9093 (amandad), uid 2: exited on signal 11 (core dumped)
pid 19762 (amandad), uid 2: exited on signal 11 (core dumped)

The amanda report shows the following:

client using dump:

/-- host       /dev/mirror/gm0s1d lev 1 STRANGE
sendbackup: start [axon:/dev/mirror/gm0s1d level 1]
sendbackup: info BACKUP=/sbin/dump
sendbackup: info RECOVER_CMD=/usr/bin/gzip -dc |/sbin/restore -f - ...
sendbackup: info COMPRESS_SUFFIX=.gz
sendbackup: info end
|   DUMP: Date of this level 1 dump: Wed Dec 13 15:22:35 2006
|   DUMP: Date of last level 0 dump: the epoch
|   DUMP: Dumping snapshot of /dev/mirror/gm0s1d (/usr) to standard output
|   DUMP: mapping (Pass I) [regular files]
|   DUMP: mapping (Pass II) [directories]
|   DUMP: estimated 2490764 tape blocks.
|   DUMP: dumping (Pass III) [directories]
? dumper: strange [missing size line from sendbackup]
? dumper: strange [missing end line from sendbackup]
\--------


Client using tar:

/-- axon       /home/Crypto/Labo lev 0 STRANGE
sendbackup: start [axon:/home/Crypto/Labo level 0]
sendbackup: info BACKUP=/usr/local/bin/gtar
sendbackup: info RECOVER_CMD=/usr/local/bin/gtar -f - ...
sendbackup: info end
? dumper: strange [missing size line from sendbackup]
? dumper: strange [missing end line from sendbackup]
\--------

on the client /tmp/amanda/client:

With dump:

sendbackup: debug 1 pid 19763 ruid 2 euid 2: start at Wed Dec 13 15:22:32 2006
axon# cat /tmp/amanda-report 
I have had no luck with amanda on this new machine. It seems that the estimates do work:

planner: time 5849.901: got result for host host disk /home/Crypto/Labo: 0 -> 48051390K, 1 -> 48051390K, 2 -> 48051400K
planner: time 5849.901: got result for host host disk /dev/mirror/gm0s1a: 0 -> 266992K, 1 -> 267006K, 2 -> 267011K

dmesg on the client shows:

pid 9093 (amandad), uid 2: exited on signal 11 (core dumped)
pid 19762 (amandad), uid 2: exited on signal 11 (core dumped)

The amanda report shows the following:

client using dump:

/-- host       /dev/mirror/gm0s1d lev 1 STRANGE
sendbackup: start [axon:/dev/mirror/gm0s1d level 1]
sendbackup: info BACKUP=/sbin/dump
sendbackup: info RECOVER_CMD=/usr/bin/gzip -dc |/sbin/restore -f - ...
sendbackup: info COMPRESS_SUFFIX=.gz
sendbackup: info end
|   DUMP: Date of this level 1 dump: Wed Dec 13 15:22:35 2006
|   DUMP: Date of last level 0 dump: the epoch
|   DUMP: Dumping snapshot of /dev/mirror/gm0s1d (/usr) to standard output
|   DUMP: mapping (Pass I) [regular files]
|   DUMP: mapping (Pass II) [directories]
|   DUMP: estimated 2490764 tape blocks.
|   DUMP: dumping (Pass III) [directories]
? dumper: strange [missing size line from sendbackup]
? dumper: strange [missing end line from sendbackup]
\--------


Client using tar:

/-- axon       /home/Crypto/Labo lev 0 STRANGE
sendbackup: start [axon:/home/Crypto/Labo level 0]
sendbackup: info BACKUP=/usr/local/bin/gtar
sendbackup: info RECOVER_CMD=/usr/local/bin/gtar -f - ...
sendbackup: info end
? dumper: strange [missing size line from sendbackup]
? dumper: strange [missing end line from sendbackup]
\--------

on the client /tmp/amanda/client:

With dump:

sendbackup: debug 1 pid 19763 ruid 2 euid 2: start at Wed Dec 13 15:22:32 2006
sendbackup: version 2.5.1p2
Reading conf file "/usr/local/etc/amanda/amanda-client.conf".
  sendbackup req: <DUMP /dev/mirror/gm0s1d  1 2006:12:13:9:23:24 OPTIONS |;auth=bsd;compress-fast;index;>
  parsed request as: program `DUMP'
                     disk `/dev/mirror/gm0s1d'
                     device `/dev/mirror/gm0s1d'
                     level 1
                     since 2006:12:13:9:23:24
                     options `|;auth=bsd;compress-fast;index;'
sendbackup: start: host:/dev/mirror/gm0s1d lev 1
sendbackup: time 0.069: spawning /usr/bin/gzip in pipeline
sendbackup: argument list: /usr/bin/gzip --fast
sendbackup-dump: time 0.070: pid 19765: /usr/bin/gzip --fast
sendbackup: time 0.071: dumping device '/dev/mirror/gm0s1d' with 'ufs'
sendbackup: time 0.071: spawning /sbin/dump in pipeline
sendbackup: argument list: dump 1ubLshf 64 1048576 0 - /dev/mirror/gm0s1d
sendbackup: time 0.072: started backup
sendbackup: time 0.077: started index creator: "/sbin/restore -tvf - 2>&1 | sed -e '
s/^leaf[        ]*[0-9]*[       ]*\.//
t
/^dir[  ]/ {
s/^dir[         ]*[0-9]*[       ]*\.//
s%$%/%
t
}
d
'"
sendbackup: time 2.811:  91:  normal(|):   DUMP: Date of this level 1 dump: Wed Dec 13 15:22:35 2006
sendbackup: time 2.812:  91:  normal(|):   DUMP: Date of last level 0 dump: the epoch
sendbackup: time 2.813:  91:  normal(|):   DUMP: Dumping snapshot of /dev/mirror/gm0s1d (/usr) to standard output
sendbackup: time 2.848:  91:  normal(|):   DUMP: mapping (Pass I) [regular files]
sendbackup: time 6.013:  91:  normal(|):   DUMP: mapping (Pass II) [directories]
sendbackup: time 6.013:  91:  normal(|):   DUMP: estimated 2490764 tape blocks.
sendbackup: time 6.019:  91:  normal(|):   DUMP: dumping (Pass III) [directories]
sendbackup: time 6.760: 113:  normal(|): 
sendbackup: time 6.761: 116: strange(?): gzip: stdout: Broken pipe
sendbackup: time 6.762: 116: strange(?): sendbackup: index tee cannot write [Broken pipe]
sendbackup: time 6.762: index tee cannot write [Broken pipe]
sendbackup: time 6.762: pid 19766 finish time Wed Dec 13 15:22:39 2006
sendbackup: time 6.770:  91:  normal(|):   DUMP: Broken pipe
sendbackup: time 6.775: 116: strange(?): sed: stdout: Broken pipe  DUMP: 
sendbackup: time 6.775: 116: strange(?): The ENTIRE dump is aborted.
sendbackup: time 9.791: error [compress returned 1, /sbin/dump returned 3]
sendbackup: time 9.791: pid 19763 finish time Wed Dec 13 15:22:42 2006

With tar:

sendbackup: debug 1 pid 18800 ruid 2 euid 2: start at Wed Dec 13 15:09:46 2006
sendbackup: version 2.5.1p2
Reading conf file "/usr/local/etc/amanda/amanda-client.conf".
  sendbackup req: <GNUTAR /home/Crypto/Labo  0 1970:1:1:0:0:0 OPTIONS |;auth=bsd;srvcomp-best;index;exclude-list=/usr/local/libexec/amanda/exclude.gtar;>
  parsed request as: program `GNUTAR'
                     disk `/home/Crypto/Labo'
                     device `/home/Crypto/Labo'
                     level 0
                     since 1970:1:1:0:0:0
                     options `|;auth=bsd;srvcomp-best;index;exclude-list=/usr/local/libexec/amanda/exclude.gtar;'
sendbackup: start: host:/home/Crypto/Labo lev 0
sendbackup-gnutar: time 0.023: doing level 0 dump as listed-incremental to '/usr/local/var/amanda/gnutar-lists/axon_home_Crypto_Labo_0.new'
sendbackup-gnutar: time 0.132: doing level 0 dump from date: 1970-01-01  0:00:00 GMT
sendbackup: time 0.167: spawning /usr/local/libexec/amanda/runtar in pipeline
sendbackup: argument list: runtar NOCONFIG GNUTAR --create --file - --directory /home/Crypto/Labo --one-file-system --listed-incremental /usr/local/var/amanda/gnutar-lists/host_home_Crypto_Labo_0.new --sparse --ignore-failed-read --totals --exclude-from /tmp/amanda/sendbackup._home_Crypto_Labo.20061213150946.exclude .
sendbackup-gnutar: time 0.169: /usr/local/libexec/amanda/runtar: pid 18803
sendbackup: time 0.169: started backup
sendbackup: time 0.169: started index creator: "/usr/local/bin/gtar -tf - 2>/dev/null | sed -e 's/^\.//'"
sendbackup: time 766.404: 118: strange(?): sendbackup: index tee cannot write [Broken pipe]
sendbackup: time 766.404: index tee cannot write [Broken pipe]
sendbackup: time 766.414: pid 18802 finish time Wed Dec 13 15:22:32 2006
sendbackup: time 766.466:  47:    size(|): Total bytes written: 10424320 (10MiB, ?/s)
sendbackup: time 766.466: 118: strange(?): GNUTAR: -: Cannot write: Broken pipe
sendbackup: time 766.466: 118: strange(?): GNUTAR: Error is not recoverable: exiting now
sendbackup: time 766.467: 118: strange(?): sed: stdout: Broken pipe
sendbackup: time 766.467: error [/usr/local/bin/gtar returned 2]
sendbackup: time 766.467: pid 18800 finish time Wed Dec 13 15:22:32 2006

>How-To-Repeat:
portupgrade -Rf amanda-client
On the backup server: start amdump

>Fix:

>Release-Note:
>Audit-Trail:
>Unformatted:



More information about the freebsd-ports-bugs mailing list