So I have been running MailScanner 4.32, Postfix 2.1.3, and Debian testing/unstable for a while now and everything was working great. Suddenly, the MCP portion of MailScanner/SpamAssassin stopped working. It still catches email and quarantines it, but it won't forward it on to my spam review account for some reason.
Can someone please help me? I have tried both Postfix and MailScanner lists, but no one has been able to find a solution. Here is the MCP portion of MailScanner.conf. And from the log files of a working machine and my broken machine, everything looks the same only the broken one never requeues the message while the working machine does. I have attached two log files as well.
# Configuration directory containing files related to MCP
# (Message Content Protection)
%mcp-dir% = /opt/MailScanner/etc/mcp
#
# MCP (Message Content Protection)
# -----------------------------
#
# This scans text and HTML messages segments for any banned text, using
# a 2nd copy of SpamAssassin to provide the searching abilities.
# This 2nd copy has its own entire set of rules, preferences and settings.
# When used together with the patches for SpamAssassin, it can also check
# the content of attachments such as office documents.
#
MCP Checks = yes
MCP Required SpamAssassin Score = 1
MCP High SpamAssassin Score = 10
MCP Error Score = 1
MCP Header = X-MailScanner-MCPCheck:
Non MCP Actions = deliver
MCP Actions = store forward
review@domain.com
High Scoring MCP Actions = store forward
review@domain.com
Log MCP = yes
Is Definitely MCP = no
Is Definitely Not MCP = no
Definite MCP Is High Scoring = no
Always Include MCP Report = no
Detailed MCP Report = yes
Include Scores In MCP Report = yes
MCP Max SpamAssassin Timeouts = 20
MCP Max SpamAssassin Size = 100000
MCP SpamAssassin Timeout = 10
MCP SpamAssassin Prefs File = %mcp-dir%/mcp.spam.assassin.prefs.conf
MCP SpamAssassin User State Dir =
MCP SpamAssassin Local Rules Dir = %mcp-dir%
MCP SpamAssassin Default Rules Dir = %mcp-dir%
MCP SpamAssassin Install Prefix = %mcp-dir%
Sender MCP Report = %report-dir%/sender.mcp.report.txt
GOOD BOX LOG
Jul 9 15:02:50 server2 postfix/smtpd[23056]: A20EF30C047: client=server1.example.com[192.168.1.24]
Jul 9 15:02:50 server2 postfix/cleanup[23057]: A20EF30C047: hold: header Received: from server1.example.com (server1.example.com [192.168.1.24])??by server2 (Postfix) with ESMTP id A20EF30C047??for <user@server2.example.com>; Fri, 9 Jul from server1.example.com[192.168.1.24]; from=<user1@server1.example.com> to=<user@server2.example.com> proto=ESMTP helo=<server1.example.com>
Jul 9 15:02:50 server2 postfix/cleanup[23057]: A20EF30C047: hold: header Received: from [192.168.1.217] (unknown [192.168.1.217])??(using TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits))??(No client certificate requested)??by
www.example.com (Postfix) with ES from server1.example.com[192.168.1.24]; from=<user1@example.com> to=<user1@server2.example.com> proto=ESMTP helo=<
www.example.com>
Jul 9 15:02:50 server2 postfix/cleanup[23057]: A20EF30C047: message-id=<40EF162D.9060901@example.com>
Jul 9 15:02:50 server2 postfix/smtpd[23056]: disconnect from server1.example.com[192.168.1.24]
Jul 9 15:02:51 server2 MailScanner[23045]: New Batch: Scanning 1 messages, 1701 bytes
Jul 9 15:02:51 server2 MailScanner[23045]: MCP Checks: Starting
Jul 9 15:02:51 server2 MailScanner[23045]: Message A20EF30C047 from 192.168.1.24 (user1@example.com) to server2.example.com is MCP, MCP-Checker (score=5, required 1, SAMPLE_RULE2)
Jul 9 15:02:51 server2 MailScanner[23045]: MCP Actions: message A20EF30C047 actions are
root@server2.example.com,store,forward
Jul 9 15:02:51 server2 MailScanner[23045]: MCP Checks completed at 1701 bytes per second
Jul 9 15:02:51 server2 MailScanner[23045]: Spam Checks: Starting
Jul 9 15:02:52 server2 MailScanner[23045]: Spam Checks completed at 1701 bytes per second
Jul 9 15:02:52 server2 MailScanner[23045]: Virus and Content Scanning: Starting
Jul 9 15:02:52 server2 MailScanner[23045]: Virus Scanning completed at 1701 bytes per second
Jul 9 15:02:52 server2 MailScanner[23045]: Requeue: A20EF30C047 to 9EB2230C048
Jul 9 15:02:52 server2 postfix/qmgr[22966]: 9EB2230C048: from=<user1@example.com>, size=1519, nrcpt=1 (queue active)
Jul 9 15:02:52 server2 postfix/local[23062]: 9EB2230C048: to=<root@server2.example.com>, relay=local, delay=2, status=sent (delivered to mailbox)
BAD BOX LOG
Jul 9 14:49:47 server1 postfix/smtpd[18010]: 73A5C11E83C: client=out2.smtp.messagingengine.com[66.111.4.26]
Jul 9 14:49:47 server1 postfix/cleanup[8883]: 73A5C11E83C: hold: header Received: from out2.smtp.messagingengine.com (out2.smtp.messagingengine.com [66.111.4.26])??by server1.example.com (Postfix) with ESMTP id 73A5C11E83C??for <user1@example.com>; Fri, from out2.smtp.messagingengine.com[66.111.4.26]; from=<me@example2.net> to=<user1@example.com> proto=ESMTP helo=<out2.smtp.messagingengine.com>
Jul 9 14:49:47 server1 postfix/cleanup[8883]: 73A5C11E83C: hold: header Received: from [192.168.1.217] (server1.example.com [1.1.1.1])??by server1.fastmail.fm (Postfix) with ESMTP id EF8B5C11217??for <user1@example.com>; Fri, 9 Jul 2004 17:49:44 -0400 ( from out2.smtp.messagingengine.com[66.111.4.26]; from=<me@example2.net> to=<user1@example.com> proto=ESMTP helo=<out2.smtp.messagingengine.com>
Jul 9 14:49:47 server1 postfix/cleanup[8883]: 73A5C11E83C: message-id=<40EF131E.6040803@example2.net>
Jul 9 14:49:47 server1 postfix/smtpd[18010]: disconnect from out2.smtp.messagingengine.com[66.111.4.26]
Jul 9 14:49:47 server1 MailScanner[20143]: New Batch: Scanning 1 messages, 1354 bytes
Jul 9 14:49:47 server1 MailScanner[20143]: MCP Checks: Starting
Jul 9 14:49:47 server1 MailScanner[20143]: Message 73A5C11E83C from 66.111.4.26 (me@example2.net) to example.com is MCP, MCP-Checker (score=1, required 1, RULE2)
Jul 9 14:49:47 server1 MailScanner[20143]: MCP Actions: message 73A5C11E83C actions are
krausem@gmail.com,store,forward,notify
Jul 9 14:49:47 server1 MailScanner[20143]: MCP Actions: Notify
user1@example.com,
sarge@example.com
Jul 9 14:49:47 server1 postfix/pickup[8772]: F1D5211E95A: uid=108 from=<postmaster>
Jul 9 14:49:47 server1 postfix/cleanup[19649]: F1D5211E95A: hold: header Received: by server1.example.com (Postfix, from userid 108)??id F1D5211E95A; Fri, 9 Jul 2004 14:49:47 -0700 (PDT) from local; from=<postmaster@server1.example.com>
Jul 9 14:49:47 server1 postfix/cleanup[19649]: F1D5211E95A: message-id=<20040709214947.F1D5211E95A@server1.example.com>
Jul 9 14:49:47 server1 MailScanner[20143]: MCP Checks completed at 1354 bytes per second
Jul 9 14:49:47 server1 MailScanner[20143]: Spam Checks: Starting
Jul 9 14:49:49 server1 MailScanner[20143]: Spam Checks completed at 677 bytes per second
Jul 9 14:49:49 server1 MailScanner[20143]: Virus and Content Scanning: Starting
Jul 9 14:49:49 server1 MailScanner[20143]: Virus Scanning completed at 1354 bytes per second
Jul 9 14:49:49 server1 MailScanner[20143]: Virus Processing completed at 1354 bytes per second
Jul 9 14:49:49 server1 MailScanner[20143]: Disinfection completed at 1354 bytes per second
Jul 9 14:49:49 server1 MailScanner[20143]: Batch completed at 677 bytes per second (1354 / 2)
Jul 9 14:49:49 server1 MailScanner[20143]: New Batch: Scanning 1 messages, 1406 bytes
Jul 9 14:49:49 server1 MailScanner[20143]: MCP Checks: Starting
Jul 9 14:49:49 server1 MailScanner[20143]: MCP Checks completed at 1406 bytes per second
Jul 9 14:49:49 server1 MailScanner[20143]: Spam Checks: Starting
Jul 9 14:49:49 server1 MailScanner[20143]: Message F1D5211E95A from 127.0.0.1 (postmaster@server1.example.com) is whitelisted
Jul 9 14:49:49 server1 MailScanner[20143]: Spam Checks completed at 1406 bytes per second
Jul 9 14:49:49 server1 MailScanner[20143]: Virus and Content Scanning: Starting
Jul 9 14:49:50 server1 MailScanner[20143]: Virus Scanning completed at 1406 bytes per second
Jul 9 14:49:50 server1 MailScanner[20143]: Requeue: F1D5211E95A to B851011E83C
Jul 9 14:49:50 server1 MailScanner[20143]: Uninfected: Delivered 1 messages
Jul 9 14:49:50 server1 MailScanner[20143]: Virus Processing completed at 1406 bytes per second
Jul 9 14:49:50 server1 MailScanner[20143]: Disinfection completed at 1406 bytes per second
Jul 9 14:49:50 server1 MailScanner[20143]: Batch completed at 1406 bytes per second (1406 / 1)
Jul 9 14:49:50 server1 postfix/qmgr[8773]: B851011E83C: from=<postmaster@server1.example.com>, size=1200, nrcpt=2 (queue active)
Jul 9 14:49:50 server1 postfix/local[19749]: B851011E83C: to=<sarge@example.com>, relay=local, delay=3, status=sent (maildir)
Jul 9 14:49:50 server1 postfix/local[19754]: B851011E83C: to=<user1@example.com>, relay=local, delay=3, status=sent (maildir)
