Amanda-Users

Amanda runs "out of tape"

2005-02-10 06:38:42
Subject: Amanda runs "out of tape"
From: Niclas Ember <niclas AT sidfotens DOT se>
To: amanda-users AT amanda DOT org
Date: Thu, 10 Feb 2005 12:23:47 +0100
Hi

I'm trying to run amanda on a Fedora Core 3 server. I use the rpm:s
supplied with the distribution.
I use a HP Surestore WS80 (I think, can't read it now because the tape
is stuck :( ), with a HP DLT IV Data Cartridge. This tape should be
capable of storing 40 GB uncompressed data.

I try to backup a directory (/Exports), on the same server as the tape-
drive, containing about 12,5 GB of data.

What happens is that amanda tries to use the whole tape and then
"complains" about being "out of tape". In bad cases, like now, the taper
even pulls the tape out of the cartridge and get stuck.

Could this be a bad configuration, by me, or some hardware-problem with
the tape-drive?

I supply parts of my conf-files and log files.

I would appreciate all help to solve this, because amanda seems to be a
nice back-up program and I really would like to get it working
properly.  

----------------------------------------------------------------------------

amanda.conf

dumpuser "amanda"       # the user to run dumps under

inparallel 4            # maximum dumpers that will run in parallel
netusage  10000 Kbps    # maximum net bandwidth for Amanda, in KB per sec

dumpcycle 4 days        # the number of days in the normal dump cycle
runspercycle 4 days     # the number of amdump runs in dumpcycle days
tapecycle 5 tapes       # the number of tapes in rotation
                        

bumpsize 20 Mb          # minimum savings (threshold) to bump level 1 -> 2
bumpdays 1              # minimum days at each level
bumpmult 4              # threshold = bumpsize * bumpmult^(level-1)

etimeout 300            # number of seconds per filesystem for estimates.
#etimeout -600          # total number of seconds for estimates.
# a positive number will be multiplied by the number of filesystems on
# each host; a negative number will be taken as an absolute total time-
out.
# The default is 5 minutes per filesystem.
                        

# Specify tape device and/or tape changer.

#runtapes 1             # number of tapes to be used in a single run of amdump
#tpchanger "chg-manual" # the tape-changer glue script
tapedev "/dev/nst0"     # the no-rewind tape device to be used
#rawtapedev "/dev/null" # the raw device to be used (ftape only)
#changerfile "/var/lib/amanda/DailySet1/changer"
#changerfile "/var/lib/amanda/DailySet1/changer-status"
#changerfile "/etc/amanda/DailySet1/changer.conf"
#changerdev "/dev/null"

tapetype DLT            # what kind of tape it is (see tapetypes below)
labelstr "^Band[0-9][0-9]*$"    # label constraint regex: all tapes must
match

# Specify holding disks.  

holdingdisk hd1 {
    comment "main holding disk"
    directory "/amanda" # where the holding disk is
    use 2500 Mb         # how much space can we use on it
                        # a negative value mean:
                        #        use all space except that value
    chunksize 2 Gb      # size of chunk if you want big dump to be
                        # dumped on multiple files on holding disks
                        #  N Kb/Mb/Gb split disks in chunks of size N
                        #  0          split disks in INT_MAX/1024 Kb chunks
                        # -N Kb/Mb/Gb dont split, dump larger
                        #             filesystems directly to tape
                        #             (example: -2 Gb)
    }
#holdingdisk hd2 {
#    directory "/dumps2/amanda"
#    use 1000 Mb
#    }
#holdingdisk hd3 {
#    directory "/mnt/disk4"
#    use 1000 Mb
#    }



# Amanda needs a few Mb of diskspace for the log and debug files,
# as well as a database.  

infofile "/var/lib/amanda/DailySet1/curinfo"    # database filename
logdir   "/var/lib/amanda/DailySet1"            # log directory
indexdir "/var/lib/amanda/DailySet1/index"      # index directory
#tapelist "/var/lib/amanda/DailySet1/tapelist"  # list of used tapes

# tapetypes


define tapetype DLT {
    comment "DLT tape drives"
    length 38095 mbytes         # 40 Gig tapes
    filemark 32   kbytes        # 
    speed 5608 kbytes           # 6   Mb/s
}


# dumptypes

define dumptype server-comp {
    global
    program "GNUTAR"
    comment "very important partitions on fast machines"
    comprate 0.50, 0.50
    compress server best
    priority high
}

---------------------------------------------------------------------------

disklist

#localhost /Exports directory
SVEFILE01.TANNE.LOCAL /Exports server-comp

----------------------------------------------------------------------------

log.20050209.0

DISK planner SVEFILE01.TANNE.LOCAL /Exports
START planner date 20050209
WARNING planner Last full dump of SVEFILE01.TANNE.LOCAL:/Exports on tape
overwritten in 1 run.
START driver date 20050209
FINISH planner date 20050209
STATS driver startup time 3.440
START taper datestamp 20050209 label Band03 tape 0
INFO taper tape Band03 kb 5523456 fm 1 writing file: Input/output error
FAIL taper SVEFILE01.TANNE.LOCAL /Exports 20050209 0 [out of tape]
ERROR taper no-tape [[writing file: Input/output error]]
FAIL dumper SVEFILE01.TANNE.LOCAL /Exports 20050209 0 ["data write:
Connection reset by peer"]
  sendbackup: start [SVEFILE01.TANNE.LOCAL:/Exports level 0]
  sendbackup: info BACKUP=/bin/tar
  sendbackup: info RECOVER_CMD=/bin/tar -f... -
  sendbackup: info end
FAIL driver SVEFILE01.TANNE.LOCAL /Exports 20050209 0 [dump to tape
failed]
FINISH driver date 20050209 time 2583.944

----------------------------------------------------------------------------

amdump.1

amdump: start at ons feb  9 01:14:45 CET 2005
amdump: datestamp 20050209
planner: pid 16654 executable /usr/lib/amanda/planner version 2.4.4p3
planner: build: VERSION="Amanda-2.4.4p3"
planner:        BUILT_DATE="Mon Jun 28 15:53:32 EDT 2004"
planner:        BUILT_MACH="Linux daffy.perf.redhat.com
2.4.21-15.5.ELsmp #1 SMP Sat May 15 17:30:24 EDT 2004 i686 i686 i386
GNU/Linux"
planner:        CC="i386-redhat-linux-gcc"
planner:        CONFIGURE_COMMAND="'./configure' '--build=i386-redhat-
linux' '--host=i386-redhat-linux' '--target=i386-redhat-linux-gnu' '--
program-prefix=' '--prefix=/usr' '--exec-prefix=/usr' '--
bindir=/usr/bin' '--sbindir=/usr/sbin' '--sysconfdir=/etc' '--
datadir=/usr/share' '--includedir=/usr/include' '--libdir=/usr/lib' '--
libexecdir=/usr/lib/amanda' '--localstatedir=/var/lib' '--
sharedstatedir=/usr/com' '--mandir=/usr/share/man' '--
infodir=/usr/share/info' '--enable-shared' '--with-index-
server=localhost' '--with-gnutar-listdir=/var/lib/amanda/gnutar-lists'
'--with-smbclient=/usr/bin/smbclient' '--with-amandahosts' '--with-
user=amanda' '--with-group=disk' '--with-tmpdir=/var/log/amanda' '--
with-gnutar=/bin/tar'"
planner: paths: bindir="/usr/bin" sbindir="/usr/sbin"
planner:        libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
planner:        AMANDA_TMPDIR="/var/log/amanda"
planner:        AMANDA_DBGDIR="/var/log/amanda" CONFIG_DIR="/etc/amanda"
planner:        DEV_PREFIX="/dev/" RDEV_PREFIX="/dev/r"
planner:        DUMP="/sbin/dump" RESTORE="/sbin/restore" VDUMP=UNDEF
planner:        VRESTORE=UNDEF XFSDUMP=UNDEF XFSRESTORE=UNDEF
VXDUMP=UNDEF
planner:        VXRESTORE=UNDEF SAMBA_CLIENT="/usr/bin/smbclient"
planner:        GNUTAR="/bin/tar" COMPRESS_PATH="/usr/bin/gzip"
planner:        UNCOMPRESS_PATH="/usr/bin/gzip" LPRCMD="/usr/bin/lpr"
planner:        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="amanda" 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.771
READING CONF FILES...
planner: time 0.004: startup took 0.004 secs

SETTING UP FOR ESTIMATES...
planner: time 0.004: setting up estimates for
SVEFILE01.TANNE.LOCAL:/Exports
SVEFILE01.TANNE.LOCAL:/Exports overdue 12819 days for level 0
setup_estimate: SVEFILE01.TANNE.LOCAL:/Exports: command 0, options:
    last_level 0 next_level0 -12819 level_days 0
    getting estimates 0 (-1) 1 (0) -1 (-1)
planner: time 0.011: setting up estimates took 0.006 secs

GETTING ESTIMATES...
driver: pid 16655 executable /usr/lib/amanda/driver version 2.4.4p3
driver: tape size 39009280
driver: send-cmd time 0.032 to taper: START-TAPER 20050209
driver: started dumper0 pid 16661
driver: started dumper1 pid 16662
driver: started dumper2 pid 16663
driver: started dumper3 pid 16664
dumper: dgram_bind: socket bound to 0.0.0.0.778
dumper: pid 16661 executable dumper0 version 2.4.4p3, using port 778
dumper: dgram_bind: socket bound to 0.0.0.0.780
dumper: pid 16663 executable dumper2 version 2.4.4p3, using port 780
dumper: dgram_bind: socket bound to 0.0.0.0.779
dumper: pid 16662 executable dumper1 version 2.4.4p3, using port 779
taper: pid 16660 executable taper version 2.4.4p3
taper: page size is 4096
taper: buffer size is 32768
taper: buffer[00] at 0xb7f4c000
taper: buffer[01] at 0xb7f54000
taper: buffer[02] at 0xb7f5c000
taper: buffer[03] at 0xb7f64000
taper: buffer[04] at 0xb7f6c000
taper: buffer[05] at 0xb7f74000
taper: buffer[06] at 0xb7f7c000
taper: buffer[07] at 0xb7f84000
taper: buffer[08] at 0xb7f8c000
taper: buffer[09] at 0xb7f94000
taper: buffer[10] at 0xb7f9c000
taper: buffer[11] at 0xb7fa4000
taper: buffer[12] at 0xb7fac000
taper: buffer[13] at 0xb7fb4000
taper: buffer[14] at 0xb7fbc000
taper: buffer[15] at 0xb7fc4000
taper: buffer[16] at 0xb7fcc000
taper: buffer[17] at 0xb7fd4000
taper: buffer[18] at 0xb7fdc000
taper: buffer[19] at 0xb7fe4000
taper: buffer structures at 0xb7fec000 for 240 bytes
dumper: dgram_bind: socket bound to 0.0.0.0.781
dumper: pid 16664 executable dumper3 version 2.4.4p3, using port 781
planner: time 3.445: got result for host SVEFILE01.TANNE.LOCAL
disk /Exports: 0 -> 13088290K, 1 -> 13088290K, -1 -> -1K
planner: time 3.445: getting estimates took 3.434 secs
FAILED QUEUE: empty
DONE QUEUE:
  0: SVEFILE01.TANNE.LOCAL /Exports

ANALYZING ESTIMATES...
pondering SVEFILE01.TANNE.LOCAL:/Exports... next_level0 -12819
last_level 0 (due for level 0) (picking inclevel for degraded mode)
picklev: last night 0, so tonight level 1

  curr level 0 size 6544145 total size 6544337 total_lev0 6544145
balanced-lev0size 1636036
INITIAL SCHEDULE (size 6544337):
  SVEFILE01.TANNE.LOCAL /Exports pri 12821 lev 0 size 6544145

DELAYING DUMPS IF NEEDED, total_size 6544337, tape length 39009280 mark
32
  delay: Total size now 6544337.

PROMOTING DUMPS IF NEEDED, total_lev0 6544145, balanced_size 1636036...
planner: time 3.446: analysis took 0.000 secs

GENERATING SCHEDULE:
--------
ENDFLUSH
DUMP SVEFILE01.TANNE.LOCAL fffffeff9ffe0f /Exports 20050209 12821 0
1970:1:1:0:0:0 6544145 218138 1 1970:1:1:0:0:0 6544145 218138
--------
driver: adding holding disk 0 dir /amanda size 2560000
reserving 2560000 out of 2560000 for degraded-mode dumps
driver: flush size 0
driver: start time 3.440 inparallel 4 bandwidth 30000 diskspace 2560000
dir OBSOLETE datestamp 20050209 driver: drain-ends tapeq FIRST big-
dumpers ttt
taper: read label `Band03' date `X'
taper: wrote label `Band03' date `20050209'
driver: result time 7.111 from taper: TAPER-OK 
driver: dumping SVEFILE01.TANNE.LOCAL:/Exports directly to tape
driver: send-cmd time 7.111 to taper: PORT-WRITE 00-00001
SVEFILE01.TANNE.LOCAL fffffeff9ffe0f /Exports 0 20050209
taper: try_socksize: receive buffer size is 65536
taper: stream_server: waiting for connection: 0.0.0.0.32781
driver: result time 7.111 from taper: PORT 32781
driver: send-cmd time 7.111 to dumper0: PORT-DUMP 01-00002 32781
SVEFILE01.TANNE.LOCAL fffffeff9ffe0f /Exports NODEVICE 0 1970:1:1:0:0:0
GNUTAR |;bsd-auth;srvcomp-best;
driver: state time 7.111 free kps: 29970 space: 2560000 taper: writing
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-
idle: not-idle
driver: interface-state time 7.111 if : free 9970 if ETH0: free 10000 if
LOCAL: free 10000
driver: hdisk-state time 7.111 hdisk 0: free 2560000 dumpers 0
dumper: stream_client: connected to 127.0.0.1.32781
dumper: stream_client: our side is 0.0.0.0.32782
dumper: try_socksize: send buffer size is 65536
taper: stream_accept: connection from 127.0.0.1.32782
taper: try_socksize: receive buffer size is 32768
dumper: stream_client: connected to 127.0.0.1.32783
dumper: stream_client: our side is 0.0.0.0.32785
dumper: stream_client: connected to 127.0.0.1.32784
dumper: stream_client: our side is 0.0.0.0.32786
taper: writing end marker. [Band03 ERR kb 5523456 fm 1]
dumper: kill compress command
driver: result time 2583.659 from dumper0: FAILED 01-00002 ["data write:
Connection reset by peer"]
driver: result time 2583.659 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 2583.823 free kps: 30000 space: 2560000 taper: DOWN
idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-
idle: not-idle
driver: interface-state time 2583.823 if : free 10000 if ETH0: free
10000 if LOCAL: free 10000
driver: hdisk-state time 2583.823 hdisk 0: free 2560000 dumpers 0
driver: QUITTING time 2583.823 telling children to quit
driver: send-cmd time 2583.823 to dumper0: QUIT
driver: send-cmd time 2583.823 to dumper1: QUIT
driver: send-cmd time 2583.824 to dumper2: QUIT
driver: send-cmd time 2583.825 to dumper3: QUIT
driver: send-cmd time 2583.826 to taper: QUIT
taper: DONE [idle wait: 0.167 secs]
driver: FINISHED time 2583.943
amdump: end at ons feb  9 01:57:49 CET 2005


Thanks in advance

Niclas


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