On Tue, Aug 18, 2009 at 08:06:46AM -0400, Jean-Louis Martineau wrote:
> stan wrote:
> >Any thoughts as to why I have twice as many debug files as I expect?
> >
>
> When a dump fail, amanda try it a second time.
>
OK here is the latest on this saga :-)
On one of the OpenBSD 4.5 machines I have built 2.5.0p1, and was able to
back this machine up successfully (using classic UDP based authentication)
On another of them, I built 2.5.2p1. The first attempt to back this machine
up failed. I checked the log files, and found they were having issues
because /etc/amdates was missing. I corrected that, and started a 2nd
backup run. (Remember amcheck reports all is well with this machine). I
got the following from amstatus when I attempted to back up this machine.
Also remember, one of the test I ran with a 2.6.1 client was to connect a
test machine directly to the client, using a crossover cable to eliminate
any firewall, or router type issues.
I am attaching, what I think is, the amadnad debug file associated with this
failure.
Can anyone suggest what I can do to further troubleshoot this?
pb48:wd0f 1 dumper: [could not connect DATA stream:
can't connect stream to pb48.meadwestvaco.com port 11996: Connection
refused] (10:37:27)
--
One of the main causes of the fall of the roman empire was that, lacking
zero, they had no way to indicate successful termination of their C
programs.
amandad: debug 1 pid 17702 ruid 1002 euid 1002: start at Fri Aug 21 10:37:27
2009
Reading conf file "/opt/amanda/etc/amanda-client.conf".
amandad: time 0.000: security_getdriver(name=BSD) returns 0x298a9240
amandad: version 2.5.2p1
amandad: time 0.001: build: VERSION="Amanda-2.5.2p1"
amandad: time 0.001: BUILT_DATE="Fri Aug 21 07:26:22 EDT 2009"
amandad: time 0.001: BUILT_MACH="OpenBSD pb48 4.5 GENERIC#1749 i386"
CC="gcc"
amandad: time 0.001: CONFIGURE_COMMAND="'./configure'
'--with-config=DailyDump' '--prefix=/opt/amanda'
'--with-index-server=amanda.meadwestvaco.com'
'--with-tape-server=amanda.meadwestvaco.com' '--with-tape-device=chg-multi'
'--with-changer-device=chg-multi' '--with-user=amanda' '--with-group=operator'
'--with-debugging' '--sbindir=/opt/amanda/sbin'
'--libexecdir=/opt/amanda/libexec' '--libdir=/opt/amanda/lib'
'--with-configdir=/opt/amanda/etc'
'--with-gnutar-listdir=/opt/amanda/var/gnutar-lists' '--mandir=/opt/amanda/man'
'--with-buffered-dump' '--without-rundump'"
amandad: time 0.001: paths: bindir="/opt/amanda/bin" sbindir="/opt/amanda/sbin"
amandad: time 0.001: libexecdir="/opt/amanda/libexec"
mandir="/opt/amanda/man"
amandad: time 0.001: AMANDA_TMPDIR="/tmp/amanda"
AMANDA_DBGDIR="/tmp/amanda"
amandad: time 0.001: CONFIG_DIR="/opt/amanda/etc" DEV_PREFIX="/dev/"
amandad: time 0.001: RDEV_PREFIX="/dev/r" DUMP="/sbin/dump"
amandad: time 0.001: RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF
amandad: time 0.001: XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF
VXRESTORE=UNDEF
amandad: time 0.001: SAMBA_CLIENT="/usr/local/bin/smbclient"
amandad: time 0.001: GNUTAR="/usr/local/bin/gtar"
COMPRESS_PATH="/usr/bin/gzip"
amandad: time 0.001: UNCOMPRESS_PATH="/usr/bin/gzip"
LPRCMD="/usr/bin/lpr"
amandad: time 0.001: MAILER="/usr/bin/Mail"
amandad: time 0.001: listed_incr_dir="/opt/amanda/var/gnutar-lists"
amandad: time 0.001: defs: DEFAULT_SERVER="amanda.meadwestvaco.com"
amandad: time 0.001: DEFAULT_CONFIG="DailyDump"
amandad: time 0.001: DEFAULT_TAPE_SERVER="amanda.meadwestvaco.com"
amandad: time 0.001: DEFAULT_TAPE_DEVICE="chg-multi" HAVE_MMAP
NEED_STRSTR
amandad: time 0.001: HAVE_SYSVSHM LOCKING=**NONE** DEBUG_CODE
AMANDA_DEBUG_DAYS=4
amandad: time 0.001: BSD_SECURITY RSH_SECURITY USE_AMANDAHOSTS
amandad: time 0.001: CLIENT_LOGIN="amanda" FORCE_USERID HAVE_GZIP
amandad: time 0.001: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
amandad: time 0.001: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
amandad: time 0.001: dgram_recv(dgram=0x298ad244, timeout=0,
fromaddr=0x298bd230)
amandad: time 0.001: (sockaddr_in *)0x298bd230 = { 2, 927, 10.209.129.22 }
amandad: time 0.001: security_handleinit(handle=0x81bbf800, driver=0x298a9240
(BSD))
amandad: time 0.006: accept recv REQ pkt:
<<<<<
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff7f;hostname=pb48;config=DailyDump;
DUMP wd0f 1 2009:8:20:2:48:55 OPTIONS |;auth=BSD;index;
>>>>>
amandad: time 0.006: creating new service: sendbackup
OPTIONS features=ffffffff9ffeffffffff7f;hostname=pb48;config=DailyDump;
DUMP wd0f 1 2009:8:20:2:48:55 OPTIONS |;auth=BSD;index;
amandad: time 0.007: sending ACK pkt:
<<<<<
>>>>>
amandad: time 0.007: dgram_send_addr(addr=0x81bbf820, dgram=0x298ad244)
amandad: time 0.007: (sockaddr_in *)0x81bbf820 = { 2, 927, 10.209.129.22 }
amandad: time 0.007: dgram_send_addr: 0x298ad244->socket = 0
amandad: time 0.013: security_streaminit(stream=0x86b67000, driver=0x298a9240
(BSD))
amandad: time 0.013: try_socksize: send buffer size is 65536
amandad: time 0.013: try_socksize: receive buffer size is 65536
amandad: time 0.014: stream_server: waiting for connection: ::.11996
amandad: time 0.014: security_streaminit(stream=0x81212000, driver=0x298a9240
(BSD))
amandad: time 0.014: try_socksize: send buffer size is 65536
amandad: time 0.014: try_socksize: receive buffer size is 65536
amandad: time 0.014: stream_server: waiting for connection: ::.45433
amandad: time 0.014: security_streaminit(stream=0x84877000, driver=0x298a9240
(BSD))
amandad: time 0.014: try_socksize: send buffer size is 65536
amandad: time 0.014: try_socksize: receive buffer size is 65536
amandad: time 0.014: stream_server: waiting for connection: ::.31908
amandad: time 0.014: sending REP pkt:
<<<<<
CONNECT DATA 11996 MESG 45433 INDEX 31908
OPTIONS features=ffffffff9ffeffffffff00;
>>>>>
amandad: time 0.014: dgram_send_addr(addr=0x81bbf820, dgram=0x298ad244)
amandad: time 0.014: (sockaddr_in *)0x81bbf820 = { 2, 927, 10.209.129.22 }
amandad: time 0.014: dgram_send_addr: 0x298ad244->socket = 0
amandad: time 0.014: dgram_recv(dgram=0x298ad244, timeout=0,
fromaddr=0x298bd230)
amandad: time 0.014: (sockaddr_in *)0x298bd230 = { 2, 927, 10.209.129.22 }
amandad: time 0.014: received ACK pkt:
<<<<<
>>>>>
amandad: time 30.019: stream_accept: timeout after 30 seconds
amandad: time 30.019: security_stream_seterr(0x86b67000, can't accept new
stream connection: No such file or directory)
amandad: time 30.019: stream 0 accept failed: unknown protocol error
amandad: time 30.019: security_stream_close(0x86b67000)
amandad: time 60.027: stream_accept: timeout after 30 seconds
amandad: time 60.027: security_stream_seterr(0x81212000, can't accept new
stream connection: No such file or directory)
amandad: time 60.027: stream 1 accept failed: unknown protocol error
amandad: time 60.027: security_stream_close(0x81212000)
amandad: time 90.035: stream_accept: timeout after 30 seconds
amandad: time 90.036: security_stream_seterr(0x84877000, can't accept new
stream connection: No such file or directory)
amandad: time 90.036: stream 2 accept failed: unknown protocol error
amandad: time 90.036: security_stream_close(0x84877000)
amandad: time 90.036: security_close(handle=0x81bbf800, driver=0x298a9240 (BSD))
amandad: time 120.044: pid 17702 finish time Fri Aug 21 10:39:27 2009
