I am running on:

NetBSD V2.0.2/i386
HP Surestore DAT24x6
Amanda-2.4.4p4

I have setup a daily backup config which runs well, but it all fits on one
tape.

I have setup parallel monthly backup config that forces level 0s and uses no
record and this does not seem to be working properly. This config has to go
to a 2nd tape and never does. I have tested the drive and tapes and all work
ok. All paritions dump properly until the last one. On the last parition it
tries, which compresses larger than my holding area, but smaller than a
tape, here is what I see in the logs around the time of the failure:

amdump.1
---------

(successful lines trimmed...here is where the issue starts)
driver: dumping ncti103:sd1f directly to tape
driver: send-cmd time 7660.547 to taper: PORT-WRITE 00-00035 ncti103
fffffeff9ffe0f sd1f 0 20050729
taper: try_socksize: receive buffer size is 65536
taper: stream_server: waiting for connection: 0.0.0.0.61737
driver: result time 7660.548 from taper: PORT 61737
driver: send-cmd time 7660.548 to dumper0: PORT-DUMP 01-00036 61737 ncti103
fffffeff9ffe0f sd1f NODEVICE 0 1970:1:1:0:0:0 DUMP
|;bsd-auth;compress-fast;no-record;index;
taper: stream_accept: connection from 127.0.0.1.61736
taper: try_socksize: receive buffer size is 32768
driver: state time 7660.548 free kps: 4370 space: 7840470 taper: writing
idle-dumpers: 7 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle:
not-idle
driver: interface-state time 7660.548 if : free 970 if FXP1: free 1000 if
BGE0: free 1000 if LE0: free 400 if LOCAL: free 1000
driver: hdisk-state time 7660.548 hdisk 0: free 7840470 dumpers 0
dumper: stream_client: connected to 127.0.0.1.61737
dumper: stream_client: our side is 0.0.0.0.61736
dumper: try_socksize: send buffer size is 65536
dumper: stream_client: connected to 200.1.1.103.61735
dumper: stream_client: our side is 0.0.0.0.61732
dumper: stream_client: connected to 200.1.1.103.61734
dumper: stream_client: our side is 0.0.0.0.61731
dumper: stream_client: connected to 200.1.1.103.61733
dumper: stream_client: our side is 0.0.0.0.61730
taper: writing end marker. [nctimonthly000 ERR kb 11990208 fm 18]

(Above: appears to have run out of tape on the first tape here. What does
ERR mean ?)

changer: opening pipe to: /usr/pkg/libexec/chg-scsi -info
changer: got exit: 0 str: 0 5 1 0
changer: opening pipe to: /usr/pkg/libexec/chg-scsi -slot current
changer: got exit: 0 str: 0 /dev/nrst0
taper: slot 0: date 20050729 label nctimonthly000 (active tape)
changer: opening pipe to: /usr/pkg/libexec/chg-scsi -slot next
changer: got exit: 0 str: 1 /dev/nrst0
taper: slot 1: date X        label nctimonthly001 (new tape)
taper: read label `nctimonthly001' date `X'
taper: wrote label `nctimonthly001' date `20050729'

(Above: now it has found the next tape, writes the label. Never seems to
retry after this.)

driver: result time 12648.338 from dumper0: FAILED 01-00036 ["data write:
Broken pipe"]
driver: result time 12648.338 from taper: TRY-AGAIN 00-00035 [writing file:
Input/output error]

(Above: is this indicating the 2nd tape failed, or just logging the first
failure ?)

dumper: kill index command
driver: state time 12648.383 free kps: 4400 space: 7840470 taper: idle
idle-dumpers: 8 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle:
not-idle
driver: interface-state time 12648.383 if : free 1000 if FXP1: free 1000 if
BGE0: free 1000 if LE0: free 400 if LOCAL: free 1000
driver: hdisk-state time 12648.383 hdisk 0: free 7840470 dumpers 0
driver: QUITTING time 12648.383 telling children to quit
driver: send-cmd time 12648.383 to dumper0: QUIT
driver: send-cmd time 12648.387 to dumper1: QUIT
driver: send-cmd time 12648.388 to dumper2: QUIT
driver: send-cmd time 12648.388 to dumper3: QUIT
driver: send-cmd time 12648.388 to dumper4: QUIT
driver: send-cmd time 12648.389 to dumper5: QUIT
driver: send-cmd time 12648.389 to dumper6: QUIT
driver: send-cmd time 12648.390 to dumper7: QUIT
driver: send-cmd time 12648.390 to taper: QUIT
taper: DONE [idle wait: 285.162 secs]
taper: writing end marker. [nctimonthly001 OK kb 0 fm 0]

(Above: writes the end marker here but it never wrote any data to the tape
?)

driver: FINISHED time 12659.810
amdump: end at Fri Jul 29 12:56:50 EDT 2005


My first 3 attempts to post failed, hopefully this one makes it. I have all
the logs from these runs. I have tried this 5 times with the same results. I
can post them on ftp if reqired.

Scott...

Scott R. Burns
NETCON Technologies Inc.
Suite 135 - 4474 Blakie Road
London, Ontario, Canada
N6L 1G7
Voice: +1.519.652.0401
Fax: +1.519.652.9275