Amanda-Users

Trouble with Quantum DLT-S4 tapedrive

2007-04-19 12:37:52
Subject: Trouble with Quantum DLT-S4 tapedrive
From: Richard Stockton <amanda AT richardleestockton DOT org>
To: amanda-users AT amanda DOT org
Date: Mon, 16 Apr 2007 15:41:04 -0700
Hi there, (I see other messages posted, but not this one, so I'm re-trying);

I am unable to get amanda to write to my new Quantum DLT-S4 drive.
I am trying to write directly to tape with no "holding" space.

    OS: FreeBSD 6.2-RELEASE #0
Amanda: 2.5.1p3

Tapes label okay, and "amcheck" is happy, but when I do an "amdump bak15"
I get errors.  Here are the log and amdump files, plus the lines that
repeat in my syslog, and the various ".debug" files and my amanda.conf
(sorry for the lengthy email, but I wanted you to have all the info).

log:
DISK planner bak-05 aacd3s1d
START planner date 20070412122332
START driver date 20070412122332
WARNING planner Last full dump of bak-05:aacd3s1d on tape overwritten in 1 run.
STATS driver startup time 0.020
START taper datestamp 20070412122332 label VOL153 tape 0
FINISH planner date 20070412122332 time 131.949
INFO taper tape VOL153 kb 0 fm 1 writing file: Input/output error
FAIL taper bak-05 aacd3s1d 20070412122332 0 [out of tape]
ERROR taper no-tape [No more writable valid tape found]
FAIL driver bak-05 aacd3s1d 20070412122332 0 [dump to tape failed]
FAIL dumper bak-05 aacd3s1d 20070412122332 0 [data write: Broken pipe]
  sendbackup: start [bak-05:aacd3s1d level 0]
  sendbackup: info BACKUP=/sbin/dump
  sendbackup: info RECOVER_CMD=/sbin/restore -f - ...
  sendbackup: info end
  |   DUMP: WARNING: should use -L when dumping live read-write filesystems!
  |   DUMP: Date of this level 0 dump: Thu Apr 12 12:25:44 2007
  |   DUMP: Date of last level 0 dump: the epoch
  |   DUMP: Dumping /dev/aacd3s1d (/bak15) to standard output
  |   DUMP: mapping (Pass I) [regular files]
  |   DUMP: mapping (Pass II) [directories]
  |   DUMP: estimated 60774776 tape blocks.
FINISH driver date 20070412122332 time 202.265
============================================================================

amdump:
amdump: start at Thu Apr 12 12:23:32 PDT 2007
amdump: datestamp 20070412
amdump: starttime 20070412122332
planner: pid 7521 executable /usr/local/libexec/planner version 2.5.1p3
planner: build: VERSION="Amanda-2.5.1p3"
planner:        BUILT_DATE="Thu Apr 12 12:13:07 PDT 2007"
planner: BUILT_MACH="FreeBSD bak-05.adhost.com 6.2-RELEASE FreeBSD 6.2-RE LEASE #0: Mon Feb 26 18:18:04 PST 2007 stockton AT bak05.adhost DOT com:/usr/src/sys/i3
86/compile/BAK-05 i386"
planner:        CC="gcc"
planner: CONFIGURE_COMMAND="'./configure' '--with-user=amanda' '--with-gr
oup=operator'"
planner: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin"
planner:        libexecdir="/usr/local/libexec" mandir="/usr/local/man"
planner:        AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
planner:        CONFIG_DIR="/usr/local/etc/amanda" DEV_PREFIX="/dev/"
planner:        RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
planner:        RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF
planner:        XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF
planner:        SAMBA_CLIENT=UNDEF GNUTAR=UNDEF
planner:        COMPRESS_PATH="/usr/bin/gzip"
planner:        UNCOMPRESS_PATH="/usr/bin/gzip" LPRCMD="/usr/bin/lpr"
planner:        MAILER="/usr/bin/Mail"
planner:        listed_incr_dir="/usr/local/var/amanda/gnutar-lists"
planner: defs:  DEFAULT_SERVER="bak-05.adhost.com"
planner:        DEFAULT_CONFIG="DailySet1"
planner:        DEFAULT_TAPE_SERVER="bak-05.adhost.com" HAVE_MMAP
planner:        HAVE_SYSVSHM LOCKING=POSIX_FCNTL DEBUG_CODE
planner:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY RSH_SECURITY USE_AMANDAHOSTS
planner:        CLIENT_LOGIN="amanda" FORCE_USERID HAVE_GZIP
planner:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
planner:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
driver: pid 7522 executable /usr/local/libexec/driver version 2.5.1p3
READING CONF FILES...
planner: timestamp 20070412122332
planner: time 0.009: startup took 0.009 secs

SENDING FLUSHES...
ENDFLUSH

SETTING UP FOR ESTIMATES...
planner: time 0.010: setting up estimates for bak-05:aacd3s1d
driver: tape size 790626304
reserving 0 out of 0 for degraded-mode dumps
driver: send-cmd time 0.011 to taper: START-TAPER 20070412122332
driver: started dumper0 pid 7524
driver: send-cmd time 0.012 to dumper0: START 20070412122332
driver: started dumper1 pid 7525
driver: send-cmd time 0.014 to dumper1: START 20070412122332
driver: started dumper2 pid 7526
driver: send-cmd time 0.015 to dumper2: START 20070412122332
driver: started dumper3 pid 7527
bak-05:aacd3s1d overdue 13602 days for level 0
setup_estimate: bak-05:aacd3s1d: command 0, options: none last_level 0 next_l
evel0 -13602 level_days 0    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.018: setting up estimates took 0.008 secs

GETTING ESTIMATES...
driver: send-cmd time 0.019 to dumper3: START 20070412122332
driver: start time 0.030 inparallel 4 bandwidth 2000 diskspace 0 dir OBSOLETE d
atestamp 20070412122332 driver: drain-ends tapeq FIRST big-dumpers sssS
dumper: pid 7526 executable dumper2 version 2.5.1p3
taper: pid 7523 executable taper version 2.5.1p3
dumper: pid 7527 executable dumper3 version 2.5.1p3
dumper: pid 7525 executable dumper1 version 2.5.1p3
dumper: pid 7524 executable dumper0 version 2.5.1p3
taper: page size = 4096
taper: buffer size is 32768
planner: time 0.126: got partial result for host bak-05 disk aacd3s1d: 0 -> -2K,
 1 -> -2K, -1 -> -2K
taper: wrote label `VOL153' date `20070412122332'
driver: result time 0.233 from taper: TAPER-OK
driver: state time 0.233 free kps: 2000 space: 0 taper: idle idle-dumpers: 4 qle
n tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 0.233 if default: free 600 if local: free 1000 if l
e0: free 400
driver: hdisk-state time 0.233
planner: time 66.151: got partial result for host bak-05 disk aacd3s1d: 0 -> 607
74776K, 1 -> -2K, -1 -> -2K
planner: time 131.947: got partial result for host bak-05 disk aacd3s1d: 0 -> 60
774776K, 1 -> 60774776K, -1 -> -2K
planner: time 131.948: got result for host bak-05 disk aacd3s1d: 0 -> 60774776K,
 1 -> 60774776K, -1 -> -2K
planner: time 131.948: getting estimates took 131.930 secs
FAILED QUEUE: empty
DONE QUEUE:
  0: bak-05     aacd3s1d

ANALYZING ESTIMATES...
pondering bak-05:aacd3s1d... next_level0 -13602 last_level 0 (due for level 0) (
picking inclevel for degraded mode)   picklev: last night 0, so tonight level 1

curr level 0 nsize 60774776 csize 60774776 total size 60774872 total_lev0 6077
4776 balanced-lev0size 15193694
INITIAL SCHEDULE (size 60774872):
  bak-05 aacd3s1d pri 13603 lev 0 nsize 60774776 csize 60774776

DELAYING DUMPS IF NEEDED, total_size 60774872, tape length 790626304 mark 0
  delay: Total size now 60774872.

PROMOTING DUMPS IF NEEDED, total_lev0 60774776, balanced_size 15193694...
planner: time 131.949: analysis took 0.000 secs

GENERATING SCHEDULE:
--------
DUMP bak-05 fffffeff9ffeffffff7f aacd3s1d 20070412122332 13603 0 1970:1:1:0:0:0
60774776 60774776 59350 1024 1 1970:1:1:0:0:0 60774776 60774776 59350 1024
--------
driver: flush size 0
driver: find_diskspace: time 131.950: want 60774816 K
find diskspace: not enough diskspace. Left with 60774816 K
driver: find_diskspace: time 131.951: want 60774816 K
find diskspace: not enough diskspace. Left with 60774816 K
driver: find_diskspace: time 131.951: want 60774816 K
find diskspace: not enough diskspace. Left with 60774816 K
driver: find_diskspace: time 131.951: want 60774816 K
find diskspace: not enough diskspace. Left with 60774816 K
driver: dumping bak-05:aacd3s1d directly to tape
driver: send-cmd time 131.951 to taper: PORT-WRITE 00-00001 bak-05 fffffeff9ffef
fffff7f aacd3s1d 0 20070412122332 0 NULL 10240
driver: result time 131.952 from taper: PORT 50678
driver: send-cmd time 131.952 to dumper0: PORT-DUMP 00-00001 50678 bak-05 fffffe
ff9ffeffffff7f aacd3s1d NODEVICE 0 1970:1:1:0:0:0 DUMP X X X |;auth=BSD;index;
driver: state time 131.952 free kps: 976 space: 0 taper: writing idle-dumpers: 3
 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 131.952 if default: free 4294966872 if local: free
1000 if le0: free 400
driver: hdisk-state time 131.952
send request:
----
SERVICE sendbackup
OPTIONS features=fffffeff9ffeffffff7f;hostname=bak-05;config=bak15;
DUMP aacd3s1d  0 1970:1:1:0:0:0 OPTIONS |;auth=BSD;index;

----

got response:
----
CONNECT DATA 55997 MESG 49545 INDEX 65321
OPTIONS features=fffffeff9ffeffffff7f;

----

taper: writing end marker. [VOL153 ERR kb 0 fm 1]
dumper: kill index command
driver: result time 201.262 from dumper0: FAILED 00-00001 "[data write: Broken p
ipe]"
driver: result time 201.262 from taper: TAPE-ERROR 00-00001 "[writing file: Inpu
t/output error]"
dump of driver schedule before start degraded mode:
--------
--------
dump of driver schedule after start degraded mode:
--------
--------
driver: state time 201.263 free kps: 2000 space: 0 taper: DOWN idle-dumpers: 4 q
len tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 201.263 if default: free 600 if local: free 1000 if
 le0: free 400
