[Dovecot] Connection refused with auth-master afterupgradingto Dovecot 1.0 rc 28

[Jason Warner]

> On 10.4.2007, at 19.30, Jason Warner wrote:
> 
> > < write(6, "VERSION\t1\t0\nUSER\t1\tuser1 at domain"..., 55) = 55
> ..
> >> write(6, "VERSION\t1\t0\nUSER\t1\tuser2 at domain"..., 54) = 54
> 
> Your original error was: "net_connect(/var/run/dovecot/auth-master)
> failed: Connection refused". However here both of them were
> successfully connected to, and this shows that the authentication
> handshake was successfully sent. So I think it wrote a different
> error message to Dovecot's logs this time.

I'm afraid that I've tampered with stuff so much on this server that things
have changed from when I originally posted. 

Apr 10 10:12:14 mail postfix/smtpd[562]: A7F1B168734:
client=mail.domain.com[127.0.0.1]
Apr 10 10:12:14 mail postfix/cleanup[496]: A7F1B168734:
message-id=<20070410161207.E4B351686E4 at mail.domain.com>
Apr 10 10:12:14 mail postfix/qmgr[452]: A7F1B168734: from=<user at domain.com>,
size=2600, nrcpt=1 (queue active)
Apr 10 10:12:14 mail postfix/smtp[464]: E4B351686E4: to=<user at domain.com>,
relay=127.0.0.1[127.0.0.1]:10024, delay=7, delays=0.22/0/0.01/6.8,
dsn=2.6.0, status=sent (250 2.6.0 Ok, id=31657-06, from MTA: 250 2.0.0 Ok:
queued as A7F1B168734)
Apr 10 10:12:15 mail dovecot: auth(default): file auth-request.c: line 474
(auth_request_lookup_credentials_callback): assertion failed:
(request->state == AUTH_REQUEST_STATE_PASSDB)
Apr 10 10:12:15 mail dovecot: auth(default): Raw backtrace: dovecot-auth
[0x806bb91] -> dovecot-auth [0x806baac] -> dovecot-auth [0x80552ce] ->
dovecot-auth [0x805f2bd] -> dovecot-auth [0x805f684] -> dovecot-auth
[0x805906b] -> dovecot-auth(io_loop_handler_run+0x110) [0x806ef30] ->
dovecot-auth(io_loop_run+0x1c) [0x806e27c] -> dovecot-auth(main+0x2fe)
[0x805a51e] -> /lib/libc.so.6(__libc_start_main+0xdc) [0x673f2c] ->
dovecot-auth {0x8050ba1]
Apr 10 10:12:15 mail dovecot: child 31532 (auth) killed with signal 6
Apr 10 10:12:15 mail postfix/pipe[563]: A7F1B168734: to=<user at domain.com>,
relay=dovecot, delay=0.75, delays=0.24/0.06/0/0.45, dsn=4.3.0,
status=deferred (temporary failure)

This looks like the auth fails due to some error, but I'm not sure what.

Apr 10 10:13:55 mail postfix/qmgr[864]: A7F1B168734: from=<user at domain.com>,
size=2600, nrcpt=1 (queue active)
Apr 10 10:13:55 mail postfix/pipe[890]: A7F1B168734: to=<user at domain.com>,
relay=dovecot, delay=101, delays=101/0.02/0/0.11, dsn=2.0.0, status=sent
(delivered via dovecot service)
Apr 10 10:13:55 mail postfix/qmgr[864]: A7F1B168734: removed

At this point, it looks like there was a retry and the dovecot service
delivers the mail, but the mail disappears into thin air. The problem is
that I have a ton of mail flooding the server and errors are overwritten
before I can take a look at them. I wasn't even able to compare the same
mail being sent in the delivery logs because by the time I stopped the
logging more mail had come into the system.