[Dovecot] Timeout while logging in

[Kevin Richter]

Timo Sirainen schrieb:
> On Mon, 2007-03-19 at 19:10 +0100, Kevin Richter wrote:
>> 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 bet this is an NFS problem too. It hangs there in chdir() dir. That's
> the only possibility I can think of. What happens with the attached
> patch? It should log a warning if chdir() takes more than 10 seconds,
> and if it takes more than 30 seconds it fallbacks to /tmp.
> 
>> dovecot: Feb 27 19:01:08 Warning: pop3-login: SSL_accept() syscall
>> failed: Connection reset by peer [x.x.x.x]
>> dovecot: Mar 07 11:33:45 Warning: pop3-login: SSL_read() syscall failed:
>> Connection timed out [x.x.x.x]
> 
> These are normal. It just means the connection died unexpectedly.
> verbose_ssl=yes logs all kinds of useless stuff.
> 
>> dovecot: Mar 13 15:05:41 Fatal: chdir(/home/ssal0041) failed with uid
>> 3092: Permission denied
> 
> Maybe related to the first problem. :)
> 


There is now this new "blocked for xx secs" line.

dovecot: Mar 22 19:08:10 Info: pop3-login: Login: user=<ssal0041>,
method=PLAIN, rip=x.x.x.x, lip=x.x.x.x, TLS
dovecot: Mar 22 19:08:30 Warning: chdir(/home/ssal0041) blocked for 20 secs
dovecot: Mar 22 19:08:30 Fatal: chdir(/home/ssal0041) failed with uid
3092: Permission denied
dovecot: Mar 22 19:08:30 Error: child 12829 (pop3) returned error 89

Can you or anyone else explain this problem?
Why are homedirs blocked?
Why does it say that the permission is denied?

A "ls -ld /home/ssal0041" gives the owner ssal0041.
A "ls -ldn /home/ssal0041" gives the owner id 3092.
This is correct.

What can I do now?
Can I debug?
And what do i have to debug?


In half of the cases the permission is ok:

dovecot: Mar 22 20:08:33 Warning: chdir(/home/ssal0041) blocked for 22 secs
dovecot: Mar 22 20:08:33 Info: execing mail process

or

dovecot: Mar 22 20:38:39 Warning: chdir(/home/ssal0041) blocked for 28 secs
dovecot: Mar 22 20:38:39 Info: execing mail process