Amanda-Users

Re: parse of reply message failed

2004-11-03 05:09:56
Subject: Re: parse of reply message failed
From: foo AT porto.bmb.uga DOT edu
To: amanda-users AT amanda DOT org
Date: Wed, 3 Nov 2004 05:06:08 -0500
On Wed, Nov 03, 2004 at 10:49:37AM +0100, Paul Bijnens wrote:
> First, I don't find that errormessage in the sources of 2.4.4p3, the
> current stable version.  So there are some changes since 2.4.2p2
> that would change/solve that behaviour...  (or at least produce
> a better error message).
> 
> Also make sure you're not running into a max dgram packet size problem.
> 
> As a first start, we like to see the contents of 
> /tmp/amanda/amandad.TIMESTAMP.debug
> That file contains a dump of the conversation from the client side
> point of view.

See the end of this message.

> The server side of the conversation is logged into the amdump file
> (which is renamed to amdump.1 when finished), in the ~amanda/ConFig
> directory.  However this logging is not as detailed as the client side.
> But I guess there are some hints in that file too.

Hmm, I can't find any files of that name...  'locate amdump' on the
server just finds the amdump binary and man page.

> It would be nice to have a trace of the network traffic that
> went along, to find out if the server did receive those packets as
> the client sent them.
> 
> To be really handy, all that info should be from the same run too.

I'll try to collect a tcpdump tonight.

amandad: debug 1 pid 4063 ruid 34 euid 34 start time Tue Nov  2 00:30:02 2004
amandad: version 2.4.2p2
amandad: build: VERSION="Amanda-2.4.2p2"
amandad:        BUILT_DATE="Tue Apr 2 21:24:21 UTC 2002"
amandad:        BUILT_MACH="Linux cyberhq 2.4.18pre2 #1 SMP Tue Jan 8 18:13:43 
PST 2002 i686 unknown"
amandad:        CC="gcc"
amandad: paths: bindir="/usr/sbin" sbindir="/usr/sbin"
amandad:        libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
amandad:        AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
amandad:        CONFIG_DIR="/etc/amanda" DEV_PREFIX="/dev/"
amandad:        RDEV_PREFIX="/dev/r" DUMP="/sbin/dump"
amandad:        RESTORE="/sbin/restore" SAMBA_CLIENT="/usr/bin/smbclient"
amandad:        GNUTAR="/bin/tar" COMPRESS_PATH="/bin/gzip"
amandad:        UNCOMPRESS_PATH="/bin/gzip" MAILER="/usr/bin/Mail"
amandad:        listed_incr_dir="/var/lib/amanda/gnutar-lists"
amandad: defs:  DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
amandad:        DEFAULT_TAPE_SERVER="localhost"
amandad:        DEFAULT_TAPE_DEVICE="/dev/null" HAVE_MMAP HAVE_SYSVSHM
amandad:        LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
amandad:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY USE_AMANDAHOSTS
amandad:        CLIENT_LOGIN="backup" 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 006-B8C20608 SEQ 1099373408
SECURITY USER backup
SERVICE sendsize
OPTIONS maxdumps=1;hostname=albarino;
DUMP /albarino2 0 1970:1:1:0:0:0 -1
DUMP /albarino2 1 2004:10:13:4:34:59 -1
DUMP /albarino1 0 1970:1:1:0:0:0 -1
DUMP /albarino1 1 2004:10:13:4:34:44 -1
DUMP / 0 1970:1:1:0:0:0 -1
DUMP / 2 2004:10:21:4:33:55 -1
DUMP / 3 2004:10:29:4:34:34 -1
--------

sending ack:
----
Amanda 2.4 ACK HANDLE 006-B8C20608 SEQ 1099373408
----

bsd security: remote host xarello.BCM.UMontreal.CA user backup local user backup
amandahosts security check passed
amandad: running service "/usr/lib/amanda/sendsize"
amandad: sending REP packet:
----
Amanda 2.4 REP HANDLE 006-B8C20608 SEQ 1099373408
OPTIONS maxdumps=1;
/ 0 SIZE 594735
/ 2 SIZE 137789
/ 3 SIZE 133960
/albarino1 0 SIZE 11009
/albarino1 1 SIZE 10987
/albarino2 0 SIZE 395
/albarino2 1 SIZE 373
----