driver: hdisk-state time 201.263
driver: QUITTING time 201.264 telling children to quit
driver: send-cmd time 201.264 to dumper0: QUIT
driver: send-cmd time 201.264 to dumper1: QUIT
driver: send-cmd time 201.264 to dumper2: QUIT
driver: send-cmd time 201.264 to dumper3: QUIT
driver: send-cmd time 201.264 to taper: QUIT
taper: DONE [idle wait: 131.723 secs]
driver: FINISHED time 202.265
amdump: end at Thu Apr 12 12:26:55 PDT 2007
0
0
0
0
0
=================================================================================

/var/log/messages:
Apr 12 12:26:54 bak-05 kernel: (sa0:ahc2:0:5:0): Unexpected busfree in Data-out phase
Apr 12 12:26:54 bak-05 kernel: SEQADDR == 0x85
Apr 12 12:26:54 bak-05 kernel: (sa0:ahc2:0:5:0): WRITE FILEMARKS. CDB: 10 0 0 0 1 0
Apr 12 12:26:54 bak-05 kernel: (sa0:ahc2:0:5:0): CAM Status: SCSI Status Error
Apr 12 12:26:54 bak-05 kernel: (sa0:ahc2:0:5:0): SCSI Status: Check Condition
Apr 12 12:26:54 bak-05 kernel: (sa0:ahc2:0:5:0): Deferred Error: ABORTED COMMAND csi:0,0,0,2 asc:44,82
Apr 12 12:26:54 bak-05 kernel: (sa0:ahc2:0:5:0): Vendor Specific ASCQ
Apr 12 12:26:54 bak-05 kernel: (sa0:ahc2:0:5:0): Retries Exhausted
Apr 12 12:26:54 bak-05 kernel: (sa0:ahc2:0:5:0): tape is now frozen- use an OFFLINE, REWIND or MTEOM command to clear this state.
Apr 12 12:26:54 bak-05 sendbackup[7545]: index tee cannot write [Broken pipe]
================================================================================

