You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@spamassassin.apache.org by Chris Datfung <ch...@gmail.com> on 2010/09/01 22:47:36 UTC

Spamassassin not checking user provided RBLs

I'm running spamassassin version 3.3.1-1 from the Debian  package. I added
several RBLs to /etc/mail/spamassassin/init.pre but spamassassin only
queries its built in RBLs and not the ones I added. An example RBL entry to
init.pre is shown below:

header IN_NJABL_ORG    rbleval:check_rbl('njabl','dnsbl.njabl.org.')
describe IN_NJABL_ORG  Received via a relay in dnsbl.njabl.org
tflags IN_NJABL_ORG    net
score IN_NJABL_ORG      5

I also find messages that aren't tagged as being in an RBL that are listed
in cbl.abuseat.org and zen.spamhaus.org which should be automatically
checked by spamassassin using the default configuration. As mentioned before
other (built-in) RBL checks work. Any hints as to why my custom RBL checks
added to init.pre (and also tried local.cf) aren't queried after restarting
spamassassin?

Thanks,
 Chris

Re: Spamassassin not checking user provided RBLs

Posted by Matt Kettler <mk...@verizon.net>.
  On 9/2/2010 8:24 AM, Chris Datfung wrote:
> On Thu, Sep 2, 2010 at 2:30 PM, Matt Kettler <mkettler_sa@verizon.net 
> <ma...@verizon.net>> wrote:
>
>
>     Can you try again using a message, such as the sample-spam.txt
>     that comes with the SA tarball.
>
>     spamassassin < sample-spam.txt 2>&1 -D
>
>     In particular, we want to look at the "dbg: dns: is DNS
>     available?" line and other DNS related ones nearby.
>
>
> Hi Matt,
>
> I included (hopefully only) the relevant data from the above command 
> below:
>
> Sep  2 22:13:42.202 [11886] dbg: dns: checking RBL combined.njabl.org 
> <http://combined.njabl.org>., set njabl

<snip>
> Sep  2 22:13:42.202 [11886] dbg: dns: checking RBL dnsbl.njabl.org 
> <http://dnsbl.njabl.org>., set njabl
>
<snip>

Well that sure looks like it is querying your dnsbl.njabl.org, and the 
default rulset rule which uses combined.njabl.org.

What makes you think that it's not running the query?

Is your rule IN_NJABL_ORG never hitting? What about RCVD_IN_NJABL_* 
rules (the rules that are part of the stock ruleset and query the same 
DNSBL, but in a different way)? Are they hitting?

Is your mail already filtered at the MTA layer rejecting messages in 
NJABL (which would make it much less likely, but not impossible, to see 
hits)?

Are you on AT&T as an internet provider and using their DNS servers? 
According to the NJABL FAQ, AT&T intentionally poisons dnsbl.njabl.org 
(starting in 2004, they may or may not still do so), returing a NS 
record pointing to 127.0.0.1.

Try this on the command line:

dig ns dnsbl.njabl.org

If you get no answer, or something like this:


;; AUTHORITY SECTION:
dnsbl.njabl.org.        82758   IN      NS      loopback.

;; ADDITIONAL SECTION:
loopback.               52978   IN      A       127.0.0.1


Your provider is messing with you.












Re: Spamassassin not checking user provided RBLs

Posted by Chris Datfung <ch...@gmail.com>.
On Thu, Sep 2, 2010 at 2:30 PM, Matt Kettler <mk...@verizon.net>wrote:

>
> Can you try again using a message, such as the sample-spam.txt that comes
> with the SA tarball.
>
> spamassassin < sample-spam.txt 2>&1 -D
>
> In particular, we want to look at the "dbg: dns: is DNS available?" line
> and other DNS related ones nearby.
>
>

Hi Matt,

I included (hopefully only) the relevant data from the above command below:

Sep  2 22:13:37.978 [11886] dbg: dns: is Net::DNS::Resolver available? yes
Sep  2 22:13:37.979 [11886] dbg: dns: Net::DNS version: 0.63
[...snip]
Sep  2 22:13:39.147 [11886] dbg: dns: is_dns_available() last checked
1283429619.1 seconds ago; re-checking
Sep  2 22:13:39.147 [11886] dbg: dns: is Net::DNS::Resolver available? yes
Sep  2 22:13:39.147 [11886] dbg: dns: Net::DNS version: 0.63
Sep  2 22:13:39.148 [11886] dbg: dns: name server: x.x.x.x, LocalAddr:
0.0.0.0
Sep  2 22:13:39.148 [11886] dbg: dns: resolver socket rx buffer size is
126976 bytes
Sep  2 22:13:39.148 [11886] dbg: dns: testing resolver nameservers: x.x.x.x
Sep  2 22:13:39.148 [11886] dbg: dns: trying (3) adelphia.net...
Sep  2 22:13:39.148 [11886] dbg: dns: looking up NS for 'adelphia.net'
Sep  2 22:13:39.149 [11886] dbg: dns: providing a callback for id: 53318/
adelphia.net/NS/IN
Sep  2 22:13:42.151 [11886] dbg: dns: NS lookup of adelphia.net using
x.x.x.x failed, no results found
Sep  2 22:13:42.151 [11886] dbg: dns: trying (2) colorado.edu...
Sep  2 22:13:42.151 [11886] dbg: dns: looking up NS for 'colorado.edu'
Sep  2 22:13:42.151 [11886] dbg: dns: providing a callback for id: 53742/
colorado.edu/NS/IN
Sep  2 22:13:42.198 [11886] dbg: dns: NS lookup of colorado.edu using
x.x.x.x succeeded => DNS available (set dns_available to override)
[...snip]
Sep  2 22:13:42.200 [11886] dbg: dns: is DNS available? 1
Sep  2 22:13:42.201 [11886] dbg: uridnsbl: domains to query:
Sep  2 22:13:42.201 [11886] dbg: dns: checking RBL bl.spamcop.net., set
spamcop
Sep  2 22:13:42.202 [11886] dbg: dns: checking RBL combined.njabl.org., set
njabl
Sep  2 22:13:42.202 [11886] dbg: dns: checking RBL bl.spamcop.net., set
spamcop
Sep  2 22:13:42.202 [11886] dbg: dns: checking RBL dnsbl.njabl.org., set
njabl
Sep  2 22:13:42.202 [11886] dbg: dns: checking RBL cbl.abuseat.org., set
abuseat
Sep  2 22:13:42.203 [11886] dbg: dns: checking RBL b.barracudacentral.org.,
set barracuda
Sep  2 22:13:42.203 [11886] dbg: dns: checking RBL zen.spamhaus.org., set
zen-lastexternal
Sep  2 22:13:42.203 [11886] dbg: dns: checking RBL dnsbl.sorbs.net., set
sorbs-lastexternal
Sep  2 22:13:42.203 [11886] dbg: dns: checking RBL dnsbl.sorbs.net., set
sorbs
Sep  2 22:13:42.203 [11886] dbg: dns: checking RBL bl.score.senderscore.com.,
set rnbl-lastexternal
Sep  2 22:13:42.203 [11886] dbg: dns: checking RBL zen.spamhaus.org., set
zen-lastexternal
Sep  2 22:13:42.203 [11886] dbg: dns: checking RBL psbl.surriel.com., set
psbl-lastexternal

Thanks,
 Chris

Re: Spamassassin not checking user provided RBLs

Posted by Matt Kettler <mk...@verizon.net>.
  On 9/2/2010 3:08 AM, Chris Datfung wrote:
