<div dir="auto">Did you send a test email from localhost? Localhost is an exception in milter processing to allow delivery after scanning without creating an endless loop.<div dir="auto"><br></div><div dir="auto">Try sending one from a remote host.</div></div><br><div class="gmail_quote"><div dir="ltr">On Fri, Oct 5, 2018, 6:57 PM Mark Sapiro <<a href="mailto:mark@msapiro.net">mark@msapiro.net</a>> wrote:<br></div><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">I am experimenting with the new MailScanner milter option.<br>
<br>
I have done the following things.<br>
<br>
Removed<br>
---------------------------------<br>
/Received: / HOLD<br>
---------------------------------<br>
from /etc/postfix/header_checks<br>
<br>
Added<br>
---------------------------------<br>
# MailScanner milter<br>
smtpd_milters = inet:<a href="http://127.0.0.1:33333" rel="noreferrer noreferrer" target="_blank">127.0.0.1:33333</a><br>
---------------------------------<br>
to /etc/postfix/<a href="http://main.cf" rel="noreferrer noreferrer" target="_blank">main.cf</a><br>
<br>
Added<br>
---------------------------------<br>
Incoming Queue Dir = /var/spool/MailScanner/milterin<br>
Outgoing Queue Dir = /var/spool/MailScanner/milterout<br>
MTA = msmail<br>
MSMail Queue Type = short<br>
Milter Scanner = yes<br>
Milter Max Children = 1<br>
---------------------------------<br>
in a conf.d file in my MailScanner config overriding any defaults in<br>
MailScanner.conf added by the installation of 5.1.1<br>
<br>
Created those directories owned by Run As User and (re)started all the<br>
services. The milter is listening on port 33333 and everything looks<br>
good, but MailScanner doesn't scan the mail and nothing gets written<br>
into the milterin and milterout directories.<br>
<br>
Here's a mail.log entry<br>
<br>
> Oct 5 14:43:12 msapiro postfix/smtpd[11012]: connect from localhost[127.0.0.1]<br>
> Oct 5 14:44:30 msapiro postfix/smtpd[11012]: 69BEC340120: client=localhost[127.0.0.1]<br>
> Oct 5 14:45:11 msapiro postfix/cleanup[11438]: 69BEC340120: message-id=<<a href="mailto:20181005212532.GC2579@msapiro.net" target="_blank" rel="noreferrer">20181005212532.GC2579@msapiro.net</a>><br>
> Oct 5 14:45:11 msapiro postfix/qmgr[30689]: 69BEC340120: from=<<a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a>>, size=665, nrcpt=1 (queue active)<br>
> Oct 5 14:45:11 msapiro postfix/local[11657]: 69BEC340120: to=<<a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a>>, relay=local, delay=58, delays=58/0.01/0/0.03, dsn=2.0.0, status=sent (delivered to mailbox)<br>
> Oct 5 14:45:11 msapiro postfix/qmgr[30689]: 69BEC340120: removed<br>
> Oct 5 14:45:18 msapiro postfix/smtpd[11012]: disconnect from localhost[127.0.0.1] helo=1 mail=1/2 rcpt=1 data=1 quit=1 commands=5/6<br>
<br>
And here are the headers from the delivered mail<br>
<br>
> From <a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a> Fri Oct 5 14:45:11 2018<br>
> Return-Path: <<a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a>><br>
> X-Original-To: <a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a><br>
> Delivered-To: <a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a><br>
> Received: from <a href="http://msapiro.net" rel="noreferrer noreferrer" target="_blank">msapiro.net</a> (localhost [127.0.0.1])<br>
> by <a href="http://msapiro.net" rel="noreferrer noreferrer" target="_blank">msapiro.net</a> (Postfix) with SMTP id 69BEC340120<br>
> for <<a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a>>; Fri, 5 Oct 2018 14:44:12 -0700 (PDT)<br>
> Date: Fri, 5 Oct 2018 14:25:32 -0700<br>
> From: Mark Sapiro <<a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a>><br>
> To: Mark Sapiro <<a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a>><br>
> Subject: Yet another<br>
> Message-ID: <<a href="mailto:20181005212532.GC2579@msapiro.net" target="_blank" rel="noreferrer">20181005212532.GC2579@msapiro.net</a>><br>
> MIME-Version: 1.0<br>
> Content-Type: text/plain; charset=us-ascii<br>
> Content-Disposition: inline<br>
> User-Agent: Mutt/1.5.24 (2015-08-30)<br>
<br>
I've tried setting debug = yes and running manuallyMailScanner as root.<br>
It hangs at "Building a message batch to scan..." even if I send a<br>
message through Postfix.<br>
<br>
This is the full mail.log starting with restarting msmilter<br>
<br>
> Oct 5 15:41:58 msapiro root[29411]: Stopping MSMilter<br>
> Oct 5 15:41:58 msapiro root[29418]: MSMilter stopped<br>
> Oct 5 15:41:58 msapiro root[29423]: Starting MSMilter<br>
> Oct 5 15:41:59 msapiro MSMilter[29431]: MSMilter Daemon starting...<br>
> Oct 5 15:41:59 msapiro MSMilter[29431]: Reading configuration file /etc/MailScanner/MailScanner.conf<br>
> Oct 5 15:41:59 msapiro MSMilter[29431]: Reading configuration file /etc/MailScanner/conf.d/00local<br>
> Oct 5 15:41:59 msapiro MSMilter[29431]: Reading configuration file /etc/MailScanner/conf.d/10milter<br>
> Oct 5 15:41:59 msapiro MSMilter[29431]: Reading configuration file /etc/MailScanner/conf.d/99testing<br>
> Oct 5 15:41:59 msapiro MSMilter[29431]: Reading configuration file /etc/MailScanner/conf.d/README<br>
> Oct 5 15:41:59 msapiro MSMilter[29431]: Read 1501 hostnames from the phishing whitelist<br>
> Oct 5 15:41:59 msapiro MSMilter[29431]: Read 16624 hostnames from the phishing blacklists<br>
> Oct 5 15:41:59 msapiro MSMilter[29433]: initialization:<br>
> Oct 5 15:41:59 msapiro MSMilter[29433]: PidFile = /var/run/MSMilter.pid<br>
> Oct 5 15:41:59 msapiro MSMilter[29433]: user = postfix<br>
> Oct 5 15:41:59 msapiro MSMilter[29433]: group = clamav<br>
> Oct 5 15:41:59 msapiro MSMilter[29433]: bind = 127.0.0.1<br>
> Oct 5 15:41:59 msapiro MSMilter[29433]: port = 33333<br>
> Oct 5 15:41:59 msapiro root[29434]: MSMilter started<br>
> Oct 5 15:42:15 msapiro root[29564]: Stopping MailScanner<br>
> Oct 5 15:42:15 msapiro MailScanner[4279]: MailScanner child caught a SIGHUP<br>
> Oct 5 15:42:15 msapiro root[29570]: Found a possible dead PID. Stopping all MailScanner rogue processes ...<br>
> Oct 5 15:42:15 msapiro root[29581]: MailScanner stopped<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: MailScanner Email Processor version 5.1.1 starting...<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: Reading configuration file /etc/MailScanner/MailScanner.conf<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: Reading configuration file /etc/MailScanner/conf.d/00local<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: Reading configuration file /etc/MailScanner/conf.d/10milter<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: Reading configuration file /etc/MailScanner/conf.d/99testing<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: Reading configuration file /etc/MailScanner/conf.d/README<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: Read 1501 hostnames from the phishing whitelist<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: Read 16624 hostnames from the phishing blacklists<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: Using SpamAssassin results cache<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: Connected to SpamAssassin cache database<br>
> Oct 5 15:42:43 msapiro MailScanner[29717]: Enabling SpamAssassin auto-whitelist functionality...<br>
> Oct 5 15:42:45 msapiro MailScanner[29717]: Connected to Processing Attempts Database<br>
> Oct 5 15:42:45 msapiro MailScanner[29717]: Found 0 messages in the Processing Attempts Database<br>
> Oct 5 15:42:45 msapiro MailScanner[29717]: <a href="http://lock.pl" rel="noreferrer noreferrer" target="_blank">lock.pl</a> sees Config LockType = flock<br>
> Oct 5 15:42:45 msapiro MailScanner[29717]: <a href="http://lock.pl" rel="noreferrer noreferrer" target="_blank">lock.pl</a> sees have_module = 0<br>
> Oct 5 15:42:45 msapiro MailScanner[29717]: Using locktype = flock<br>
> Oct 5 15:44:15 msapiro postfix/smtpd[30228]: connect from localhost[127.0.0.1]<br>
> Oct 5 15:44:15 msapiro MSMilter[29436]: Localhost relay detected<br>
> Oct 5 15:44:57 msapiro postfix/smtpd[30228]: 2444D340120: client=localhost[127.0.0.1]<br>
> Oct 5 15:45:14 msapiro postfix/cleanup[30434]: 2444D340120: message-id=<<a href="mailto:20181005224457.2444D340120@msapiro.net" target="_blank" rel="noreferrer">20181005224457.2444D340120@msapiro.net</a>><br>
> Oct 5 15:45:14 msapiro postfix/qmgr[30689]: 2444D340120: from=<<a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a>>, size=580, nrcpt=1 (queue active)<br>
> Oct 5 15:45:14 msapiro postfix/local[30540]: 2444D340120: to=<<a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a>>, relay=local, delay=28, delays=28/0/0/0.03, dsn=2.0.0, status=sent (delivered to mailbox)<br>
> Oct 5 15:45:14 msapiro postfix/qmgr[30689]: 2444D340120: removed<br>
> Oct 5 15:45:21 msapiro postfix/smtpd[30228]: disconnect from localhost[127.0.0.1] helo=1 mail=1 rcpt=1 data=1 quit=1 commands=5<br>
<br>
<br>
There is probably something obvious that I'm missing, but I don't know what.<br>
<br>
-- <br>
Mark Sapiro <<a href="mailto:mark@msapiro.net" target="_blank" rel="noreferrer">mark@msapiro.net</a>> The highway is for gamblers,<br>
San Francisco Bay Area, California better use your sense - B. Dylan<br>
<br>
<br>
-- <br>
MailScanner mailing list<br>
<a href="mailto:mailscanner@lists.mailscanner.info" target="_blank" rel="noreferrer">mailscanner@lists.mailscanner.info</a><br>
<a href="http://lists.mailscanner.info/mailman/listinfo/mailscanner" rel="noreferrer noreferrer" target="_blank">http://lists.mailscanner.info/mailman/listinfo/mailscanner</a><br>
<br>
</blockquote></div>