On 10.4.2007, at 19.30, Jason Warner wrote:
< write(6, "VERSION\t1\t0\nUSER\t1\tuser1@domain"..., 55) = 55 ..
write(6, "VERSION\t1\t0\nUSER\t1\tuser2@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@mail.domain.com Apr 10 10:12:14 mail postfix/qmgr[452]: A7F1B168734: from=user@domain.com, size=2600, nrcpt=1 (queue active) Apr 10 10:12:14 mail postfix/smtp[464]: E4B351686E4: to=user@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@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@domain.com, size=2600, nrcpt=1 (queue active) Apr 10 10:13:55 mail postfix/pipe[890]: A7F1B168734: to=user@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.