> On Thu, Sep 2, 2010 at 5:06 AM, Benny Pedersen <me@junc.org 
> <ma...@junc.org>> wrote:
>
>     On ons 01 sep 2010 22:47:36 CEST, Chris Datfung wrote
>
>
>         header IN_NJABL_ORG  
>          rbleval:check_rbl('njabl','dnsbl.njabl.org.')
>         describe IN_NJABL_ORG  Received via a relay in dnsbl.njabl.org
>         <http://dnsbl.njabl.org>
>         tflags IN_NJABL_ORG    net
>         score IN_NJABL_ORG      5
>
>
>     first errpr is you added it to a pre file, next is that it is in
>     std rules being checked
>
>     did you or debian run sa-update ?
>
>
> Hi Benny,
>
> I ran sa-update. As you pointed out, there are a number of RBLs in the 
> standard configuration that for some reason aren't checked, thus I 
> thought I could easily fix that by adding them to local.cf 
> <http://local.cf> and when that didn't work I tried in init.pre. I do 
> see other RBL generated scores in the message headers, so it appears 
> that some RBL checks are indeed performed.
>
>
>     what gives spamassassin 2>&1 -D --lint | less
>
>
<snip>
> Do you see any obvious problems?

Hmm, --lint isn't really the best option for a DNS problem. --lint 
implies local-only mode, which limits its usefulness for this particular 
kind of issue.

I can see you do have Net::DNS installed, which is good:
> Sep  2 16:56:51.709 [986] dbg: dns: is Net::DNS::Resolver available? yes
> Sep  2 16:56:51.709 [986] dbg: dns: Net::DNS version: 0.63

and I don't see any parse errors, which is also good..

> Sep  2 16:56:53.771 [986] dbg: dns: is DNS available? 0
> Sep  2 16:56:53.771 [986] dbg: rules: local tests only, ignoring RBL eval
But local-only mode is enforced, cutting our investigation short.

Can you try again using a message, such as the sample-spam.txt that 
comes with the SA tarball.

spamassassin < sample-spam.txt 2>&1 -D

In particular, we want to look at the "dbg: dns: is DNS available?" line 
and other DNS related ones nearby.



>
> Thanks!
>  Chris


Re: Spamassassin not checking user provided RBLs

Posted by Benny Pedersen <me...@junc.org>.
On søn 10 okt 2010 12:55:56 CEST, Chris Datfung wrote

> Oct 10 21:49:58.510 [16255] warn: archive-iterator: unable to open msg: No
> such file or directory

> Any idea what the problem might be?

warn line says you did not provide a spam msg file to test with


-- 
xpoint http://www.unicom.com/pw/reply-to-harmful.html


Re: Spamassassin not checking user provided RBLs

Posted by John Hardin <jh...@impsec.org>.
On Sun, 10 Oct 2010, Chris Datfung wrote:

>> try to debug it as below with
>>
>> spamassassing 2>&1 -D -t msg | less

Try this instead:

   spamassassin 2>&1 -D -t <msg | less

Pipe the test message through spamassassin.

-- 
  John Hardin KA7OHZ                    http://www.impsec.org/~jhardin/
  jhardin@impsec.org    FALaholic #11174     pgpk -a jhardin@impsec.org
  key: 0xB8732E79 -- 2D8C 34F4 6411 F507 136C  AF76 D822 E6E6 B873 2E79
-----------------------------------------------------------------------
   North Korea: the only country in the world where people would risk
   execution to flee to communist China.                  -- Ride Fast
-----------------------------------------------------------------------
  68 days until TRON Legacy

Re: Spamassassin not checking user provided RBLs

Posted by Chris Datfung <ch...@gmail.com>.
On Thu, Sep 2, 2010 at 1:56 PM, Benny Pedersen <me...@junc.org> wrote:

> On tor 02 sep 2010 09:08:30 CEST, Chris Datfung wrote
>
>  I ran sa-update. As you pointed out, there are a number of RBLs in the
>> standard configuration that for some reason aren't checked, thus I thought
>> I
>> could easily fix that by adding them to local.cf and when that didn't
>> work I
>> tried in init.pre. I do see other RBL generated scores in the message
>> headers, so it appears that some RBL checks are indeed performed.
>>
>
> try to debug it as below with
>
> spamassassing 2>&1 -D -t msg | less
>
> what are being tested at what fails ?


Hi,

I'm still having the RBL lookup problem. It seems that no RBLs are checked
now. I just looked up an unidentified spam message received that's listed in
zen.spamhaus.org but no mention of any RBLs in the spamassassin headers. I
reran the above command and found the following error:

Oct 10 21:49:58.510 [16255] dbg: dns: Net::DNS version: 0.63
Oct 10 21:49:58.510 [16255] warn: archive-iterator: no access to msg: No
such file or directory at
/usr/share/perl5/Mail/SpamAssassin/ArchiveIterator.pm line 771.
Oct 10 21:49:58.510 [16255] warn: archive-iterator: unable to open msg: No
such file or directory

I'm running spamassassin 3.3.1-1 from the Debian package. Line 771 says:

  if (!defined $AICache) {
    my @s = stat($mail);
    @s  or warn "archive-iterator: no access to $mail: $!";
    return unless $self->_message_is_useful_by_file_modtime($s[9]);
  }

Any idea what the problem might be?

Thanks,
  Chris

Re: Spamassassin not checking user provided RBLs

Posted by Benny Pedersen <me...@junc.org>.
On tor 02 sep 2010 09:08:30 CEST, Chris Datfung wrote
> I ran sa-update. As you pointed out, there are a number of RBLs in the
> standard configuration that for some reason aren't checked, thus I thought I
> could easily fix that by adding them to local.cf and when that didn't work I
> tried in init.pre. I do see other RBL generated scores in the message
> headers, so it appears that some RBL checks are indeed performed.

try to debug it as below with

spamassassing 2>&1 -D -t msg | less

what are being tested at what fails ?

> Do you see any obvious problems?

only that you posted to much on a maillist :)

well fun aside check the pre files that you load plugins right as you  
like it to be, and then check again for not installed plugins

eg if you have DKIM loaded in pre it does not wotk if the DKIM plugin  
is not installed

-- 
xpoint http://www.unicom.com/pw/reply-to-harmful.html


Re: Spamassassin not checking user provided RBLs

Posted by Chris Datfung <ch...@gmail.com>.
On Thu, Sep 2, 2010 at 5:06 AM, Benny Pedersen <me...@junc.org> wrote:

> On ons 01 sep 2010 22:47:36 CEST, Chris Datfung wrote
>
>
>  header IN_NJABL_ORG    rbleval:check_rbl('njabl','dnsbl.njabl.org.')
>> describe IN_NJABL_ORG  Received via a relay in dnsbl.njabl.org
>> tflags IN_NJABL_ORG    net
>> score IN_NJABL_ORG      5
>>
>
> first errpr is you added it to a pre file, next is that it is in std rules
> being checked
>
> did you or debian run sa-update ?
>

Hi Benny,

I ran sa-update. As you pointed out, there are a number of RBLs in the
standard configuration that for some reason aren't checked, thus I thought I
could easily fix that by adding them to local.cf and when that didn't work I
tried in init.pre. I do see other RBL generated scores in the message
headers, so it appears that some RBL checks are indeed performed.


>
> what gives spamassassin 2>&1 -D --lint | less
>

