Amanda-Users

amanda keeps asking for same tape

2009-06-02 17:00:50
Subject: amanda keeps asking for same tape
From: Joe Konecny <jkonecny AT rmtohio DOT com>
To: amanda-users AT amanda DOT org
Date: Tue, 02 Jun 2009 16:50:25 -0400
Amanda was working fine and now is always asking for the same tape.
I cannot figure out what is wrong.  Any tips?


** 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


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