Amanda-Users

UPDATE: Still getting timeout: Long entry

2003-05-20 14:32:48
Subject: UPDATE: Still getting timeout: Long entry
From: "Rebecca Pakish Crum" <rebecca AT unterlaw DOT com>
To: "Rebecca Pakish Crum" <rebecca AT unterlaw DOT com>, <gene.heskett AT verizon DOT net>, <amanda-users AT amanda DOT org>
Date: Tue, 20 May 2003 13:30:20 -0500
I increased my timeouts and moved the dump back an hour to 3:30 (in case
there were some crazy timing conflicts) and still walked in to a failed
backup today. So I created a new backup instance, with only that server
on it this morning and ran it.

It came back as strange (most likely because I was running this at 7:45
am when the company starts business at 8!), but ultimately completed the
level 0 dump of the entire disk.

What's up with that?!?!
Also - I have two amandad.*.debug files on the client from the 3:30 am
dump attempt...neither one say anything about the ack timeout...but the
report looks the same. I've attached that report, the strange report
from this morning and here are the amandad.*.debugs:
(I'm still 3 minutes off on time...so it starts at 3:27)

amandad.20030520032702.debug:
amandad: debug 1 pid 8389 ruid 1003 euid 1003 start time Tue May 20
03:27:02 2003
amandad: version 2.4.2p2
amandad: build: VERSION="Amanda-2.4.2p2"
amandad:        BUILT_DATE="Tue Dec 3 10:50:50 CST 2002"
amandad:        BUILT_MACH="SunOS www 5.6 Generic_105181-21 sun4u sparc
SUNW,Ultra-60"
amandad:        CC="gcc"
amandad: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin"
amandad:        libexecdir="/usr/local/libexec" mandir="/usr/local/man"
amandad:        AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
amandad:        CONFIG_DIR="/usr/local/etc/amanda"
DEV_PREFIX="/dev/dsk/"
amandad:        RDEV_PREFIX="/dev/rdsk/" DUMP="/usr/sbin/ufsdump"
amandad:        RESTORE="/usr/sbin/ufsrestore"
GNUTAR="/usr/local/bin/tar"
amandad:        COMPRESS_PATH="/opt/OSE/bin/gzip"
amandad:        UNCOMPRESS_PATH="/opt/OSE/bin/gzip" MAILER="/bin/mailx"
amandad:        listed_incr_dir="/usr/local/var/amanda/gnutar-lists"
amandad: defs:  DEFAULT_SERVER="web.isymmetrics.com"
amandad:        DEFAULT_CONFIG="DailySet1"
amandad:        DEFAULT_TAPE_SERVER="web.isymmetrics.com"
amandad:        DEFAULT_TAPE_DEVICE="/dev/rmt/0bn" HAVE_MMAP
HAVE_SYSVSHM
amandad:        LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
amandad:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY USE_AMANDAHOSTS
amandad:        CLIENT_LOGIN="amanda" FORCE_USERID HAVE_GZIP
amandad:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
amandad:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
got packet:
--------
Amanda 2.4 REQ HANDLE 002-F0AB0708 SEQ 1053423002
SECURITY USER amanda
SERVICE sendsize
OPTIONS maxdumps=1;hostname=www.iclear.com;
GNUTAR / 0 1970:1:1:0:0:0 -1
exclude-list=/usr/local/lib/amanda/exclude.gtar
GNUTAR / 1 2003:4:26:9:44:22 -1
exclude-list=/usr/local/lib/amanda/exclude.gtar
GNUTAR / 2 2003:5:7:8:40:15 -1
exclude-list=/usr/local/lib/amanda/exclude.gtar
--------
sending ack:
----
Amanda 2.4 ACK HANDLE 002-F0AB0708 SEQ 1053423002
----

bsd security: remote host web.isymmetrics.com user amanda local user
amanda
amandahosts security check passed
amandad: running service "/usr/local/libexec/sendsize"
amandad: sending REP packet:
----
Amanda 2.4 REP HANDLE 002-F0AB0708 SEQ 1053423002
OPTIONS maxdumps=1;
/ 0 SIZE 5456080
/ 1 SIZE 610140
/ 2 SIZE 399710
----

amandad: got packet:
----
Amanda 2.4 ACK HANDLE 002-F0AB0708 SEQ 1053423002
----

amandad: pid 8389 finish time Tue May 20 03:27:22 2003

AND 
amandad.20030520043455.debug:
amandad: debug 1 pid 8408 ruid 1003 euid 1003 start time Tue May 20
04:34:55 2003
amandad: version 2.4.2p2
amandad: build: VERSION="Amanda-2.4.2p2"
amandad:        BUILT_DATE="Tue Dec 3 10:50:50 CST 2002"
amandad:        BUILT_MACH="SunOS www 5.6 Generic_105181-21 sun4u sparc
SUNW,Ultra-60"
amandad:        CC="gcc"
amandad: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin"
amandad:        libexecdir="/usr/local/libexec" mandir="/usr/local/man"
amandad:        AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
amandad:        CONFIG_DIR="/usr/local/etc/amanda"
DEV_PREFIX="/dev/dsk/"
amandad:        RDEV_PREFIX="/dev/rdsk/" DUMP="/usr/sbin/ufsdump"
amandad:        RESTORE="/usr/sbin/ufsrestore"
GNUTAR="/usr/local/bin/tar"
amandad:        COMPRESS_PATH="/opt/OSE/bin/gzip"
amandad:        UNCOMPRESS_PATH="/opt/OSE/bin/gzip" MAILER="/bin/mailx"
amandad:        listed_incr_dir="/usr/local/var/amanda/gnutar-lists"
amandad: defs:  DEFAULT_SERVER="web.isymmetrics.com"
amandad:        DEFAULT_CONFIG="DailySet1"
amandad:        DEFAULT_TAPE_SERVER="web.isymmetrics.com"
amandad:        DEFAULT_TAPE_DEVICE="/dev/rmt/0bn" HAVE_MMAP
HAVE_SYSVSHM
amandad:        LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
amandad:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY USE_AMANDAHOSTS
amandad:        CLIENT_LOGIN="amanda" FORCE_USERID HAVE_GZIP
amandad:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
amandad:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
got packet:
--------
Amanda 2.4 REQ HANDLE 000-F8BB0708 SEQ 1053423007
SECURITY USER amanda
SERVICE sendbackup
OPTIONS hostname=www.iclear.com;
GNUTAR / 0 1970:1:1:0:0:0 OPTIONS
|;bsd-auth;index;exclude-list=/usr/local/lib/amanda/exclude.gtar;
--------

sending ack:
----
Amanda 2.4 ACK HANDLE 000-F8BB0708 SEQ 1053423007
----

bsd security: remote host web.isymmetrics.com user amanda local user
amanda
amandahosts security check passed
amandad: running service "/usr/local/libexec/sendbackup"
amandad: sending REP packet:
----
Amanda 2.4 REP HANDLE 000-F8BB0708 SEQ 1053423007
CONNECT DATA 40082 MESG 40083 INDEX 40084
OPTIONS ;bsd-auth;index;
----

amandad: got packet:
----
Amanda 2.4 ACK HANDLE 000-F8BB0708 SEQ 1053423007
----

amandad: pid 8408 finish time Tue May 20 04:34:55 2003

Am I missing something in sendbackup.*.debug, because I don't see
anything freaky...
# more sendbackup.20030520043455.debug
sendbackup: debug 1 pid 8409 ruid 1003 euid 1003 start time Tue May 20
04:34:55 2003
/usr/local/libexec/sendbackup: version 2.4.2p2
sendbackup: got input request: GNUTAR / 0 1970:1:1:0:0:0 OPTIONS
|;bsd-auth;index;exclude-list=/usr/local/
lib/amanda/exclude.gtar;
  parsed request as: program `GNUTAR'
                     disk `/'
                     lev 0
                     since 1970:1:1:0:0:0
                     opt
`|;bsd-auth;index;exclude-list=/usr/local/lib/amanda/exclude.gtar;'
sendbackup: exclude list file "/usr/local/lib/amanda/exclude.gtar" does
not exist, ignoring
sendbackup: try_socksize: send buffer size is 65536
sendbackup: stream_server: waiting for connection: 0.0.0.0.40082
sendbackup: stream_server: waiting for connection: 0.0.0.0.40083
sendbackup: stream_server: waiting for connection: 0.0.0.0.40084
  waiting for connect on 40082, then 40083, then 40084
sendbackup: stream_accept: connection from 204.95.152.69.42108
sendbackup: stream_accept: connection from 204.95.152.69.42109
sendbackup: stream_accept: connection from 204.95.152.69.42110
  got all connections
sendbackup-gnutar: doing level 0 dump as listed-incremental to
/usr/local/var/amanda/gnutar-lists/www.icle
ar.com__0.new
sendbackup-gnutar: doing level 0 dump from date: 1970-01-01  0:00:00 GMT
sendbackup: started index creator: "/usr/local/bin/tar -tf - 2>/dev/null
| sed -e 's/^\.//'"
sendbackup: spawning /usr/local/libexec/runtar in pipeline
sendbackup: argument list: gtar --create --file - --directory /
--one-file-system --listed-incremental /us
r/local/var/amanda/gnutar-lists/www.iclear.com__0.new --sparse
--ignore-failed-read --totals .
sendbackup-gnutar: /usr/local/libexec/runtar: pid 8414
sendbackup: index created successfully

And the runtar.*.debug all look normal as well?

I don't flippin' get it.



Attachment: icleardump.txt
Description: icleardump.txt

Attachment: failed_bu.txt
Description: failed_bu.txt

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