SA 3.1.7 returning no result to MS?

Mon Nov 13 10:56:09 GMT 2006

On Fri, 10 Nov 2006, Matt Kettler wrote:

> David Lee wrote:
> > (Linux/FC5; sendmail 8.13.7; MS 4.56.8)
> >
> > Yesterday on our two main inbound mailrelays I upgraded SA from 3.1.4 to
> > 3.1.7.  The MS config has:
> >    Log Spam = yes
> >    Log Non Spam = yes
> >
> > In the daily logs we now seem to be getting several occurence of:
> >    Message XXX from YYY to ZZZ is not spam, SpamAssassin (not cached, score=0, required 6, autolearn=)
> >
> > and:
> >    Message PPP from QQQ to RRR is not spam, SpamAssassin (cached, score=0, required 6, autolearn=)
> >
> > scattered amongst the occurences of more real data.  (Around 7% of entries
> > on one machine, around 4% on the other, are in such truncated/empty forms).
> >
> > The daily logs prior to this show no occurences at all.
> >
> > Any thoughts?
>
> spamassassin --lint any errors reported, or just runs and exits quietly?

Runs and exits quietly.

> spamassassin -D --lint, and see what the "default rules dir" is, and make sure
> all the default .cf files are there.

Attached.  Looks mostly clean.

There's an SA "FP_MIXED_PORN3" problem, but apparently several people have
reported this in various places and, if I read those reports correctly, it
is not deemed to be a major problem (rather just a warning).

Googling around a little, I find:
   http://www.gossamer-threads.com/lists/spamassassin/users/87230

and one of the replies in the thread says:
   "There's been some discussion about scores with 0 rating popping
   similar so I wonder if that's related."

That "0 rating" sounds like the symptoms I'm seeing.
   Message ... is not spam, SpamAssassin (cached, score=0, required 6, autolearn=)

When I do a manual "sa-update", the entries continue to appear in the log
file.  But when I follow this with "service MailScanner reload", these
entries almost cease for a while.

("For a while"?  I tried before the weekend and they seemed to have
ceased.  Returning after the weekend, they seem to have resumed.
Re-tried just now: seem to have ceased.)

Note that this void scoring is only on a minority of the emails (nothing
like all of them).

>
> > 2. When I check on a third (higher MX, lower preference) machine on which
> > I did a similar upgrade, but on which Razor had been working properly
> > working both before and after the upgrade, this has such entries both
> > before and after.  Which sort of points the finger towards Razor, rather
> > than the SA upgrade.
>
> I highly doubt razor is involved. From the sounds of it, SA isn't parsing its
> ruleset.

But (speculation!) might some sort of SA/Razor timeout cause subsequent SA
results to be discarded/ignored, causing emptiness to be returned to MS?

(I'm happy not to get distracted onto this razor datapoint, but I though I
at least ought to have mentioned it...)

-- 

