My amanda installation has been working flawlessly for many years. It is currently amend 3.3.6 running on Fedora 20. In the last couple of weeks, one client has started to fail with the infamous error
Amanda Backup Client Hosts Check -------------------------------- WARNING: agree-10: selfcheck request failed: tcpm_recv_token: invalid size I initially assumed that this was permissions as the FAQ suggests but they all check out. On the assumption it was still permissions I enabled debug-auth for the client and it seems to think that the perms are OK. I have included the amandad log below. If anyone has any other suggestions as to how to troubleshoot or what to look for I would very much like some help. Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: bsd: processing line: <agree-10.xxxxxxx.xxx.nz amandabackup amdump> Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: bsd: comparing "agree-10.xxxxxxx.xxx.nz" with Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: bsd: "agree-10.xxxxxxx.xxx.nz" (match) Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: bsd: and "amandabackup" with Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: bsd: "amandabackup" (match) Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: authenticated peer name is 'agree-10.xxxxxxx.xxx.nz' Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: accept recv REQ pkt: <<<<< SERVICE noop OPTIONS features=ffffffff9efefbffffffffff3f; >>>>> Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: creating new service: noop OPTIONS features=ffffffff9efefbffffffffff3f; Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: sending ACK pkt: <<<<< >>>>> Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: sec: stream_sendpkt: enter Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: sec: stream_sendpkt: ACK (3) pkt_t (len 0) contains: "" Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: sec: stream_write: writing 2 bytes to agree-10.xxxxxxx.xxx.nz:2 1 Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: tcpm_send_token: data is still flowing Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: tcpm_send_token: write 2 bytes to handle 2 Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: sec: recvpkt registered for agree-10.xxxxxxx.xxx.nz Sat Feb 21 14:48:07 2015: thd-0x7f264e41ce00: amandad: sec: conn_read: incremented ev_read_refcnt to 2 for agree-10.xxxxxxx.xxx.nz Sat Feb 21 14:48:08 2015: thd-0x7f264e41ce00: amandad: sending REP pkt: <<<<< OPTIONS features=ffffffff9efefbffffffffff3f; >>>>> Sat Feb 21 14:48:08 2015: thd-0x7f264e41ce00: amandad: sec: stream_sendpkt: enter Sat Feb 21 14:48:08 2015: thd-0x7f264e41ce00: amandad: sec: stream_sendpkt: REP (1) pkt_t (len 45) contains: "OPTIONS features=ffffffff9efefbffffffffff3f; " Sat Feb 21 14:48:08 2015: thd-0x7f264e41ce00: amandad: sec: stream_write: writing 47 bytes to agree-10.xxxxxxx.xxx.nz:2 1 Sat Feb 21 14:48:08 2015: thd-0x7f264e41ce00: amandad: tcpm_send_token: write 47 bytes to handle 2 Sat Feb 21 14:48:08 2015: thd-0x7f264e41ce00: amandad: sec: recvpkt registered for agree-10.xxxxxxx.xxx.nz Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec: conn_read_callback 1 0 Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: tcpm_recv_token: A return(-1) Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec: conn_read_callback: tcpm_recv_token returned -1 Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec: stream_read_callback: handle 2 Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec: stream_read_callback: recv error: Connection reset by peer Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec: conn_read_cancel: decremented ev_read_refcnt to 1 for agree-10.xxxxxxx.xxx.nz Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: security_stream_seterr(0x7f264e427e40, recv error: Connection reset by peer) Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec_tcp_conn_put: decrementing refcnt for agree-10.xxxxxxx.xxx.nz to 1 Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec: recvpkt_callback: -1 Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec: cancelling recvpkt for agree-10.xxxxxxx.xxx.nz Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: security_seterror(handle=0x7f264e427c50, driver=0x7f264cb7b7c0 (BSDTCP) error=recv error: Connection reset by peer) Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: receive error: recv error: Connection reset by peer Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec: conn_read_callback: event_wakeup return 1 Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec_tcp_conn_put: decrementing refcnt for agree-10.xxxxxxx.xxx.nz to 0 Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: sec_tcp_conn_put: closing connection to agree-10.xxxxxxx.xxx.nz Sat Feb 21 14:48:14 2015: thd-0x7f264e41ce00: amandad: pid 29987 finish time Sat Feb 21 14:48:14 2015 -- =-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-= Glen Eustace, GodZone Internet Services, a division of AGRE Enterprises Ltd., P.O. Box 8020, Palmerston North, New Zealand 4446 Ph +64 6 357 8168 Fax +64 6 357 8165 Mob +64 27 542 4015 “Specialising in providing low-cost professional Internet since 1997"
