Delay before key is serverd

[Dick Middleton]

Hi,

   I have a problem with a long delay between my client prompting from the
passcode and the mandos server responding.   The delay seems to be around
75..90s consistently.

With debug on the server I can see the server starts and goes quiet once
zeroconf is started.  I start the client which prompts after 10s or so but
there is no response from the server.

The next thing that happens is the checker is started (although I'm not sure
it's relevant) and then it all works.  The checker interval is the default
PT2M (2 minutes).

The checker I use is either "sleep 1" or "host <hostname>" but it makes no
difference.

I attach a segment of server log to illustrate.

Mnndos serves the key quickly when tested from the client using mandos-client
from the CLI. This problem is confined to client boot.

What I can't work out is whether this is a network issue or mandos client or
server is holding off for something.

Client and server version 1.8.9.  Client is Debian but server is Arch.

Any suggestions appreciated.

Dick

-------------- next part --------------
Jan 30 07:28:16 mimas mandos[17070]: 2020-01-30 07:28:16,712 root [17070]: DEBUG: Avahi entry group state change: 2
Jan 30 07:28:16 mimas mandos[17070]: Mandos [17070]: DEBUG: Zeroconf service established.
Jan 30 07:28:16 mimas mandos[17070]: 2020-01-30 07:28:16,714 root [17070]: DEBUG: Zeroconf service established.

Jan 30 07:30:15 mimas mandos[17070]: Mandos [17070]: INFO: Starting checker '/usr/bin/host penguin\\.lingbrae' for penguin
Jan 30 07:30:15 mimas mandos[17070]: 2020-01-30 07:30:15,909 root [17070]: INFO: Starting checker '/usr/bin/host penguin\\.lingbrae' fo
r penguin
Jan 30 07:30:15 mimas mandos[17103]: penguin.lingbrae has address 192.168.46.100
Jan 30 07:30:15 mimas mandos[17070]: Mandos [17070]: INFO: Checker for
penguin succeeded
Jan 30 07:30:15 mimas mandos[17070]: 2020-01-30 07:30:15,956 root [17070]:
INFO: Checker for penguin succeeded

Jan 30 07:30:23 mimas mandos[17108]: Mandos [17108]: INFO: TCP connection from: ('fe80::1e1b:dff:fe94:eeb0', 39296, 0, 2)
Jan 30 07:30:23 mimas mandos[17108]: Mandos [17108]: DEBUG: Pipe FD: 14
Jan 30 07:30:23 mimas mandos[17108]: 2020-01-30 07:30:23,389 root [17108]: INFO: TCP connection from: ('fe80::1e1b:dff:fe94:eeb0', 3929
6, 0, 2)
Jan 30 07:30:23 mimas mandos[17108]: 2020-01-30 07:30:23,390 root [17108]: DEBUG: Pipe FD: 14
Jan 30 07:30:23 mimas mandos[17108]: Mandos [17108]: WARNING: /usr/lib/python3.8/logging/__init__.py:369: BytesWarning: str() on a byte
s instance