Sep  2 16:56:51.680 [986] dbg: logger: adding facilities: all
Sep  2 16:56:51.681 [986] dbg: logger: logging level is DBG
Sep  2 16:56:51.681 [986] dbg: generic: SpamAssassin version 3.3.1
Sep  2 16:56:51.681 [986] dbg: generic: Perl 5.010001, PREFIX=/usr,
DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/spamassassin,
LOCAL_STATE_DIR=/var/lib/spamassassin
Sep  2 16:56:51.681 [986] dbg: config: timing enabled
Sep  2 16:56:51.682 [986] dbg: config: score set 0 chosen.
Sep  2 16:56:51.696 [986] dbg: util: running in taint mode? yes
Sep  2 16:56:51.696 [986] dbg: util: taint mode: deleting unsafe environment
variables, resetting PATH
Sep  2 16:56:51.696 [986] dbg: util: PATH included '/usr/local/sbin',
keeping
Sep  2 16:56:51.696 [986] dbg: util: PATH included '/usr/local/bin', keeping
Sep  2 16:56:51.696 [986] dbg: util: PATH included '/usr/sbin', keeping
Sep  2 16:56:51.697 [986] dbg: util: PATH included '/usr/bin', keeping
Sep  2 16:56:51.697 [986] dbg: util: PATH included '/sbin', keeping
Sep  2 16:56:51.697 [986] dbg: util: PATH included '/bin', keeping
Sep  2 16:56:51.697 [986] dbg: util: final PATH set to:
/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
Sep  2 16:56:51.709 [986] dbg: dns: is Net::DNS::Resolver available? yes
Sep  2 16:56:51.709 [986] dbg: dns: Net::DNS version: 0.63
Sep  2 16:56:52.471 [986] dbg: diag: perl platform: 5.010001 linux
Sep  2 16:56:52.471 [986] dbg: diag: [...] module installed: Digest::SHA1,
version 2.11
Sep  2 16:56:52.471 [986] dbg: diag: [...] module installed: HTML::Parser,
version 3.56
Sep  2 16:56:52.471 [986] dbg: diag: [...] module installed: Net::DNS,
version 0.63
Sep  2 16:56:52.471 [986] dbg: diag: [...] module installed: NetAddr::IP,
version 4.007
Sep  2 16:56:52.471 [986] dbg: diag: [...] module installed: Time::HiRes,
version 1.9719
Sep  2 16:56:52.471 [986] dbg: diag: [...] module installed: Archive::Tar,
version 1.52
Sep  2 16:56:52.471 [986] dbg: diag: [...] module installed: IO::Zlib,
version 1.09
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed: Digest::SHA1,
version 2.11
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed: MIME::Base64,
version 3.08
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed: DB_File,
version 1.82
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed: Net::SMTP,
version 2.31
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed: Mail::SPF,
version v2.005
Sep  2 16:56:52.472 [986] dbg: diag: [...] module not installed:
IP::Country::Fast ('require' failed)
Sep  2 16:56:52.472 [986] dbg: diag: [...] module not installed:
Razor2::Client::Agent ('require' failed)
Sep  2 16:56:52.472 [986] dbg: diag: [...] module not installed: Net::Ident
('require' failed)
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed:
IO::Socket::INET6, version 2.54
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed:
IO::Socket::SSL, version 1.30
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed: Compress::Zlib,
version 2.02
Sep  2 16:56:52.472 [986] dbg: diag: [...] module not installed: Mail::DKIM
('require' failed)
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed: DBI, version
1.607
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed: Getopt::Long,
version 2.38
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed: LWP::UserAgent,
version 5.829
Sep  2 16:56:52.472 [986] dbg: diag: [...] module installed: HTTP::Date,
version
 5.831
