[Dovecot] Timeout while logging in

[Kevin Richter]

Timo Sirainen schrieb:
> On Tue, 2007-03-06 at 21:05 +0100, Kevin Richter wrote:
>>
>> i tried it twice, but the "--log-error" throws an error to the log:
>>
>> dovecot: Mar 06 21:01:14 Info: Dovecot v1.0.rc24 starting up
>> dovecot: Mar 06 21:01:14 Fatal: This is Dovecot's error log
>>
>> there is no dovecot running after this. 
> 
> Yes, that was the point. I just wanted to know if the error would have
> been logged elsewhere..
> 
> How often does the timeouting happen? If you apply the attached patch,
> what does it log then when timeout happens?
> 


Now Ive got three delay issues. In these cases it wasnt a timeout, only
a big delay.

dovecot: Mar 12 13:28:54 Info: pop3-login: Login: user=<ssal0041>,
method=PLAIN, rip=x.x.x.x, lip=x.x.x.x, TLS
dovecot: Mar 12 13:29:49 Info: execing mail process
dovecot: Mar 12 13:29:49 Info: POP3(ssal0041): Effective uid=3092, gid=1100

dovecot: Mar 12 19:06:06 Info: pop3-login: Login: user=<ssal0041>,
method=PLAIN, rip=x.x.x.x, lip=x.x.x.x, TLS
dovecot: Mar 12 19:06:21 Info: execing mail process
dovecot: Mar 12 19:06:21 Info: POP3(ssal0041): Effective uid=3092, gid=1100

dovecot: Mar 13 14:45:21 Info: created child process 17704
dovecot: Mar 13 14:45:21 Info: pop3-login: Login: user=<ssal0041>,
method=PLAIN, rip=x.x.x.x, lip=x.x.x.x, TLS
dovecot: Mar 13 14:51:12 Info: execing mail process
dovecot: Mar 13 14:51:12 Info: execing mail process
dovecot: Mar 13 14:51:12 Info: POP3(ssal0041): Effective uid=3092, gid=1100

I cutted the rest in each case. Everything was normal till the
"Disconnected: Logged out" line.

The two "execing mail process" lines are in the log indeed.



Another issue sometimes:

dovecot: Feb 27 19:01:08 Warning: pop3-login: SSL_accept() syscall
failed: Connection reset by peer [x.x.x.x]
dovecot: Feb 27 19:01:08 Info: pop3-login: Disconnected: rip=x.x.x.x,
lip=x.x.x.x, TLS handshake

or

dovecot: Mar 07 11:33:45 Warning: pop3-login: SSL_read() syscall failed:
Connection timed out [x.x.x.x]
dovecot: Mar 07 11:33:45 Info: POP3(ssal0041): Disconnected top=0/0,
retr=1/2176, del=0/3, size=5670




And a third issue sometimes:

dovecot: Mar 13 15:05:21 Info: created child process 17717
dovecot: Mar 13 15:05:21 Info: pop3-login: Login: user=<ssal0041>,
method=PLAIN, rip=x.x.x.x, lip=x.x.x.x, TLS
dovecot: Mar 13 15:05:41 Fatal: chdir(/home/ssal0041) failed with uid
3092: Permission denied
dovecot: Mar 13 15:05:41 Error: child 17717 (pop3) returned error 89

The homedirs are mounted via NFSv4 from a fileserver. As to that I guess
its a NFS problem and not a dovecot problem.