[milters] Archive

Lists Index Date Thread Search

Article: 807
From: Stefan Suurmeijer
Date: 2006-02-03 18:41:55 -0500
Subject: Re: milter-spamc fails with cmd read returned 0

Removal...........: milters-request@milter.info?subject=remove
More information..: http://www.milter.info/#Support
--------------------------------------------------------

-- Attached file included as plaintext by Ecartis --

Anthony Howe wrote:
> It would appear that sendmail/milter communications are timing out. Look 
> to your T= values in the INPUT_MAIL_FILTER() macro for the milter found 
> in your sendmail .mc file. I suspect your R: value is too short.
>
> Also are you scanning whole message bodies or just the first 64K chunk 
> (default)?
>
> You might also make sure your SpamAssassin process is running AND that 
> the milter can still connect to it. It might be timing out while trying 
> to connect to the SpamAssassin daemon.
>
>   
>> Feb  2 22:04:31 mentat1 sendmail[7549]: k12L4KOd007549: Milter
>> (milter-spamc): to error state
>> Feb  2 22:04:31 mentat1 sendmail[7549]: k12L4KOd007549: --- 250 2.1.5
>> <stefan@mentat1.raptorweb.nl>... Recipient ok
>> Feb  2 22:04:31 mentat1 sendmail[7549]: k12L4KOd007549: <-- DATA
>>     
>
> Also please in future provide the whole log for the message/milter 
> thread. As its not clear exactly where the milter is when it fails 
> without more of the surrounding context.
>
> You might try increasing the milter's log detail to
>
> 	-v info,trace,db,dialog
>
> which will show more of what's happening (at least for me :-)
>
> Anthony
>
>   
Ok, I've restarted the milter with the above options, see attached logs.
The R value is R:6m, the value I copied off your site. From sendmail.cf:

Xmilter-spamc, S=unix:/var/run/milter/milter-spamc.socket,
T=C:1m;S:30s;R:6m;E:1m

Still have no clue as to why it fails. We do have it running on a
solaris machine without trouble, though that has an older Berkely DB version

Any help would be greatly appreciated,

Stefan


-- Attached file included as plaintext by Ecartis --
-- File: mail

