Amanda-Users

old server 2.4.2p2 fails to backup newer client 2.5.1p1

2007-06-06 17:32:26
Subject: old server 2.4.2p2 fails to backup newer client 2.5.1p1
From: "Carl D. Blake" <carl AT boeckeler DOT com>
To: amanda-users AT amanda DOT org
Date: Tue, 05 Jun 2007 11:47:44 -0700
I am running a 2.4.2p2 amanda server which is attempting to backup a
machine with 2.5.1p1 amanda client (Debian Etch).  The server is backing
up several machines, but it seems to have trouble with the machines
which are running 2.5.1p1 client.  The symptom is that the server
reports that the sendsize failed.  The messages in the amdump log file
are:

setting up estimates for misty.tucson.boeckeler.com:/
setup_estimate: misty.tucson.boeckeler.com:/: command 0, options:
    last_level 0 next_level0 7 level_days 0
    getting estimates 0 (666580) 1 (0) -1 (-1)

and later in the file:

error result for host misty.tucson.boeckeler.com disk /: missing
estimate

the planner later reports:

planner: FAILED misty.tucson.boeckeler.com / 0 [missing result for / in
misty.tucson.boeckeler.com response]

The amandad debug file on the client says:

amandad: debug 1 pid 19080 ruid 34 euid 34: start at Fri Jun  1 19:00:01
2007
security_getdriver(name=bsd) returns 0xb7f330e0
amandad: version 2.5.1p1
amandad: build: VERSION="Amanda-2.5.1p1"
amandad:        BUILT_DATE="Wed Nov 29 02:15:07 CET 2006"
amandad:        BUILT_MACH="Linux intrepid 2.6.18-1-686 #1 SMP Fri Sep
29 16:25:40 UTC 2006 i686 GNU/Linux"
amandad:        CC="gcc"
amandad:        CONFIGURE_COMMAND="'./configure' '--prefix=/usr'
'--bindir=/usr/sbin' '--mandir=/usr/share/man'
'--libexecdir=/usr/lib/amanda' '--enable-shared' '--sysconfdir=/etc'
'--localstatedir=/var/lib'
'--with-gnutar-listdir=/var/lib/amanda/gnutar-lists'
'--with-index-server=localhost' '--with-user=backup'
'--with-group=backup' '--with-bsd-security' '--with-amandahosts'
'--with-smbclient=/usr/bin/smbclient' '--with-debugging=/var/log/amanda'
'--with-dumperdir=/usr/lib/amanda/dumper.d'
'--with-tcpportrange=50000,50100' '--with-udpportrange=840,860'
'--with-maxtapeblocksize=256' '--with-ssh-security'"
amandad: paths: bindir="/usr/sbin" sbindir="/usr/sbin"
amandad:        libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
amandad:        AMANDA_TMPDIR="/tmp/amanda"
amandad:        AMANDA_DBGDIR="/var/log/amanda" CONFIG_DIR="/etc/amanda"
amandad:        DEV_PREFIX="/dev/" RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
amandad:        RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF
amandad:        XFSDUMP="/sbin/xfsdump" XFSRESTORE="/sbin/xfsrestore"
amandad:        VXDUMP=UNDEF VXRESTORE=UNDEF
amandad:        SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/tar"
amandad:        COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip"
amandad:        LPRCMD="/usr/bin/lpr" 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" HAVE_MMAP HAVE_SYSVSHM
amandad:        LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
amandad:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY RSH_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"
amandad: time 0.000: dgram_recv(dgram=0xb7f34084, timeout=0,
fromaddr=0xb7f44070)
amandad: time 0.000: (sockaddr_in *)0xb7f44070 = { 2, 632, 10.0.1.135 }
security_handleinit(handle=0x8050140, driver=0xb7f330e0 (BSD))
amandad: time 0.008: accept recv REQ pkt:
<<<<<
SERVICE sendsize
OPTIONS maxdumps=1;hostname=misty.tucson.boeckeler.com;
GNUTAR / 0 1970:1:1:0:0:0 -1
exclude-list=/usr/local/lib/amanda/exclude.gtar
GNUTAR / 1 2007:5:26:4:53:46 -1
exclude-list=/usr/local/lib/amanda/exclude.gtar
>>>>>
amandad: time 0.009: creating new service: sendsize
OPTIONS maxdumps=1;hostname=misty.tucson.boeckeler.com;
GNUTAR / 0 1970:1:1:0:0:0 -1
exclude-list=/usr/local/lib/amanda/exclude.gtar
GNUTAR / 1 2007:5:26:4:53:46 -1
exclude-list=/usr/local/lib/amanda/exclude.gtar

amandad: time 0.020: sending ACK pkt:
<<<<<
>>>>>
amandad: dgram_send_addr(addr=0xbfd16bb0, dgram=0xb7f34084)
amandad: time 0.020: (sockaddr_in *)0xbfd16bb0 = { 2, 632, 10.0.1.135 }
amandad: dgram_send_addr: 0xb7f34084->socket = 0
amandad: time 0.027: sending REP pkt:
<<<<<
OPTIONS 
>>>>>
amandad: dgram_send_addr(addr=0xbfd16cf0, dgram=0xb7f34084)
amandad: time 0.027: (sockaddr_in *)0xbfd16cf0 = { 2, 632, 10.0.1.135 }
amandad: dgram_send_addr: 0xb7f34084->socket = 0
amandad: time 0.027: dgram_recv(dgram=0xb7f34084, timeout=0,
fromaddr=0xb7f44070)
amandad: time 0.028: (sockaddr_in *)0xb7f44070 = { 2, 632, 10.0.1.135 }
amandad: time 0.028: received ACK pkt:
<<<<<
>>>>>
security_close(handle=0x8050140, driver=0xb7f330e0 (BSD))
amandad: time 30.031: pid 19080 finish time Fri Jun  1 19:00:31 2007

and the sendsize debug file on the client machine says:

sendsize: debug 1 pid 19081 ruid 34 euid 34: start at Fri Jun  1
19:00:01 2007
sendsize: version 2.5.1p1
Could not open conf file "/etc/amanda/amanda-client.conf": No such file
or directory

I've tried adding an empty amanda-client.conf file, but it doesn't make
any difference.  Running amcheck works fine - the server doesn't report
any problems in attempting to access the 2.5.1p1 client, it's only when
the actual dump occurs and then sendsize fails.  If I downgrade the
version of amanda to 2.4.4p3 then it seems to work fine.  Any
suggestions?

-- 
Carl D. Blake
Director of Engineering
Boeckeler Instruments, Inc.
4650 S. Butterfield Dr.
Tucson, AZ  85714
PH: 520-745-0001
FAX: 520-745-0004
EMAIL: carl AT boeckeler DOT com