[Dovecot] master request not found
I am setting up dovecot 1.0-rc15 as a proxy. When I try to log in, I get errors such as the following:
Dec 4 17:39:23 vega dovecot: Dovecot v1.0.rc15 starting up Dec 4 17:39:24 vega dovecot: auth-worker(default): mysql: Connected to localhost (ad_mail) Dec 4 17:39:31 vega dovecot: auth(default): client in: AUTH 1 PLAINservice=POP3 lip=xx.xx.xx.xx rip=xx.xx.xx.xx resp=<hidden> Dec 4 17:39:31 vega dovecot: auth(default): client out: CONT 1 Dec 4 17:39:31 vega dovecot: auth(default): client in: CONT<hidden> Dec 4 17:39:31 vega dovecot: auth-worker(default): sql(XXXX,xx.xx.xx.xx: query: SELECT NULL AS password, host, destuser, 'Y' AS proxy FROM exchange_proxy WHERE user='XXXX' Dec 4 17:39:31 vega dovecot: auth(default): client out: OK 1 user=XXXX host=XXXX destuser=newrock.marshal@acd.net proxy pass=<hidden> Dec 4 17:39:31 vega dovecot: auth(default): master in: REQUEST 1 171931 Dec 4 17:39:31 vega dovecot: auth(default): Master request 17193.1 not found Dec 4 17:39:31 vega dovecot: auth(default): master out: NOTFOUND 1 Dec 4 17:39:31 vega dovecot: pop3-login: Internal login failure: user=<XXXX>, method=PLAIN, rip=xx.xx.xx.xx, lip=xx.xx.xx.xx
dovecot.conf contains: auth default { mechanisms = plain login passdb sql { args = /etc/dovecot/dovecot-sql.conf } userdb static { args = uid=0 gid=0 } user = nobody }
In dovecot-sql.conf: password_query = SELECT NULL AS password, host, destuser, 'Y' AS proxy FROM exchange_proxy WHERE user='%u'
I have verified that I can log into the remote server from the proxy via pop3. Unfortunately, there isn't currently imap access to the remote server. I am connecting to the proxy using pop3 (with "telnet localhost 110").
Any suggestions what the problem could be?
-- Marshal Newrock, Ideal Solution LLC http://www.idealso.com
On 5.12.2006, at 0.49, Marshal Newrock wrote:
Dec 4 17:39:31 vega dovecot: auth(default): Master request 17193.1
not found Dec 4 17:39:31 vega dovecot: auth(default): master out: NOTFOUND 1 Dec 4 17:39:31 vega dovecot: pop3-login: Internal login failure: user=<XXXX>, method=PLAIN, rip=xx.xx.xx.xx, lip=xx.xx.xx.xx
If this happens consistently, I think there's something wrong in your
system. What OS are you using, and do you use some weird kernel
modifications?
The 17193 should be the process ID of the pop3-login process. I'm
guessing it's not. You could add pid=%p to login_log_format_elements
to see if this is the case.
I've heard earlier also that some kernels make fork() return a
different PID than what the child process's getpid() function
returns. Dovecot really doesn't like that.
On Tue, 5 Dec 2006 01:04:16 +0200 Timo Sirainen <tss@iki.fi> wrote:
On 5.12.2006, at 0.49, Marshal Newrock wrote:
Dec 4 17:39:31 vega dovecot: auth(default): Master request 17193.1 not found Dec 4 17:39:31 vega dovecot: auth(default): master out: NOTFOUND 1 Dec 4 17:39:31 vega dovecot: pop3-login: Internal login failure: user=<XXXX>, method=PLAIN, rip=xx.xx.xx.xx, lip=xx.xx.xx.xx
If this happens consistently, I think there's something wrong in your system. What OS are you using, and do you use some weird kernel
modifications?
OS is Gentoo Linux, using the gentoo-sources kernel. patches listed at http://dev.gentoo.org/~dsd/genpatches/patches-2.6.18-3.htm though I don't see anything which would drastically change the behavior of the kernel.
I could put a 2.6.17 kernel in place if you think this would make a difference.
The 17193 should be the process ID of the pop3-login process. I'm
guessing it's not. You could add pid=%p to login_log_format_elements
to see if this is the case.
The number given in the "master out: NOTFOUND" error message is the same as the pid reported by adding pid=%p to login_log_format_elements. If I set login_process_per_connection = no, then pop3-login appears in the process list with the stated uid.
I've heard earlier also that some kernels make fork() return a
different PID than what the child process's getpid() function
returns. Dovecot really doesn't like that.
-- Marshal Newrock Ideal Solution, LLC - http://www.idealso.com
On Mon, 2006-12-04 at 17:49 -0500, Marshal Newrock wrote:
Dec 4 17:39:31 vega dovecot: auth(default): Master request 17193.1 not found Dec 4 17:39:31 vega dovecot: auth(default): master out: NOTFOUND 1 Dec 4 17:39:31 vega dovecot: pop3-login: Internal
Did this problem get fixed in later rcs?
On Sat, 17 Feb 2007 14:30:04 +0200 Timo Sirainen <tss@iki.fi> wrote:
On Mon, 2006-12-04 at 17:49 -0500, Marshal Newrock wrote:
Dec 4 17:39:31 vega dovecot: auth(default): Master request 17193.1 not found Dec 4 17:39:31 vega dovecot: auth(default): master out: NOTFOUND 1 Dec 4 17:39:31 vega dovecot: pop3-login: Internal
Did this problem get fixed in later rcs?
As I recall, this was caused by "nologin" being required as part of the password query, which was quickly fixed. I just tested version 1.0.rc22, and it works without "nologin".
-- Marshal Newrock Ideal Solution, LLC - http://www.idealso.com
participants (2)
-
Marshal Newrock
-
Timo Sirainen