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=<rickt...@example.com>, 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: <rickt...@example.com>
->
<test222#int.example....@autoreply.int.example.com>,<test...@int.example.com>
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 <rickt...@example.com> ->
<test222#int.example....@autoreply.int.example.com>,<test...@int.example.com>
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=<rickt...@example.com>, size=3313, nrcpt=1 (queue active)
Feb 23 13:51:13 maildev amavis[1742]: (01742-04) FWD via SMTP:
<rickt...@example.com> ->
<test222#int.example....@autoreply.int.example.com>,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=<rickt...@example.com>, 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:
<rickt...@example.com> -> <test222+s...@int.example.com>,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] <rickt...@example.com> ->
<test222#int.example....@autoreply.int.example.com>,<test...@int.example.com>,
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#int.example....@autoreply.int.example.com>,
orig_to=<test...@int.example.com>, 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=<test...@int.example.com>, 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(test...@int.example.com):
msgid=<e265ac6da349994f9ef439e2f724439f079f8...@nofexchange.int.example.com>:
saved mail to Spam
Feb 23 13:51:13 maildev postfix/pipe[4687]: 5030BEFEEB:
to=<test222+s...@int.example.com>, 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#int.example.com+s...@autoreply.int.example.com>,
orig_to=<test222+s...@int.example.com>, 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=<20090223185113.a92c5ef...@maildev.int.example.com>
Feb 23 13:51:13 maildev postfix/qmgr[29758]: A92C5EFEB3:
from=<test...@int.example.com>, 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#int.example....@autoreply.int.example.com>,
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:
<test...@int.example.com> -> <rickt...@example.com> 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 <rickt...@example.com>; Mon, 23 Feb 2009 13:51:13 -0500 (EST)
Feb 23 13:51:13 maildev amavis[1743]: (01743-04) Checking:
SVeeHHtTb0JO <test...@int.example.com> -> <rickt...@example.com>
Feb 23 13:51:13 maildev amavis[1743]: (01743-04) cached
18ae55002e74ec1b196397858e3a88fe from <test...@int.example.com> (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=<20090223185113.a92c5ef...@maildev.int.example.com>
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=<test...@int.example.com>, size=1162, nrcpt=1 (queue active)
Feb 23 13:51:17 maildev amavis[1743]: (01743-04) FWD via SMTP:
<test...@int.example.com> -> <rickt...@example.com>,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] <test...@int.example.com> -> <rickt...@example.com>,
Message-ID: <20090223185113.a92c5ef...@maildev.int.example.com>,
mail_id: SVeeHHtTb0JO, Hits: -101.44, size: 656, queued_as: 373C3EFEE8, 3493 ms
Feb 23 13:51:17 maildev postfix/smtp[4680]: A92C5EFEB3:
to=<rickt...@example.com>, 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=<rickt...@example.com>, 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 <20090223185113.a92c5ef...@maildev.int.example.com> Queued
mail for delivery)
Feb 23 13:51:18 maildev postfix/qmgr[29758]: 373C3EFEE8: removed
