I had the same problem and went back to 2.0.4, here is the lmtp log
(trace=5), due to this I lost 1 day
of mails
Ming-Wei
Ville Ahonen wrote:
Sorry, I don't know exactly what debugging info you want, but I can tell you
that the error started between revisions 1796 and 1797. In other words, 1797
bounces all my incoming mail, while 1796 works like a charm (the change has
occurred in the file dbmsgbuf.c). If you need more exact debug-data please let
me know what to do and I'll post the output.
- Ville
Quoting "Danil V. Gerun" <[EMAIL PROTECTED]>:
I guess, Paul (as always and as anybody else) would write that you'd
better post the debug info here.
The more - the better =))
Today I updated my server to the current head of the 2.0 branch. Until
now my server (last updated on 3 June) was functioning fine. After the
update, all my incoming mails were being bounced with the following
message in the log:
Jun 22 10:59:29 [postfix/lmtp] 26DDC64CF1:
to=<[EMAIL PROTECTED]>, relay=127.0.0.1[127.0.0.1], delay=0,
status=bounced (host 127.0.0.1[127.0.0.1] said: 550 Recipient
<[EMAIL PROTECTED]> FAIL (in reply to RCPT TO command))
AFAIK the compilation went fine without any noticable errors. After i
reverted to an earlier revision the server normally again. I'm not
sure at which exact revision the problem has started, but I suppose it
has to have been between 3 June and today..
- Ville
Jun 18 10:59:01 spaceball postfix/qmgr[7966]: 6FCBF2B1A96: removed
Jun 18 10:59:13 spaceball postfix/smtpd[12452]: connect from
unknown[192.168.0.3]
Jun 18 10:59:13 spaceball postfix/smtpd[12452]: 6EEDC2B1A98:
client=unknown[192.168.0.3]
Jun 18 10:59:13 spaceball postfix/cleanup[12453]: 6EEDC2B1A98:
message-id=<[EMAIL PROTECTED]>
Jun 18 10:59:13 spaceball postfix/smtpd[12452]: disconnect from
unknown[192.168.0.3]
Jun 18 10:59:13 spaceball postfix/qmgr[7966]: 6EEDC2B1A98: from=<[EMAIL
PROTECTED]>, size=594, nrcpt=1 (queue active)
Jun 18 10:59:13 spaceball postfix/smtpd[12456]: connect from
localhost[127.0.0.1]
Jun 18 10:59:13 spaceball postfix/smtpd[12456]: 8F4642B1A9B:
client=localhost[127.0.0.1]
Jun 18 10:59:13 spaceball postfix/cleanup[12453]: 8F4642B1A9B:
message-id=<[EMAIL PROTECTED]>
Jun 18 10:59:14 spaceball postfix/qmgr[7966]: 8F4642B1A9B: from=<[EMAIL
PROTECTED]>, size=956, nrcpt=1 (queue active)
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): incoming buffer: [RSET]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): command looked up as
commandtype 2
Jun 18 10:59:14 spaceball postfix/smtpd[12456]: disconnect from
localhost[127.0.0.1]
Jun 18 10:59:14 spaceball postfix/smtp[12454]: 6EEDC2B1A98: to=<[EMAIL
PROTECTED]>, relay=127.0.0.1[127.0.0.1], delay=1, status=sent (250 Ok: queued
as 8F4642B1A9B)
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): incoming buffer: [MAIL
FROM:<[EMAIL PROTECTED]> SIZE=956]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): command issued :cmd
[MAIL], value [FROM:<[EMAIL PROTECTED]> SIZE=956]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): command looked up as
commandtype 4
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: misc.c, find_bounded: Found
[EMAIL PROTECTED] of length [23] between '<' and '>' so next skip [29]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): incoming buffer: [RCPT
TO:<[EMAIL PROTECTED]>]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): command issued :cmd
[RCPT], value [TO:<[EMAIL PROTECTED]>]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): command looked up as
commandtype 9
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: misc.c, find_bounded: Found
[EMAIL PROTECTED] of length [23] between '<' and '>' so next skip [27]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_init: dsnuser
initialized
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user [EMAIL PROTECTED] in alias table
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dbmysql.c,db_query: executing
query [SELECT deliver_to FROM dbmail_aliases WHERE lower(alias) = lower('[EMAIL
PROTECTED]') AND lower(alias) <> lower(deliver_to)]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
into checking loop
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user [EMAIL PROTECTED] to 1
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user [1] in alias table
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dbmysql.c,db_query: executing
query [SELECT deliver_to FROM dbmail_aliases WHERE lower(alias) = lower('1')
AND lower(alias) <> lower(deliver_to)]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
user 1 not in aliases table
Jun 18 10:59:14 spaceball postfix/qmgr[7966]: 6EEDC2B1A98: removed
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_resolve: user
[EMAIL PROTECTED] found total of [0] aliases
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dbmysql.c,db_query: executing
query [SELECT user_idnr FROM dbmail_users WHERE userid='[EMAIL PROTECTED]']
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_resolve: user
[EMAIL PROTECTED] checking for domain forwards.
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_resolve: domain
[EMAIL PROTECTED] checking for domain forwards
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user [EMAIL PROTECTED] in alias table
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dbmysql.c,db_query: executing
query [SELECT deliver_to FROM dbmail_aliases WHERE lower(alias) =
lower('@mydoamin.com') AND lower(alias) <> lower(deliver_to)]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
into checking loop
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user @mydoamin.com to 1
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user [1] in alias table
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dbmysql.c,db_query: executing
query [SELECT deliver_to FROM dbmail_aliases WHERE lower(alias) = lower('1')
AND lower(alias) <> lower(deliver_to)]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
user 1 not in aliases table
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_resolve: domain
[EMAIL PROTECTED] found total of [0] aliases
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_free: dsnuser
freed
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): incoming buffer: [DATA]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): command looked up as
commandtype 3
Jun 18 10:59:14 spaceball postfix/lmtp[12465]: 8F4642B1A9B: to=<[EMAIL
PROTECTED]>, relay=localhost.mydoamin.com[127.0.0.1], delay=1, status=bounced
(host localhost.mydoamin.com[127.0.0.1] said: 550 Recipient <[EMAIL PROTECTED]>
FAIL (in reply to RCPT TO command))
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): incoming buffer: [RSET]
Jun 18 10:59:14 spaceball dbmail/lmtpd[11951]: lmtp(): command looked up as
commandtype 2
Jun 18 10:59:14 spaceball postfix/cleanup[12453]: E61DC2B1AA1:
message-id=<[EMAIL PROTECTED]>
Jun 18 10:59:15 spaceball postfix/qmgr[7966]: E61DC2B1AA1: from=<>, size=2843,
nrcpt=1 (queue active)
Jun 18 10:59:15 spaceball postfix/qmgr[7966]: 8F4642B1A9B: removed
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): incoming buffer: [RSET]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): command looked up as
commandtype 2
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): incoming buffer: [MAIL
FROM:<> SIZE=2843]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): command issued :cmd
[MAIL], value [FROM:<> SIZE=2843]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): command looked up as
commandtype 4
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: misc.c, find_bounded: Found []
of length [0] between '<' and '>' so next skip [6]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): incoming buffer: [RCPT
TO:<[EMAIL PROTECTED]>]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): command issued :cmd
[RCPT], value [TO:<[EMAIL PROTECTED]>]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): command looked up as
commandtype 9
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: misc.c, find_bounded: Found
[EMAIL PROTECTED] of length [23] between '<' and '>' so next skip [27]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_init: dsnuser
initialized
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user [EMAIL PROTECTED] in alias table
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dbmysql.c,db_query: executing
query [SELECT deliver_to FROM dbmail_aliases WHERE lower(alias) = lower('[EMAIL
PROTECTED]') AND lower(alias) <> lower(deliver_to)]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
into checking loop
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user [EMAIL PROTECTED] to 1
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user [1] in alias table
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dbmysql.c,db_query: executing
query [SELECT deliver_to FROM dbmail_aliases WHERE lower(alias) = lower('1')
AND lower(alias) <> lower(deliver_to)]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
user 1 not in aliases table
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_resolve: user
[EMAIL PROTECTED] found total of [0] aliases
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dbmysql.c,db_query: executing
query [SELECT user_idnr FROM dbmail_users WHERE userid='[EMAIL PROTECTED]']
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_resolve: user
[EMAIL PROTECTED] checking for domain forwards.
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_resolve: domain
[EMAIL PROTECTED] checking for domain forwards
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user [EMAIL PROTECTED] in alias table
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dbmysql.c,db_query: executing
query [SELECT deliver_to FROM dbmail_aliases WHERE lower(alias) =
lower('@mydoamin.com') AND lower(alias) <> lower(deliver_to)]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
into checking loop
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user @mydoamin.com to 1
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
checking user [1] in alias table
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dbmysql.c,db_query: executing
query [SELECT deliver_to FROM dbmail_aliases WHERE lower(alias) = lower('1')
AND lower(alias) <> lower(deliver_to)]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: authsql.c,auth_check_user_ext:
user 1 not in aliases table
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_resolve: domain
[EMAIL PROTECTED] found total of [0] aliases
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: dsn.c, dsnuser_free: dsnuser
freed
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): incoming buffer: [DATA]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): command looked up as
commandtype 3
Jun 18 10:59:15 spaceball postfix/lmtp[12465]: E61DC2B1AA1: to=<[EMAIL
PROTECTED]>, relay=localhost.mydoamin.com[127.0.0.1], delay=1, status=bounced
(host localhost.mydoamin.com[127.0.0.1] said: 550 Recipient <[EMAIL PROTECTED]>
FAIL (in reply to RCPT TO command))
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): incoming buffer: [RSET]
Jun 18 10:59:15 spaceball dbmail/lmtpd[11951]: lmtp(): command looked up as
commandtype 2
