Amanda-Users

FAIL taper ( help me )

2003-09-29 13:54:17
Subject: FAIL taper ( help me )
From: "Marcelo Block Teixeira" <cpd AT cadservice.com DOT br>
To: <amanda-users AT amanda DOT org>
Date: Mon, 29 Sep 2003 14:39:35 -0300
Hello,
    The time of backup was very fast and the log show FAIL TAPER.
    I'd like know what is happen?
    My driver went to be fixed a one week ago and I think that it have problems yet but I want be sure.
    I have 500mb of swap disk and I think it was very slow. The amdump is using all the space of swap disk. Do you think I need more space of swap disk?
 
 
log.20030929:
DISK planner server.cadservice.com.br /home/ntcad/software
START planner date 20030929
INFO planner Adding new disk server.cadservice.com.br:/home/ntcad/software.
START driver date 20030929
FINISH planner date 20030929
STATS driver startup time 2.244
START taper datestamp 20030929 label backup01 tape 0
INFO taper tape backup01 kb 168064 fm 1 writing file: Input/output error
FAIL taper server.cadservice.com.br /home/ntcad/software 20030929 0 [out of tape]
ERROR taper no-tape [[writing file: Input/output error]]
WARNING driver dumper0 pid 2927 is messed up, ignoring it.
FAIL driver server.cadservice.com.br /home/ntcad/software 20030929 0 [dump to tape failed]
WARNING driver dumper0 exited with signal 9
FINISH driver date 20030929 time 8812.188
 
 
Amdump:
amdump: start at Mon Sep 29 09:40:18 BRT 2003
amdump: datestamp 20030929
planner: pid 2923 executable /usr/lib/amanda/planner version 2.4.3
planner: build: VERSION="Amanda-2.4.3"
planner: BUILT_DATE="Thu Feb 13 11:14:37 EST 2003"
planner: BUILT_MACH="Linux stripples.devel.redhat.com 2.4.20-2.41smp #1 SMP Sun Feb 9 09:47:42 EST 2003 i686 i686 i386 GNU/Linux"
planner: CC="gcc"
planner: CONFIGURE_COMMAND="'./configure' '--host=i386-redhat-linux' '--build=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-gnutar=/bin/tar'"
planner: paths: bindir="/usr/bin" sbindir="/usr/sbin"
planner: libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
planner: AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
planner: CONFIG_DIR="/etc/amanda" DEV_PREFIX="/dev/"
planner: RDEV_PREFIX="/dev/r" DUMP="/sbin/dump"
planner: RESTORE="/sbin/restore" SAMBA_CLIENT="/usr/bin/smbclient"
planner: GNUTAR="/bin/tar" COMPRESS_PATH="/usr/bin/gzip"
planner: UNCOMPRESS_PATH="/usr/bin/gzip" 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.001: dgram_bind: socket bound to 0.0.0.0.925
READING CONF FILES...
planner: time 0.013: startup took 0.013 secs
SETTING UP FOR ESTIMATES...
planner: time 0.013: setting up estimates for server.cadservice.com.br:/home/ntcad/software
server.cadservice.com.br:/home/ntcad/software overdue 12325 days for level 0
setup_estimate: server.cadservice.com.br:/home/ntcad/software: command 0, options:
last_level -1 next_level0 -12325 level_days 0
getting estimates 0 (0) -1 (-1) -1 (-1)
planner: time 0.013: setting up estimates took 0.000 secs
GETTING ESTIMATES...
driver: pid 2924 executable /usr/lib/amanda/driver version 2.4.3
driver: send-cmd time 0.016 to taper: START-TAPER 20030929
driver: started dumper0 pid 2927
driver: started dumper1 pid 2928
driver: started dumper2 pid 2929
driver: started dumper3 pid 2933
dumper: dgram_bind: socket bound to 0.0.0.0.935
dumper: pid 2933 executable dumper3 version 2.4.3, using port 935
dumper: dgram_bind: socket bound to 0.0.0.0.930
dumper: pid 2928 executable dumper1 version 2.4.3, using port 930
dumper: dgram_bind: socket bound to 0.0.0.0.929
dumper: pid 2927 executable dumper0 version 2.4.3, using port 929
taper: pid 2926 executable taper version 2.4.3
taper: page size is 4096
taper: buffer size is 32768
taper: buffer[00] at 0x400d9000
taper: buffer[01] at 0x400e1000
taper: buffer[02] at 0x400e9000
taper: buffer[03] at 0x400f1000
taper: buffer[04] at 0x400f9000
taper: buffer[05] at 0x40101000
taper: buffer[06] at 0x40109000
taper: buffer[07] at 0x40111000
taper: buffer[08] at 0x40119000
taper: buffer[09] at 0x40121000
taper: buffer[10] at 0x40129000
taper: buffer[11] at 0x40131000
taper: buffer[12] at 0x40139000
taper: buffer[13] at 0x40141000
taper: buffer[14] at 0x40149000
taper: buffer[15] at 0x40151000
taper: buffer[16] at 0x40159000
taper: buffer[17] at 0x40161000
taper: buffer[18] at 0x40169000
taper: buffer[19] at 0x40171000
taper: buffer structures at 0x40179000 for 240 bytes
changer: opening pipe to: /usr/lib/amanda/chg-multi -info
dumper: dgram_bind: socket bound to 0.0.0.0.931
dumper: pid 2929 executable dumper2 version 2.4.3, using port 931
changer: got exit: 0 str: 1 1 1
changer: opening pipe to: /usr/lib/amanda/chg-multi -slot current
planner: time 2.293: got result for host server.cadservice.com.br disk /home/ntcad/software: 0 -> 2584110K, -1 -> -1K, -1 -> -1K
planner: time 2.293: getting estimates took 2.279 secs
FAILED QUEUE: empty
DONE QUEUE:
0: server.cadservice.com.br /home/ntcad/software
ANALYZING ESTIMATES...
pondering server.cadservice.com.br:/home/ntcad/software... next_level0 -12325 last_level -1 (due for level 0) (new disk, can't switch to degraded mode)
curr level 0 size 1292055 total size 1298646 total_lev0 1292055 balanced-lev0size 46144
INITIAL SCHEDULE (size 1298646):
server.cadservice.com.br /home/ntcad/software pri 12325 lev 0 size 1292055
DELAYING DUMPS IF NEEDED, total_size 1298646, tape length 3232768 mark 2165
delay: Total size now 1298646.
PROMOTING DUMPS IF NEEDED, total_lev0 1292055, balanced_size 46144...
planner: time 2.294: analysis took 0.000 secs
GENERATING SCHEDULE:
--------
ENDFLUSH
DUMP server.cadservice.com.br fffffeff9f00 /home/ntcad/software 20030929 12325 0 1970:1:1:0:0:0 1292055 43068
--------
reserving 0 out of 0 for degraded-mode dumps
driver: start time 2.244 inparallel 4 bandwidth 2000 diskspace 0 dir OBSOLETE datestamp 20030929 driver: drain-ends tapeq LFFO big-dumpers ttt
changer: got exit: 0 str: 1 /dev/nst0
taper: slot 1: date X label backup01 (first labelstr match)
taper: read label `backup01' date `X'
taper: wrote label `backup01' date `20030929'
driver: result time 18.790 from taper: TAPER-OK
driver: dumping server.cadservice.com.br:/home/ntcad/software directly to tape
driver: send-cmd time 18.790 to taper: PORT-WRITE 00-00001 server.cadservice.com.br fffffeff9f00 /home/ntcad/software 0 20030929
taper: try_socksize: receive buffer size is 65536
taper: stream_server: waiting for connection: 0.0.0.0.32774
driver: result time 18.791 from taper: PORT 32774
driver: send-cmd time 18.792 to dumper0: PORT-DUMP 01-00002 32774 server.cadservice.com.br fffffeff9f00 /home/ntcad/software NODEVICE 0 1970:1:1:0:0:0 GNUTAR |;auth=bsd;compress-fast;index;
driver: state time 18.792 free kps: 1970 space: 0 taper: writing idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: not-idle
driver: interface-state time 18.792 if : free 570 if ETH0: free 400 if LOCAL: free 1000
driver: hdisk-state time 18.792
dumper: stream_client: connected to 127.0.0.1.32774
dumper: stream_client: our side is 0.0.0.0.32775
dumper: try_socksize: send buffer size is 65536
taper: stream_accept: connection from 127.0.0.1.32775
taper: try_socksize: receive buffer size is 32768
dumper: stream_client: connected to 192.168.0.102.32776
dumper: stream_client: our side is 0.0.0.0.32779
dumper: stream_client: connected to 192.168.0.102.32777
dumper: stream_client: our side is 0.0.0.0.32780
dumper: stream_client: connected to 192.168.0.102.32778
dumper: stream_client: our side is 0.0.0.0.32781
taper: writing end marker. [backup01 ERR kb 168064 fm 1]
driver: result time 8808.298 from dumper0:
driver: result time 8809.415 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 8810.725 free kps: 2000 space: 0 taper: DOWN idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: not-idle
driver: interface-state time 8810.725 if : free 600 if ETH0: free 400 if LOCAL: free 1000
driver: hdisk-state time 8810.725
driver: QUITTING time 8810.725 telling children to quit
driver: send-cmd time 8810.726 ignored to down dumper dumper0: QUIT
driver: send-cmd time 8810.726 to dumper1: QUIT
driver: send-cmd time 8810.726 to dumper2: QUIT
driver: send-cmd time 8810.726 to dumper3: QUIT
driver: send-cmd time 8810.726 to taper: QUIT
taper: DONE [idle wait: 8094.194 secs]
driver: dumper0 exited with signal 9
driver: FINISHED time 8812.188
amdump: end at Mon Sep 29 12:07:12 BRT 2003
 
 
 

Sem mais,
Atenciosamente
Marcelo Block - CPD
CADService Produtos Eletrônicos
Do more, Grow more, Be more....
ICQ: 319176888
Fone:55-19-3246-1333
<Prev in Thread] Current Thread [Next in Thread>
  • FAIL taper ( help me ), Marcelo Block Teixeira <=