amandad.200704122332.debug:
amandad: debug 1 pid 7528 ruid 99 euid 99: start at Thu Apr 12 12:23:32 2007
security_getdriver(name=BSD) returns 0x280ad020
amandad: version 2.5.1p3
amandad: build: VERSION="Amanda-2.5.1p3"
amandad:        BUILT_DATE="Thu Apr 12 12:13:07 PDT 2007"
amandad: BUILT_MACH="FreeBSD bak-05.adhost.com 6.2-RELEASE FreeBSD 6.2-RE LEASE #0: Mon Feb 26 18:18:04 PST 2007 stockton AT bak05.adhost DOT com:/usr/src/sys/i3
86/compile/BAK-05 i386"
amandad:        CC="gcc"
amandad: CONFIGURE_COMMAND="'./configure' '--with-user=amanda' '--with-gr
oup=operator'"
amandad: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin"
amandad:        libexecdir="/usr/local/libexec" mandir="/usr/local/man"
amandad:        AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
amandad:        CONFIG_DIR="/usr/local/etc/amanda" DEV_PREFIX="/dev/"
amandad:        RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
amandad:        RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF
amandad:        XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF
amandad:        SAMBA_CLIENT=UNDEF GNUTAR=UNDEF
amandad:        COMPRESS_PATH="/usr/bin/gzip"
amandad:        UNCOMPRESS_PATH="/usr/bin/gzip" LPRCMD="/usr/bin/lpr"
amandad:        MAILER="/usr/bin/Mail"
amandad:        listed_incr_dir="/usr/local/var/amanda/gnutar-lists"
amandad: defs:  DEFAULT_SERVER="bak-05.adhost.com"
amandad:        DEFAULT_CONFIG="DailySet1"
amandad:        DEFAULT_TAPE_SERVER="bak-05.adhost.com" HAVE_MMAP
amandad:        HAVE_SYSVSHM LOCKING=POSIX_FCNTL DEBUG_CODE
amandad:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY RSH_SECURITY USE_AMANDAHOSTS
amandad:        CLIENT_LOGIN="amanda" FORCE_USERID HAVE_GZIP
amandad:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
amandad:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
amandad: time 0.000: dgram_recv(dgram=0x280ae004, timeout=0, fromaddr=0x280bdff0
)
amandad: time 0.000: (sockaddr_in *)0x280bdff0 = { 2, 661, 10.211.1.105 }
security_handleinit(handle=0x8056080, driver=0x280ad020 (BSD))
amandad: time 0.002: accept recv REQ pkt:
<<<<<
SERVICE noop
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: time 0.002: creating new service: noop
OPTIONS features=fffffeff9ffeffffff7f;