Feb  4 00:31:33 mentat1 milter-spamc[13128]: parseCfKline(Kaccess hash -T<TMPF>
/etc/mail/access)
Feb  4 00:31:33 mentat1 milter-spamc[13128]: accessFile=/etc/mail/access.db
Feb  4 00:31:33 mentat1 milter-spamc[13128]: milter-spamc: smfi_register: version mismatch
application: 4 != milter: 2
Feb  4 00:31:33 mentat1 milter-spamc[13128]: milter-spamc: smfi_register: version mismatch
application: 3 != milter: 2
Feb  4 00:31:33 mentat1 milter-spamc[13128]: process ruid=8 rgid=12 euid=8 egid=12
Feb  4 00:31:33 mentat1 milter-spamc[13128]: milter-spamc/1.4.366 Copyright 2003, 2005 by
Anthony Howe. All rights reserved.
Feb  4 00:31:33 mentat1 milter-spamc[13128]: LibSnert/1.56.769 Copyright 1996, 2005 by
Anthony Howe. All rights reserved.
Feb  4 00:31:33 mentat1 milter-spamc[13128]: libmilter version 2 (4)
Feb  4 00:31:33 mentat1 milter-spamc[13128]: Sleepycat Software: Berkeley DB 4.3.27:
(September  9, 2005)
Feb  4 00:32:55 mentat1 sendmail-client[13174]: starting daemon (8.13.5):
persistent-queueing@00:01:00
Feb  4 00:33:52 mentat1 sendmail[13169]: NOQUEUE: stopping daemon, reason=signal
Feb  4 00:33:54 mentat1 sendmail[13202]: starting daemon (8.13.5): SMTP+queueing@00:30:00
Feb  4 00:33:54 mentat1 sendmail[13202]: STARTTLS: CRLFile missing
Feb  4 00:33:54 mentat1 sendmail[13202]: STARTTLS=server, Diffie-Hellman init, key=512 bit
(1)
Feb  4 00:33:54 mentat1 sendmail[13202]: STARTTLS=server, init=1
Feb  4 00:33:54 mentat1 sendmail[13202]: started as: /usr/sbin/sendmail -L sendmail -Am
-bd -q30m -om
Feb  4 00:33:54 mentat1 sendmail-client[13207]: starting daemon (8.13.5):
persistent-queueing@00:01:00
Feb  4 00:34:48 mentat1 sendmail[13223]: NOQUEUE: connect from smtp1.rug.nl
[129.125.50.11]
Feb  4 00:34:48 mentat1 sendmail[13223]: AUTH: available mech=PLAIN DIGEST-MD5 CRAM-MD5
LOGIN ANONYMOUS, allowed mech=EXTERNAL GSSAPI KERBEROS_V4 DIGEST-MD5 CRAM-MD5
Feb  4 00:34:54 mentat1 milter-spamc[13128]: 00001 NOQUEUE: filterOpen(806a538,
'smtp1.rug.nl', [129.125.50.11])
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-connect:129.125.50.11"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-connect:129.125.50"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-connect:129.125"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-connect:129"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-connect:smtp1.rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-connect:rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-connect:nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"connect:129.125.50.11"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"connect:129.125.50"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"connect:129.125"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"connect:129"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"129.125.50.11"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"129.125.50"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"129.125"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"129"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"connect:smtp1.rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"connect:rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"connect:nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"smtp1.rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"nl"
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjB013223: --- 220 mentat1.raptorweb.nl
ESMTP Sendmail 8.13.5/8.13.5; Sat, 4 Feb 2006 00:34:48 +0100
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjB013223: <-- HELO smtp1.rug.nl
Feb  4 00:34:54 mentat1 milter-spamc[13128]: 00001 NOQUEUE: filterHelo(806a538,
'smtp1.rug.nl')
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjB013223: --- 250 mentat1.raptorweb.nl
Hello smtp1.rug.nl [129.125.50.11], pleased to meet you
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjB013223: <-- RSET
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjB013223: --- 250 2.0.0 Reset state
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjC013223: <-- MAIL
From:<S.M.Suurmeijer@rug.nl>
Feb  4 00:34:54 mentat1 milter-spamc[13128]: 00001 k13NYmjC013223: filterMail(806a538,
80747b8) MAIL='<S.M.Suurmeijer@rug.nl>'
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-from:s.m.suurmeijer@rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-from:rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-from:nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-from:s.m.suurmeijer@"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"from:s.m.suurmeijer@rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"from:rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"from:nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"from:s.m.suurmeijer@"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"s.m.suurmeijer@rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"rug.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"s.m.suurmeijer@"
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjC013223: --- 250 2.1.0
<S.M.Suurmeijer@rug.nl>... Sender ok
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjC013223: <-- RCPT
To:<stefan@mentat1.raptorweb.nl>
Feb  4 00:34:54 mentat1 milter-spamc[13128]: 00001 k13NYmjC013223:
RCPT='<stefan@mentat1.raptorweb.nl>' rcpt_addr='stefan' rcpt_host=''
rcpt_mailer='local'
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-to:stefan@mentat1.raptorweb.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-to:mentat1.raptorweb.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: checking "/etc/mail/access.db" for
"milter-spamc-to:raptorweb.nl"
Feb  4 00:34:54 mentat1 milter-spamc[13128]: access DB
key="milter-spamc-to:raptorweb.nl" value="REJECT"
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjC013223: milter_read(milter-spamc): cmd
read returned 0, expecting 5
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjC013223: Milter (milter-spamc): to error
state
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjC013223: --- 250 2.1.5
<stefan@mentat1.raptorweb.nl>... Recipient ok
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjC013223: <-- DATA
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjC013223: --- 354 Enter mail, end with
"." on a line by itself
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjC013223:
from=<S.M.Suurmeijer@rug.nl>, size=922, class=0, nrcpts=1,
msgid=<web-15715494@mail3.rug.nl>, proto=SMTP, daemon=MTA, relay=smtp1.rug.nl
[129.125.50.11]
Feb  4 00:34:54 mentat1 sendmail[13223]: k13NYmjC013223: --- 250 2.0.0 k13NYmjC013223
Message accepted for delivery
Feb  4 00:34:54 mentat1 sendmail[13225]: k13NYmjC013223: alias
<stefan@mentat1.raptorweb.nl> => stefan@rc-lin02.rc.rug.nl
Feb  4 00:34:54 mentat1 sendmail[13225]: k13NYmjC013223: SMTP outgoing connect on
mentat1.raptorweb.nl


-- Attached file included as plaintext by Ecartis --
-- File: signature.asc
-- Desc: OpenPGP digital signature

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.2 (GNU/Linux)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org

iD8DBQFD4+pNUCGW1yc7kPMRAnRWAJ49vV4iKVQOYXN6r8+9fm/7hD9vCACdFPd8
zFpDy6OQzB6JLlJ91hoztAI=
=RfEf
-----END PGP SIGNATURE-----



Lists Index Date Thread Search