:  David Lee                                I.T. Service          :
:  Senior Systems Programmer                Computer Centre       :
:                                           Durham University     :
:  http://www.dur.ac.uk/t.d.lee/            South Road            :
:                                           Durham DH1 3LE        :
:  Phone: +44 191 334 2752                  U.K.                  :
-------------- next part --------------
[30406] dbg: logger: adding facilities: all
[30406] dbg: logger: logging level is DBG
[30406] dbg: generic: SpamAssassin version 3.1.7
[30406] dbg: config: score set 0 chosen.
[30406] dbg: util: running in taint mode? yes
[30406] dbg: util: taint mode: deleting unsafe environment variables, resetting PATH
[30406] dbg: util: PATH included '/usr/kerberos/sbin', keeping
[30406] dbg: util: PATH included '/usr/kerberos/bin', keeping
[30406] dbg: util: PATH included '/sbin', keeping
[30406] dbg: util: PATH included '/bin', keeping
[30406] dbg: util: PATH included '/usr/sbin', keeping
[30406] dbg: util: PATH included '/usr/bin', keeping
[30406] dbg: util: PATH included '/root/bin', which doesn't exist, dropping
[30406] dbg: util: PATH included '/usr/local/clamav/bin', keeping
[30406] dbg: util: final PATH set to: /usr/kerberos/sbin:/usr/kerberos/bin:/sbin:/bin:/usr/sbin:/usr/bin:/usr/local/clamav/bin
[30406] dbg: message: ---- MIME PARSER START ----
[30406] dbg: message: main message type: text/plain
[30406] dbg: message: parsing normal part
[30406] dbg: message: added part, type: text/plain
[30406] dbg: message: ---- MIME PARSER END ----
[30406] dbg: dns: is Net::DNS::Resolver available? yes
[30406] dbg: dns: Net::DNS version: 0.59
[30406] dbg: diag: perl platform: 5.008008 linux
[30406] dbg: diag: module installed: Digest::SHA1, version 2.11
[30406] dbg: diag: module installed: HTML::Parser, version 3.54
[30406] dbg: diag: module installed: MIME::Base64, version 3.07
[30406] dbg: diag: module installed: DB_File, version 1.814
[30406] dbg: diag: module installed: Net::DNS, version 0.59
[30406] dbg: diag: module installed: Net::SMTP, version 2.29
[30406] dbg: diag: module installed: Mail::SPF::Query, version 1.999001
[30406] dbg: diag: module installed: IP::Country::Fast, version 604.001
[30406] dbg: diag: module installed: Razor2::Client::Agent, version 2.82
[30406] dbg: diag: module not installed: Net::Ident ('require' failed)
[30406] dbg: diag: module not installed: IO::Socket::INET6 ('require' failed)
[30406] dbg: diag: module not installed: IO::Socket::SSL ('require' failed)
[30406] dbg: diag: module installed: Time::HiRes, version 1.86
[30406] dbg: diag: module installed: DBI, version 1.52
[30406] dbg: diag: module installed: Getopt::Long, version 2.35
[30406] dbg: diag: module installed: LWP::UserAgent, version 2.033
[30406] dbg: diag: module installed: HTTP::Date, version 1.47
[30406] dbg: diag: module installed: Archive::Tar, version 1.29
[30406] dbg: diag: module installed: IO::Zlib, version 1.04
[30406] dbg: ignore: using a test message to lint rules
[30406] dbg: config: using "/etc/mail/spamassassin" for site rules pre files
[30406] dbg: config: read file /etc/mail/spamassassin/init.pre
[30406] dbg: config: read file /etc/mail/spamassassin/v310.pre
[30406] dbg: config: read file /etc/mail/spamassassin/v312.pre
[30406] dbg: config: using "/var/lib/spamassassin/3.001007" for sys rules pre files
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org.pre
[30406] dbg: config: using "/var/lib/spamassassin/3.001007" for default rules dir
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org.cf
[30406] dbg: config: using "/etc/mail/spamassassin" for site rules dir
[30406] dbg: config: read file /etc/mail/spamassassin/70_sare_adult.cf
[30406] dbg: config: read file /etc/mail/spamassassin/70_sare_bayes_poison_nxm.cf
[30406] dbg: config: read file /etc/mail/spamassassin/70_sare_evilnum0.cf
[30406] dbg: config: read file /etc/mail/spamassassin/70_sare_evilnum1.cf
[30406] dbg: config: read file /etc/mail/spamassassin/70_sare_evilnum2.cf
[30406] dbg: config: read file /etc/mail/spamassassin/70_sare_stocks.cf
[30406] dbg: config: read file /etc/mail/spamassassin/72_sare_bml_post25x.cf
[30406] dbg: config: read file /etc/mail/spamassassin/99_sare_fraud_post25x.cf
[30406] dbg: config: read file /etc/mail/spamassassin/german.cf
[30406] dbg: config: read file /etc/mail/spamassassin/local.cf
[30406] dbg: config: read file /etc/mail/spamassassin/mailscanner.cf
[30406] dbg: config: read file /etc/mail/spamassassin/nazi.cf
[30406] dbg: config: read file /etc/mail/spamassassin/spamassassin.cf
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x83ce40)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::Hashcash=HASH(0x256fa30)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::SPF=HASH(0x2532530)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayCountry from @INC
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::RelayCountry=HASH(0x2532ff0)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC
[30406] dbg: razor2: local tests only, skipping Razor
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::Razor2=HASH(0x26f38a0)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::DCC from @INC
[30406] dbg: dcc: local tests only, disabling DCC
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::DCC=HASH(0x2765410)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC
[30406] dbg: pyzor: local tests only, disabling Pyzor
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::Pyzor=HASH(0x27977a0)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC
[30406] dbg: razor2: local tests only, skipping Razor
[30406] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Razor2=HASH(0x25c9350), already registered
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC
[30406] dbg: reporter: local tests only, disabling SpamCop
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::SpamCop=HASH(0x27c96f0)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::AWL from @INC
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::AWL=HASH(0x27f7240)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::AutoLearnThreshold=HASH(0x2807ca0)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::WhiteListSubject=HASH(0x281dbc0)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::MIMEHeader=HASH(0x282ba40)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC
[30406] dbg: plugin: registered Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x28407b0)
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayCountry from @INC
[30406] dbg: plugin: did not register Mail::SpamAssassin::Plugin::RelayCountry=HASH(0x2840850), already registered
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
[30406] dbg: plugin: did not register Mail::SpamAssassin::Plugin::SPF=HASH(0x25c9590), already registered
[30406] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
[30406] dbg: plugin: did not register Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x2532820), already registered
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/empty.pre
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/empty.pre" for included file
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_antivirus.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_antivirus.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_antivirus.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/80_additional.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/80_additional.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/80_additional.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_dkim.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_dkim.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_dkim.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_net_tests.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_net_tests.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_net_tests.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_dcc.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_dcc.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_dcc.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_advance_fee.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_advance_fee.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_advance_fee.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_hashcash.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_hashcash.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_hashcash.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_drugs.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_drugs.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_drugs.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_phrases.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_phrases.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_phrases.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_uribl.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_uribl.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_uribl.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_awl.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/60_awl.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_awl.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_body_tests_es.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_body_tests_es.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_body_tests_es.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_head_tests.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_head_tests.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_head_tests.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_dnsbl_tests.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_dnsbl_tests.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_dnsbl_tests.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_ratware.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_ratware.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_ratware.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_accessdb.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_accessdb.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_accessdb.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_uri_tests.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_uri_tests.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_uri_tests.cf
[30406] dbg: config: adding redirector regex: /^http:\/\/chkpt\.zdnet\.com\/chkpt\/\w+\/(.*)$/i
[30406] dbg: config: adding redirector regex: /^http:\/\/www(?:\d+)?\.nate\.com\/r\/\w+\/(.*)$/i
[30406] dbg: config: adding redirector regex: /^http:\/\/.+\.gov\/(?:.*\/)?externalLink\.jhtml\?.*url=(.*?)(?:&.*)?$/i
[30406] dbg: config: adding redirector regex: /^http:\/\/redir\.internet\.com\/.+?\/.+?\/(.*)$/i
[30406] dbg: config: adding redirector regex: /^http:\/\/(?:.*?\.)?adtech\.de\/.*(?:;|\|)link=(.*?)(?:;|$)/i
[30406] dbg: config: adding redirector regex: m'^http.*?/redirect\.php\?.*(?<=[?&])goto=(.*?)(?:$|[&#])'i
[30406] dbg: config: adding redirector regex: m'^https?:/*(?:[^/]+\.)?emf\d\.com/r\.cfm.*?&r=(.*)'i
[30406] dbg: config: adding redirector regex: m'/(?:index.php)?\?.*(?<=[?&])URL=(.*?)(?:$|[&#])'i
[30406] dbg: config: adding redirector regex: m'^http:/*(?:\w+\.)?google(?:\.\w{2,3}){1,2}/url\?.*?(?<=[?&])q=(.*?)(?:$|[&#])'i
[30406] dbg: config: adding redirector regex: m'^http:/*(?:\w+\.)?google(?:\.\w{2,3}){1,2}/search\?.*?(?<=[?&])q=[^&]*?(?<=%20|..[=+\s])site:(.*?)(?:$|%20|[\s+&#])'i
[30406] dbg: config: adding redirector regex: m'^http:/*(?:\w+\.)?google(?:\.\w{2,3}){1,2}/search\?.*?(?<=[?&])q=[^&]*?(?<=%20|..[=+\s])(?:"|%22)(.*?)(?:$|%22|["\s+&#])'i
[30406] dbg: config: adding redirector regex: m'^http:/*(?:\w+\.)?google(?:\.\w{2,3}){1,2}/translate\?.*?(?<=[?&])u=(.*?)(?:$|[&#])'i
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_spf.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_spf.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_spf.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_meta_tests.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_meta_tests.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_meta_tests.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_razor2.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_razor2.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_razor2.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_pl.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_pl.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_pl.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_dkim.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_dkim.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_dkim.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/50_scores.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/50_scores.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/50_scores.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_anti_ratware.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_anti_ratware.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_anti_ratware.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_it.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_it.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_it.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_fr.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_fr.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_fr.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_porn.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_porn.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_porn.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_body_tests_pl.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_body_tests_pl.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_body_tests_pl.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_replace.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_replace.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_replace.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/23_bayes.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/23_bayes.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/23_bayes.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_nl.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_nl.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_nl.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_body_tests.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_body_tests.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_body_tests.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_subject.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_subject.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_subject.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/70_iadb.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/70_iadb.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/70_iadb.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/10_misc.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/10_misc.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/10_misc.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_pyzor.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_pyzor.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_pyzor.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_textcat.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_textcat.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_textcat.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_domainkeys.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/25_domainkeys.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/25_domainkeys.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_de.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_de.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_de.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_fake_helo_tests.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_fake_helo_tests.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_fake_helo_tests.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_compensate.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_compensate.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_compensate.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_dk.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_dk.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_dk.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_pt_br.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_pt_br.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/30_text_pt_br.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_html_tests.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/20_html_tests.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/20_html_tests.cf
[30406] dbg: plugin: fixed relative path: /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_spf.cf
[30406] dbg: config: using "/var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_spf.cf" for included file
[30406] dbg: config: read file /var/lib/spamassassin/3.001007/updates_spamassassin_org/60_whitelist_spf.cf
[30406] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x28407b0) implements 'finish_parsing_end'
[30406] dbg: replacetags: replacing tags
[30406] dbg: replacetags: done replacing tags
[30406] dbg: bayes: tie-ing to DB file R/O /root/.spamassassin/bayes_toks
[30406] dbg: bayes: tie-ing to DB file R/O /root/.spamassassin/bayes_seen
[30406] dbg: bayes: found bayes db version 3
[30406] dbg: bayes: DB journal sync: last sync: 1163413288
[30406] dbg: config: score set 2 chosen.
[30406] dbg: message: ---- MIME PARSER START ----
[30406] dbg: message: main message type: text/plain
[30406] dbg: message: parsing normal part
[30406] dbg: message: added part, type: text/plain
[30406] dbg: message: ---- MIME PARSER END ----
[30406] dbg: dns: is DNS available? 0
[30406] dbg: metadata: X-Spam-Relays-Trusted: 
[30406] dbg: metadata: X-Spam-Relays-Untrusted: 
[30406] dbg: metadata: X-Spam-Relays-Internal: 
[30406] dbg: metadata: X-Spam-Relays-External: 
[30406] dbg: plugin: Mail::SpamAssassin::Plugin::RelayCountry=HASH(0x2532ff0) implements 'extract_metadata'
[30406] dbg: metadata: X-Relay-Countries: 
[30406] dbg: message: no encoding detected
[30406] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x83ce40) implements 'parsed_metadata'
[30406] dbg: plugin: Mail::SpamAssassin::Plugin::RelayCountry=HASH(0x2532ff0) implements 'parsed_metadata'
[30406] dbg: rules: local tests only, ignoring RBL eval
[30406] dbg: check: running tests for priority: 0
[30406] dbg: rules: running header regexp tests; score so far=0
[30406] dbg: rules: ran header rule __HAS_MSGID ======> got hit: "<"
[30406] dbg: rules: ran header rule __SANE_MSGID ======> got hit: "<1163413298 at lint_rules>
[30406] dbg: rules: "
[30406] dbg: rules: ran header rule __MSGID_OK_HOST ======> got hit: "@lint_rules>"
[30406] dbg: rules: ran header rule __MSGID_OK_DIGITS ======> got hit: "1163413298"
[30406] dbg: eval: all '*From' addrs: ignore at compiling.spamassassin.taint.org
[30406] dbg: eval: all '*To' addrs: 
[30406] dbg: rules: ran eval rule NO_RELAYS ======> got hit
[30406] dbg: rules: ran eval rule __UNUSABLE_MSGID ======> got hit
[30406] dbg: rules: running body-text per-line regexp tests; score so far=-0.001
[30406] dbg: rules: ran body rule __NONEMPTY_BODY ======> got hit: "I"
[30406] dbg: uri: running uri tests; score so far=-0.001
[30406] dbg: bayes: DB journal sync: last sync: 1163413288
[30406] dbg: bayes: corpus size: nspam = 2768964, nham = 846951
[30406] dbg: bayes: score = 0.146100146430509
[30406] dbg: bayes: DB journal sync: last sync: 1163413288
[30406] dbg: bayes: untie-ing
[30406] dbg: bayes: untie-ing db_toks
[30406] dbg: bayes: untie-ing db_seen
[30406] dbg: rules: ran eval rule BAYES_20 ======> got hit
[30406] dbg: rules: running raw-body-text per-line regexp tests; score so far=-0.741
[30406] dbg: rules: running full-text regexp tests; score so far=-0.741
[30406] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x83ce40) implements 'check_tick'
[30406] dbg: check: running tests for priority: 500
[30406] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x83ce40) implements 'check_post_dnsbl'
[30406] dbg: rules: running meta tests; score so far=-0.741
[30406] info: rules: meta test FP_MIXED_PORN3 has undefined dependency 'FP_PENETRATION'
[30406] dbg: rules: running header regexp tests; score so far=1.416
[30406] dbg: rules: running body-text per-line regexp tests; score so far=1.416
[30406] dbg: uri: running uri tests; score so far=1.416
[30406] dbg: rules: running raw-body-text per-line regexp tests; score so far=1.416
[30406] dbg: rules: running full-text regexp tests; score so far=1.416
[30406] dbg: check: running tests for priority: 1000
[30406] dbg: rules: running meta tests; score so far=1.416
[30406] dbg: rules: running header regexp tests; score so far=1.416
[30406] dbg: rules: running body-text per-line regexp tests; score so far=1.416
[30406] dbg: uri: running uri tests; score so far=1.416
[30406] dbg: rules: running raw-body-text per-line regexp tests; score so far=1.416
[30406] dbg: rules: running full-text regexp tests; score so far=1.416
[30406] dbg: check: is spam? score=1.416 required=5
[30406] dbg: check: tests=BAYES_20,MISSING_SUBJECT,NO_RECEIVED,NO_RELAYS,TO_CC_NONE
[30406] dbg: check: subtests=__HAS_MSGID,__MSGID_OK_DIGITS,__MSGID_OK_HOST,__NONEMPTY_BODY,__SANE_MSGID,__UNUSABLE_MSGID