Date: Wed, 13 Dec 2006 20:36:35 GMT From: Andrew Bogecho<andrewb@cs.mcgill.ca> To: freebsd-gnats-submit@FreeBSD.org Subject: ports/106685: amanda-client-2.5.1p2, 1 backup failures (both dump and tar) Message-ID: <200612132036.kBDKaZBq098900@www.freebsd.org> Resent-Message-ID: <200612132040.kBDKeFFH067125@freefall.freebsd.org>
next in thread | raw e-mail | index | archive | help
>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@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:
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?200612132036.kBDKaZBq098900>