You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@spamassassin.apache.org by Stefan Suurmeijer <S....@rc.rug.nl> on 2004/06/04 17:16:03 UTC
spamd problem
Hi list,
I've recently installed a new linux box (SuSE 9.1) and want to move my
mailserver to it. I'm having trouble however getting Spamassassin to
work. Somehow, spamd doesn't get the message (literally and figuratively
speaking).
I'm using kernel 2.6.4 with sendmail 8.12.11, spamassassin 2.63, Berkely
DB 4.2.52, milter-spamc (www.snert.org) 0.17 and libsnert 1.33.
When I connect, everything seems to go ok, right up to the time where
the message should be passed to spamd. It simply never gets there (two
logs included below, one working from my old server and the one from my
new server. The spamd lines are missing. The milter is working (senders
with non-rfc compliant addresses are rejected), but it can't seem to
pass the message on to spamd.
I've tried recompiling every single part of the list above, and now I'm
out of ideas. Anyone have a suggestion what might be causing this? The
two machines have practically the same hardware, the only differences
being the linux version (SuSE 9.0 vs 9.1) and therefore the kernel
version (2.4.21 vs 2.6.4) and the spamassassin versions. My old server
is running 2.61, the new one 2.63.
Is there any kind of issue with spamassassin and kernel 2.6 that might
be causing this?
Any help is greatly appreciated
TIA
Stefan
Logging:
new server:
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: Milter
(milter-spamc): init success to negotiate
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: Milter
(avmilter): init success to negotiate
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: Milter: connect
to filters
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821:
milter=milter-spamc, action=connect, continue
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 220
mentat.raptorweb.nl ESMTP Sendmail 8.12.11/8.12.11; Fri, 4 Jun 2004
15:38:14 +0200
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: <-- EHLO
mentat.raptorweb.nl
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821:
milter=milter-spamc, action=helo, continue
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: ---
250-mentat.raptorweb.nl Hello localhost [127.0.0.1], pleased to meet you
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: ---
250-ENHANCEDSTATUSCODES
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 250-PIPELINING
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 250-8BITMIME
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 250-SIZE
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 250-DSN
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 250-ETRN
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 250-AUTH
DIGEST-MD5 CRAM-MD5
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 250-DELIVERBY
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 250 HELP
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: <-- MAIL
From:<An...@mentat.raptorweb.nl> SIZE=1390
AUTH=AntiVir@mentat.raptorweb.nl
Jun 4 15:38:14 mentat sendmail[23821]: ruleset=trust_auth,
arg1=AntiVir@mentat.raptorweb.nl, relay=ad.doubleclick.net [127.0.0.1],
reject=550 5.7.1 <AntiV
ir@mentat.raptorweb.nl>... not authenticated
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: Milter: senders:
<An...@mentat.raptorweb.nl>
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821:
milter=milter-spamc, action=mail, continue
Jun 4 15:38:14 mentat sendmail[23809]: i54DcDIW023809: <-- QUIT
Jun 4 15:38:14 mentat sendmail[23809]: i54DcDIW023809: --- 221 2.0.0
mentat.raptorweb.nl closing connection
Jun 4 15:38:14 mentat sendmail[23809]: i54DcDIW023809: Milter
(milter-spamc): quit filter
Jun 4 15:38:14 mentat sendmail[23809]: i54DcDIW023809: Milter
(avmilter): quit filter
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: milter=avmilter,
action=mail, continue
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 250 2.1.0
<An...@mentat.raptorweb.nl>... Sender ok
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: <-- RCPT
To:<po...@mentat.raptorweb.nl>
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: Milter: rcpts:
<po...@mentat.raptorweb.nl>
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821:
milter=milter-spamc, action=rcpt, continue
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: milter=avmilter,
action=rcpt, continue
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 250 2.1.5
<po...@mentat.raptorweb.nl>... Recipient ok
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: <-- DATA
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: --- 354 Enter
mail, end with "." on a line by itself
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821:
from=<An...@mentat.raptorweb.nl>, size=1672, class=0, nrcpts=1,
msgid=<200406041338.i54DcESG02382
0@mentat.raptorweb.nl>, proto=ESMTP, daemon=MTA,
relay=ad.doubleclick.net [127.0.0.1]
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: Milter
(milter-spamc): headers, send
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821:
milter=milter-spamc, action=header, continue
Jun 4 15:38:14 mentat last message repeated 6 times
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: Milter
(milter-spamc): headers, sent
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821:
milter=milter-spamc, action=eoh, continue
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: Milter
(milter-spamc): body, send
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821:
milter=milter-spamc, action=body, continue
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: Milter
(milter-spamc): body, sent
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: Milter
(avmilter): headers, send
Jun 4 15:38:14 mentat sendmail[23821]: i54DcEgN023821: milter=avmilter,
action=header, continue
Jun 4 15:38:15 mentat last message repeated 6 times
Jun 4 15:38:15 mentat sendmail[23821]: i54DcEgN023821: Milter
(avmilter): headers, sent
Jun 4 15:38:15 mentat sendmail[23821]: i54DcEgN023821: milter=avmilter,
action=eoh, continue
Jun 4 15:38:15 mentat1 sendmail[23821]: i54DcEgN023821:
milter=avmilter, action=body, continue
Jun 4 15:38:15 mentat1 sendmail[23821]: i54DcEgN023821: Milter
(avmilter): body, sent
Jun 4 15:38:15 mentat1 sendmail[23821]: i54DcEgN023821: Milter add:
header: X-AntiVirus: checked by AntiVir Milter 1.0.6; AVE 6.25.0.61; VDF
6.25.0.84
Jun 4 15:38:15 mentat1 sendmail[23821]: i54DcEgN023821: Milter accept:
message
Jun 4 15:38:15 mentat1 sendmail[23821]: i54DcEgN023821: --- 250 2.0.0
i54DcEgN023821 Message accepted for delivery
Jun 4 15:38:15 mentat1 sendmail[23820]: i54DcESG023820: to=postmaster,
delay=00:00:01, xdelay=00:00:01, mailer=relay, pri=31390,
relay=[127.0.0.1] [127.0.0.
1], dsn=2.0.0, stat=Sent (i54DcEgN023821 Message accepted for delivery)
Old server (note the spamd lines):
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: Milter
(milter-spamc): init success to negotiate
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: Milter
(avmilter): init success to negotiate
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: Milter: connect
to filters
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 220
lin03.rc.rug.nl ESMTP Sendmail 8.12.10/8.12.10; Fri, 4 Jun 2004 15:00:32
+0200
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: <-- EHLO
rc-284.rc.rug.nl
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: ---
250-lin03.rc.rug.nl Hello rc-284.rc.rug.nl [129.125.3.52], pleased to
meet you
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: ---
250-ENHANCEDSTATUSCODES
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 250-PIPELINING
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 250-8BITMIME
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 250-SIZE
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 250-DSN
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 250-ETRN
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 250-DELIVERBY
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 250 HELP
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: <-- MAIL
From:<S....@rc.rug.nl> SIZE=1444
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: Milter: senders:
<S....@rc.rug.nl>
Jun 4 15:00:32 lin03 sendmail[11357]: i54D0UuQ011348: --- 050
<st...@rc-284.rc.rug.nl>... Sent (i54D0YhL005480 Message accepted for
delivery)
Jun 4 15:00:32 lin03 sendmail[11357]: i54D0UuQ011348:
to=<st...@rc-284.rc.rug.nl>, delay=00:00:01, xdelay=00:00:00,
mailer=esmtp, pri=30974, relay=rc-28
4.rc.rug.nl. [129.125.3.52], dsn=2.0.0, stat=Sent (i54D0YhL005480
Message accepted for delivery)
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: milter=avmilter,
action=mail, continue
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 250 2.1.0
<S....@rc.rug.nl>... Sender ok
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: <-- RCPT
To:<st...@lin03.rc.rug.nl>
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: Milter: rcpts:
<st...@lin03.rc.rug.nl>
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358:
milter=milter-spamc, action=rcpt, continue
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: milter=avmilter,
action=rcpt, continue
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 250 2.1.5
<st...@lin03.rc.rug.nl>... Recipient ok
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: <-- DATA
Jun 4 15:00:32 lin03 sendmail[11358]: i54D0WuQ011358: --- 354 Enter
mail, end with "." on a line by itself
Jun 4 15:00:32 lin03 sendmail[11357]: i54D0UuQ011348: done;
delay=00:00:01, ntries=1
Jun 4 15:00:32 lin03 sendmail[11357]: NOQUEUE: --- 050 Closing
connection to rc-284.rc.rug.nl.
Jun 4 15:00:33 lin03 sendmail[11358]: i54D0WuQ011358:
from=<S....@rc.rug.nl>, size=1443, class=0, nrcpts=1,
msgid=<40...@rc.rug.nl>,
proto=ESMTP, daemon=MTA, relay=rc-284.rc.rug.nl [129.125.3.52]
Jun 4 15:00:33 lin03 sendmail[11358]: i54D0WuQ011358: Milter
(milter-spamc): headers, send
Jun 4 15:00:33 lin03 sendmail[11358]: i54D0WuQ011358:
milter=milter-spamc, action=header, continue
Jun 4 15:00:33 lin03 last message repeated 15 times
Jun 4 15:00:33 lin03 sendmail[11358]: i54D0WuQ011358: Milter
(milter-spamc): headers, sent
Jun 4 15:00:33 lin03 sendmail[11358]: i54D0WuQ011358: Milter
(milter-spamc): body, send
Jun 4 15:00:33 lin03 spamd[1194]: connection from localhost [127.0.0.1]
at port 13747
Jun 4 15:00:33 lin03 sendmail[11358]: i54D0WuQ011358:
milter=milter-spamc, action=body, continue
Jun 4 15:00:33 lin03 sendmail[11358]: i54D0WuQ011358: Milter
(milter-spamc): body, sent
Jun 4 15:00:33 lin03 spamd[11361]: info: setuid to stefan succeeded
Jun 4 15:00:33 lin03 spamd[11361]: processing message
<40...@rc.rug.nl> for stefan:1000.
Jun 4 15:00:34 lin03 sendmail[11358]: i54D0WuQ011358: Milter add:
header: X-Spam-Checker-Version: SpamAssassin 2.61
(1.212.2.1-2003-12-09-exp) on\n\tlin03.rc.rug.nl
Jun 4 15:00:34 lin03 sendmail[11358]: i54D0WuQ011358: Milter add:
header: X-Spam-Status: No, hits=-57.3 required=3.0
tests=AWL,BAYES_00,\n\tUSER_IN_WHITE
LIST autolearn=ham version=2.61
Jun 4 15:00:34 lin03 sendmail[11358]: i54D0WuQ011358: Milter add:
header: X-Spam-Level:
Jun 4 15:00:34 lin03 sendmail[11358]: i54D0WuQ011358: Milter
(avmilter): headers, send
Jun 4 15:00:34 lin03 spamd[11361]: clean message (-57.3/3.0) for
stefan:1000 in 1.4 seconds, 1477 bytes.
Jun 4 15:00:34 lin03 sendmail[11358]: i54D0WuQ011358: milter=avmilter,
action=header, continue
etc