Amanda-Users

data timeout error

2005-08-23 10:53:59
Subject: data timeout error
From: Guy Dallaire <clepeterd AT gmail DOT com>
To: amanda-users AT amanda DOT org
Date: Tue, 23 Aug 2005 10:35:46 -0400
I still have a data timeout error for a DLE in my amanda log this morning.

This is the second time this happens and this DLE is very important
for us. It has to be backed up correctly.

I've looked in the sendbackup.debug files on the client side and there
is no error for this DLE.

I asked the network admin to check in the firewall (CheckPoint FW-1)
logs to see anything unusual between the tape server and the client,
he spotted this:

TCP packet out of state: First packet isn't SYN

I don't know if it is related. I don't know where else to look. There
does not seem to be any error message on the server either.

I also increased the dtimeout from 1800 to 2400 but that does not seem
to be the problem, in the sendbackup.debug for the DLE in question you
see that it takes more than 1800 secs to complete (it took 5945.296
secs to complete), I think the dtimeout must be an "idle time" limit.

Here is the sendbackup.debug:

sendbackup: debug 1 pid 6921 ruid 555 euid 555: start at Tue Aug 23 04:40:36 200
5
/usr/local/libexec/sendbackup: version 2.4.5
  parsed request as: program `GNUTAR'
                     disk `/disk1'
                     device `/disk1'
                     level 1
                     since 2005:8:20:8:37:56
                     options `|;bsd-auth;srvcomp-best;index;exclude-list=.amanda
.excludes;exclude-optional;'
sendbackup: try_socksize: send buffer size is 65536
sendbackup: time 0.000: stream_server: waiting for connection: 0.0.0.0.50090
sendbackup: time 0.000: stream_server: waiting for connection: 0.0.0.0.50091
sendbackup: time 0.001: stream_server: waiting for connection: 0.0.0.0.50092
sendbackup: time 0.001: waiting for connect on 50090, then 50091, then 50092
sendbackup: time 0.008: stream_accept: connection from 192.197.124.40.50070
sendbackup: time 0.012: stream_accept: connection from 192.197.124.40.50071
sendbackup: time 0.015: stream_accept: connection from 192.197.124.40.50072
sendbackup: time 0.015: got all connections
sendbackup-gnutar: time 0.058: doing level 1 dump as listed-incremental from /us
r/local/var/amanda/gnutar-lists/sol_disk1_0 to /usr/local/var/amanda/gnutar-list
s/sol_disk1_1.new
sendbackup-gnutar: time 0.074: doing level 1 dump from date: 2005-08-20  8:37:56
 GMT
sendbackup: time 0.077: spawning /usr/local/libexec/runtar in pipeline
sendbackup: argument list: gtar --create --file - --directory /disk1 --one-file-
system --listed-incremental /usr/local/var/amanda/gnutar-lists/sol_disk1_1.new -
-sparse --ignore-failed-read --totals --exclude-from /tmp/amanda/sendbackup._dis
k1.20050823044036.exclude .
sendbackup-gnutar: time 0.078: /usr/local/libexec/runtar: pid 6924
sendbackup: time 0.079: started index creator: "/usr/local/bin/tar -tf - 2>/dev/
null | sed -e 's/^\.//'"
sendbackup: time 5945.219: index created successfully
sendbackup: time 5945.253:  53:    size(|): Total bytes written: 5450424320 (5.1
GiB, 896KiB/s)
sendbackup: time 5945.296: pid 6921 finish time Tue Aug 23 06:19:41 2005


<Prev in Thread] Current Thread [Next in Thread>