My last dozen or so emails to amanda-users have gone into a black
hole, I'll try this from another network under another subject.


So let's try and walk (crawl?) before we run.

I've set things up with only one small disk to be backed up, set
dumpcycle 0 and removed the holding disk and tape changer from the
config.  It's still failing.  There is a signal 13 (PIPE) error in
sendbackup.debug.

The amanda log shows:
  INFO taper tape Norstead01 kb 192 fm 1 writing file: Input/output error
  FAIL dumper alsike alsike_boot 20050311 0 ["data write: Connection reset by 
peer"]

The amdump log shows:
 taper : writing end marker. [Norstead01 ERR kb 192 fm 1]
 dumper : kill compress command
 driver : result time 18.083 from dumper0: FAILED 01-00002 ["data write: 
Connection reset by peer"]
 driver : result time 18.084 from taper: TAPE-ERROR 00-00001 [writing file: 
Input/output error]

The sendbackup.20050311152708.debug shows:
 sendbackup : time 9.062: error [/bin/tar got signal 13]

Does anybody know what this means?

regards :-)
BruceS


 - - - - - - - - - /var/log/amanda/Norstead/log.20050311.0 - - - - - - - - -

> DISK planner alsike alsike_boot
> START planner date 20050311
> WARNING planner Last full dump of alsike:alsike_boot on tape  overwritten in 
> 1 run.
> START driver date 20050311
> FINISH planner date 20050311
> STATS driver startup time 0.029
> START taper datestamp 20050311 label Norstead01 tape 0

   v
> INFO taper tape Norstead01 kb 192 fm 1 writing file: Input/output error
> FAIL dumper alsike alsike_boot 20050311 0 ["data write: Connection reset by 
> peer"]
   ^

>   sendbackup: start [alsike:alsike_boot level 0]
>   sendbackup: info BACKUP=/bin/tar
>   sendbackup: info RECOVER_CMD=/bin/tar -f... -
>   sendbackup: info end
> FAIL taper alsike alsike_boot 20050311 0 [out of tape]
> ERROR taper no-tape [[writing file: Input/output error]]
> FAIL driver alsike alsike_boot 20050311 0 [dump to tape failed]
> FINISH driver date 20050311 time 18.084





 - - - - - - - - - - /var/log/amanda/Norstead/amdump.1 - - - - - - - - - - 

> amdump: start at Fri Mar 11 15:26:59 GMT 2005
> amdump: datestamp 20050311
> planner: pid 16003 executable /usr/lib/amanda/planner version 2.4.4p2
> planner: build: VERSION="Amanda-2.4.4p2"
> planner:        BUILT_DATE="Fri Aug 13 18:32:08 UTC 2004"
> planner:        BUILT_MACH="Linux mcmurdo 2.4.27-es #1 SMP Sun Aug 8 13:02:24 
> BST 2004 i686 GNU/Linux"
> planner:        CC="gcc"
> planner:        CONFIGURE_COMMAND="'./configure' '--prefix=/usr' 
> '--bindir=/usr/sbin' '--mandir=/usr/share/man' '--libexecdir=/usr/lib/amanda' 
> '--enable-shared' '--sysconfdir=/etc' '--localstatedir=/var/lib' 
> '--with-gnutar-listdir=/var/lib/amanda/gnutar-lists' 
> '--with-index-server=localhost' '--with-user=backup' '--with-group=backup' 
> '--with-bsd-security' '--with-amandahosts' 
> '--with-smbclient=/usr/bin/smbclient' '--with-tcpportrange=50000,50100' 
> '--with-udpportrange=840,860'"
> 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/r" DUMP="/sbin/dump"
> planner:        RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF
> planner:        XFSDUMP="/sbin/xfsdump" XFSRESTORE="/sbin/xfsrestore"
> planner:        VXDUMP=UNDEF VXRESTORE=UNDEF
> planner:        SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/tar"
> planner:        COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip"
> planner:        LPRCMD="/usr/bin/lpr" 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
> planner:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY USE_AMANDAHOSTS
> planner:        CLIENT_LOGIN="backup" FORCE_USERID HAVE_GZIP
> planner:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
> planner:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
> planner: time 0.000: dgram_bind: socket bound to 0.0.0.0.858
> READING CONF FILES...
> planner: time 0.000: startup took 0.000 secs
> 
> SETTING UP FOR ESTIMATES...
> planner: time 0.000: setting up estimates for alsike:alsike_boot
> alsike:alsike_boot overdue 12854 days for level 0
> setup_estimate: alsike:alsike_boot: command 0, options:
>     last_level 0 next_level0 -12854 level_days 0
>     getting estimates 0 (-1) 1 (0) -1 (-1)
> planner: time 0.000: setting up estimates took 0.000 secs
> 
> GETTING ESTIMATES...
> driver: pid 16004 executable /usr/lib/amanda/driver version 2.4.4p2
> driver: tape size 40960000
> driver: send-cmd time 0.001 to taper: START-TAPER 20050311
> driver: started dumper0 pid 16006
> dumper: dgram_bind: socket bound to 0.0.0.0.846
> dumper: pid 16012 executable dumper1 version 2.4.4p2, using port 846
> driver: started dumper1 pid 16012
> planner: time 0.032: got result for host alsike disk alsike_boot: 0 -> 
> 31700K, 1 -> 10K, -1 -> -1K
> planner: time 0.032: getting estimates took 0.031 secs
> FAILED QUEUE: empty
> DONE QUEUE:
>   0: alsike     alsike_boot
> 
> ANALYZING ESTIMATES...
> pondering alsike:alsike_boot... next_level0 -12854 last_level 0 (due for 
> level 0) (picking inclevel for degraded mode)   picklev: last night 0, so 
> tonight level 1
> 
>   curr level 0 size 15850 total size 15970 total_lev0 15850 balanced-lev0size 
> 2264
> INITIAL SCHEDULE (size 15970):
>   alsike alsike_boot pri 12856 lev 0 size 15850
> 
> DELAYING DUMPS IF NEEDED, total_size 15970, tape length 40960000 mark 8
>   delay: Total size now 15970.
> 
> PROMOTING DUMPS IF NEEDED, total_lev0 15850, balanced_size 2264...
> planner: time 0.032: analysis took 0.000 secs
> 
> GENERATING SCHEDULE:
> --------
> ENDFLUSH
> DUMP alsike fffffeff9ffe0f alsike_boot 20050311 12856 0 1970:1:1:0:0:0 15850 
> 528 1 1970:1:1:0:0:0 5 0
> --------
> reserving 0 out of 0 for degraded-mode dumps
> driver: flush size 0
> driver: start time 0.029 inparallel 2 bandwidth 600 diskspace 0 dir OBSOLETE 
> datestamp 20050311 driver: drain-ends tapeq LARGESTFIT big-dumpers ttt
> dumper: dgram_bind: socket bound to 0.0.0.0.840
> dumper: pid 16006 executable dumper0 version 2.4.4p2, using port 840
> taper: read label `Norstead01' date `X'
> taper: wrote label `Norstead01' date `20050311'
> driver: result time 9.015 from taper: TAPER-OK
> driver: dumping alsike:alsike_boot directly to tape
> driver: send-cmd time 9.016 to taper: PORT-WRITE 00-00001 alsike 
> fffffeff9ffe0f alsike_boot 0 20050311
> taper: try_socksize: receive buffer size is 65536
> taper: stream_server: waiting for connection: 0.0.0.0.50083
> driver: result time 9.016 from taper: PORT 50083
> driver: send-cmd time 9.016 to dumper0: PORT-DUMP 01-00002 50083 alsike 
> fffffeff9ffe0f alsike_boot /boot 0 1970:1:1:0:0:0 GNUTAR 
> |;bsd-auth;srvcomp-fast;
> dumper: stream_client: connected to 127.0.0.1.50083
> dumper: stream_client: our side is 0.0.0.0.50084
> dumper: try_socksize: send buffer size is 65536
> taper: stream_accept: connection from 127.0.0.1.50084
> taper: try_socksize: receive buffer size is 32768
> driver: state time 9.018 free kps: 570 space: 0 taper: writing idle-dumpers: 
> 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: not-idle
> driver: interface-state time 9.018 if : free 570
> driver: hdisk-state time 9.018
> dumper: stream_client: connected to 127.0.0.1.50097
> dumper: stream_client: our side is 0.0.0.0.50085
> dumper: stream_client: connected to 127.0.0.1.50098
> dumper: stream_client: our side is 0.0.0.0.50086

   v
> taper: writing end marker. [Norstead01 ERR kb 192 fm 1]
> dumper: kill compress command
> driver: result time 18.083 from dumper0: FAILED 01-00002 ["data write: 
> Connection reset by peer"]
> driver: result time 18.084 from taper: TAPE-ERROR 00-00001 [writing file: 
> Input/output error]
   ^

> dump of driver schedule before start degraded mode:
> --------
> --------
> dump of driver schedule after start degraded mode:
> --------
> --------
> driver: state time 18.084 free kps: 600 space: 0 taper: DOWN idle-dumpers: 2 
> qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: not-idle
> driver: interface-state time 18.084 if : free 600
> driver: hdisk-state time 18.084
> driver: QUITTING time 18.084 telling children to quit
> driver: send-cmd time 18.084 to dumper0: QUIT
> driver: send-cmd time 18.084 to dumper1: QUIT
> driver: send-cmd time 18.084 to taper: QUIT
> taper: DONE [idle wait: 0.000 secs]
> driver: FINISHED time 18.084
> amdump: end at Fri Mar 11 15:27:17 GMT 2005
 

 - - - - - - - - - /tmp/amanda/sendbackup.20050311152708.debug - - - - - - - - 
- 

> sendbackup: debug 1 pid 16019 ruid 34 euid 34: start at Fri Mar 11 15:27:08 
> 2005
> /usr/lib/amanda/sendbackup: version 2.4.4p2
>   parsed request as: program `GNUTAR'
>                      disk `alsike_boot'
>                      device `/boot'
>                      level 0
>                      since 1970:1:1:0:0:0
>                      options `|;bsd-auth;srvcomp-fast;'
> sendbackup: try_socksize: send buffer size is 65536
> sendbackup: time 0.000: stream_server: waiting for connection: 0.0.0.0.50097
> sendbackup: time 0.000: stream_server: waiting for connection: 0.0.0.0.50098
> sendbackup: time 0.001: waiting for connect on 50097, then 50098
> sendbackup: time 0.001: stream_accept: connection from 127.0.0.1.50085
> sendbackup: time 0.003: stream_accept: connection from 127.0.0.1.50086
> sendbackup: time 0.003: got all connections
> sendbackup-gnutar: time 0.003: doing level 0 dump as listed-incremental to 
> /var/lib/amanda/gnutar-lists/alsikealsike_boot_0.new
> sendbackup-gnutar: time 0.006: doing level 0 dump from date: 1970-01-01  
> 0:00:00 GMT
> sendbackup: time 0.007: spawning /usr/lib/amanda/runtar in pipeline
> sendbackup: argument list: gtar --create --file - --directory /boot 
> --one-file-system --listed-incremental 
> /var/lib/amanda/gnutar-lists/alsikealsike_boot_0.new --sparse 
> --ignore-failed-read --totals .
> sendbackup-gnutar: time 0.122: /usr/lib/amanda/runtar: pid 16022

   v
> sendbackup: time 9.062: error [/bin/tar got signal 13]
   ^

> sendbackup: time 9.062: pid 16019 finish time Fri Mar 11 15:27:17 2005


-- 

Norstead Farm - Bruce & Carole Skinner
RR#1 Waterville NS Canada B0P 1V0
Tel: 902-538-1765
Fax: 902-538-1794
<mailto:norstead AT ns.sympatico DOT ca>