Hi Jean-Louis,
Thanks for the reply, I was feeling like the only Kerberos user in
amanda land for a little while.
OK to clarify :
1) Other clients are 2.5.0p2, but this DLE also had the problem when
it was also 2.5.0p2.
2) Only other client with multiple DLE doesn't use kerberos (OS/X..
amanda won't link against it's libraries). It's fine.
3) I've tried setting maxdumpers = 1 so only one dump is running on
the affected client. Same result.
File attached, any help gratefully appreciated...
[EMAIL PROTECTED] amandad]$ ls *02031*
amandad.20070203124746.debug amandad.20070203125605.debug amandad.
20070203125821.debug amandad.20070203132442.debug
amandad.20070203124807.debug amandad.20070203125610.debug amandad.
20070203130802.debug amandad.20070203132459.debug
amandad.20070203125231.debug amandad.20070203125810.debug amandad.
20070203130822.debug
[EMAIL PROTECTED] amandad]$ cat amandad.20070203130802.debug
amandad: debug 1 pid 5429 ruid 0 euid 0: start at Sat Feb 3 13:08:02
2007
security_getdriver(name=krb5) returns 0x7370c0
amandad: version 2.5.1p2
amandad: build: VERSION="Amanda-2.5.1p2"
amandad: BUILT_DATE="Thu Feb 1 03:24:00 GMT 2007"
amandad: BUILT_MACH="Linux qa-apps 2.6.9-42.ELsmp #1 SMP Wed
Jul 12 23:27:17 EDT 2006 i686 i686 i386 GNU/Linux"
amandad: CC="gcc"
amandad: CONFIGURE_COMMAND="'./configure' '--with-krb5-
security' '--with-user=amanda' '--with-group=backup' '--with-
config=DailySet1' '--sbindir=/usr/sbin' '--libexecdir=/usr/libexec'
'--libdir=/usr/lib' '--with-configdir=/etc/amanda' '--with-gnutar-
listdir=/var/amanda/gnutar-lists' '--mandir=/usr/share/man'"
amandad: paths: bindir="/usr/local/bin" sbindir="/usr/sbin"
amandad: libexecdir="/usr/libexec" 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" VDUMP=UNDEF VRESTORE=UNDEF
amandad: XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF
VXRESTORE=UNDEF
amandad: SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/gtar"
amandad: COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip"
amandad: LPRCMD="/usr/bin/lpr" MAILER="/usr/bin/Mail"
amandad: listed_incr_dir="/var/amanda/gnutar-lists"
amandad: defs: DEFAULT_SERVER="qa-apps" DEFAULT_CONFIG="DailySet1"
amandad: DEFAULT_TAPE_SERVER="qa-apps" HAVE_MMAP HAVE_SYSVSHM
amandad: LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
amandad: AMANDA_DEBUG_DAYS=4 BSD_SECURITY KRB5_SECURITY
RSH_SECURITY
amandad: USE_AMANDAHOSTS CLIENT_LOGIN="amanda" FORCE_USERID
HAVE_GZIP
amandad: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
amandad: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
security_handleinit(handle=0x8317488, driver=0x7370c0 (krb5))
security_streaminit(stream=0x83174d8, driver=0x7370c0 (krb5))
amandad: time 0.109: accept recv REQ pkt:
<<<<<
SERVICE noop
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: time 0.109: creating new service: noop
OPTIONS features=fffffeff9ffeffffff7f;
amandad: time 0.111: sending ACK pkt:
<<<<<
>>>>>
amandad: time 0.118: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: time 0.152: received ACK pkt:
<<<<<
>>>>>
security_close(handle=0x8317488, driver=0x7370c0 (krb5))
security_stream_close(0x83174d8)
security_handleinit(handle=0x8316f68, driver=0x7370c0 (krb5))
security_streaminit(stream=0x8317cf0, driver=0x7370c0 (krb5))
amandad: time 0.732: accept recv REQ pkt:
<<<<<
SERVICE sendsize
OPTIONS
features=fffffeff9ffeffffff7f;maxdumps=1;hostname=qtvsrv1.xxxx;config=Of
fSite;
GNUTAR /usr 0 1970:1:1:0:0:0 -1 OPTIONS |;auth=krb5;compress-
fast;index;exclude-file=./public;exclude-file=./transfer;exclude-
file=./tmp/*;exclude-file=./var/tmp/*;exclude-file=./Network/Servers/
*;exclude-file=*/*.ts;exclude-file=*/*.mpg;exclude-file=*/
*.mpeg;exclude-file=*/*.mp4;exclude-file=./proc;exclude-file=./
sys;exclude-file=./vstore;exclude-file=./home/perforce/backup/
*;exclude-file=./lon_transfer/*;exclude-file=./lon_public/*;exclude-
file=./downloads/*;exclude-file=./u01/*;
GNUTAR /usr 1 1970:1:1:0:0:0 -1 OPTIONS |;auth=krb5;compress-
fast;index;exclude-file=./public;exclude-file=./transfer;exclude-
file=./tmp/*;exclude-file=./var/tmp/*;exclude-file=./Network/Servers/
*;exclude-file=*/*.ts;exclude-file=*/*.mpg;exclude-file=*/
*.mpeg;exclude-file=*/*.mp4;exclude-file=./proc;exclude-file=./
sys;exclude-file=./vstore;exclude-file=./home/perforce/backup/
*;exclude-file=./lon_transfer/*;exclude-file=./lon_public/*;exclude-
file=./downloads/*;exclude-file=./u01/*;
GNUTAR / 0 1970:1:1:0:0:0 -1 OPTIONS |;auth=krb5;compress-
fast;index;exclude-file=./public;exclude-file=./transfer;exclude-
file=./tmp/*;exclude-file=./var/tmp/*;exclude-file=./Network/Servers/
*;exclude-file=*/*.ts;exclude-file=*/*.mpg;exclude-file=*/
*.mpeg;exclude-file=*/*.mp4;exclude-file=./proc;exclude-file=./
sys;exclude-file=./vstore;exclude-file=./home/perforce/backup/
*;exclude-file=./lon_transfer/*;exclude-file=./lon_public/*;exclude-
file=./downloads/*;exclude-file=./u01/*;
GNUTAR / 1 2007:2:2:22:22:1 -1 OPTIONS |;auth=krb5;compress-
fast;index;exclude-file=./public;exclude-file=./transfer;exclude-
file=./tmp/*;exclude-file=./var/tmp/*;exclude-file=./Network/Servers/
*;exclude-file=*/*.ts;exclude-file=*/*.mpg;exclude-file=*/
*.mpeg;exclude-file=*/*.mp4;exclude-file=./proc;exclude-file=./
sys;exclude-file=./vstore;exclude-file=./home/perforce/backup/
*;exclude-file=./lon_transfer/*;exclude-file=./lon_public/*;exclude-
file=./downloads/*;exclude-file=./u01/*;
>>>>>
amandad: time 0.732: creating new service: sendsize
OPTIONS
features=fffffeff9ffeffffff7f;maxdumps=1;hostname=qtvsrv1.xxxx;config=Of
fSite;
GNUTAR /usr 0 1970:1:1:0:0:0 -1 OPTIONS |;auth=krb5;compress-
fast;index;exclude-file=./public;exclude-file=./transfer;exclude-
file=./tmp/*;exclude-file=./var/tmp/*;exclude-file=./Network/Servers/
*;exclude-file=*/*.ts;exclude-file=*/*.mpg;exclude-file=*/
*.mpeg;exclude-file=*/*.mp4;exclude-file=./proc;exclude-file=./
sys;exclude-file=./vstore;exclude-file=./home/perforce/backup/
*;exclude-file=./lon_transfer/*;exclude-file=./lon_public/*;exclude-
file=./downloads/*;exclude-file=./u01/*;
GNUTAR /usr 1 1970:1:1:0:0:0 -1 OPTIONS |;auth=krb5;compress-
fast;index;exclude-file=./public;exclude-file=./transfer;exclude-
file=./tmp/*;exclude-file=./var/tmp/*;exclude-file=./Network/Servers/
*;exclude-file=*/*.ts;exclude-file=*/*.mpg;exclude-file=*/
*.mpeg;exclude-file=*/*.mp4;exclude-file=./proc;exclude-file=./
sys;exclude-file=./vstore;exclude-file=./home/perforce/backup/
*;exclude-file=./lon_transfer/*;exclude-file=./lon_public/*;exclude-
file=./downloads/*;exclude-file=./u01/*;
GNUTAR / 0 1970:1:1:0:0:0 -1 OPTIONS |;auth=krb5;compress-
fast;index;exclude-file=./public;exclude-file=./transfer;exclude-
file=./tmp/*;exclude-file=./var/tmp/*;exclude-file=./Network/Servers/
*;exclude-file=*/*.ts;exclude-file=*/*.mpg;exclude-file=*/
*.mpeg;exclude-file=*/*.mp4;exclude-file=./proc;exclude-file=./
sys;exclude-file=./vstore;exclude-file=./home/perforce/backup/
*;exclude-file=./lon_transfer/*;exclude-file=./lon_public/*;exclude-
file=./downloads/*;exclude-file=./u01/*;
GNUTAR / 1 2007:2:2:22:22:1 -1 OPTIONS |;auth=krb5;compress-
fast;index;exclude-file=./public;exclude-file=./transfer;exclude-
file=./tmp/*;exclude-file=./var/tmp/*;exclude-file=./Network/Servers/
*;exclude-file=*/*.ts;exclude-file=*/*.mpg;exclude-file=*/
*.mpeg;exclude-file=*/*.mp4;exclude-file=./proc;exclude-file=./
sys;exclude-file=./vstore;exclude-file=./home/perforce/backup/
*;exclude-file=./lon_transfer/*;exclude-file=./lon_public/*;exclude-
file=./downloads/*;exclude-file=./u01/*;
amandad: time 0.734: sending ACK pkt:
<<<<<
>>>>>
amandad: time 0.812: sending PREP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: time 3.187: sending PREP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
/ 0 SIZE 355090
>>>>>
amandad: time 3.750: sending PREP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
/ 0 SIZE 355090
/ 1 SIZE 15170
>>>>>
amandad: time 10.614: sending PREP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
/ 0 SIZE 355090
/ 1 SIZE 15170
/usr 0 SIZE 1597520
>>>>>
amandad: time 15.644: sending PREP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
/ 0 SIZE 355090
/ 1 SIZE 15170
/usr 0 SIZE 1597520
/usr 1 SIZE 12580
>>>>>
amandad: time 15.645: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
/ 0 SIZE 355090
/ 1 SIZE 15170
/usr 0 SIZE 1597520
/usr 1 SIZE 12580
>>>>>
amandad: time 15.645: received ACK pkt:
<<<<<
>>>>>
security_close(handle=0x8316f68, driver=0x7370c0 (krb5))
security_stream_close(0x8317cf0)
amandad: time 30.642: pid 5429 finish time Sat Feb 3 13:08:33 2007
---
AlanP
On 5 Feb 2007, at 19:29, Jean-Louis Martineau wrote:
Alan,
Could you send the amandad.<timestamps>.debug file?
You said all other client works, are they using the same amanda
version? Do they have one or more DLE?
Jean-Louis
Alan Pearson wrote:
Ok,
I've managed to get more info on this.
If I run amdump with the failing entry in the disklist on it's
own, all is fine.
If put another entry from the same machine, the other entry
backups up fine, but the second will fail.
So it sounds like some sort of timing issue ?
Here's the dumper debug which shows the problem, and remember it's
only a problem when there is more that one disklist entry for that
host.
Any help appreciated !
[EMAIL PROTECTED] OffSite]$ cat dumper.20070203130802.debug
dumper: debug 1 pid 5422 ruid 1083 euid 0: start at Sat Feb 3
13:08:02 2007
dumper: debug 1 pid 5422 ruid 1083 euid 0: rename at Sat Feb 3
13:08:02 2007
dumper: connect_port: Try port 1025: Available -
dumper: connected to 127.0.0.1.35406
dumper: our side is 0.0.0.0.1025
dumper: try_socksize: send buffer size is 65536
security_getdriver(name=krb5) returns 0xa0e0c0
security_handleinit(handle=0x8eb0a50, driver=0xa0e0c0 (krb5))
security_streaminit(stream=0x8eb3478, driver=0xa0e0c0 (krb5))
dumper: connect_port: Try port 1025: Available -
dumper: connected to 172.16.1.9.10082
dumper: our side is 0.0.0.0.1025
dumper: try_socksize: send buffer size is 65536
dumper: try_socksize: receive buffer size is 65536
security_streaminit(stream=0x8ed42f0, driver=0xa0e0c0 (krb5))
security_streaminit(stream=0x8ee4318, driver=0xa0e0c0 (krb5))
security_streaminit(stream=0x8ef4340, driver=0xa0e0c0 (krb5))
security_close(handle=0x8eb0a50, driver=0xa0e0c0 (krb5))
security_stream_close(0x8eb3478)
dumper: Building type 4 (FILE) header of size 32768 using:
dumper: Contents of *(dumpfile_t *)0x80503e0:
dumper: type = 4 (FILE)
dumper: datestamp = '20070203'
dumper: dumplevel = 0
dumper: compressed = 1
dumper: encrypted = 0
dumper: comp_suffix = '.gz'
dumper: encrypt_suffix = 'N'
dumper: name = 'qtvsrv1.xxxx'
dumper: disk = '/'
dumper: program = '/bin/gtar'
dumper: srvcompprog = ''
dumper: clntcompprog = ''
dumper: srv_encrypt = ''
dumper: clnt_encrypt = ''
dumper: recover_cmd = '/bin/gzip -dc |/bin/gtar -f - ...'
dumper: uncompress_cmd = ''
dumper: encrypt_cmd = ''
dumper: decrypt_cmd = ''
dumper: srv_decrypt_opt = ''
dumper: clnt_decrypt_opt = ''
dumper: cont_filename = ''
dumper: is_partial = 0
dumper: partnum = 0
dumper: totalparts = 0
dumper: blocksize = 32768
security_stream_close(0x8ee4318)
security_stream_close(0x8ed42f0)
security_stream_close(0x8ef4340)
dumper: connect_port: Try port 1025: Available -
dumper: connected to 127.0.0.1.35424
dumper: our side is 0.0.0.0.1025
dumper: try_socksize: send buffer size is 65536
security_getdriver(name=krb5) returns 0xa0e0c0
security_handleinit(handle=0x8eb0a50, driver=0xa0e0c0 (krb5))
security_streaminit(stream=0x8ed42f0, driver=0xa0e0c0 (krb5))
security_seterror(handle=0x8eb0a50, driver=0xa0e0c0 (krb5)
error=EOF on read from qtvsrv1.xxxx)
security_close(handle=0x8eb0a50, driver=0xa0e0c0 (krb5))
security_stream_close(0x8ed42f0)
dumper: connect_port: Try port 1025: Available -
dumper: connected to 127.0.0.1.35428
dumper: our side is 0.0.0.0.1025
dumper: try_socksize: send buffer size is 65536
security_getdriver(name=krb5) returns 0xa0e0c0
security_handleinit(handle=0x8ed4270, driver=0xa0e0c0 (krb5))
security_streaminit(stream=0x8ed42f0, driver=0xa0e0c0 (krb5))
security_stream_seterr(0x8ed42f0, krb5 write error to
qtvsrv1.xxxx: Broken pipe)
security_seterror(handle=0x8ed4270, driver=0xa0e0c0 (krb5)
error=krb5 write error to qtvsrv1.xxxx: Broken pipe)
security_seterror(handle=0x8ed4270, driver=0xa0e0c0 (krb5)
error=error sending REQ: krb5 write error to qtvsrv1.xxxx: Broken
pipe)
security_close(handle=0x8ed4270, driver=0xa0e0c0 (krb5))
security_stream_close(0x8ed42f0)
dumper: pid 5422 finish time Sat Feb 3 13:09:38 2007
---
AlanP