Sep  2 16:56:52.472 [986] dbg: diag: [...] module not installed:
Encode::Detect ('require' failed)
Sep  2 16:56:52.472 [986] dbg: ignore: using a test message to lint rules
Sep  2 16:56:52.473 [986] dbg: config: using "/etc/spamassassin" for site
rules pre files
Sep  2 16:56:52.490 [986] dbg: config: read file /etc/spamassassin/init.pre
Sep  2 16:56:52.498 [986] dbg: config: read file /etc/spamassassin/v310.pre
Sep  2 16:56:52.498 [986] dbg: config: read file /etc/spamassassin/v312.pre
Sep  2 16:56:52.500 [986] dbg: config: read file /etc/spamassassin/v320.pre
Sep  2 16:56:52.502 [986] dbg: config: read file /etc/spamassassin/v330.pre
Sep  2 16:56:52.502 [986] dbg: config: using
"/var/lib/spamassassin/3.003001" for sys rules pre files
Sep  2 16:56:52.509 [986] dbg: config: using
"/var/lib/spamassassin/3.003001" for default rules dir
Sep  2 16:56:52.511 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org.cf
Sep  2 16:56:52.511 [986] dbg: config: using "/etc/spamassassin" for site
rules dir
Sep  2 16:56:52.520 [986] dbg: config: read file /etc/spamassassin/
65_debian.cf
Sep  2 16:56:52.532 [986] dbg: config: read file /etc/spamassassin/local.cf
Sep  2 16:56:52.533 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::URIDNSBL from @INC
Sep  2 16:56:52.543 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::Hashcash from @INC
Sep  2 16:56:52.546 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::SPF from @INC
Sep  2 16:56:52.554 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::Pyzor from @INC
Sep  2 16:56:52.562 [986] dbg: pyzor: local tests only, disabling Pyzor
Sep  2 16:56:52.563 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::Razor2 from @INC
Sep  2 16:56:52.569 [986] dbg: razor2: local tests only, skipping Razor
Sep  2 16:56:52.569 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::SpamCop from @INC
Sep  2 16:56:52.573 [986] dbg: reporter: local tests only, disabling SpamCop
Sep  2 16:56:52.573 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC
Sep  2 16:56:52.575 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::WhiteListSubject from @INC
Sep  2 16:56:52.577 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::MIMEHeader from @INC
Sep  2 16:56:52.579 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::ReplaceTags from @INC
Sep  2 16:56:52.581 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::DKIM from @INC
Sep  2 16:56:52.589 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::Check from @INC
Sep  2 16:56:52.605 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC
Sep  2 16:56:52.607 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::URIDetail from @INC
Sep  2 16:56:52.614 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::Bayes from @INC
Sep  2 16:56:52.635 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::BodyEval from @INC
Sep  2 16:56:52.638 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::DNSEval from @INC
Sep  2 16:56:52.642 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::HTMLEval from @INC
Sep  2 16:56:52.645 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::HeaderEval from @INC
Sep  2 16:56:52.658 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::MIMEEval from @INC
Sep  2 16:56:52.663 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::RelayEval from @INC
Sep  2 16:56:52.667 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::URIEval from @INC
Sep  2 16:56:52.668 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::WLBLEval from @INC
Sep  2 16:56:52.672 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::VBounce from @INC
Sep  2 16:56:52.673 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::ImageInfo from @INC
Sep  2 16:56:52.677 [986] dbg: plugin: loading
Mail::SpamAssassin::Plugin::FreeMail from @INC
Sep  2 16:56:52.682 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf
Sep  2 16:56:52.683 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf"
for included file
Sep  2 16:56:52.683 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf
Sep  2 16:56:52.686 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf
Sep  2 16:56:52.687 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf"
for included file
Sep  2 16:56:52.687 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf
Sep  2 16:56:52.693 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf
Sep  2 16:56:52.693 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf" for
included file
Sep  2 16:56:52.693 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf
Sep  2 16:56:52.700 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf
Sep  2 16:56:52.700 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf"
for included file
Sep  2 16:56:52.701 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/
updates_spamassassin_org/20_body_tests.cf
Sep  2 16:56:52.707 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf
Sep  2 16:56:52.707 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf"
for included file
Sep  2 16:56:52.707 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf
Sep  2 16:56:52.708 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf
Sep  2 16:56:52.708 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf"
for included file
Sep  2 16:56:52.709 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf
Sep  2 16:56:52.716 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf
Sep  2 16:56:52.716 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf" for
included file
Sep  2 16:56:52.717 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf
Sep  2 16:56:52.731 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf
Sep  2 16:56:52.731 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf" for
included file
Sep  2 16:56:52.732 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf
Sep  2 16:56:52.740 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/
20_fake_helo_tests.cf
Sep  2 16:56:52.740 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/
20_fake_helo_tests.cf" for included file
Sep  2 16:56:52.741 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/
20_fake_helo_tests.cf
Sep  2 16:56:52.746 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf
Sep  2 16:56:52.746 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf" for
included file
Sep  2 16:56:52.746 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf
Sep  2 16:56:52.749 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/
20_freemail_domains.cf
Sep  2 16:56:52.749 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/
20_freemail_domains.cf" for included file
Sep  2 16:56:52.750 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/
20_freemail_domains.cf
Sep  2 16:56:52.752 [986] dbg: plugin:
Mail::SpamAssassin::Plugin::FreeMail=HASH(0x2906e00) implements
'parse_config', priority 0
Sep  2 16:56:52.778 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf
Sep  2 16:56:52.778 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf"
for included file
Sep  2 16:56:52.779 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf
Sep  2 16:56:52.808 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf
Sep  2 16:56:52.808 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf"
for included file
Sep  2 16:56:52.809 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf
Sep  2 16:56:52.820 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf
Sep  2 16:56:52.820 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf"
for included file
Sep  2 16:56:52.820 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf
Sep  2 16:56:52.824 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf
Sep  2 16:56:52.824 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf"
for included file
Sep  2 16:56:52.824 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf
Sep  2 16:56:52.828 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf
Sep  2 16:56:52.828 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf"
for included file
Sep  2 16:56:52.828 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf
Sep  2 16:56:52.829 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf
Sep  2 16:56:52.829 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf" for
included file
Sep  2 16:56:52.830 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf
Sep  2 16:56:52.840 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf
Sep  2 16:56:52.840 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf" for
included file
Sep  2 16:56:52.841 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf
Sep  2 16:56:52.842 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf
Sep  2 16:56:52.842 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf" for
included file
Sep  2 16:56:52.843 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf
Sep  2 16:56:52.859 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf
Sep  2 16:56:52.859 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf"
for included file
Sep  2 16:56:52.860 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf
Sep  2 16:56:52.865 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf
Sep  2 16:56:52.865 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf" for
included file
Sep  2 16:56:52.866 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf
Sep  2 16:56:52.888 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf
Sep  2 16:56:52.888 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf" for
included file
Sep  2 16:56:52.888 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf
Sep  2 16:56:52.891 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf
Sep  2 16:56:52.891 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf" for
included file
Sep  2 16:56:52.891 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf
Sep  2 16:56:52.892 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf
Sep  2 16:56:52.892 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf"
for included file
Sep  2 16:56:52.892 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf
Sep  2 16:56:52.893 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf
Sep  2 16:56:52.893 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf" for
included file
Sep  2 16:56:52.893 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf
Sep  2 16:56:52.894 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf
Sep  2 16:56:52.894 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf" for
included file
Sep  2 16:56:52.894 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf
Sep  2 16:56:52.895 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf
Sep  2 16:56:52.895 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf" for
included file
Sep  2 16:56:52.896 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf
Sep  2 16:56:52.900 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf
Sep  2 16:56:52.900 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf" for
included file
Sep  2 16:56:52.900 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf
Sep  2 16:56:52.902 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf
Sep  2 16:56:52.902 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf" for
included file
Sep  2 16:56:52.903 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf
Sep  2 16:56:52.903 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf
Sep  2 16:56:52.903 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf" for
included file
Sep  2 16:56:52.904 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf
Sep  2 16:56:52.905 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf
Sep  2 16:56:52.906 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf" for
included file
Sep  2 16:56:52.906 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf
Sep  2 16:56:52.916 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf
Sep  2 16:56:52.916 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf" for
included file
Sep  2 16:56:52.916 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf
Sep  2 16:56:52.919 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf
Sep  2 16:56:52.919 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf" for
included file
Sep  2 16:56:52.919 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf
Sep  2 16:56:52.920 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf
Sep  2 16:56:52.920 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf" for
included file
Sep  2 16:56:52.920 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf
Sep  2 16:56:52.927 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf
Sep  2 16:56:52.927 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf" for
included file
Sep  2 16:56:52.927 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf
Sep  2 16:56:52.933 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf
Sep  2 16:56:52.933 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf" for
included file
Sep  2 16:56:52.934 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf
Sep  2 16:56:52.938 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf
Sep  2 16:56:52.938 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf" for
included file
Sep  2 16:56:52.939 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf
Sep  2 16:56:52.939 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf
Sep  2 16:56:52.939 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf" for
included file
Sep  2 16:56:52.940 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf
Sep  2 16:56:52.944 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf
Sep  2 16:56:52.944 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf" for
included file
Sep  2 16:56:52.944 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf
Sep  2 16:56:52.948 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf
Sep  2 16:56:52.948 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf"
for included file
Sep  2 16:56:52.949 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf
Sep  2 16:56:52.950 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf
Sep  2 16:56:52.950 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf" for
included file
Sep  2 16:56:52.951 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf
Sep  2 16:56:53.016 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/
60_adsp_override_dkim.cf
Sep  2 16:56:53.017 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/
60_adsp_override_dkim.cf" for included file
Sep  2 16:56:53.017 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/
60_adsp_override_dkim.cf
Sep  2 16:56:53.021 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf
Sep  2 16:56:53.022 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf" for
included file
Sep  2 16:56:53.022 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf
Sep  2 16:56:53.022 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf
Sep  2 16:56:53.023 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf"
for included file
Sep  2 16:56:53.023 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf
Sep  2 16:56:53.024 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf
Sep  2 16:56:53.024 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf"
for included file
Sep  2 16:56:53.024 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf
Sep  2 16:56:53.027 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf
Sep  2 16:56:53.028 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/
60_whitelist_dkim.cf" for included file
Sep  2 16:56:53.028 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf
Sep  2 16:56:53.034 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf
Sep  2 16:56:53.034 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf"
for included file
Sep  2 16:56:53.034 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf
Sep  2 16:56:53.037 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/
60_whitelist_subject.cf
Sep  2 16:56:53.037 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/
60_whitelist_subject.cf" for included file
Sep  2 16:56:53.037 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/
60_whitelist_subject.cf
Sep  2 16:56:53.038 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf
Sep  2 16:56:53.038 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf" for
included file
Sep  2 16:56:53.048 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf
Sep  2 16:56:53.209 [986] dbg: config: uri_detail adding (text =~
/(?-xism:(?i)\b(?:cli(?:quez\W|ck\Wa)ici\b|cli(?:cca\W|c\Wa|que\Wa)qu[^<.,a
]|klie?k(?:\Whi?er|ni(?:j|nite)\Wtu[tk]aj)\b))/) to T_KHOP_FOREIGN_CLICK
Sep  2 16:56:53.209 [986] dbg: config: uri_detail added T_KHOP_FOREIGN_CLICK
Sep  2 16:56:53.310 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf
Sep  2 16:56:53.310 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf" for
included file
Sep  2 16:56:53.311 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf
Sep  2 16:56:53.313 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf
Sep  2 16:56:53.313 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf" for
included file
Sep  2 16:56:53.314 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf
Sep  2 16:56:53.314 [986] dbg: config: fixed relative path:
/var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf
Sep  2 16:56:53.314 [986] dbg: config: using
"/var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf"
for included file
Sep  2 16:56:53.315 [986] dbg: config: read file
/var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf
Sep  2 16:56:53.359 [986] dbg: config: warning: score set for non-existent
rule SHORTCIRCUIT
Sep  2 16:56:53.359 [986] dbg: config: warning: score set for non-existent
rule SUBJ_RE_NUM
Sep  2 16:56:53.359 [986] dbg: config: warning: score set for non-existent
rule FM_VIAGRA_SPAM1114
Sep  2 16:56:53.360 [986] dbg: config: warning: score set for non-existent
rule ADVANCE_FEE_2
Sep  2 16:56:53.360 [986] dbg: config: warning: score set for non-existent
rule AXB_HELO_LH_HOME
Sep  2 16:56:53.360 [986] dbg: config: warning: score set for non-existent
rule ADVANCE_FEE_3
Sep  2 16:56:53.360 [986] dbg: config: warning: score set for non-existent
rule ACCESSDB
Sep  2 16:56:53.360 [986] dbg: config: warning: score set for non-existent
rule ADVANCE_FEE_4
Sep  2 16:56:53.705 [986] dbg: rules: __MO_OL_9B90B merged duplicates:
__MO_OL_C65FA
Sep  2 16:56:53.705 [986] dbg: rules: __XM_OL_22B61 merged duplicates:
__XM_OL_A842E
Sep  2 16:56:53.705 [986] dbg: rules: __MO_OL_07794 merged duplicates:
__MO_OL_8627E __MO_OL_F3B05
Sep  2 16:56:53.705 [986] dbg: rules: __XM_OL_07794 merged duplicates:
__XM_OL_25340 __XM_OL_3857F __XM_OL_4F240 __XM_OL_58CB5 __XM_OL_6554A
__XM_OL_812FF __XM_OL_C65FA __XM_OL_CF0C0 __XM_OL_F475E __XM_OL_F6D01
Sep  2 16:56:53.705 [986] dbg: rules: FH_MSGID_01C67 merged duplicates:
__MSGID_VGA
Sep  2 16:56:53.706 [986] dbg: rules: FS_NEW_SOFT_UPLOAD merged duplicates:
HS_SUBJ_NEW_SOFTWARE
Sep  2 16:56:53.706 [986] dbg: rules: __MO_OL_91287 merged duplicates:
__MO_OL_B30D1 __MO_OL_CF0C0
Sep  2 16:56:53.706 [986] dbg: rules: KAM_STOCKOTC merged duplicates:
KAM_STOCKTIP15 KAM_STOCKTIP20 KAM_STOCKTIP21 KAM_STOCKTIP4 KAM_STOCKTIP6
Sep  2 16:56:53.706 [986] dbg: rules: __XM_OL_015D5 merged duplicates:
__XM_OL_4BF4C __XM_OL_4EEDB __XM_OL_5B79A __XM_OL_9B90B __XM_OL_ADFF7
__XM_OL_B30D1 __XM_OL_B4B40 __XM_OL_BC7E6 __XM_OL_F3B05 __XM_OL_FF5C8
Sep  2 16:56:53.706 [986] dbg: rules: __MO_OL_015D5 merged duplicates:
__MO_OL_6554A
Sep  2 16:56:53.706 [986] dbg: rules: __MO_OL_22B61 merged duplicates:
__MO_OL_4F240 __MO_OL_ADFF7
Sep  2 16:56:53.706 [986] dbg: rules: __MO_OL_812FF merged duplicates:
__MO_OL_BC7E6
Sep  2 16:56:53.706 [986] dbg: rules: __MO_OL_25340 merged duplicates:
__MO_OL_4EEDB __MO_OL_7533E
Sep  2 16:56:53.706 [986] dbg: rules: __DOS_HAS_MAILING_LIST merged
duplicates: __ML4 __freemail_safe_ml2
Sep  2 16:56:53.706 [986] dbg: rules: __ML2 merged duplicates:
__freemail_safe_ml1
Sep  2 16:56:53.706 [986] dbg: rules: __MO_OL_58CB5 merged duplicates:
__MO_OL_B4B40
Sep  2 16:56:53.706 [986] dbg: rules: __DOS_HAS_ANY_URI merged duplicates:
__HAS_ANY_URI
Sep  2 16:56:53.707 [986] dbg: rules: __FH_HAS_XMSMAIL merged duplicates:
__HAS_MSMAIL_PRI
Sep  2 16:56:53.707 [986] dbg: rules: CTYPE_001C_A merged duplicates:
DATE_IN_FUTURE_96_XX
Sep  2 16:56:53.707 [986] dbg: rules: __MO_OL_72641 merged duplicates:
__MO_OL_A842E
Sep  2 16:56:53.707 [986] dbg: rules: __HAS_X_MAILING_LIST merged
duplicates: __freemail_safe_ml3
Sep  2 16:56:53.707 [986] dbg: rules: __MO_OL_4BF4C merged duplicates:
__MO_OL_F6D01
Sep  2 16:56:53.707 [986] dbg: rules: __MO_OL_F475E merged duplicates:
__MO_OL_FF5C8
Sep  2 16:56:53.708 [986] dbg: config: finish parsing
Sep  2 16:56:53.731 [986] dbg: plugin:
Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x24f8d10) implements
'finish_parsing_end', priority 0
Sep  2 16:56:53.731 [986] dbg: plugin:
Mail::SpamAssassin::Plugin::FreeMail=HASH(0x2906e00) implements
'finish_parsing_end', priority 0
Sep  2 16:56:53.732 [986] dbg: replacetags: replacing tags
Sep  2 16:56:53.764 [986] dbg: replacetags: done replacing tags
Sep  2 16:56:53.765 [986] dbg: FreeMail: loaded freemail_domains entries:
2106 normal, 23 wildcard
Sep  2 16:56:53.767 [986] dbg: config: score set 0 chosen.
Sep  2 16:56:53.769 [986] dbg: message: main message type: text/plain
Sep  2 16:56:53.769 [986] dbg: message: ---- MIME PARSER START ----
Sep  2 16:56:53.769 [986] dbg: message: parsing normal part
Sep  2 16:56:53.769 [986] dbg: message: ---- MIME PARSER END ----
Sep  2 16:56:53.769 [986] dbg: plugin:
Mail::SpamAssassin::Plugin::DNSEval=HASH(0x2732bd0) implements
'check_start', priority 0
Sep  2 16:56:53.770 [986] dbg: plugin:
Mail::SpamAssassin::Plugin::Check=HASH(0x260d898) implements 'check_main',
priority 0
Sep  2 16:56:53.770 [986] dbg: config: internal_networks not configured,
using trusted_networks configuration for internal_networks; if you really
want internal_networks to only contain the required 127/8 add
'internal_networks !0/0' to your configuration
Sep  2 16:56:53.770 [986] dbg: metadata: X-Spam-Relays-Trusted:
Sep  2 16:56:53.771 [986] dbg: metadata: X-Spam-Relays-Untrusted:
Sep  2 16:56:53.771 [986] dbg: metadata: X-Spam-Relays-Internal:
Sep  2 16:56:53.771 [986] dbg: metadata: X-Spam-Relays-External:
Sep  2 16:56:53.771 [986] dbg: message: no encoding detected
Sep  2 16:56:53.771 [986] dbg: plugin:
Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x23c6010) implements
'parsed_metadata', priority 0
Sep  2 16:56:53.771 [986] dbg: dns: is_dns_available() last checked
1283410613.8 seconds ago; re-checking
Sep  2 16:56:53.771 [986] dbg: dns: is DNS available? 0
Sep  2 16:56:53.771 [986] dbg: rules: local tests only, ignoring RBL eval
Sep  2 16:56:53.773 [986] dbg: check: running tests for priority: -1000
Sep  2 16:56:53.773 [986] dbg: rules: running head tests; score so far=0
Sep  2 16:56:53.773 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 282 chars of
Mail::SpamAssassin::Plugin::Check::_head_tests_neg1000_1
Sep  2 16:56:53.774 [986] dbg: rules: run_generic_tests - compiling eval
code: head, priority -1000
Sep  2 16:56:53.774 [986] dbg: rules: compiled head tests
Sep  2 16:56:53.774 [986] dbg: rules: running head_eval tests; score so
far=0
Sep  2 16:56:53.774 [986] dbg: rules: run_eval_tests - compiling eval code:
9, priority -1000
Sep  2 16:56:53.775 [986] dbg: eval: all '*From' addrs:
ignore@compiling.spamassassin.taint.org
Sep  2 16:56:53.776 [986] dbg: eval: all '*To' addrs:
Sep  2 16:56:53.777 [986] dbg: rules: running body tests; score so far=0
Sep  2 16:56:53.777 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 225 chars of
Mail::SpamAssassin::Plugin::Check::_body_tests_neg1000_1
Sep  2 16:56:53.777 [986] dbg: rules: run_generic_tests - compiling eval
code: body, priority -1000
Sep  2 16:56:53.777 [986] dbg: rules: compiled body tests
Sep  2 16:56:53.777 [986] dbg: rules: running uri tests; score so far=0
Sep  2 16:56:53.777 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 223 chars of
Mail::SpamAssassin::Plugin::Check::_uri_tests_neg1000_1
Sep  2 16:56:53.777 [986] dbg: rules: run_generic_tests - compiling eval
code: uri, priority -1000
Sep  2 16:56:53.778 [986] dbg: rules: compiled uri tests
Sep  2 16:56:53.778 [986] dbg: rules: running rawbody tests; score so far=0
Sep  2 16:56:53.778 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 231 chars of
Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg1000_1
Sep  2 16:56:53.778 [986] dbg: rules: run_generic_tests - compiling eval
code: rawbody, priority -1000
Sep  2 16:56:53.778 [986] dbg: rules: compiled rawbody tests
Sep  2 16:56:53.778 [986] dbg: rules: running full tests; score so far=0
Sep  2 16:56:53.778 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 260 chars of
Mail::SpamAssassin::Plugin::Check::_full_tests_neg1000_1
Sep  2 16:56:53.779 [986] dbg: rules: run_generic_tests - compiling eval
code: full, priority -1000
Sep  2 16:56:53.779 [986] dbg: rules: compiled full tests
Sep  2 16:56:53.779 [986] dbg: rules: running meta tests; score so far=0
Sep  2 16:56:53.779 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 285 chars of
Mail::SpamAssassin::Plugin::Check::_meta_tests_neg1000_1
Sep  2 16:56:53.779 [986] dbg: rules: run_generic_tests - compiling eval
code: meta, priority -1000
Sep  2 16:56:53.779 [986] dbg: rules: compiled meta tests
Sep  2 16:56:53.780 [986] dbg: check: running tests for priority: -950
Sep  2 16:56:53.780 [986] dbg: rules: running head tests; score so far=0
Sep  2 16:56:53.780 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 280 chars of
Mail::SpamAssassin::Plugin::Check::_head_tests_neg950_1
Sep  2 16:56:53.780 [986] dbg: rules: run_generic_tests - compiling eval
code: head, priority -950
Sep  2 16:56:53.780 [986] dbg: rules: compiled head tests
Sep  2 16:56:53.781 [986] dbg: rules: running head_eval tests; score so
far=0
Sep  2 16:56:53.781 [986] dbg: rules: run_eval_tests - compiling eval code:
9, priority -950
Sep  2 16:56:53.781 [986] dbg: rules: running body tests; score so far=0
Sep  2 16:56:53.781 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 223 chars of
Mail::SpamAssassin::Plugin::Check::_body_tests_neg950_1
Sep  2 16:56:53.782 [986] dbg: rules: run_generic_tests - compiling eval
code: body, priority -950
Sep  2 16:56:53.782 [986] dbg: rules: compiled body tests
Sep  2 16:56:53.782 [986] dbg: rules: running uri tests; score so far=0
Sep  2 16:56:53.782 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 221 chars of
Mail::SpamAssassin::Plugin::Check::_uri_tests_neg950_1
Sep  2 16:56:53.782 [986] dbg: rules: run_generic_tests - compiling eval
code: uri, priority -950
Sep  2 16:56:53.782 [986] dbg: rules: compiled uri tests
Sep  2 16:56:53.782 [986] dbg: rules: running rawbody tests; score so far=0
Sep  2 16:56:53.782 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 229 chars of
Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg950_1
Sep  2 16:56:53.783 [986] dbg: rules: run_generic_tests - compiling eval
code: rawbody, priority -950
Sep  2 16:56:53.783 [986] dbg: rules: compiled rawbody tests
Sep  2 16:56:53.783 [986] dbg: rules: running full tests; score so far=0
Sep  2 16:56:53.783 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 258 chars of
Mail::SpamAssassin::Plugin::Check::_full_tests_neg950_1
Sep  2 16:56:53.783 [986] dbg: rules: run_generic_tests - compiling eval
code: full, priority -950
Sep  2 16:56:53.783 [986] dbg: rules: compiled full tests
Sep  2 16:56:53.784 [986] dbg: rules: running meta tests; score so far=0
Sep  2 16:56:53.784 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 283 chars of
Mail::SpamAssassin::Plugin::Check::_meta_tests_neg950_1
Sep  2 16:56:53.784 [986] dbg: rules: run_generic_tests - compiling eval
code: meta, priority -950
Sep  2 16:56:53.784 [986] dbg: rules: compiled meta tests
Sep  2 16:56:53.784 [986] dbg: check: running tests for priority: -900
Sep  2 16:56:53.784 [986] dbg: rules: running head tests; score so far=0
Sep  2 16:56:53.784 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 280 chars of
Mail::SpamAssassin::Plugin::Check::_head_tests_neg900_1
Sep  2 16:56:53.785 [986] dbg: rules: run_generic_tests - compiling eval
code: head, priority -900
Sep  2 16:56:53.785 [986] dbg: rules: compiled head tests
Sep  2 16:56:53.785 [986] dbg: rules: running head_eval tests; score so
far=0
Sep  2 16:56:53.785 [986] dbg: rules: run_eval_tests - compiling eval code:
9, priority -900
Sep  2 16:56:53.786 [986] dbg: rules: running body tests; score so far=0
Sep  2 16:56:53.786 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 223 chars of
Mail::SpamAssassin::Plugin::Check::_body_tests_neg900_1
Sep  2 16:56:53.786 [986] dbg: rules: run_generic_tests - compiling eval
code: body, priority -900
Sep  2 16:56:53.786 [986] dbg: rules: compiled body tests
Sep  2 16:56:53.787 [986] dbg: rules: running uri tests; score so far=0
Sep  2 16:56:53.787 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 221 chars of
Mail::SpamAssassin::Plugin::Check::_uri_tests_neg900_1
Sep  2 16:56:53.787 [986] dbg: rules: run_generic_tests - compiling eval
code: uri, priority -900
Sep  2 16:56:53.787 [986] dbg: rules: compiled uri tests
Sep  2 16:56:53.787 [986] dbg: rules: running rawbody tests; score so far=0
Sep  2 16:56:53.787 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 229 chars of
Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg900_1
Sep  2 16:56:53.787 [986] dbg: rules: run_generic_tests - compiling eval
code: rawbody, priority -900
Sep  2 16:56:53.788 [986] dbg: rules: compiled rawbody tests
Sep  2 16:56:53.788 [986] dbg: rules: running full tests; score so far=0
Sep  2 16:56:53.788 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 258 chars of
Mail::SpamAssassin::Plugin::Check::_full_tests_neg900_1
Sep  2 16:56:53.788 [986] dbg: rules: run_generic_tests - compiling eval
code: full, priority -900
Sep  2 16:56:53.788 [986] dbg: rules: compiled full tests
Sep  2 16:56:53.788 [986] dbg: rules: running meta tests; score so far=0
Sep  2 16:56:53.788 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 283 chars of
Mail::SpamAssassin::Plugin::Check::_meta_tests_neg900_1
Sep  2 16:56:53.789 [986] dbg: rules: run_generic_tests - compiling eval
code: meta, priority -900
Sep  2 16:56:53.789 [986] dbg: rules: compiled meta tests
Sep  2 16:56:53.789 [986] dbg: check: running tests for priority: -400
Sep  2 16:56:53.789 [986] dbg: rules: running head tests; score so far=0
Sep  2 16:56:53.789 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 280 chars of
Mail::SpamAssassin::Plugin::Check::_head_tests_neg400_1
Sep  2 16:56:53.789 [986] dbg: rules: run_generic_tests - compiling eval
code: head, priority -400
Sep  2 16:56:53.790 [986] dbg: rules: compiled head tests
Sep  2 16:56:53.790 [986] dbg: rules: running body tests; score so far=0
Sep  2 16:56:53.790 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 223 chars of
Mail::SpamAssassin::Plugin::Check::_body_tests_neg400_1
Sep  2 16:56:53.790 [986] dbg: rules: run_generic_tests - compiling eval
code: body, priority -400
Sep  2 16:56:53.790 [986] dbg: rules: compiled body tests
Sep  2 16:56:53.790 [986] dbg: rules: running uri tests; score so far=0
Sep  2 16:56:53.790 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 221 chars of
Mail::SpamAssassin::Plugin::Check::_uri_tests_neg400_1
Sep  2 16:56:53.791 [986] dbg: rules: run_generic_tests - compiling eval
code: uri, priority -400
Sep  2 16:56:53.791 [986] dbg: rules: compiled uri tests
Sep  2 16:56:53.791 [986] dbg: rules: running body_eval tests; score so
far=0
Sep  2 16:56:53.791 [986] dbg: rules: run_eval_tests - compiling eval code:
11, priority -400
Sep  2 16:56:53.791 [986] dbg: rules: running rawbody tests; score so far=0
Sep  2 16:56:53.791 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 229 chars of
Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg400_1
Sep  2 16:56:53.792 [986] dbg: rules: run_generic_tests - compiling eval
code: rawbody, priority -400
Sep  2 16:56:53.792 [986] dbg: rules: compiled rawbody tests
Sep  2 16:56:53.792 [986] dbg: rules: running full tests; score so far=0
Sep  2 16:56:53.792 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 258 chars of
Mail::SpamAssassin::Plugin::Check::_full_tests_neg400_1
Sep  2 16:56:53.792 [986] dbg: rules: run_generic_tests - compiling eval
code: full, priority -400
Sep  2 16:56:53.792 [986] dbg: rules: compiled full tests
Sep  2 16:56:53.792 [986] dbg: rules: running meta tests; score so far=0
Sep  2 16:56:53.793 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 283 chars of
Mail::SpamAssassin::Plugin::Check::_meta_tests_neg400_1
Sep  2 16:56:53.793 [986] dbg: rules: run_generic_tests - compiling eval
code: meta, priority -400
Sep  2 16:56:53.793 [986] dbg: rules: compiled meta tests
Sep  2 16:56:53.793 [986] dbg: check: running tests for priority: 0
Sep  2 16:56:53.793 [986] dbg: rules: running head tests; score so far=0
Sep  2 16:56:53.813 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60065 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_1
Sep  2 16:56:53.824 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60386 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_2
Sep  2 16:56:53.835 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60063 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_3
Sep  2 16:56:53.845 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60437 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_4
Sep  2 16:56:53.855 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60472 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_5
Sep  2 16:56:53.863 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 22854 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_6
Sep  2 16:56:53.867 [986] dbg: rules: run_generic_tests - compiling eval
code: head, priority 0
Sep  2 16:56:53.867 [986] dbg: rules: compiled head tests
Sep  2 16:56:53.868 [986] dbg: rules: ran header rule __MISSING_REF ======>
got hit: "UNSET"
Sep  2 16:56:53.868 [986] dbg: rules: ran header rule __MSGID_OK_HOST
======> got hit: "@lint_rules>"
Sep  2 16:56:53.869 [986] dbg: rules: ran header rule __MSGID_OK_DIGITS
======> got hit: "1283410612"
Sep  2 16:56:53.869 [986] dbg: rules: ran header rule __HAS_MESSAGE_ID
======> got hit: "<YES>"
Sep  2 16:56:53.870 [986] dbg: rules: ran header rule __HAS_SUBJECT ======>
got hit: "<YES>"
Sep  2 16:56:53.870 [986] dbg: rules: ran header rule __MSOE_MID_WRONG_CASE
======> got hit: "
Sep  2 16:56:53.870 [986] dbg: rules: [...] Message-Id: "
Sep  2 16:56:53.870 [986] dbg: rules: ran header rule __HAS_MSGID ======>
got hit: "<"
Sep  2 16:56:53.871 [986] dbg: rules: ran header rule __SANE_MSGID ======>
got hit: "<12...@lint_rules>
Sep  2 16:56:53.871 [986] dbg: rules: [...] "
Sep  2 16:56:53.871 [986] dbg: rules: ran header rule __TO_NO_ARROWS_R
======> got hit: "negative match"
Sep  2 16:56:53.872 [986] dbg: rules: running head_eval tests; score so
far=0
Sep  2 16:56:53.874 [986] dbg: rules: run_eval_tests - compiling eval code:
9, priority 0
Sep  2 16:56:53.881 [986] dbg: eval: no dates found in message
Sep  2 16:56:53.881 [986] dbg: FreeMail: RULE (FREEMAIL_FROM)
check_freemail_from
Sep  2 16:56:53.881 [986] dbg: FreeMail: all from-addresses:
ignore@compiling.spamassassin.taint.org
Sep  2 16:56:53.882 [986] dbg: FreeMail: RULE (__freemail_reply)
check_freemail_replyto
Sep  2 16:56:53.882 [986] dbg: FreeMail: From address:
ignore@compiling.spamassassin.taint.org
Sep  2 16:56:53.882 [986] dbg: FreeMail: No Reply-To and From is not
freemail, skipping check
Sep  2 16:56:53.883 [986] dbg: rules: ran eval rule NO_RELAYS ======> got
hit (1)
Sep  2 16:56:53.883 [986] dbg: rules: ran eval rule
__GATED_THROUGH_RCVD_REMOVER ======> got hit (1)
Sep  2 16:56:53.884 [986] dbg: FreeMail: RULE (FREEMAIL_REPLYTO_END_DIGIT)
check_freemail_header regex:\d@
Sep  2 16:56:53.884 [986] dbg: FreeMail: header Reply-To not found from mail
Sep  2 16:56:53.884 [986] dbg: FreeMail: RULE (__freemail_replyto)
check_freemail_replyto
Sep  2 16:56:53.884 [986] dbg: FreeMail: From address:
ignore@compiling.spamassassin.taint.org
Sep  2 16:56:53.884 [986] dbg: FreeMail: Reply-To is not freemail, skipping
check
Sep  2 16:56:53.885 [986] dbg: FreeMail: RULE (__freemail_hdr_replyto)
check_freemail_header
Sep  2 16:56:53.885 [986] dbg: FreeMail: header Reply-To not found from mail
Sep  2 16:56:53.885 [986] dbg: spf: cannot get Envelope-From, cannot use SPF
Sep  2 16:56:53.885 [986] dbg: spf: def_spf_whitelist_from: could not find
useable envelope sender
Sep  2 16:56:53.885 [986] dbg: FreeMail: RULE (FREEMAIL_ENVFROM_END_DIGIT)
check_freemail_header regex:\d@
Sep  2 16:56:53.885 [986] dbg: FreeMail: header EnvelopeFrom not found from
mail
Sep  2 16:56:53.886 [986] dbg: spf: spf_whitelist_from: could not find
useable envelope sender
Sep  2 16:56:53.886 [986] dbg: rules: ran eval rule MISSING_HEADERS ======>
got hit (1)
Sep  2 16:56:53.887 [986] dbg: rules: running body tests; score so far=0.914
Sep  2 16:56:53.891 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60404 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_1
Sep  2 16:56:53.904 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60318 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_2
Sep  2 16:56:53.917 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60313 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_3
Sep  2 16:56:53.930 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60160 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_4
Sep  2 16:56:53.943 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60180 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_5
Sep  2 16:56:53.956 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60280 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_6
Sep  2 16:56:53.969 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60748 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_7
Sep  2 16:56:53.981 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60545 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_8
Sep  2 16:56:53.994 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60060 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_9
Sep  2 16:56:54.007 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60481 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_10
Sep  2 16:56:54.020 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60485 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_11
Sep  2 16:56:54.033 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60524 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_12
Sep  2 16:56:54.044 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 45565 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_13
Sep  2 16:56:54.052 [986] dbg: rules: run_generic_tests - compiling eval
code: body, priority 0
Sep  2 16:56:54.052 [986] dbg: rules: compiled body tests
Sep  2 16:56:54.112 [986] dbg: rules: ran body rule __NONEMPTY_BODY ======>
got hit: "I"
Sep  2 16:56:54.144 [986] dbg: rules: running uri tests; score so far=0.914
Sep  2 16:56:54.146 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 23318 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_0_1
Sep  2 16:56:54.150 [986] dbg: rules: run_generic_tests - compiling eval
code: uri, priority 0
Sep  2 16:56:54.150 [986] dbg: rules: compiled uri tests
Sep  2 16:56:54.150 [986] dbg: rules: running body_eval tests; score so
far=0.914
Sep  2 16:56:54.153 [986] dbg: rules: run_eval_tests - compiling eval code:
11, priority 0
Sep  2 16:56:54.168 [986] dbg: https_http_mismatch: anchors 0
Sep  2 16:56:54.175 [986] dbg: rules: running rawbody tests; score so
far=0.914
Sep  2 16:56:54.176 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 14996 chars of
Mail::SpamAssassin::Plugin::Check::_rawbody_tests_0_1
Sep  2 16:56:54.178 [986] dbg: rules: run_generic_tests - compiling eval
code: rawbody, priority 0
Sep  2 16:56:54.178 [986] dbg: rules: compiled rawbody tests
Sep  2 16:56:54.179 [986] dbg: rules: running rawbody_eval tests; score so
far=0.914
Sep  2 16:56:54.179 [986] dbg: rules: run_eval_tests - compiling eval code:
15, priority 0
Sep  2 16:56:54.180 [986] dbg: rules: running full tests; score so far=0.914
Sep  2 16:56:54.180 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 1167 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_0_1
Sep  2 16:56:54.181 [986] dbg: rules: run_generic_tests - compiling eval
code: full, priority 0
Sep  2 16:56:54.181 [986] dbg: rules: compiled full tests
Sep  2 16:56:54.181 [986] dbg: rules: running full_eval tests; score so
far=0.914
Sep  2 16:56:54.181 [986] dbg: rules: run_eval_tests - compiling eval code:
13, priority 0
Sep  2 16:56:54.182 [986] dbg: dkim: signature verification disabled, DNS
resolving not available
Sep  2 16:56:54.182 [986] dbg: rules: running meta tests; score so far=0.914
Sep  2 16:56:54.182 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 275 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_0_1
Sep  2 16:56:54.182 [986] dbg: rules: run_generic_tests - compiling eval
code: meta, priority 0
Sep  2 16:56:54.182 [986] dbg: rules: compiled meta tests
Sep  2 16:56:54.183 [986] dbg: check: running tests for priority: 500
Sep  2 16:56:54.183 [986] dbg: dns: harvest_dnsbl_queries
Sep  2 16:56:54.183 [986] dbg: rules: running head tests; score so far=0.914
Sep  2 16:56:54.183 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 276 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_500_1
Sep  2 16:56:54.183 [986] dbg: rules: run_generic_tests - compiling eval
code: head, priority 500
Sep  2 16:56:54.184 [986] dbg: rules: compiled head tests
Sep  2 16:56:54.184 [986] dbg: rules: running body tests; score so far=0.914
Sep  2 16:56:54.184 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 219 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_500_1
Sep  2 16:56:54.184 [986] dbg: rules: run_generic_tests - compiling eval
code: body, priority 500
Sep  2 16:56:54.184 [986] dbg: rules: compiled body tests
Sep  2 16:56:54.184 [986] dbg: rules: running uri tests; score so far=0.914
Sep  2 16:56:54.184 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 217 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_500_1
Sep  2 16:56:54.185 [986] dbg: rules: run_generic_tests - compiling eval
code: uri, priority 500
Sep  2 16:56:54.185 [986] dbg: rules: compiled uri tests
Sep  2 16:56:54.185 [986] dbg: rules: running rawbody tests; score so
far=0.914
Sep  2 16:56:54.185 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 225 chars of
Mail::SpamAssassin::Plugin::Check::_rawbody_tests_500_1
Sep  2 16:56:54.185 [986] dbg: rules: run_generic_tests - compiling eval
code: rawbody, priority 500
Sep  2 16:56:54.185 [986] dbg: rules: compiled rawbody tests
Sep  2 16:56:54.185 [986] dbg: rules: running full tests; score so far=0.914
Sep  2 16:56:54.186 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 254 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_500_1
Sep  2 16:56:54.186 [986] dbg: rules: run_generic_tests - compiling eval
code: full, priority 500
Sep  2 16:56:54.186 [986] dbg: rules: compiled full tests
Sep  2 16:56:54.186 [986] dbg: rules: running meta tests; score so far=0.914
Sep  2 16:56:54.187 [986] dbg: rules: meta test DIGEST_MULTIPLE has
undefined dependency 'DCC_CHECK'
Sep  2 16:56:54.218 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60058 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_1
Sep  2 16:56:54.231 [986] dbg: rules: flush_evalstr (add_evalstr) compiling
60080 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_2
Sep  2 16:56:54.242 [986] dbg: rules: flush_evalstr (run_generic_tests)
compiling 19478 chars of
Mail::SpamAssassin::Plugin::Check::_meta_tests_500_3
Sep  2 16:56:54.245 [986] dbg: rules: run_generic_tests - compiling eval
code: meta, priority 500
Sep  2 16:56:54.246 [986] dbg: rules: compiled meta tests
Sep  2 16:56:54.249 [986] dbg: check: is spam? score=3.652 required=4.2
Sep  2 16:56:54.249 [986] dbg: check:
tests=MISSING_DATE,MISSING_HEADERS,NO_RECEIVED,NO_RELAYS
Sep  2 16:56:54.249 [986] dbg: check:
subtests=__GATED_THROUGH_RCVD_REMOVER,__HAS_MESSAGE_ID,__HAS_MSGID,__HAS_SUBJECT,__MISSING_REF,__MSGID_OK_DIGITS,__MSGID_OK_HOST,__MSOE_MID_WRONG_CASE,__NONEMPTY_BODY,__NOT_SPOOFED,__SANE_MSGID,__TO_NO_ARROWS_R,__UNUSABLE_MSGID
Sep  2 16:56:54.249 [986] dbg: timing: total 1776 ms - init: 1295 (72.9%),
parse: 1.56 (0.1%), extract_message_metadata: 1.20 (0.1%),
get_uri_detail_list: 1.21 (0.1%), tests_pri_-1000: 7 (0.4%), compile_gen:
176 (9.9%), compile_eval: 22 (1.3%), tests_pri_-950: 4 (0.2%),
tests_pri_-900: 5 (0.3%), tests_pri_-400: 4 (0.2%), tests_pri_0: 390
(21.9%), tests_pri_500: 66 (3.7%)