amandad: time 0.004: sending ACK pkt:
<<<<<
>>>>>
amandad: dgram_send_addr(addr=0xbfbfe8b0, dgram=0x280ae004)
amandad: time 0.004: (sockaddr_in *)0xbfbfe8b0 = { 2, 661, 10.211.1.105 }
amandad: dgram_send_addr: 0x280ae004->socket = 0
amandad: time 0.014: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: dgram_send_addr(addr=0xbfbfe940, dgram=0x280ae004)
amandad: time 0.014: (sockaddr_in *)0xbfbfe940 = { 2, 661, 10.211.1.105 }
amandad: dgram_send_addr: 0x280ae004->socket = 0
amandad: time 0.015: dgram_recv(dgram=0x280ae004, timeout=0, fromaddr=0x280bdff0
)
amandad: time 0.015: (sockaddr_in *)0x280bdff0 = { 2, 661, 10.211.1.105 }
amandad: time 0.016: received ACK pkt:
<<<<<
>>>>>
security_close(handle=0x8056080, driver=0x280ad020 (BSD))
amandad: time 0.019: dgram_recv(dgram=0x280ae004, timeout=0, fromaddr=0x280bdff0
)
amandad: time 0.019: (sockaddr_in *)0x280bdff0 = { 2, 661, 10.211.1.105 }
security_handleinit(handle=0x8056080, driver=0x280ad020 (BSD))
amandad: time 0.021: accept recv REQ pkt:
<<<<<
SERVICE sendsize
OPTIONS features=fffffeff9ffeffffff7f;maxdumps=1;hostname=bak-05;config=bak15;
DUMP aacd3s1d  0 1970:1:1:0:0:0 -1  OPTIONS |;auth=BSD;index;
DUMP aacd3s1d  1 1970:1:1:0:0:0 -1  OPTIONS |;auth=BSD;index;
>>>>>
amandad: time 0.021: creating new service: sendsize
OPTIONS features=fffffeff9ffeffffff7f;maxdumps=1;hostname=bak-05;config=bak15;
DUMP aacd3s1d  0 1970:1:1:0:0:0 -1  OPTIONS |;auth=BSD;index;
DUMP aacd3s1d  1 1970:1:1:0:0:0 -1  OPTIONS |;auth=BSD;index;

