MailScanner scanning everything twice

Daniel Malmgren daniel at kolefors.se
Fri Mar 4 14:36:45 UTC 2016


Hi.
I'm completely new to MailScanner (installed it today), so please don't 
shoot me if this is dead simple. I got everything working, but it seems 
for some reason all mails are being scanned twice. I suspect this isn't 
MailScanners fault, but rather me having set up my postfix in a stupid 
manner. There are different message id's for the two mails being 
scanned. Any hints about what could be wrong? Please tell me what 
further information could be useful. This is on a Debian 8.3 server, 
running postfix and dovecot.

This is what I get in my mail.log (domains replaced):
(note that daniel is an alias for malmgren, so all mail to daniel ends 
up in malmgrens mailbox. I get the exact same problem when sending 
directly to malmgren though.)

Mar  4 14:14:16 cube postfix/smtpd[29904]: connect from 
webadmin.myworkdomain.se[193.42.159.5]
Mar  4 14:14:17 cube postfix/cleanup[29908]: 1B4BD4004F: hold: header 
Received: from smtp.myworkdomain.se (webadmin.myworkdomain.se 
[193.42.159.5])??by cube (Postfix) with ESMTPS id 1B4BD4004F??for 
<daniel at myhomedomain.se>; Fri,  4 Mar 2016 14:14:16 +0100 (CET) from 
webadmin.myworkdomain.se[193.42.159.5]; 
from=<prvs=187193eaf2=Daniel.Malmgren at myworkdomain.se> 
to=<daniel at myhomedomain.se> proto=ESMTP helo=<smtp.myworkdomain.se>
Mar  4 14:14:17 cube postfix/cleanup[29908]: 1B4BD4004F: 
message-id=<1193eba94cd847849abfc1e6e595511a at ucs-ex-02.myworkdomain.se>
Mar  4 14:14:17 cube postfix/smtpd[29904]: disconnect from 
webadmin.myworkdomain.se[193.42.159.5]
Mar  4 14:14:22 cube MailScanner[29485]: New Batch: Scanning 1 messages, 
14054 bytes
Mar  4 14:14:22 cube MailScanner[29485]: Virus and Content Scanning: 
Starting
Mar  4 14:15:04 cube postfix/smtpd[29904]: connect from localhost[127.0.0.1]
Mar  4 14:15:04 cube postfix/smtpd[29904]: disconnect from 
localhost[127.0.0.1]
Mar  4 14:15:27 cube MailScanner[29485]: Requeue: 1B4BD4004F.AB70C to 
EC1A64028E
Mar  4 14:15:27 cube postfix/qmgr[15119]: EC1A64028E: 
from=<prvs=187193eaf2=daniel.malmgren at myworkdomain.se>, size=13369, 
nrcpt=1 (queue active)
Mar  4 14:15:27 cube MailScanner[29485]: Uninfected: Delivered 1 messages
Mar  4 14:15:27 cube spamd[9821]: spamd: connection from localhost 
[127.0.0.1]:47051 to port 783, fd 5
Mar  4 14:15:27 cube spamd[9821]: spamd: setuid to debian-spamd succeeded
Mar  4 14:15:27 cube spamd[9821]: spamd: processing message 
<1193eba94cd847849abfc1e6e595511a at ucs-ex-02.myworkdomain.se> for 
debian-spamd:116
Mar  4 14:15:27 cube MailScanner[29485]: Deleted 1 messages from 
processing-database
Mar  4 14:15:27 cube MailScanner[29485]: Logging message 
1B4BD4004F.AB70C to SQL
Mar  4 14:15:35 cube spamd[9821]: spamd: clean message (0.0/5.0) for 
debian-spamd:116 in 7.9 seconds, 13418 bytes.
Mar  4 14:15:35 cube spamd[9821]: spamd: result: . 0 - 
HTML_IMAGE_ONLY_32,HTML_MESSAGE,RCVD_IN_DNSWL_NONE 
scantime=7.9,size=13418,user=debian-spamd,uid=116,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=47051,mid=<1193eba94cd847849abfc1e6e595511a at ucs-ex-02.myworkdomain.se>,autolearn=ham 
autolearn_force=no
Mar  4 14:15:36 cube spamd[4003]: prefork: child states: I
Mar  4 14:15:36 cube postfix/pickup[23000]: 289EE4004F: uid=116 
from=<prvs=187193eaf2=daniel.malmgren at myworkdomain.se>
Mar  4 14:15:36 cube postfix/pipe[29946]: EC1A64028E: 
to=<malmgren at myhomedomain.se>, orig_to=<daniel at myhomedomain.se>, 
relay=spamfilter, delay=79, delays=71/0.02/0/8.2, dsn=2.0.0, status=sent 
(delivered via spamfilter service)
Mar  4 14:15:36 cube postfix/qmgr[15119]: EC1A64028E: removed
Mar  4 14:15:36 cube postfix/cleanup[29908]: 289EE4004F: hold: header 
Received: by cube (Postfix, from userid 116)??id 289EE4004F; Fri,  4 Mar 
2016 14:15:35 +0100 (CET) from local; 
from=<prvs=187193eaf2=daniel.malmgren at myworkdomain.se> 
to=<malmgren at myhomedomain.se>
Mar  4 14:15:36 cube postfix/cleanup[29908]: 289EE4004F: 
message-id=<1193eba94cd847849abfc1e6e595511a at ucs-ex-02.myworkdomain.se>
Mar  4 14:15:43 cube MailScanner[29485]: New Batch: Scanning 1 messages, 
14059 bytes
Mar  4 14:15:43 cube MailScanner[29485]: Virus and Content Scanning: 
Starting
Mar  4 14:16:44 cube MailScanner[29485]: SpamAssassin cache hit for 
message 289EE4004F.A3592
Mar  4 14:16:44 cube MailScanner[29485]: Requeue: 289EE4004F.A3592 to 
7F6B1402AB
Mar  4 14:16:44 cube postfix/qmgr[15119]: 7F6B1402AB: 
from=<prvs=187193eaf2=daniel.malmgren at myworkdomain.se>, size=13818, 
nrcpt=1 (queue active)
Mar  4 14:16:44 cube MailScanner[29485]: Uninfected: Delivered 1 messages
Mar  4 14:16:44 cube MailScanner[29485]: Deleted 1 messages from 
processing-database
Mar  4 14:16:44 cube MailScanner[29485]: Logging message 
289EE4004F.A3592 to SQL
Mar  4 14:16:44 cube dovecot: lmtp(30007): Connect from local
Mar  4 14:16:45 cube dovecot: lmtp(30007, malmgren): 
XKKvNbyK2VY3dQAAs8rsrw: sieve: 
msgid=<1193eba94cd847849abfc1e6e595511a at ucs-ex-02.myworkdomain.se>: 
stored mail into mailbox 'Daniel'
Mar  4 14:16:45 cube postfix/lmtp[30006]: 7F6B1402AB: 
to=<malmgren at myhomedomain.se>, relay=cube[private/dovecot-lmtp], 
delay=78, delays=77/0.02/0.05/0.91, dsn=2.0.0, status=sent (250 2.0.0 
<malmgren at myhomedomain.se> XKKvNbyK2VY3dQAAs8rsrw Saved)
Mar  4 14:16:45 cube dovecot: lmtp(30007): Disconnect from local: 
Successful quit
Mar  4 14:16:45 cube postfix/qmgr[15119]: 7F6B1402AB: removed

Regards
Daniel Malmgren


More information about the MailScanner mailing list