I'm running Spamassassin 3.1.4, spamass-milter 0.3.1, sendmail 8.13.8 (all from the Blastwave builds) on a Sun 220R running Solaris 9. I upgraded to the very latest versions last Tuesday. On Wednesday I saw some "451
4.3.2 Please try again later" messages, gradually increasing until now I am getting pretty much 100% dropped messages. Any message that gets connected to spamassassin gets tempfailed. (so, I had to turn spamassassin OFF, which isn't pretty)
My backup email server is running an identical configuration with Spamass-milter 0.3.0 and sendmail 8.13.7, and working perfectly.
Anyone else having problems, or thoughts on what to check next? All the permissions look right but I could have missed something obvious.
spamd conf:
--
# more /opt/csw/etc/spamassassin/local.cf
# This is the right place to customize your installation of SpamAssassin.
#
# See 'perldoc Mail::SpamAssassin::Conf' for details of what can be
# tweaked.
#
###########################################################################
#
# rewrite_header Subject *****SPAM*****
# report_safe 1
# trusted_networks 212.17.35.
# lock_method flock
rewrite_header Subject *****MAYBESPAM*****
whitelist_from [EMAIL PROTECTED]
report_safe 0
#bayes_expiry_max_db_size 100000
#bayes_path /opt/csw/share/bayes
#bayes_file_mode 666
#use_dcc 0
use_bayes 0
use_pyzor 0
#use_razor2 0
skip_rbl_checks 1
score DEAR_SOMETHING 0
#
Running spamd in debug mode, this is /var/log/mail for a short message I just sent to myself:
Sep 18 12:02:15 isis sm-mta[1134]: [ID 801593 mail.info] k8IG2FL1001134: from=<[EMAIL PROTECTED]>, size=624, class=0, nrcpts=1, msgid=<
[EMAIL PROTECTED]>, proto=ESMTP, daemon=MTA-v4, relay=lapis [128.103.170.16
]
Sep 18 12:02:15 isis sm-mta[1134]: [ID 801593 mail.info] k8IG2FL1001134: Milter add: header: X-Virus-Scanned: ClamAV version 0.88.4, clamav-milter version 0.88.4 on isis
Sep 18 12:02:15 isis sm-mta[1134]: [ID 801593 mail.info] k8IG2FL1001134: Milter add: header: X-Virus-Status: Clean
Sep 18 12:02:15 isis spamass-milter[215]: [ID 566887 mail.error] Thrown error: read error: Interrupted system call
Sep 18 12:02:15 isis sm-mta[1126]: [ID 801593 mail.info] k8IG2Dqu001126: Milter: data, reject=451 4.3.2 Please try again later
Sep 18 12:02:15 isis sm-mta[1126]: [ID 801593 mail.info] k8IG2Dqu001126: to=<[EMAIL PROTECTED]>, delay=00:00:02, pri=32275, stat=Please try again later
Sep 18 12:02:15 localhost spamd[204]: prefork: ordered 1148 to accept
Sep 18 12:02:15 localhost spamd[204]: prefork: sysread(14) not ready, wait max 300 secs
Sep 18 12:02:15 localhost spamd[204]: prefork: child 1148: entering state 2
Sep 18 12:02:15 localhost spamd[204]: prefork: new lowest idle kid: none
Sep 18 12:02:15 localhost spamd[1148]: spamd: connection from localhost [127.0.0.1] at port 33411
Sep 18 12:02:15 localhost spamd[1148]: info: user has changed
Sep 18 12:02:15 localhost spamd[1148]: config: score set 1 chosen.
Sep 18 12:02:15 localhost spamd[1148]: message: line ending changed to CRLF
Sep 18 12:02:15 localhost spamd[1148]: spamd: processing message <[EMAIL PROTECTED]> for root:66
Sep 18 12:02:15 localhost spamd[1148]: dns: name server: 128.103.201.100, family: 2, ipv6: 0
Sep 18 12:02:15 localhost spamd[1148]: received-header: parsed as [ ip=128.103.170.16 rdns=lapis helo=lapis.gsd.harvard.edu by=isis.gsd.harvard.edu!8.13.0/8.13.0!
ident= envfrom=[EMAIL PROTECTED] intl=0 id=k8IG2FL1001134 auth= ]
Sep 18 12:02:15 localhost spamd[1148]: dns: looking up A records for 'isis.gsd.harvard.edu!8.13.0/8.13.0!'
Sep 18 12:02:15 localhost spamd[1148]: dns: A records for 'isis.gsd.harvard.edu!8.13.0/8.13.0!':
Sep 18 12:02:15 localhost spamd[1148]: dns: looking up A records for 'isis.gsd.harvard.edu!8.13.0/8.13.0!'
Sep 18 12:02:15 localhost spamd[1148]: dns: A records for 'isis.gsd.harvard.edu!8.13.0/8.13.0!':
Sep 18 12:02:15 localhost spamd[1148]: received-header: relay 128.103.170.16 trusted? no internal? no
Sep 18 12:02:15 localhost spamd[1148]: metadata: X-Spam-Relays-Trusted:
Sep 18 12:02:15 localhost spamd[1148]: metadata: X-Spam-Relays-Untrusted: [ ip=128.103.170.16 rdns=lapis helo=lapis.gsd.harvard.edu by=isis.gsd.harvard.edu!8.13.0/8.13.0!
ident= envfrom=[EMAIL PROTECTED] intl=0 id=k8IG2FL1001134 auth= ]
Sep 18 12:02:15 localhost spamd[1148]: metadata: X-Spam-Relays-Internal:
Sep 18 12:02:15 localhost spamd[1148]: metadata: X-Spam-Relays-External: [ ip=128.103.170.16 rdns=lapis helo=lapis.gsd.harvard.edu by=isis.gsd.harvard.edu!8.13.0/8.13.0!
ident= envfrom=[EMAIL PROTECTED] intl=0 id=k8IG2FL1001134 auth= ]
Sep 18 12:02:15 localhost spamd[1148]: message: ---- MIME PARSER START ----
Sep 18 12:02:15 localhost spamd[1148]: message: main message type: text/plain
Sep 18 12:02:15 localhost spamd[1148]: message: parsing normal part
Sep 18 12:02:15 localhost spamd[1148]: message: added part, type: text/plain
Sep 18 12:02:15 localhost spamd[1148]: message: ---- MIME PARSER END ----
Sep 18 12:02:15 localhost spamd[1148]: message: no encoding detected
Sep 18 12:02:15 localhost spamd[1148]: uri: parsed uri found, mailto:[EMAIL PROTECTED]
Sep 18 12:02:15 localhost spamd[1148]: uri: cleaned parsed uri, mailto:[EMAIL PROTECTED]
Sep 18 12:02:15 localhost spamd[1148]: uri: parsed domain, harvard.edu
Sep 18 12:02:15 localhost spamd[1148]: uridnsbl: domains to query:
Sep 18 12:02:15 localhost spamd[1148]: dns: checking A and MX for host gsd.harvard.edu
Sep 18 12:02:15 localhost spamd[1148]: dns: launching DNS A query for gsd.harvard.edu in background
Sep 18 12:02:15 localhost spamd[1148]: dns: launching DNS MX query for gsd.harvard.edu in background
Sep 18 12:02:15 localhost spamd[1148]: check: running tests for priority: 0
Sep 18 12:02:15 localhost spamd[1148]: rules: running header regexp tests; score so far=0
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __HAS_MSGID ======> got hit: "<"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __CT ======> got hit: "t"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __HAS_RCVD ======> got hit: "f"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __SANE_MSGID ======> got hit: "<[EMAIL PROTECTED]
>
Sep 18 12:02:15 localhost spamd[1148]: rules: "
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __CT_TEXT_PLAIN ======> got hit: "text/plain"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __CTYPE_CHARSET_QUOTED ======> got hit: "charset=""
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __EUDORA_MSGID ======> got hit: "<[EMAIL PROTECTED]
>"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __MSGID_OK_HOST ======> got hit: "@pop.gsd.harvard.edu>"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __ANY_QUALCOMM_MUA ======> got hit: "QUALCOMM"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __MIME_VERSION ======> got hit: "1"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __EUDORA_MUA ======> got hit: "QUALCOMM Windows Eudora Version 6."
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __TOCC_EXISTS ======> got hit: "b"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __MSGID_OK_DIGITS ======> got hit: "2006091812"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __HAS_SUBJECT ======> got hit: "t"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __MSGID_OK_HEX ======> got hit: "031e78a8"
Sep 18 12:02:15 localhost spamd[1148]: rules: ran header rule __HAS_X_MAILER ======> got hit: "Q"
Sep 18 12:02:15 localhost spamd[1148]: spf: checking HELO (helo=lapis.gsd.harvard.edu, ip=128.103.170.16)
Sep 18 12:02:15 localhost spamd[1148]: Insecure dependency in `` while running with -T switch at /opt/csw/share/perl/csw/Sys/Hostname/Long.pm line 91, <GEN86> line 31.
Sep 18 12:02:15 localhost spamd[1148]: spf: cannot load or create Mail::SPF::Query module: Insecure dependency in `` while running with -T switch at /opt/csw/share/perl/csw/Sys/Hostname/Long.pm line 91, <GEN86> line 31.
Sep 18 12:02:15 localhost spamd[1148]: eval: all '*From' addrs: [EMAIL PROTECTED]
Sep 18 12:02:15 localhost spamd[1148]: eval: trying Received header date for real time: 18 Sep 2006 12:02:15 %z
Sep 18 12:02:15 localhost spamd[1148]: eval: time_t from date=1158580935, rcvd= 18 Sep 2006 12:02:15 %z
Sep 18 12:02:15 localhost spamd[1148]: eval: all '*To' addrs: [EMAIL PROTECTED]
Sep 18 12:02:15 localhost spamd[1148]: spf: found Envelope-From in first external Received header
Sep 18 12:02:15 localhost spamd[1148]: spf: checking EnvelopeFrom (helo=lapis.gsd.harvard.edu, ip=128.103.170.16, envfrom=
[EMAIL PROTECTED])
Sep 18 12:02:15 localhost spamd[1148]: spf: query for [EMAIL PROTECTED]/128.103.170.16/lapis.gsd.harvard.edu: result: none, comment: SPF: domain of sender
[EMAIL PROTECTED] does not designate mailers
Sep 18 12:02:15 localhost spamd[1148]: rules: ran eval rule DATE_IN_FUTURE_03_06 ======> got hit
Sep 18 12:02:15 localhost spamd[1148]: rules: ran eval rule __ENV_AND_HDR_FROM_MATCH ======> got hit
Sep 18 12:02:15 localhost spamd[1148]: spf: def_whitelist_from_spf: [EMAIL PROTECTED] is not in DEF_WHITELIST_FROM_SPF
Sep 18 12:02:15 localhost spamd[1148]: eval: date chosen from message: Mon Sep 18 08:02:15 2006
Sep 18 12:02:15 localhost spamd[1148]: spf: whitelist_from_spf: [EMAIL PROTECTED] is not in user's WHITELIST_FROM_SPF
Sep 18 12:02:15 localhost spamd[1148]: rules: running body-text per-line regexp tests; score so far=2.007
Sep 18 12:02:15 isis sm-mta[1133]: [ID 801593 mail.info] k8IG2Ejr001133: from=<sentto-9926671-1989-1158595321-jgriggs=[EMAIL PROTECTED]
>, size=3701, class=-60, nrcpts=1, msgid=<[EMAIL PROTECTED]>, proto=SMTP, daemon=MTA-v4, relay=n30.bullet.scd.yahoo.com
[66.94.237.23]
Sep 18 12:02:15 localhost spamd[1148]: rules: ran body rule __NONEMPTY_BODY ======> got hit: "t"
Sep 18 12:02:15 localhost spamd[1148]: uri: running uri tests; score so far=2.007
Sep 18 12:02:15 localhost spamd[1148]: bayes: not scoring message, returning undef
Sep 18 12:02:15 localhost spamd[1148]: bayes: opportunistic call attempt failed, DB not readable
Sep 18 12:02:15 isis sm-mta[1133]: [ID 801593 mail.info] k8IG2Ejr001133: Milter add: header: X-Virus-Scanned: ClamAV version 0.88.4, clamav-milter version 0.88.4 on isis
Sep 18 12:02:15 isis sm-mta[1133]: [ID 801593 mail.info] k8IG2Ejr001133: Milter add: header: X-Virus-Status: Clean
Sep 18 12:02:15 isis spamass-milter[215]: [ID 566887 mail.error] Thrown error: read error: Interrupted system call
Sep 18 12:02:15 isis sm-mta[1134]: [ID 801593 mail.info] k8IG2FL1001134: Milter: data, reject=451 4.3.2 Please try again later
Sep 18 12:02:15 isis sm-mta[1134]: [ID 801593 mail.info] k8IG2FL1001134: to=<[EMAIL PROTECTED]>, delay=00:00:00, pri=30624, stat=Please try again later
