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