Do you see any obvious problems?

Thanks!
 Chris

Re: Spamassassin not checking user provided RBLs

Posted by Benny Pedersen <me...@junc.org>.
On ons 01 sep 2010 22:47:36 CEST, Chris Datfung wrote

> header IN_NJABL_ORG    rbleval:check_rbl('njabl','dnsbl.njabl.org.')
> describe IN_NJABL_ORG  Received via a relay in dnsbl.njabl.org
> tflags IN_NJABL_ORG    net
> score IN_NJABL_ORG      5

first errpr is you added it to a pre file, next is that it is in std  
rules being checked

did you or debian run sa-update ?

what gives spamassassin 2>&1 -D --lint | less

-- 
xpoint http://www.unicom.com/pw/reply-to-harmful.html


Re: Spamassassin not checking user provided RBLs

Posted by Yet Another Ninja <sa...@alexb.ch>.
On 2010-09-01 22:47, Chris Datfung wrote:
> I'm running spamassassin version 3.3.1-1 from the Debian  package. I added
> several RBLs to /etc/mail/spamassassin/init.pre but spamassassin only
> queries its built in RBLs and not the ones I added. An example RBL entry to
> init.pre is shown below:
> 
> header IN_NJABL_ORG    rbleval:check_rbl('njabl','dnsbl.njabl.org.')
> describe IN_NJABL_ORG  Received via a relay in dnsbl.njabl.org
> tflags IN_NJABL_ORG    net
> score IN_NJABL_ORG      5
> 
> I also find messages that aren't tagged as being in an RBL that are listed
> in cbl.abuseat.org and zen.spamhaus.org which should be automatically
> checked by spamassassin using the default configuration. As mentioned before
> other (built-in) RBL checks work. Any hints as to why my custom RBL checks
> added to init.pre (and also tried local.cf) aren't queried after restarting
> spamassassin?
> 
> Thanks,
>  Chris

You don't EVER add rules to a .pre file

only .cf are rules files
use local.cf for custom rules