amandad: got packet:
----
Amanda 2.4 ACK HANDLE 006-B8C20608 SEQ 1099373408
----

amandad: pid 4063 finish time Tue Nov  2 00:30:36 2004
amandad: debug 1 pid 4079 ruid 34 euid 34 start time Tue Nov  2 00:34:09 2004
amandad: version 2.4.2p2
amandad: build: VERSION="Amanda-2.4.2p2"
amandad:        BUILT_DATE="Tue Apr 2 21:24:21 UTC 2002"
amandad:        BUILT_MACH="Linux cyberhq 2.4.18pre2 #1 SMP Tue Jan 8 18:13:43 
PST 2002 i686 unknown"
amandad:        CC="gcc"
amandad: paths: bindir="/usr/sbin" sbindir="/usr/sbin"
amandad:        libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
amandad:        AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
amandad:        CONFIG_DIR="/etc/amanda" DEV_PREFIX="/dev/"
amandad:        RDEV_PREFIX="/dev/r" DUMP="/sbin/dump"
amandad:        RESTORE="/sbin/restore" SAMBA_CLIENT="/usr/bin/smbclient"
amandad:        GNUTAR="/bin/tar" COMPRESS_PATH="/bin/gzip"
amandad:        UNCOMPRESS_PATH="/bin/gzip" MAILER="/usr/bin/Mail"
amandad:        listed_incr_dir="/var/lib/amanda/gnutar-lists"
amandad: defs:  DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
amandad:        DEFAULT_TAPE_SERVER="localhost"
amandad:        DEFAULT_TAPE_DEVICE="/dev/null" HAVE_MMAP HAVE_SYSVSHM
amandad:        LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
amandad:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY USE_AMANDAHOSTS
amandad:        CLIENT_LOGIN="backup" 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-98CE0608 SEQ 1099373402
SECURITY USER backup
SERVICE sendbackup
OPTIONS hostname=albarino;
DUMP / 2 2004:10:21:4:33:55 OPTIONS |;bsd-auth;compress-fast;index;
--------

sending ack:
----
Amanda 2.4 ACK HANDLE 000-98CE0608 SEQ 1099373402
----

bsd security: remote host xarello.BCM.UMontreal.CA user backup local user backup
amandahosts security check passed
amandad: running service "/usr/lib/amanda/sendbackup"
amandad: sending REP packet:
----
Amanda 2.4 REP HANDLE 000-98CE0608 SEQ 1099373402
CONNECT DATA 32812 MESG 32813 INDEX 32814
OPTIONS ;compress-fast;bsd-auth;index;
----

amandad: got packet:
----
Amanda 2.4 ACK HANDLE 000-98CE0608 SEQ 1099373402
----

amandad: pid 4079 finish time Tue Nov  2 00:34:09 2004
amandad: debug 1 pid 4081 ruid 34 euid 34 start time Tue Nov  2 00:34:24 2004
amandad: version 2.4.2p2
amandad: build: VERSION="Amanda-2.4.2p2"
amandad:        BUILT_DATE="Tue Apr 2 21:24:21 UTC 2002"
amandad:        BUILT_MACH="Linux cyberhq 2.4.18pre2 #1 SMP Tue Jan 8 18:13:43 
PST 2002 i686 unknown"
amandad:        CC="gcc"
amandad: paths: bindir="/usr/sbin" sbindir="/usr/sbin"
amandad:        libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
amandad:        AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
amandad:        CONFIG_DIR="/etc/amanda" DEV_PREFIX="/dev/"
amandad:        RDEV_PREFIX="/dev/r" DUMP="/sbin/dump"
amandad:        RESTORE="/sbin/restore" SAMBA_CLIENT="/usr/bin/smbclient"
amandad:        GNUTAR="/bin/tar" COMPRESS_PATH="/bin/gzip"
amandad:        UNCOMPRESS_PATH="/bin/gzip" MAILER="/usr/bin/Mail"
amandad:        listed_incr_dir="/var/lib/amanda/gnutar-lists"
amandad: defs:  DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
amandad:        DEFAULT_TAPE_SERVER="localhost"
amandad:        DEFAULT_TAPE_DEVICE="/dev/null" HAVE_MMAP HAVE_SYSVSHM
amandad:        LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
amandad:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY USE_AMANDAHOSTS
amandad:        CLIENT_LOGIN="backup" 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-F8D40608 SEQ 1099373403
SECURITY USER backup
SERVICE sendbackup
OPTIONS hostname=albarino;
DUMP /albarino2 0 1970:1:1:0:0:0 OPTIONS |;bsd-auth;compress-fast;index;
--------

