PDA

View Full Version : Remote backup just seems to miss the mark



wcteskey
April 22nd, 2007, 01:37 PM
Hello forum:

This is what is successful. I can back up from the server "LOCAL" to linux machines on the local network no problem. I installed a server on the remote client and I can backup on the remote machine "REMOTE" to itself no problem.

I cannot back up from the "LOCAL" to the remote.

Here is my documentation taken from the debug logs and tcpdump 'port 900 or port 50000 or port 10080' done both on "LOCAL" and "REMOTE".

I run amcheck on "LOCAL" and I get the following debug log:

amcheck: debug 1 pid 18736 ruid 0 euid 0: start at Sun Apr 22 13:57:19 2007
amcheck: debug 1 pid 18736 ruid 0 euid 0: rename at Sun Apr 22 13:57:19 2007
security_getdriver(name=BSD) returns 0xb7f8e020
security_handleinit(handle=0x805ede8, driver=0xb7f8e020 (BSD))
amcheck-clients: time 0.001: bind_portrange2: Try port 900: Available - Success
amcheck-clients: time 0.001: dgram_bind: socket bound to 0.0.0.0.900
amcheck-clients: dgram_send_addr(addr=0xbfff5cc0, dgram=0xb7f8f024)
amcheck-clients: time 0.003: (sockaddr_in *)0xbfff5cc0 = { 2, 10080, the-remotemachine-ipaddr}
amcheck-clients: dgram_send_addr: 0xb7f8f024->socket = 4
changer_query: changer return was 9 1
changer_query: searchable = 0
changer_find: looking for NULL changer is searchable = 0
amcheck-clients: time 0.089: dgram_recv(dgram=0xb7f8f024, timeout=0, fromaddr=0xb7f9f010)
amcheck-clients: time 0.089: (sockaddr_in *)0xb7f9f010 = { 2, 10080, the-remotemachine-ipaddr}
amcheck-clients: time 0.089: dgram_recv(dgram=0xb7f8f024, timeout=0, fromaddr=0xb7f9f010)
amcheck-clients: time 0.089: (sockaddr_in *)0xb7f9f010 = { 2, 10080, the-remotemachine-ipaddr}
amcheck-clients: dgram_send_addr(addr=0xbfff5bd0, dgram=0xb7f8f024)
amcheck-clients: time 10.087: (sockaddr_in *)0xbfff5bd0 = { 2, 10080, the-remotemachine-ipaddr}
amcheck-clients: dgram_send_addr: 0xb7f8f024->socket = 4
amcheck-clients: time 10.087: dgram_recv(dgram=0xb7f8f024, timeout=0, fromaddr=0xb7f9f010)
amcheck-clients: time 10.087: (sockaddr_in *)0xb7f9f010 = { 2, 10080, the-remotemachine-ipaddr}
amcheck-clients: time 10.166: dgram_recv(dgram=0xb7f8f024, timeout=0, fromaddr=0xb7f9f010)
amcheck-clients: time 10.166: (sockaddr_in *)0xb7f9f010 = { 2, 10080, the-remotemachine-ipaddr}
amcheck-clients: dgram_send_addr(addr=0xbfff5bd0, dgram=0xb7f8f024)
amcheck-clients: time 20.164: (sockaddr_in *)0xbfff5bd0 = { 2, 10080, the-remotemachine-ipaddr}
amcheck-clients: dgram_send_addr: 0xb7f8f024->socket = 4
amcheck-clients: time 20.164: dgram_recv(dgram=0xb7f8f024, timeout=0, fromaddr=0xb7f9f010)
amcheck-clients: time 20.164: (sockaddr_in *)0xb7f9f010 = { 2, 10080, the-remotemachine-ipaddr}
amcheck-clients: time 20.260: dgram_recv(dgram=0xb7f8f024, timeout=0, fromaddr=0xb7f9f010)
amcheck-clients: time 20.260: (sockaddr_in *)0xb7f9f010 = { 2, 10080, the-remotemachine-ipaddr}
security_seterror(handle=0x805ede8, driver=0xb7f8e020 (BSD) error=timeout waiting for ACK)
security_close(handle=0x805ede8, driver=0xb7f8e020 (BSD))
amcheck: pid 18736 finish time Sun Apr 22 13:57:49 2007

################ END OF LOCAL DEBUG LOG ###########

HERE IS TCPDUMP from LOCAL which is called 192.168.0.2 to
theremotemachine.com

