Joe Konecny wrote:
> Amanda was working fine and now is always asking for the same tape.
> I cannot figure out what is wrong. Any tips?
Looks like it had a problem with whatever tape it is trying to use:
driver: result time 37.821 from taper: TAPE-ERROR <nohandle> "[writing label:
short write]"
and then falling back to degraded mode, but the dump is larger than your
holding disk (trying to do a 22GB backup onto a 16GB disk, and failing).
You might try relabeling the tape it's trying to use and see if
there are any write errors due to device permissions, write-protected
tape, or perhaps hardware issues with the tape drive. Also, did you do
any system upgrades recently? Perhaps your tape device got renumbered
or a symlink to it was changed or removed.
Frank
>
>
> ** tapelist
> 20090528 DailySet1005 reuse
> 20090522 DailySet1008 reuse
> 20090521 DailySet1004 reuse
> 20090520 DailySet1002 reuse
> 20090519 DailySet1006 reuse
> 20090518 DailySet1007 reuse
>
> ** amdump.1
> amdump: start at Mon Jun 1 23:00:00 EDT 2009
> amdump: datestamp 20090601
> amdump: starttime 20090601230000
> driver: pid 28068 executable /usr/local/libexec/driver version 2.5.1p3
> planner: pid 28067 executable /usr/local/libexec/planner version 2.5.1p3
> planner: build: VERSION="Amanda-2.5.1p3"
> planner: BUILT_DATE="Wed Mar 21 15:05:45 EDT 2007"
> planner: BUILT_MACH="FreeBSD r4p17.rmtohio.com 5.2.1-RELEASE FreeBSD
> 5.2.1-RELEASE #0: Mon Feb 23 20:45:55 GMT
> 2004 root AT wv1u.btc.adaptec DOT com:/usr/obj/usr/src/sys/GENERIC i386"
> planner: CC="gcc"
> planner: CONFIGURE_COMMAND="'./configure' '--with-user=bin'
> '--with-group=operator' '--with-config=DailySet1'"
> 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="/usr/bin/smbclient" GNUTAR="/usr/bin/tar"
> 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="r4p17.rmtohio.com"
> planner: DEFAULT_CONFIG="DailySet1"
> planner: DEFAULT_TAPE_SERVER="r4p17.rmtohio.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="bin" FORCE_USERID HAVE_GZIP
> planner: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
> planner: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
> READING CONF FILES...
> planner: timestamp 20090601
> planner: time 0.089: startup took 0.089 secs
>
> SENDING FLUSHES...
> ENDFLUSH
>
> SETTING UP FOR ESTIMATES...
> planner: time 0.089: setting up estimates for r4p17.rmtohio.com:/usr
> driver: tape size 36802560
> driver: adding holding disk 0 dir /usr/dumps/amanda size 16080392 chunksize
> 1048576
> reserving 16080392 out of 16080392 for degraded-mode dumps
> driver: send-cmd time 0.132 to taper: START-TAPER 20090601
> driver: started dumper0 pid 28070
> driver: send-cmd time 0.133 to dumper0: START 20090601
> driver: started dumper1 pid 28071
> driver: send-cmd time 0.133 to dumper1: START 20090601
> driver: started dumper2 pid 28072
> driver: send-cmd time 0.134 to dumper2: START 20090601
> driver: started dumper3 pid 28073
> driver: send-cmd time 0.134 to dumper3: START 20090601
> driver: start time 0.135 inparallel 4 bandwidth 2000 diskspace 16080392 dir
> OBSOLETE datestamp 20090601 driver:
> drain-ends tapeq FIRST big-dumpers sssS
> r4p17.rmtohio.com:/usr overdue 4 days for level 0
> setup_estimate: r4p17.rmtohio.com:/usr: command 0, options: none
> last_level 0 next_level0 -4 level_days 0 getting
> estimates 0 (-2) 1 (-2) -1 (-2)
> planner: time 0.134: setting up estimates took 0.045 secs
>
> GETTING ESTIMATES...
> taper: pid 28069 executable taper version 2.5.1p3
> taper: page size = 4096
> taper: buffer size is 32768
> dumper: pid 28073 executable dumper3 version 2.5.1p3
> dumper: pid 28072 executable dumper2 version 2.5.1p3
> dumper: pid 28070 executable dumper0 version 2.5.1p3
> dumper: pid 28071 executable dumper1 version 2.5.1p3
> planner: time 0.282: got partial result for host r4p17.rmtohio.com disk /usr:
> 0 -> -2K, 1 -> -2K, -1 -> -2K
> planner: time 13.371: got partial result for host r4p17.rmtohio.com disk
> /usr: 0 -> 41959467K, 1 -> -2K, -1 -> -2K
> driver: result time 37.821 from taper: TAPE-ERROR <nohandle> "[writing label:
> short write]"
> driver: state time 37.821 free kps: 2000 space: 16080392 taper: idle
> idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0
> wakeup: 0 driver-idle: not-idle
> driver: interface-state time 37.821 if default: free 600 if local: free 1000
> if le0: free 400
> driver: hdisk-state time 37.821 hdisk 0: free 16080392 dumpers 0
> planner: time 167.729: got partial result for host r4p17.rmtohio.com disk
> /usr: 0 -> 41959467K, 1 -> 9341565K, -1 -> -2K
> planner: time 167.731: got result for host r4p17.rmtohio.com disk /usr: 0 ->
> 41959467K, 1 -> 9341565K, -1 -> -2K
> planner: time 167.731: getting estimates took 167.596 secs
> FAILED QUEUE: empty
> DONE QUEUE:
> 0: r4p17.rmtohio.com /usr
>
> ANALYZING ESTIMATES...
> pondering r4p17.rmtohio.com:/usr... next_level0 -4 last_level 0 (due for
> level 0) (picking inclevel for degraded mode)
> picklev: last night 0, so tonight level 1
>
> curr level 0 nsize 41959467 csize 22418551 total size 22418647 total_lev0
> 22418551 balanced-lev0size 22418551
> INITIAL SCHEDULE (size 22418647):
> r4p17.rmtohio.com /usr pri 6 lev 0 nsize 41959467 csize 22418551
>
> DELAYING DUMPS IF NEEDED, total_size 22418647, tape length 36802560 mark 0
> delay: Total size now 22418647.
>
> PROMOTING DUMPS IF NEEDED, total_lev0 22418551, balanced_size 22418551...
> planner: time 167.732: analysis took 0.000 secs
>
> GENERATING SCHEDULE:
> --------
> DUMP r4p17.rmtohio.com fffffeff9ffeffffff7f /usr 20090601 6 0 1970:1:1:0:0:0
> 41959467 22418551 10292 2178 1
> 2009:5:29:3:2:40 9341565 4670782 4561 1024
> --------
> driver: flush size 0
> dump of driver schedule before start degraded mode:
> --------
> r4p17.rmtohio.com /usr lv 0 t 10292 s 22418592 p 6
> --------
> dump of driver schedule after start degraded mode:
> --------
> r4p17.rmtohio.com /usr lv 1 t 4561 s 22418592 p 6
> --------
> driver: find_diskspace: time 167.734: want 22418592 K
> driver: find_diskspace: time 167.734: find diskspace: size 22418592 hf
> 16080392 df 16079880 da 16079880 ha 16080392
> find diskspace: not enough diskspace. Left with 6338712 K
> driver: find_diskspace: time 167.734: want 22418592 K
> driver: find_diskspace: time 167.734: find diskspace: size 22418592 hf
> 16080392 df 16079880 da 16079880 ha 16080392
> find diskspace: not enough diskspace. Left with 6338712 K
> driver: find_diskspace: time 167.734: want 22418592 K
> driver: find_diskspace: time 167.734: find diskspace: size 22418592 hf
> 16080392 df 16079880 da 16079880 ha 16080392
> find diskspace: not enough diskspace. Left with 6338712 K
> driver: find_diskspace: time 167.734: want 22418592 K
> driver: find_diskspace: time 167.734: find diskspace: size 22418592 hf
> 16080392 df 16079880 da 16079880 ha 16080392
> find diskspace: not enough diskspace. Left with 6338712 K
> driver: state time 167.734 free kps: 2000 space: 16080392 taper: DOWN
> idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0
> wakeup: 0 driver-idle: no-diskspace
> driver: interface-state time 167.734 if default: free 600 if local: free 1000
> if le0: free 400
> driver: hdisk-state time 167.734 hdisk 0: free 16080392 dumpers 0
> driver: QUITTING time 167.735 telling children to quit
> driver: send-cmd time 167.735 to dumper0: QUIT
> driver: send-cmd time 167.735 to dumper1: QUIT
> driver: send-cmd time 167.736 to dumper2: QUIT
> driver: send-cmd time 167.737 to dumper3: QUIT
> driver: send-cmd time 167.737 to taper: QUIT
> taper: DONE [idle wait: 129.916 secs]
> driver: FINISHED time 168.740
> amdump: end at Mon Jun 1 23:02:49 EDT 2009
> Scanning /usr/dumps/amanda...
> 0
> 0
> 0
> 0
> 0
> 0
> 0
>
--
Frank Smith fsmith AT hoovers DOT com
Sr. Systems Administrator Voice: 512-374-4673
Hoover's Online Fax: 512-374-4501
|