sending ack:
----
Amanda 2.4 ACK HANDLE 000-F8D40608 SEQ 1099373403
----

bsd security: remote host xarello.BCM.UMontreal.CA user backup local user backup
amandahosts security check passed
amandad: running service "/usr/lib/amanda/sendbackup"
amandad: sending REP packet:
----
Amanda 2.4 REP HANDLE 000-F8D40608 SEQ 1099373403
CONNECT DATA 32815 MESG 32816 INDEX 32817
OPTIONS ;compress-fast;bsd-auth;index;
----

amandad: got packet:
----
Amanda 2.4 ACK HANDLE 000-F8D40608 SEQ 1099373403
----

amandad: pid 4081 finish time Tue Nov  2 00:34:24 2004
amandad: debug 1 pid 4083 ruid 34 euid 34 start time Tue Nov  2 00:34:39 2004
amandad: version 2.4.2p2
amandad: build: VERSION="Amanda-2.4.2p2"
amandad:        BUILT_DATE="Tue Apr 2 21:24:21 UTC 2002"
amandad:        BUILT_MACH="Linux cyberhq 2.4.18pre2 #1 SMP Tue Jan 8 18:13:43 
PST 2002 i686 unknown"
amandad:        CC="gcc"
amandad: paths: bindir="/usr/sbin" sbindir="/usr/sbin"
amandad:        libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
amandad:        AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
amandad:        CONFIG_DIR="/etc/amanda" DEV_PREFIX="/dev/"
amandad:        RDEV_PREFIX="/dev/r" DUMP="/sbin/dump"
amandad:        RESTORE="/sbin/restore" SAMBA_CLIENT="/usr/bin/smbclient"
amandad:        GNUTAR="/bin/tar" COMPRESS_PATH="/bin/gzip"
amandad:        UNCOMPRESS_PATH="/bin/gzip" MAILER="/usr/bin/Mail"
amandad:        listed_incr_dir="/var/lib/amanda/gnutar-lists"
amandad: defs:  DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
amandad:        DEFAULT_TAPE_SERVER="localhost"
amandad:        DEFAULT_TAPE_DEVICE="/dev/null" HAVE_MMAP HAVE_SYSVSHM
amandad:        LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
amandad:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY USE_AMANDAHOSTS
amandad:        CLIENT_LOGIN="backup" 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-78D50608 SEQ 1099373404
SECURITY USER backup
SERVICE sendbackup
OPTIONS hostname=albarino;
DUMP /albarino1 0 1970:1:1:0:0:0 OPTIONS |;bsd-auth;compress-fast;index;
--------

sending ack:
----
Amanda 2.4 ACK HANDLE 000-78D50608 SEQ 1099373404
----

bsd security: remote host xarello.BCM.UMontreal.CA user backup local user backup
amandahosts security check passed
amandad: running service "/usr/lib/amanda/sendbackup"
amandad: sending REP packet:
----
Amanda 2.4 REP HANDLE 000-78D50608 SEQ 1099373404
CONNECT DATA 32818 MESG 32819 INDEX 32820
OPTIONS ;compress-fast;bsd-auth;index;
----

amandad: got packet:
----
Amanda 2.4 ACK HANDLE 000-78D50608 SEQ 1099373404
----

amandad: pid 4083 finish time Tue Nov  2 00:34:39 2004