I'm having a problem getting spamassassin up and running. When a message comes into postfix it seems (from the logs) to get passed to spamassassin, BUT, then the message never gets to the local delivery, instead it keeps on (what seems to be) getting passed back to the mail queue and then filtered again. I've tried a couple different ways of implementing this, all with similar results. I am following the simple configuration instructions posted on the spamassassin wiki. Here are my details and log excerpt...
Spamassassin version: spamassassin-3.0.4
Postfix Version: postfix-2.1.5
I have the following in my master.conf, all paths have been confirmed:
smtp inet n - n - - smtpd -o content_filter=spamassassin
...
spamassassin
unix - n n - - pipe
user=filter argv=/usr/bin/spamc -e /usr/sbin/sendmail -oi -f ${sender} ${recipient}
Here is a log excerpt that would go on forever if I didn't change the config back and then reload postfix:
:21:40 morpheus postfix/smtpd[8412]: 9EB8F1E304D1: client=blackhole.hosting4less.com[65.200.22.9]
Dec 19 17:21:40 morpheus postfix/cleanup[8414]: 9EB8F1E304D1: message-id=<[EMAIL PROTECTED]>
Dec 19 17:21:40 morpheus postfix/qmgr[8411]: 9EB8F1E304D1: from=<[EMAIL PROTECTED]>, size=1833, nrcpt=1 (queue active)
Dec 19 17:21:40 morpheus spamd[3128]: connection from morpheus.domain.com [127.0.0.1] at port 41677
Dec 19 17:21:40 morpheus spamd[3128]: info: setuid to filter succeeded
Dec 19 17:21:40 morpheus spamd[3128]: processing message <[EMAIL PROTECTED]> for filter:634.
Dec 19 17:21:40 morpheus postfix/smtpd[8412]: disconnect from blackhole.hosting4less.com[65.200.22.9]
Dec 19 17:21:43 morpheus spamd[3128]: clean message (1.6/5.0) for filter:634 in 3.2 seconds, 1793 bytes.
Dec 19 17:21:43 morpheus spamd[3128]: result: . 1 - AWL,HTML_00_10,HTML_MESSAGE,HTML_SHORT_LENGTH,RCVD_BY_IP,SUBJ_ALL_CAPS,TRACKER_ID scantime=3.2,size=1793,mid=<297d258e
[EMAIL PROTECTED]>,autolearn=no
Dec 19 17:21:44 morpheus sendmail[8416]: jBK1Lh74008416: Authentication-Warning: morpheus.domain.com: filter set sender to [EMAIL PROTECTED] using -f
Dec 19 17:21:44 morpheus sendmail[8416]: jBK1Lh74008416: [EMAIL PROTECTED], size=2056, class=0, nrcpts=1, msgid=<[EMAIL PROTECTED]
ail.com>, relay=[EMAIL PROTECTED]
Dec 19 17:21:44 morpheus postfix/smtpd[8412]: connect from morpheus.domain.com[127.0.0.1]
Dec 19 17:21:44 morpheus postfix/smtpd[8412]: 240621E31C68: client=morpheus.domain.com[127.0.0.1]
Dec 19 17:21:44 morpheus postfix/cleanup[8414]: 240621E31C68: message-id=<[EMAIL PROTECTED]>
Dec 19 17:21:44 morpheus sendmail[8416]: jBK1Lh74008416: [EMAIL PROTECTED], [EMAIL PROTECTED] (634/634), delay=00:00:01, xdelay=00:00:00, mailer=re
lay, pri=32056, relay=[127.0.0.1] [127.0.0.1], dsn=2.0.0, stat=Sent (Ok: queued as 240621E31C68)
Dec 19 17:21:44 morpheus postfix/qmgr[8411]: 240621E31C68: from=<[EMAIL PROTECTED]>, size=2603, nrcpt=1 (queue active)
Dec 19 17:21:44 morpheus postfix/smtpd[8412]: disconnect from morpheus.domain.com[127.0.0.1]
Dec 19 17:21:44 morpheus postfix/pipe[8415]: 9EB8F1E304D1: to=<[EMAIL PROTECTED]>, relay=spamassassin, delay=4, status=sent (morpheus.domain.com)
Dec 19 17:21:44 morpheus postfix/qmgr[8411]: 9EB8F1E304D1: removed
Dec 19 17:21:44 morpheus spamd[3129]: connection from morpheus.domain.com [127.0.0.1] at port 41679
Dec 19 17:21:44 morpheus spamd[3129]: info: setuid to filter succeeded
Dec 19 17:21:44 morpheus spamd[3129]: processing message <[EMAIL PROTECTED]> for filter:634.
Dec 19 17:21:44 morpheus spamd[3129]: clean message (1.6/5.0) for filter:634 in 0.1 seconds, 2550 bytes.
Dec 19 17:21:44 morpheus spamd[3129]: result: . 1 - AWL,HTML_00_10,HTML_MESSAGE,HTML_SHORT_LENGTH,RCVD_BY_IP,SUBJ_ALL_CAPS,TRACKER_ID scantime=0.1,size=2550,mid=<297d258e
[EMAIL PROTECTED]>,autolearn=no
Dec 19 17:21:44 morpheus sendmail[8419]: jBK1Li2d008419: Authentication-Warning: morpheus.domain.com: filter set sender to [EMAIL PROTECTED] using -f
Dec 19 17:21:44 morpheus sendmail[8419]: jBK1Li2d008419: [EMAIL PROTECTED], size=2550, class=0, nrcpts=1, msgid=<[EMAIL PROTECTED]
ail.com>, relay=[EMAIL PROTECTED]
Dec 19 17:21:44 morpheus postfix/smtpd[8412]: connect from morpheus.domain.com[127.0.0.1]
Dec 19 17:21:44 morpheus postfix/smtpd[8412]: 5BBAD1E31C49: client=morpheus.domain.com[127.0.0.1]
Dec 19 17:21:44 morpheus postfix/cleanup[8414]: 5BBAD1E31C49: message-id=<[EMAIL PROTECTED]>
Dec 19 17:21:44 morpheus sendmail[8419]: jBK1Li2d008419: [EMAIL PROTECTED], [EMAIL PROTECTED] (634/634), delay=00:00:00, xdelay=00:00:00, mailer=re
lay, pri=32550, relay=[127.0.0.1] [127.0.0.1], dsn=2.0.0, stat=Sent (Ok: queued as 5BBAD1E31C49)
Dec 19 17:21:44 morpheus postfix/qmgr[8411]: 5BBAD1E31C49: from=<[EMAIL PROTECTED]>, size=3104, nrcpt=1 (queue active)
Dec 19 17:21:44 morpheus spamd[3123]: connection from morpheus.domain.com [127.0.0.1] at port 41681
Dec 19 17:21:44 morpheus spamd[3123]: info: setuid to filter succeeded
Dec 19 17:21:44 morpheus postfix/smtpd[8412]: disconnect from morpheus.domain.com[127.0.0.1]
Dec 19 17:21:44 morpheus postfix/pipe[8418]: 240621E31C68: to=<[EMAIL PROTECTED]>, relay=spamassassin, delay=0, status=sent (morpheus.domain.com)
Dec 19 17:21:44 morpheus postfix/qmgr[8411]: 240621E31C68: removed
Dec 19 17:21:44 morpheus spamd[3123]: processing message <[EMAIL PROTECTED]> for filter:634.
Dec 19 17:21:44 morpheus spamd[3123]: clean message (1.6/5.0) for filter:634 in 0.1 seconds, 3044 bytes.
Dec 19 17:21:44 morpheus spamd[3123]: result: . 1 - AWL,HTML_00_10,HTML_MESSAGE,HTML_SHORT_LENGTH,RCVD_BY_IP,SUBJ_ALL_CAPS,TRACKER_ID scantime=0.1,size=3044,mid=<297d258e
[EMAIL PROTECTED]>,autolearn=no
Dec 19 17:21:44 morpheus sendmail[8421]: jBK1Li7u008421: Authentication-Warning: morpheus.domain.com: filter set sender to [EMAIL PROTECTED] using -f
Any idea what's going on with this?
Thanks,
Kris
