Amanda-Users

Re: request failed: timeout waiting for ACK

2006-03-01 03:43:34
Subject: Re: request failed: timeout waiting for ACK
From: Stefan Herrmann <magic99de AT web DOT de>
To: Amanda Users <amanda-users AT amanda DOT org>
Date: Wed, 1 Mar 2006 09:25:46 +0100
Am 23.02.2006 um 16:15 schrieb Paul Bijnens:
On 2006-02-23 15:54, Stefan Herrmann wrote:
i think there was no useful information in the leftout part, but look for yourself:

Yes indeed.  No REQ packet at all.
Are you sure this debug file is the result from a amdump request,
and not one of those that were generated by all different commands
to solve this strange problem?
e.g. starting amandad from the command line, gives exactly the
same output ?

yes i am sure, this was from the amdump run...

Are the datestamps consistent with the amdump.1 file?

Another thing to use a network packet dumper to see if the packet
got dropped/lost somewhere.
Both on the server and the client, and verify if the client receives
what the server sends.

   tcpdump -X -s 1500 udp and port 10080

that's the email i got from the last amdump:

These dumps were to tape hourly025.
The next 2 tapes Amanda expects to use are: a new tape, a new tape.

FAILURE AND STRANGE DUMP SUMMARY:
pille.hq.imos.net /usr lev 1 FAILED [cannot read header: got 0 instead of 32768]
  pille.hq.imos.net  /usr  lev 1  FAILED [too many dumper retry]
pille.hq.imos.net /usr lev 1 FAILED [cannot read header: got 0 instead of 32768] pille.hq.imos.net /var lev 1 FAILED [cannot read header: got 0 instead of 32768]
  pille.hq.imos.net  /var  lev 1  FAILED [too many dumper retry]
pille.hq.imos.net /var lev 1 FAILED [cannot read header: got 0 instead of 32768] pille.hq.imos.net / lev 0 FAILED [cannot read header: got 0 instead of 32768]
  pille.hq.imos.net  /     lev 0  FAILED [too many dumper retry]
pille.hq.imos.net / lev 0 FAILED [cannot read header: got 0 instead of 32768] pille.hq.imos.net /opt lev 1 FAILED [cannot read header: got 0 instead of 32768]
  pille.hq.imos.net  /opt  lev 1  FAILED [too many dumper retry]
pille.hq.imos.net /opt lev 1 FAILED [cannot read header: got 0 instead of 32768]


STATISTICS:
                          Total       Full      Incr.
                        --------   --------   --------
Estimate Time (hrs:min)    0:02
Run Time (hrs:min)         0:06
Dump Time (hrs:min)        0:00       0:00       0:00
Output Size (meg)           0.0        0.0        0.0
Original Size (meg)         0.0        0.0        0.0
Avg Compressed Size (%)     --         --         --
Filesystems Dumped            0          0          0
Avg Dump Rate (k/s)         --         --         --

Tape Time (hrs:min)        0:00       0:00       0:00
Tape Size (meg)             0.0        0.0        0.0
Tape Used (%)               0.0        0.0        0.0
Filesystems Taped             0          0          0

Chunks Taped                  0          0          0
Avg Tp Write Rate (k/s)     --         --         --

USAGE BY TAPE:
  Label           Time      Size      %    Nb    Nc
  hourly025       0:00        0M    0.0     0     0


NOTES:
  planner: Adding new disk pille.hq.imos.net:/.
  taper: tape hourly025 kb 0 fm 0 [OK]


DUMP SUMMARY:
DUMPER STATS TAPER STATS HOSTNAME DISK L ORIG-MB OUT-MB COMP% CRYPT% MMM:SS KB/s MMM:SS KB/s -------------------------- ---------------------------------------- ------------ pille.hq.imo / 0 FAILED ---------------------------------------------- pille.hq.imo /opt 1 FAILED ---------------------------------------------- pille.hq.imo /usr 1 FAILED ---------------------------------------------- pille.hq.imo /var 1 FAILED ----------------------------------------------

(brought to you by Amanda version 2.5.0b2)



and i did also the tcpdump test. after the estimates the amanda server contacts the client but doesnt
get an answer. this is the last packet:

12:52:33.488055 IP amanda.hq.imos.net.909 > pille.hq.imos.net.amanda: UDP, length 261 0x0000: 4500 0121 0017 4000 4011 8af1 c0a8 96ce E..!..@.@....... 0x0010: c0a8 96a4 038d 2760 010d e3d1 416d 616e ......'`....Aman 0x0020: 6461 2032 2e35 2052 4551 2048 414e 444c da.2.5.REQ.HANDL 0x0030: 4520 3030 302d 3030 3030 3030 3037 2053 E.000-00000007.S 0x0040: 4551 2031 3134 3130 3634 3734 380a 5345 EQ.1141064748.SE 0x0050: 4355 5249 5459 2055 5345 5220 616d 616e CURITY.USER.aman 0x0060: 6461 0a53 4552 5649 4345 2073 656e 6462 da.SERVICE.sendb 0x0070: 6163 6b75 700a 4f50 5449 4f4e 5320 6665 ackup.OPTIONS.fe 0x0080: 6174 7572 6573 3d66 6666 6666 6566 6639 atures=fffffeff9 0x0090: 6666 6566 6666 6630 333b 686f 7374 6e61 ffeffff03;hostna 0x00a0: 6d65 3d70 696c 6c65 2e68 712e 696d 6f73 me=pille.hq.imos 0x00b0: 2e6e 6574 3b0a 474e 5554 4152 202f 6f70 .net;.GNUTAR./op 0x00c0: 7420 2031 2032 3030 363a 323a 3232 3a38 t..1.2006:2:22:8 0x00d0: 3a34 373a 3137 204f 5054 494f 4e53 207c :47:17.OPTIONS.| 0x00e0: 3b61 7574 683d 4253 443b 696e 6465 783b ;auth=BSD;index; 0x00f0: 6578 636c 7564 652d 6c69 7374 3d2f 7573 exclude-list=/us 0x0100: 722f 6c6f 6361 6c2f 616d 616e 6461 2f6c r/local/amanda/l 0x0110: 6962 2f65 7863 6c75 6465 2e67 7461 723b ib/exclude.gtar;
        0x0120:  0a


bye
Stefan Herrmann


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