reading from file local, link-type EN10MB (Ethernet)
13:57:19.705417 IP 192.168.0.2.900 > smtp.theremotemachine.com.amanda: UDP, length 121
13:57:19.791466 IP s235.theremotemachine.com.amanda > 192.168.0.2.900: UDP, length 50
13:57:19.791701 IP s235.theremotemachine.com.amanda > 192.168.0.2.900: UDP, length 89
13:57:29.789997 IP s235.theremotemachine.com.amanda > 192.168.0.2.900: UDP, length 89
13:57:29.790146 IP 192.168.0.2.900 > smtp.theremotemachine.com.amanda: UDP, length 121
13:57:29.868831 IP s235.theremotemachine.com.amanda > 192.168.0.2.900: UDP, length 89
13:57:39.866689 IP s235.theremotemachine.com.amanda > 192.168.0.2.900: UDP, length 89
13:57:39.866846 IP 192.168.0.2.900 > smtp.theremotemachine.com.amanda: UDP, length 121
13:57:39.962240 IP s235.theremotemachine.com.amanda > 192.168.0.2.900: UDP, length 89
13:57:49.961351 IP s235.theremotemachine.com.amanda > 192.168.0.2.900: UDP, length 89
13:57:59.960142 IP s235.theremotemachine.com.amanda > 192.168.0.2.900: UDP, length 89

END OF TCPDUMP*****


NOW FOR THE REMOTE GUY HERE IS THE DEBUG FILE


amandad: debug 1 pid 24167 ruid 0 euid 0: start at Sun Apr 22 13:57:19 2007
security_getdriver(name=bsd) returns 0xb7fc1020
amandad: version 2.5.1p3
amandad: build: VERSION="Amanda-2.5.1p3"
amandad: BUILT_DATE="Fri Apr 20 13:59:57 PDT 2007"
amandad: BUILT_MACH="Linux ndc 2.6.11 #2 SMP Tue Jun 7 10:07:58 PDT 2005 i686 GNU/Linux"
amandad: CC="gcc"
amandad: CONFIGURE_COMMAND="'./configure' '--with-user=root' '--with-group=root' '--with-gnutar=/usr/local/bin/tar' '--with-gnutar-listdir=/usr/local/var/amanda/gnutar-lists' '--with-portrange=50000,50005' '--with-udpportrange=900,900'"
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/"
amandad: 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"
amandad: GNUTAR="/usr/local/bin/tar" COMPRESS_PATH="/bin/gzip"
amandad: UNCOMPRESS_PATH="/bin/gzip" LPRCMD=UNDEF
amandad: MAILER="/usr/bin/Mail"
amandad: listed_incr_dir="/usr/local/var/amanda/gnutar-lists"
amandad: defs: DEFAULT_SERVER="ndc" DEFAULT_CONFIG="DailySet1"
amandad: DEFAULT_TAPE_SERVER="ndc" 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="root" 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=0xb7fc2024, timeout=0, fromaddr=0xb7fd2010)
amandad: time 0.000: (sockaddr_in *)0xb7fd2010 = { 2, 900, the.local.machine.ip }
security_handleinit(handle=0x8050278, driver=0xb7fc1020 (BSD))
amandad: time 0.001: accept recv REQ pkt:
<<<<<
SERVICE noop
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: time 0.001: creating new service: noop
OPTIONS features=fffffeff9ffeffffff7f;