amandad: time 0.025: sending ACK pkt:
<<<<<
>>>>>
amandad: dgram_send_addr(addr=0xbfbfe8b0, dgram=0x280ae004)
amandad: time 0.025: (sockaddr_in *)0xbfbfe8b0 = { 2, 661, 10.211.1.105 }
amandad: dgram_send_addr: 0x280ae004->socket = 0
amandad: time 0.036: sending PREP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: dgram_send_addr(addr=0xbfbfe930, dgram=0x280ae004)
amandad: time 0.036: (sockaddr_in *)0xbfbfe930 = { 2, 661, 10.211.1.105 }
amandad: dgram_send_addr: 0x280ae004->socket = 0
amandad: time 66.060: sending PREP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
aacd3s1d 0 SIZE 60774776
>>>>>
amandad: dgram_send_addr(addr=0xbfbfe930, dgram=0x280ae004)
amandad: time 66.060: (sockaddr_in *)0xbfbfe930 = { 2, 661, 10.211.1.105 }
amandad: dgram_send_addr: 0x280ae004->socket = 0
amandad: time 131.856: sending PREP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
aacd3s1d 0 SIZE 60774776
aacd3s1d 1 SIZE 60774776
>>>>>
amandad: dgram_send_addr(addr=0xbfbfe930, dgram=0x280ae004)
amandad: time 131.857: (sockaddr_in *)0xbfbfe930 = { 2, 661, 10.211.1.105 }
amandad: dgram_send_addr: 0x280ae004->socket = 0
amandad: time 131.858: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
aacd3s1d 0 SIZE 60774776
aacd3s1d 1 SIZE 60774776
>>>>>
amandad: dgram_send_addr(addr=0xbfbfe940, dgram=0x280ae004)
amandad: time 131.858: (sockaddr_in *)0xbfbfe940 = { 2, 661, 10.211.1.105 }
amandad: dgram_send_addr: 0x280ae004->socket = 0
amandad: time 131.858: dgram_recv(dgram=0x280ae004, timeout=0, fromaddr=0x280bdf
f0)
amandad: time 131.858: (sockaddr_in *)0x280bdff0 = { 2, 661, 10.211.1.105 }
amandad: time 131.858: received ACK pkt:
<<<<<
>>>>>
security_close(handle=0x8056080, driver=0x280ad020 (BSD))
amandad: time 131.878: dgram_recv(dgram=0x280ae004, timeout=0, fromaddr=0x280bdf
f0)
amandad: time 131.878: (sockaddr_in *)0x280bdff0 = { 2, 796, 10.211.1.105 }
security_handleinit(handle=0x8056080, driver=0x280ad020 (BSD))
amandad: time 131.879: accept recv REQ pkt:
<<<<<
SERVICE sendbackup
OPTIONS features=fffffeff9ffeffffff7f;hostname=bak-05;config=bak15;
DUMP aacd3s1d  0 1970:1:1:0:0:0 OPTIONS |;auth=BSD;index;
>>>>>
amandad: time 131.879: creating new service: sendbackup
OPTIONS features=fffffeff9ffeffffff7f;hostname=bak-05;config=bak15;
DUMP aacd3s1d  0 1970:1:1:0:0:0 OPTIONS |;auth=BSD;index;

