The problem still: users on vacation generate auto-response to
incoming email id'd as spam. I use a recipient_delimiter and
amavisd/spamassassin to flad incoming mail as spam with "+Spam". I'd
really like to stop this to avoid backscatter issues, be a good
netizen, and not validate addresses to spammers.
After some discussion on the postfixadmin list about virtual
vacation, they indicated that what the logs look like is that postfix
is actually responding twice for these messages. Once goes through
postfix directly and one goes through vacation, and that vacation is
correctly dropping the messages.
I have dovecot_destination_recipient_limit=1 and
vacation_destination_recipient_limit=1
Here's /var/log/maillog for the transaction:
Feb 23 13:51:12 maildev postfix/smtpd[4674]: connect from
nofwebmail.int.example.com[10.10.20.150]
Feb 23 13:51:12 maildev postfix/smtpd[4674]: C0440EFEB3:
client=nofwebmail.int.example.com[10.10.20.150]
Feb 23 13:51:12 maildev postfix/cleanup[4679]: C0440EFEB3:
message-id=<e265ac6da349994f9ef439e2f724439f079f8...@nofexchange.int.example.com>
Feb 23 13:51:12 maildev postfix/qmgr[29758]: C0440EFEB3:
from=<[email protected]>, size=2807, nrcpt=2 (queue active)
Feb 23 13:51:12 maildev postfix/smtp[4680]: initializing the
client-side TLS engine
Feb 23 13:51:12 maildev postfix/smtpd[4674]: disconnect from
nofwebmail.int.example.com[10.10.20.150]
Feb 23 13:51:12 maildev amavis[1742]: (01742-04) ESMTP::10024
/var/amavis/tmp/amavis-20090223T040209-01742: <[email protected]>
->
<test222#[email protected]>,<[email protected]>
SIZE=2807 Received: from maildev.int.example.com ([127.0.0.1]) by
localhost (maildev.int.example.com [127.0.0.1]) (amavisd-new, port
10024) with ESMTP; Mon, 23 Feb 2009 13:51:12 -0500 (EST)
Feb 23 13:51:12 maildev amavis[1742]: (01742-04) Checking:
Q3aQIQyEISKD <[email protected]> ->
<test222#[email protected]>,<[email protected]>
Feb 23 13:51:13 maildev postfix/smtpd[4683]: connect from
maildev.int.example.com[127.0.0.1]
Feb 23 13:51:13 maildev postfix/smtpd[4683]: 44F6DEFEE8:
client=maildev.int.example.com[127.0.0.1]
Feb 23 13:51:13 maildev postfix/cleanup[4679]: 44F6DEFEE8:
message-id=<e265ac6da349994f9ef439e2f724439f079f8...@nofexchange.int.example.com>
Feb 23 13:51:13 maildev postfix/qmgr[29758]: 44F6DEFEE8:
from=<[email protected]>, size=3313, nrcpt=1 (queue active)
Feb 23 13:51:13 maildev amavis[1742]: (01742-04) FWD via SMTP:
<[email protected]> ->
<test222#[email protected]>,BODY=7BIT 250
2.6.0 Ok, id=01742-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok:
queued as 44F6DEFEE8
Feb 23 13:51:13 maildev postfix/smtpd[4683]: disconnect from
maildev.int.example.com[127.0.0.1]
Feb 23 13:51:13 maildev postfix/smtpd[4683]: connect from
maildev.int.example.com[127.0.0.1]
Feb 23 13:51:13 maildev postfix/smtpd[4683]: 5030BEFEEB:
client=maildev.int.example.com[127.0.0.1]
Feb 23 13:51:13 maildev postfix/cleanup[4679]: 5030BEFEEB:
message-id=<e265ac6da349994f9ef439e2f724439f079f8...@nofexchange.int.example.com>
Feb 23 13:51:13 maildev postfix/qmgr[29758]: 5030BEFEEB:
from=<[email protected]>, size=3575, nrcpt=2 (queue active)
Feb 23 13:51:13 maildev postfix/smtpd[4683]: disconnect from
maildev.int.example.com[127.0.0.1]
Feb 23 13:51:13 maildev amavis[1742]: (01742-04) FWD via SMTP:
<[email protected]> -> <[email protected]>,BODY=7BIT
250 2.6.0 Ok, id=01742-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok:
queued as 5030BEFEEB
Feb 23 13:51:13 maildev amavis[1742]: (01742-04) Passed SPAM,
[10.10.20.150] <[email protected]> ->
<test222#[email protected]>,<[email protected]>,
Message-ID:
<e265ac6da349994f9ef439e2f724439f079f8...@nofexchange.int.example.com>,
mail_id: Q3aQIQyEISKD, Hits: 902.9, size: 2806, queued_as:
44F6DEFEE8/5030BEFEEB, 503 ms
Feb 23 13:51:13 maildev postfix/smtp[4680]: C0440EFEB3:
to=<test222#[email protected]>,
orig_to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024,
delay=0.6, delays=0.05/0.05/0/0.51, dsn=2.0.0, status=sent (250 2.0.0
Ok: queued as 44F6DEFEE8)
Feb 23 13:51:13 maildev postfix/smtp[4680]: C0440EFEB3:
to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024,
delay=0.6, delays=0.05/0.05/0/0.51, dsn=2.0.0, status=sent (250 2.0.0
Ok: queued as 44F6DEFEE8)
Feb 23 13:51:13 maildev postfix/qmgr[29758]: C0440EFEB3: removed
Feb 23 13:51:13 maildev deliver([email protected]):
msgid=<e265ac6da349994f9ef439e2f724439f079f8...@nofexchange.int.example.com>:
saved mail to Spam
Feb 23 13:51:13 maildev postfix/pipe[4687]: 5030BEFEEB:
to=<[email protected]>, relay=dovecot, delay=0.15,
delays=0.04/0.01/0/0.1, dsn=2.0.0, status=sent (delivered via dovecot service)
Feb 23 13:51:13 maildev postfix/pipe[4686]: 5030BEFEEB:
to=<test222#[email protected]>,
orig_to=<[email protected]>, relay=vacation, delay=0.3,
delays=0.04/0.01/0/0.25, dsn=2.0.0, status=sent (delivered via
vacation service)
Feb 23 13:51:13 maildev postfix/qmgr[29758]: 5030BEFEEB: removed
Feb 23 13:51:13 maildev postfix/smtpd[4674]: connect from
maildev.int.example.com[127.0.0.1]
Feb 23 13:51:13 maildev postfix/smtpd[4674]: A92C5EFEB3:
client=maildev.int.example.com[127.0.0.1]
Feb 23 13:51:13 maildev postfix/cleanup[4679]: A92C5EFEB3:
message-id=<[email protected]>
Feb 23 13:51:13 maildev postfix/qmgr[29758]: A92C5EFEB3:
from=<[email protected]>, size=656, nrcpt=1 (queue active)
Feb 23 13:51:13 maildev postfix/smtpd[4674]: disconnect from
maildev.int.example.com[127.0.0.1]
Feb 23 13:51:13 maildev postfix/pipe[4684]: 44F6DEFEE8:
to=<test222#[email protected]>,
relay=vacation, delay=0.5, delays=0.04/0.01/0/0.46, dsn=2.0.0,
status=sent (delivered via vacation service)
Feb 23 13:51:13 maildev postfix/qmgr[29758]: 44F6DEFEE8: removed
Feb 23 13:51:13 maildev amavis[1743]: (01743-04) ESMTP::10024
/var/amavis/tmp/amavis-20090223T040218-01743:
<[email protected]> -> <[email protected]> SIZE=656
Received: from maildev.int.example.com ([127.0.0.1]) by localhost
(maildev.int.example.com [127.0.0.1]) (amavisd-new, port 10024) with
ESMTP for <[email protected]>; Mon, 23 Feb 2009 13:51:13 -0500 (EST)
Feb 23 13:51:13 maildev amavis[1743]: (01743-04) Checking:
SVeeHHtTb0JO <[email protected]> -> <[email protected]>
Feb 23 13:51:13 maildev amavis[1743]: (01743-04) cached
18ae55002e74ec1b196397858e3a88fe from <[email protected]> (0,0)
Feb 23 13:51:17 maildev postfix/smtpd[4683]: connect from
maildev.int.example.com[127.0.0.1]
Feb 23 13:51:17 maildev postfix/smtpd[4683]: 373C3EFEE8:
client=maildev.int.example.com[127.0.0.1]
Feb 23 13:51:17 maildev postfix/cleanup[4679]: 373C3EFEE8:
message-id=<[email protected]>
Feb 23 13:51:17 maildev postfix/smtpd[4683]: disconnect from
maildev.int.example.com[127.0.0.1]
Feb 23 13:51:17 maildev postfix/qmgr[29758]: 373C3EFEE8:
from=<[email protected]>, size=1162, nrcpt=1 (queue active)
Feb 23 13:51:17 maildev amavis[1743]: (01743-04) FWD via SMTP:
<[email protected]> -> <[email protected]>,BODY=7BIT 250
2.6.0 Ok, id=01743-04, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok:
queued as 373C3EFEE8
Feb 23 13:51:17 maildev postfix/smtp[4693]: initializing the
client-side TLS engine
Feb 23 13:51:17 maildev amavis[1743]: (01743-04) Passed CLEAN,
[127.0.0.1] <[email protected]> -> <[email protected]>,
Message-ID: <[email protected]>,
mail_id: SVeeHHtTb0JO, Hits: -101.44, size: 656, queued_as: 373C3EFEE8, 3493 ms
Feb 23 13:51:17 maildev postfix/smtp[4680]: A92C5EFEB3:
to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024,
delay=3.6, delays=0.09/0/0/3.5, dsn=2.0.0, status=sent (250 2.0.0 Ok:
queued as 373C3EFEE8)
Feb 23 13:51:17 maildev postfix/qmgr[29758]: A92C5EFEB3: removed
Feb 23 13:51:18 maildev postfix/smtp[4693]: 373C3EFEE8:
to=<[email protected]>, relay=webmail2.example.com[10.11.0.26]:25,
delay=1.1, delays=0.04/0.05/0.69/0.28, dsn=2.6.0, status=sent (250
2.6.0 <[email protected]> Queued
mail for delivery)
Feb 23 13:51:18 maildev postfix/qmgr[29758]: 373C3EFEE8: removed