amandad: time 0.002: sending ACK pkt:
<<<<<
>>>>>
amandad: dgram_send_addr(addr=0xbfffe3b0, dgram=0xb7fc2024)
amandad: time 0.002: (sockaddr_in *)0xbfffe3b0 = { 2, 900, the.local.machine.ip }
amandad: dgram_send_addr: 0xb7fc2024->socket = 0
amandad: time 0.004: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: dgram_send_addr(addr=0xbfffe460, dgram=0xb7fc2024)
amandad: time 0.004: (sockaddr_in *)0xbfffe460 = { 2, 900, the.local.machine.ip }
amandad: dgram_send_addr: 0xb7fc2024->socket = 0
amandad: time 10.002: timeout
amandad: time 10.002: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: dgram_send_addr(addr=0xbfffe420, dgram=0xb7fc2024)
amandad: time 10.002: (sockaddr_in *)0xbfffe420 = { 2, 900, the.local.machine.ip }
amandad: dgram_send_addr: 0xb7fc2024->socket = 0
amandad: time 10.080: dgram_recv(dgram=0xb7fc2024, timeout=0, fromaddr=0xb7fd2010)
amandad: time 10.080: (sockaddr_in *)0xb7fd2010 = { 2, 900, the.local.machine.ip }
amandad: time 10.081: received REQ pkt:
<<<<<
SERVICE noop
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: time 10.081: received dup P_REQ packet, resending REP
amandad: time 10.081: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: dgram_send_addr(addr=0xbfffe3c0, dgram=0xb7fc2024)
amandad: time 10.081: (sockaddr_in *)0xbfffe3c0 = { 2, 900, the.local.machine.ip }
amandad: dgram_send_addr: 0xb7fc2024->socket = 0
amandad: time 20.079: timeout
amandad: time 20.079: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: dgram_send_addr(addr=0xbfffe420, dgram=0xb7fc2024)
amandad: time 20.079: (sockaddr_in *)0xbfffe420 = { 2, 900, the.local.machine.ip }
amandad: dgram_send_addr: 0xb7fc2024->socket = 0
amandad: time 20.175: dgram_recv(dgram=0xb7fc2024, timeout=0, fromaddr=0xb7fd2010)
amandad: time 20.175: (sockaddr_in *)0xb7fd2010 = { 2, 900, the.local.machine.ip }
amandad: time 20.175: received REQ pkt:
<<<<<
SERVICE noop
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: time 20.175: received dup P_REQ packet, resending REP
amandad: time 20.175: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: dgram_send_addr(addr=0xbfffe3c0, dgram=0xb7fc2024)
amandad: time 20.175: (sockaddr_in *)0xbfffe3c0 = { 2, 900, the.local.machine.ip }
amandad: dgram_send_addr: 0xb7fc2024->socket = 0
amandad: time 30.174: timeout
amandad: time 30.174: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: dgram_send_addr(addr=0xbfffe420, dgram=0xb7fc2024)
amandad: time 30.174: (sockaddr_in *)0xbfffe420 = { 2, 900, the.local.machine.ip }
amandad: dgram_send_addr: 0xb7fc2024->socket = 0
amandad: time 40.173: timeout
amandad: time 40.173: sending REP pkt:
<<<<<
OPTIONS features=fffffeff9ffeffffff7f;
>>>>>
amandad: dgram_send_addr(addr=0xbfffe420, dgram=0xb7fc2024)
amandad: time 40.173: (sockaddr_in *)0xbfffe420 = { 2, 900, the.local.machine.ip }
amandad: dgram_send_addr: 0xb7fc2024->socket = 0
amandad: time 50.172: timeout
amandad: time 50.172: timeout waiting for ACK for our REP
security_close(handle=0x8050278, driver=0xb7fc1020 (BSD))
amandad: time 60.170: pid 24167 finish time Sun Apr 22 13:58:19 2007

END OF DEBUG FILE *******

Now for the TCPDUMP remote *******
reading from file remote, link-type EN10MB (Ethernet)
13:57:19.737704 IP the-local-machine-ip.tukw.qwest.net.900 > mail.theremotemachine.com.amanda: UDP, length 121
13:57:19.744131 IP s235.theremotemachine.com.amanda > the-local-machine-ip.tukw.qwest.net.900: UDP, length 50
13:57:19.745743 IP s235.theremotemachine.com.amanda > the-local-machine-ip.tukw.qwest.net.900: UDP, length 89
13:57:29.744564 IP s235.theremotemachine.com.amanda > the-local-machine-ip.tukw.qwest.net.900: UDP, length 89
13:57:29.822365 IP the-local-machine-ip.tukw.qwest.net.900 > mail.theremotemachine.com.amanda: UDP, length 121
13:57:29.822673 IP s235.theremotemachine.com.amanda > the-local-machine-ip.tukw.qwest.net.900: UDP, length 89
13:57:39.821436 IP s235.theremotemachine.com.amanda > the-local-machine-ip.tukw.qwest.net.900: UDP, length 89
13:57:39.916927 IP the-local-machine-ip.tukw.qwest.net.900 > mail.theremotemachine.com.amanda: UDP, length 121
13:57:39.917240 IP s235.theremotemachine.com.amanda > the-local-machine-ip.tukw.qwest.net.900: UDP, length 89
13:57:49.915961 IP s235.theremotemachine.com.amanda > the-local-machine-ip.tukw.qwest.net.900: UDP, length 89

There are no firewalls that I know of and know ipchains or
ipfilters in the way. The "LOCAL" computer exists in the DMZ
and I am lost.

wcteskey
May 3rd, 2007, 10:56 AM
Well it looks likes sending it to the smtp.theremotemachine.com. and
receiving it from the s235.theremotemachine.com was the problem.
When I sent the request to the remote machine it sent it *.*.*.233
but it received it on *.*.*.235. Woops it just missed the mark. So
I defined in /etc/hosts a 235remotemachione *.*.*.235 and lo and
behold it all worked.

Thanks all for your help.