amandad: time 131.881: sending ACK pkt:
<<<<<
>>>>>
amandad: dgram_send_addr(addr=0xbfbfe8b0, dgram=0x280ae004)
amandad: time 131.881: (sockaddr_in *)0xbfbfe8b0 = { 2, 796, 10.211.1.105 }
amandad: dgram_send_addr: 0x280ae004->socket = 0
security_streaminit(stream=0x8070000, driver=0x280ad020 (BSD))
amandad: try_socksize: send buffer size is 65536
amandad: try_socksize: receive buffer size is 65536
amandad: time 131.895: stream_server: waiting for connection: 0.0.0.0.55997
security_streaminit(stream=0x8079000, driver=0x280ad020 (BSD))
amandad: try_socksize: send buffer size is 65536
amandad: try_socksize: receive buffer size is 65536
amandad: time 131.895: stream_server: waiting for connection: 0.0.0.0.49545
security_streaminit(stream=0x8082000, driver=0x280ad020 (BSD))
amandad: try_socksize: send buffer size is 65536
amandad: try_socksize: receive buffer size is 65536
amandad: time 131.895: stream_server: waiting for connection: 0.0.0.0.65321
amandad: time 131.895: sending REP pkt:
<<<<<
CONNECT DATA 55997 MESG 49545 INDEX 65321
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: dgram_send_addr(addr=0xbfbfe940, dgram=0x280ae004)
amandad: time 131.895: (sockaddr_in *)0xbfbfe940 = { 2, 796, 10.211.1.105 }
amandad: dgram_send_addr: 0x280ae004->socket = 0
amandad: time 131.896: dgram_recv(dgram=0x280ae004, timeout=0, fromaddr=0x280bdf
f0)
amandad: time 131.896: (sockaddr_in *)0x280bdff0 = { 2, 796, 10.211.1.105 }
amandad: time 131.896: received ACK pkt:
<<<<<
>>>>>
amandad: time 131.904: stream_accept: connection from 10.211.1.105.1026
amandad: try_socksize: send buffer size is 65536
amandad: try_socksize: receive buffer size is 65536
amandad: time 131.907: stream_accept: connection from 10.211.1.105.1026
amandad: try_socksize: send buffer size is 65536
amandad: try_socksize: receive buffer size is 65536
amandad: time 131.913: stream_accept: connection from 10.211.1.105.1026
amandad: try_socksize: send buffer size is 65536
amandad: try_socksize: receive buffer size is 65536
security_close(handle=0x8056080, driver=0x280ad020 (BSD))
security_stream_seterr(0x8070000, write error on stream 55997: Broken pipe)
amandad: time 201.173: sending NAK pkt:
<<<<<
ERROR write error on stream 55997: write error on stream 55997: Broken pipe
>>>>>
security_stream_close(0x8070000)
security_stream_close(0x8079000)
security_stream_close(0x8082000)
amandad: time 209.187: pid 7528 finish time Thu Apr 12 12:27:02 2007
============================================================================

killpgrp.20070412122438.debug:
killpgrp: debug 1 pid 7541 ruid 99 euid 0: start at Thu Apr 12 12:24:38 2007
killpgrp: version 2.5.1p3
config: bak15
killpgrp: debug 1 pid 7541 ruid 99 euid 0: rename at Thu Apr 12 12:24:39 2007
sending SIGTERM to process group 7541
child process exited with status 0
============================================================================

sendbackup.20070412122544.debug:
sendbackup: debug 1 pid 7543 ruid 99 euid 99: start at Thu Apr 12 12:25:44 2007
sendbackup: version 2.5.1p3
Could not open conf file "/usr/local/etc/amanda/amanda-client.conf": No such fil
e or directory
Reading conf file "/usr/local/etc/amanda/bak15/amanda-client.conf".
sendbackup: debug 1 pid 7543 ruid 99 euid 99: rename at Thu Apr 12 12:25:44 2007
  sendbackup req: <DUMP aacd3s1d  0 1970:1:1:0:0:0 OPTIONS |;auth=BSD;index;>
  parsed request as: program `DUMP'
                     disk `aacd3s1d'
                     device `aacd3s1d'
                     level 0
                     since 1970:1:1:0:0:0
                     options `|;auth=BSD;index;'
sendbackup: start: bak-05:aacd3s1d lev 0
sendbackup: time 0.004: dumping device '/dev/aacd3s1d' with 'ufs'
sendbackup: time 0.005: spawning /sbin/dump in pipeline
sendbackup: argument list: dump 0usf 1048576 - /dev/aacd3s1d
sendbackup: time 0.006: started backup
sendbackup: time 0.011: 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 0.013: 87: normal(|): DUMP: WARNING: should use -L when dum
ping live read-write filesystems!
sendbackup: time 0.020: 87: normal(|): DUMP: Date of this level 0 dump: Thu
Apr 12 12:25:44 2007
sendbackup: time 0.026: 87: normal(|): DUMP: Date of last level 0 dump: the
epoch
sendbackup: time 0.031: 87: normal(|): DUMP: Dumping /dev/aacd3s1d (/bak15)
to standard output
sendbackup: time 0.200: 87: normal(|): DUMP: mapping (Pass I) [regular files
]
sendbackup: time 64.843: 87: normal(|): DUMP: mapping (Pass II) [directories
]
sendbackup: time 64.845: 87: normal(|): DUMP: estimated 60774776 tape blocks

<Prev in Thread] Current Thread [Next in Thread>