Hello, and thank you in advanced for looking into the below, I've been pondering over this issue for the past number of days, and don't feel I'm getting anywhere now.
I am a system administrator and have been using a Exim/ClamAV/SpamAssassin setup for around 8 years, over which time I had felt I had learnt all the in's and out of the system, but this one has struck me as straight out annoying. I have got seven systems of different age, and operating systems (either Fedora or CentOS). I'll base the below on one of these systems, but it's important to note I'm having this issue on four of the seven machines. So therefore I have compared the config files I would normally use between working and nonworking systems to try and find the fault, but everything appears to be the same. All systems are at different point of updates, and I've tried to "yum update" a faulty machine to fix any bugs but not updated a working machine to see if it breaks. What I am finding is any config saved in the "/etc/mail/spamassassin/" folder is loaded during a "spamassassin --lint -D" but ignored during operations. For example I have a "addresses.cf" file for whitelist_to & from as well as blacklisted_to & from and these are simply ignored, no 100 or -100 score is added to the email header report. I've also got a "latestspam.cf" that I've hand written to block the latest breed of spams that I find an "sa-update" doesn't correct, again these are ignored. I've managed to get my local rules going again by moving the contents of these files into " /var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf" so my users aren't screaming at me anymore, this is confirmed using a "blacklist_from" and a 100 score is added. This is a fine temporary fix, but I would love for this directory to start working correctly again and to separate these data sets, for synchronization and update proposes. Exim is talking to the spamassassin process by using 127.0.0.1:783, and I've confirmed this connection, as when I stop the SA process, Exim complains it is missing in action. I have checked all the files in "/etc/mail/spamassassin/" in case something isn't toggled correct but I'm wondering where SA looks initially for it configuration folders. I.E. how does it know about the "/var/lib/spamassassin/3.003001/updates_spamassassin_org/" folder? I feel as if I'm missing a master configuration file somewhere. Or is anyone aware what would be making SA ignore my local.cf file in the expected LOCAL_RULES_DIR as listed in the "lint". I feel I've had a good look online and through the http://spamassassin.apache.org/ wiki and etc. I'm sorry if it is a simple issue and I'm just doing something wrong. Thanks again. [root@mail2 ~]# ss -tlp State Recv-Q Send-Q Local Address:Port Peer Address:Port LISTEN 0 128 127.0.0.1:783 *:* users:(("spamd",18049,5),("spamd",20074,5),("spamd",35678,5)) [root@mail2 ~]# spamassassin -V SpamAssassin version 3.3.1 running on Perl version 5.10.1 [root@mail2 ~]# exim -bV Exim version 4.72 #1 built 10-Oct-2014 09:23:33 Copyright (c) University of Cambridge, 1995 - 2007 Berkeley DB: Berkeley DB 4.7.25: (September 9, 2013) Support for: crypteq iconv() IPv6 PAM Perl Expand_dlfunc TCPwrappers OpenSSL Content_Scanning DKIM Old_Demime Lookups (built-in): lsearch wildlsearch nwildlsearch iplsearch cdb dbm dbmnz dnsdb dsearch ldap ldapdn ldapm nis nis0 nisplus passwd sqlite Authenticators: cram_md5 cyrus_sasl dovecot plaintext spa Routers: accept dnslookup ipliteral manualroute queryprogram redirect Transports: appendfile/maildir/mailstore/mbx autoreply lmtp pipe smtp Fixed never_users: 0 Size of off_t: 8 OpenSSL compile-time version: OpenSSL 1.0.1e-fips 11 Feb 2013 OpenSSL runtime version: OpenSSL 1.0.1e-fips 11 Feb 2013 Configuration file is /etc/exim/exim.conf [root@mail2 ~]# service spamassassin stop Stopping spamd: [ OK ] ==> /var/log/exim/main.log <== 2015-09-15 15:00:43 1ZbkEL-0009T1-Ob DKIM: d=icontactmail6.com s=default c=relaxed/relaxed a=rsa-sha256 [verification succeeded] 2015-09-15 15:00:43 1ZbkEL-0009T1-Ob spam acl condition: warning - spamd connection to 127.0.0.1, port 783 failed: Connection refused 2015-09-15 15:00:43 1ZbkEL-0009T1-Ob spam acl condition: all spamd servers failed 2015-09-15 15:00:44 1ZbkEL-0009T1-Ob <= bounces+1507538.10770803.82...@icpbounce.com H=drone058.ral.icpbounce.com [64.132.109.50] P=esmtp S=13408 [root@mail2 ~]# service spamassassin start Starting spamd: [ OK ] ==> /var/log/exim/main.log <== 2015-09-15 14:46:26 1Zbk0W-000Bcg-Bv DKIM: d=yashikszix.com s=key c=relaxed/relaxed a=rsa-sha1 i=ch...@yashikszix.com [verification succeeded] 2015-09-15 14:46:29 1Zbk0W-000Bcg-Bv H=ec2-52-24-140-66.us-west-2.compute.amazonaws.com [52.24.140.66] F=<ax...@yashikszix.com> rejected after DATA: Your message scored 19.4 SpamAssassin points on mail2, and has been bounced. [root@mail2 ~]# cat /etc/exim/exim.conf ............. # For spam scanning, there is a similar option that defines the interface to # SpamAssassin. You do not need to set this if you are using the default, which # is shown in this commented example. As for virus scanning, you must also # modify the acl_check_data access control list to enable spam scanning. spamd_address = 127.0.0.1 783 ............. # Bypass SpamAssassin checks if the message is too large. # accept condition = ${if >={$message_size}{200000} {1}} add_header = X-Spam-Note: SpamAssassin run bypassed due to message size # Run SpamAssassin, but allow for it to fail or time out. Add a warning message # and accept the mail if that happens. Add an X-Spam-Flag: header if the SA # score exceeds the SA system threshold. # warn spam = nobody/defer_ok # add_header = X-Spam-Flag: YES # accept condition = ${if !def:spam_score_int {1}} add_header = X-Spam-Note: SpamAssassin invocation failed # # Unconditionally add score and report headers # # warn add_header = X-Spam-Score: $spam_score ($spam_bar)\n\ # X-Spam-Report: $spam_report warn add_header = $spam_report # And reject if the SpamAssassin score is greater than ten # deny condition = ${if >{$spam_score_int}{50} {1}} # message = Your message scored $spam_score SpamAssassin point. Report follows:\n\ # $spam_report message = Your message scored $spam_score SpamAssassin points on mail1, and has been bounced.\n\ $spam_report # Trigger greylisting (if enabled) if the SpamAssassin score is greater than 0.5 # warn condition = ${if >{$spam_score_int}{25} {1}} set acl_m_greylistreasons = Message has $spam_score SpamAssassin points\n$acl_m_greylistreasons ............. [root@mail2 ~]# spamassassin --lint -D Sep 15 14:13:33.850 [35984] dbg: logger: adding facilities: all Sep 15 14:13:33.866 [35984] dbg: logger: logging level is DBG Sep 15 14:13:33.867 [35984] dbg: generic: SpamAssassin version 3.3.1 Sep 15 14:13:33.867 [35984] dbg: generic: Perl 5.010001, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/mail/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin Sep 15 14:13:33.867 [35984] dbg: config: timing enabled Sep 15 14:13:33.868 [35984] dbg: config: score set 0 chosen. Sep 15 14:13:33.873 [35984] dbg: util: running in taint mode? yes Sep 15 14:13:33.873 [35984] dbg: util: taint mode: deleting unsafe environment variables, resetting PATH Sep 15 14:13:33.874 [35984] dbg: util: PATH included '/usr/local/sbin', keeping Sep 15 14:13:33.874 [35984] dbg: util: PATH included '/usr/local/bin', keeping Sep 15 14:13:33.874 [35984] dbg: util: PATH included '/sbin', keeping Sep 15 14:13:33.874 [35984] dbg: util: PATH included '/bin', keeping Sep 15 14:13:33.874 [35984] dbg: util: PATH included '/usr/sbin', keeping Sep 15 14:13:33.874 [35984] dbg: util: PATH included '/usr/bin', keeping Sep 15 14:13:33.899 [35984] dbg: util: PATH included '/root/bin', which is unusable, dropping: No such file or directory Sep 15 14:13:33.899 [35984] dbg: util: final PATH set to: /usr/local/sbin:/usr/local/bin:/sbin:/bin:/usr/sbin:/usr/bin Sep 15 14:13:33.950 [35984] dbg: dns: is Net::DNS::Resolver available? yes Sep 15 14:13:33.950 [35984] dbg: dns: Net::DNS version: 0.65 Sep 15 14:13:36.255 [35984] dbg: diag: perl platform: 5.010001 linux Sep 15 14:13:36.255 [35984] dbg: diag: [...] module installed: Digest::SHA1, version 2.12 Sep 15 14:13:36.256 [35984] dbg: diag: [...] module installed: HTML::Parser, version 3.64 Sep 15 14:13:36.256 [35984] dbg: diag: [...] module installed: Net::DNS, version 0.65 Sep 15 14:13:36.256 [35984] dbg: diag: [...] module installed: NetAddr::IP, version 4.027 Sep 15 14:13:36.256 [35984] dbg: diag: [...] module installed: Time::HiRes, version 1.9721 Sep 15 14:13:36.256 [35984] dbg: diag: [...] module installed: Archive::Tar, version 1.58 Sep 15 14:13:36.256 [35984] dbg: diag: [...] module installed: IO::Zlib, version 1.09 Sep 15 14:13:36.256 [35984] dbg: diag: [...] module installed: Digest::SHA1, version 2.12 Sep 15 14:13:36.256 [35984] dbg: diag: [...] module installed: MIME::Base64, version 3.08 Sep 15 14:13:36.257 [35984] dbg: diag: [...] module installed: DB_File, version 1.82 Sep 15 14:13:36.257 [35984] dbg: diag: [...] module installed: Net::SMTP, version 2.31 Sep 15 14:13:36.257 [35984] dbg: diag: [...] module not installed: Mail::SPF ('require' failed) Sep 15 14:13:36.257 [35984] dbg: diag: [...] module not installed: IP::Country::Fast ('require' failed) Sep 15 14:13:36.257 [35984] dbg: diag: [...] module not installed: Razor2::Client::Agent ('require' failed) Sep 15 14:13:36.257 [35984] dbg: diag: [...] module not installed: Net::Ident ('require' failed) Sep 15 14:13:36.258 [35984] dbg: diag: [...] module installed: IO::Socket::INET6, version 2.56 Sep 15 14:13:36.258 [35984] dbg: diag: [...] module installed: IO::Socket::SSL, version 1.31 Sep 15 14:13:36.258 [35984] dbg: diag: [...] module installed: Compress::Zlib, version 2.021 Sep 15 14:13:36.258 [35984] dbg: diag: [...] module installed: Mail::DKIM, version 0.37 Sep 15 14:13:36.258 [35984] dbg: diag: [...] module not installed: DBI ('require' failed) Sep 15 14:13:36.258 [35984] dbg: diag: [...] module installed: Getopt::Long, version 2.38 Sep 15 14:13:36.258 [35984] dbg: diag: [...] module installed: LWP::UserAgent, version 5.833 Sep 15 14:13:36.258 [35984] dbg: diag: [...] module installed: HTTP::Date, version 5.831 Sep 15 14:13:36.258 [35984] dbg: diag: [...] module installed: Encode::Detect, version 1.01 Sep 15 14:13:36.259 [35984] dbg: ignore: using a test message to lint rules Sep 15 14:13:36.267 [35984] dbg: config: using "/etc/mail/spamassassin" for site rules pre files Sep 15 14:13:36.336 [35984] dbg: config: read file /etc/mail/spamassassin/init.pre Sep 15 14:13:36.770 [35984] dbg: config: read file /etc/mail/spamassassin/v310.pre Sep 15 14:13:36.979 [35984] dbg: config: read file /etc/mail/spamassassin/v312.pre Sep 15 14:13:37.019 [35984] dbg: config: read file /etc/mail/spamassassin/v320.pre Sep 15 14:13:37.125 [35984] dbg: config: read file /etc/mail/spamassassin/v330.pre Sep 15 14:13:37.125 [35984] dbg: config: using "/var/lib/spamassassin/3.003001" for sys rules pre files Sep 15 14:13:37.126 [35984] dbg: config: using "/var/lib/spamassassin/3.003001" for default rules dir Sep 15 14:13:37.236 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/sought_rules_yerp_org.cf Sep 15 14:13:37.237 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org.cf Sep 15 14:13:37.268 [35984] dbg: config: using "/etc/mail/spamassassin" for site rules dir Sep 15 14:13:37.445 [35984] dbg: config: read file /etc/mail/spamassassin/addresses.cf Sep 15 14:13:37.650 [35984] dbg: config: read file /etc/mail/spamassassin/latestspam.cf Sep 15 14:13:37.666 [35984] dbg: config: read file /etc/mail/spamassassin/local.cf Sep 15 14:13:37.888 [35984] dbg: config: using "/root/.spamassassin/user_prefs" for user prefs file Sep 15 14:13:38.085 [35984] dbg: config: read file /root/.spamassassin/user_prefs Sep 15 14:13:38.087 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC Sep 15 14:13:38.264 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC Sep 15 14:13:38.270 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC Sep 15 14:13:38.348 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC Sep 15 14:13:38.522 [35984] dbg: pyzor: local tests only, disabling Pyzor Sep 15 14:13:38.522 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC Sep 15 14:13:38.643 [35984] dbg: razor2: local tests only, skipping Razor Sep 15 14:13:38.644 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC Sep 15 14:13:38.650 [35984] dbg: reporter: local tests only, disabling SpamCop Sep 15 14:13:38.651 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC Sep 15 14:13:38.828 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC Sep 15 14:13:39.003 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC Sep 15 14:13:39.044 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC Sep 15 14:13:39.048 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::DKIM from @INC Sep 15 14:13:39.101 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC Sep 15 14:13:39.176 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC Sep 15 14:13:39.471 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC Sep 15 14:13:39.475 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC Sep 15 14:13:40.389 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC Sep 15 14:13:40.553 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC Sep 15 14:13:40.559 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC Sep 15 14:13:40.995 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC Sep 15 14:13:41.188 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC Sep 15 14:13:41.195 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC Sep 15 14:13:41.346 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC Sep 15 14:13:41.483 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC Sep 15 14:13:41.488 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC Sep 15 14:13:41.686 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC Sep 15 14:13:41.712 [35984] dbg: plugin: loading Mail::SpamAssassin::Plugin::FreeMail from @INC Sep 15 14:13:42.240 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/sought_rules_yerp_org/20_sought.cf Sep 15 14:13:42.240 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/sought_rules_yerp_org/20_sought.cf" for included file Sep 15 14:13:42.727 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/sought_rules_yerp_org/20_sought.cf Sep 15 14:13:42.728 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/sought_rules_yerp_org/20_sought_fraud.cf Sep 15 14:13:42.728 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/sought_rules_yerp_org/20_sought_fraud.cf" for included file Sep 15 14:13:42.833 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/sought_rules_yerp_org/20_sought_fraud.cf Sep 15 14:13:42.873 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf Sep 15 14:13:42.873 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf" for included file Sep 15 14:13:43.000 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf Sep 15 14:13:43.006 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_hasbase.cf Sep 15 14:13:43.006 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/10_hasbase.cf" for included file Sep 15 14:13:43.279 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_hasbase.cf Sep 15 14:13:43.284 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf Sep 15 14:13:43.284 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf" for included file Sep 15 14:13:43.473 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf Sep 15 14:13:43.480 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf Sep 15 14:13:43.481 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf" for included file Sep 15 14:13:43.522 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf Sep 15 14:13:43.553 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf Sep 15 14:13:43.553 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf" for included file Sep 15 14:13:43.567 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf Sep 15 14:13:43.577 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf Sep 15 14:13:43.577 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf" for included file Sep 15 14:13:43.578 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf Sep 15 14:13:43.581 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf Sep 15 14:13:43.581 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf" for included file Sep 15 14:13:43.613 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf Sep 15 14:13:43.622 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf Sep 15 14:13:43.622 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf" for included file Sep 15 14:13:43.659 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf Sep 15 14:13:43.680 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf Sep 15 14:13:43.680 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf" for included file Sep 15 14:13:43.965 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf Sep 15 14:13:43.977 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_fake_helo_tests.cf Sep 15 14:13:43.977 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_fake_helo_tests.cf" for included file Sep 15 14:13:44.009 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_fake_helo_tests.cf Sep 15 14:13:44.016 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf Sep 15 14:13:44.017 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf" for included file Sep 15 14:13:44.066 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf Sep 15 14:13:44.071 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_domains.cf Sep 15 14:13:44.071 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_domains.cf" for included file Sep 15 14:13:44.251 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_domains.cf Sep 15 14:13:44.254 [35984] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x443e518) implements 'parse_config', priority 0 Sep 15 14:13:44.303 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_mailcom_domains.cf Sep 15 14:13:44.303 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_mailcom_domains.cf" for included file Sep 15 14:13:44.493 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_mailcom_domains.cf Sep 15 14:13:44.498 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf Sep 15 14:13:44.498 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf" for included file Sep 15 14:13:44.621 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf Sep 15 14:13:44.678 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf Sep 15 14:13:44.678 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf" for included file Sep 15 14:13:44.679 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf Sep 15 14:13:44.710 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf Sep 15 14:13:44.710 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf" for included file Sep 15 14:13:44.793 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf Sep 15 14:13:44.799 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_mailspike.cf Sep 15 14:13:44.799 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_mailspike.cf" for included file Sep 15 14:13:44.800 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_mailspike.cf Sep 15 14:13:44.802 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf Sep 15 14:13:44.802 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf" for included file Sep 15 14:13:44.816 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf Sep 15 14:13:44.834 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf Sep 15 14:13:44.834 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf" for included file Sep 15 14:13:44.836 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf Sep 15 14:13:44.839 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_pdfinfo.cf Sep 15 14:13:44.839 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_pdfinfo.cf" for included file Sep 15 14:13:44.841 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_pdfinfo.cf Sep 15 14:13:44.847 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf Sep 15 14:13:44.848 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf" for included file Sep 15 14:13:44.856 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf Sep 15 14:13:44.878 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf Sep 15 14:13:44.878 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf" for included file Sep 15 14:13:44.879 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf Sep 15 14:13:44.882 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf Sep 15 14:13:44.882 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf" for included file Sep 15 14:13:44.898 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf Sep 15 14:13:44.928 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf Sep 15 14:13:44.928 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf" for included file Sep 15 14:13:45.039 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf Sep 15 14:13:45.050 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf Sep 15 14:13:45.051 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf" for included file Sep 15 14:13:45.126 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf Sep 15 14:13:45.157 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf Sep 15 14:13:45.157 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf" for included file Sep 15 14:13:45.158 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf Sep 15 14:13:45.163 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf Sep 15 14:13:45.163 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf" for included file Sep 15 14:13:45.263 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf Sep 15 14:13:45.264 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf Sep 15 14:13:45.264 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf" for included file Sep 15 14:13:45.524 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf Sep 15 14:13:45.525 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf Sep 15 14:13:45.526 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf" for included file Sep 15 14:13:45.527 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf Sep 15 14:13:45.528 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf Sep 15 14:13:45.528 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf" for included file Sep 15 14:13:45.529 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf Sep 15 14:13:45.531 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf Sep 15 14:13:45.531 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf" for included file Sep 15 14:13:45.532 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf Sep 15 14:13:45.539 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf Sep 15 14:13:45.539 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf" for included file Sep 15 14:13:45.540 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf Sep 15 14:13:45.544 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf Sep 15 14:13:45.544 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf" for included file Sep 15 14:13:45.546 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf Sep 15 14:13:45.548 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf Sep 15 14:13:45.548 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf" for included file Sep 15 14:13:45.549 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf Sep 15 14:13:45.552 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf Sep 15 14:13:45.552 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf" for included file Sep 15 14:13:45.556 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf Sep 15 14:13:45.572 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf Sep 15 14:13:45.572 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf" for included file Sep 15 14:13:45.574 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf Sep 15 14:13:45.582 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf Sep 15 14:13:45.582 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf" for included file Sep 15 14:13:45.583 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf Sep 15 14:13:45.584 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf Sep 15 14:13:45.585 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf" for included file Sep 15 14:13:45.590 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf Sep 15 14:13:45.606 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf Sep 15 14:13:45.606 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf" for included file Sep 15 14:13:45.608 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf Sep 15 14:13:45.618 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf Sep 15 14:13:45.619 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf" for included file Sep 15 14:13:45.620 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf Sep 15 14:13:45.629 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf Sep 15 14:13:45.629 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf" for included file Sep 15 14:13:45.630 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf Sep 15 14:13:45.632 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf Sep 15 14:13:45.632 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf" for included file Sep 15 14:13:45.634 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf Sep 15 14:13:45.640 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf Sep 15 14:13:45.640 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf" for included file Sep 15 14:13:45.642 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf Sep 15 14:13:45.648 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf Sep 15 14:13:45.648 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf" for included file Sep 15 14:13:45.650 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf Sep 15 14:13:45.664 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf Sep 15 14:13:45.664 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf" for included file Sep 15 14:13:45.975 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf Sep 15 14:13:46.052 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_adsp_override_dkim.cf Sep 15 14:13:46.053 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_adsp_override_dkim.cf" for included file Sep 15 14:13:46.055 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_adsp_override_dkim.cf Sep 15 14:13:46.062 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf Sep 15 14:13:46.063 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf" for included file Sep 15 14:13:46.134 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf Sep 15 14:13:46.135 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf Sep 15 14:13:46.136 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf" for included file Sep 15 14:13:46.137 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf Sep 15 14:13:46.138 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_txrep.cf Sep 15 14:13:46.138 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_txrep.cf" for included file Sep 15 14:13:46.242 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_txrep.cf Sep 15 14:13:46.243 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf Sep 15 14:13:46.243 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf" for included file Sep 15 14:13:46.245 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf Sep 15 14:13:46.250 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf Sep 15 14:13:46.250 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf" for included file Sep 15 14:13:46.251 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf Sep 15 14:13:46.263 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf Sep 15 14:13:46.263 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf" for included file Sep 15 14:13:46.265 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf Sep 15 14:13:46.270 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_subject.cf Sep 15 14:13:46.271 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_subject.cf" for included file Sep 15 14:13:46.272 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_subject.cf Sep 15 14:13:46.274 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf Sep 15 14:13:46.274 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf" for included file Sep 15 14:13:46.279 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf Sep 15 14:13:46.503 [35984] dbg: config: uri_detail adding (text =~ /(?-xism:You may browse this\.)/) to T_AXB_URIDETAIL_BROWSETHIS Sep 15 14:13:46.504 [35984] dbg: config: uri_detail added T_AXB_URIDETAIL_BROWSETHIS Sep 15 14:13:46.666 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf Sep 15 14:13:46.667 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf" for included file Sep 15 14:13:46.668 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf Sep 15 14:13:46.683 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/73_sandbox_manual_scores.cf Sep 15 14:13:46.684 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/73_sandbox_manual_scores.cf" for included file Sep 15 14:13:46.684 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/73_sandbox_manual_scores.cf Sep 15 14:13:46.686 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf Sep 15 14:13:46.686 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf" for included file Sep 15 14:13:46.719 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf Sep 15 14:13:46.744 [35984] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf Sep 15 14:13:46.744 [35984] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf" for included file Sep 15 14:13:46.746 [35984] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf Sep 15 14:13:46.861 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_H4 Sep 15 14:13:46.862 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_WL Sep 15 14:13:46.862 [35984] dbg: config: warning: score set for non-existent rule HK_NAME_FM_MR_MRS Sep 15 14:13:46.862 [35984] dbg: config: warning: score set for non-existent rule FREEMAIL_FORGED_FROMDOMAIN Sep 15 14:13:46.862 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_L5 Sep 15 14:13:46.863 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_H3 Sep 15 14:13:46.863 [35984] dbg: config: warning: score set for non-existent rule PDS_FROM_2_EMAILS Sep 15 14:13:46.863 [35984] dbg: config: warning: score set for non-existent rule FRT_SYMBOL Sep 15 14:13:46.864 [35984] dbg: config: warning: score set for non-existent rule PP_TOO_MUCH_UNICODE02 Sep 15 14:13:46.864 [35984] dbg: config: warning: score set for non-existent rule HEADER_HOST_IN_WHITELIST Sep 15 14:13:46.864 [35984] dbg: config: warning: score set for non-existent rule URI_HOST_IN_BLACKLIST Sep 15 14:13:46.864 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_H2 Sep 15 14:13:46.864 [35984] dbg: config: warning: score set for non-existent rule HEADER_FROM_DIFFERENT_DOMAINS Sep 15 14:13:46.865 [35984] dbg: config: warning: score set for non-existent rule URI_HOST_IN_WHITELIST Sep 15 14:13:46.865 [35984] dbg: config: warning: score set for non-existent rule HEADER_HOST_IN_BLACKLIST Sep 15 14:13:46.865 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_ZBI Sep 15 14:13:46.865 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_L2 Sep 15 14:13:46.866 [35984] dbg: config: warning: score set for non-existent rule HK_NAME_MR_MRS Sep 15 14:13:46.866 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_BL Sep 15 14:13:46.866 [35984] dbg: config: warning: score set for non-existent rule PP_TOO_MUCH_UNICODE05 Sep 15 14:13:46.866 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_L4 Sep 15 14:13:46.867 [35984] dbg: config: warning: score set for non-existent rule HK_LOTTO_NAME Sep 15 14:13:46.867 [35984] dbg: config: warning: score set for non-existent rule FH_DATE_PAST_20XX Sep 15 14:13:46.867 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_H5 Sep 15 14:13:46.868 [35984] dbg: config: warning: score set for non-existent rule URIBL_SBL_A Sep 15 14:13:46.868 [35984] dbg: config: warning: score set for non-existent rule PP_MIME_FAKE_ASCII_TEXT Sep 15 14:13:46.868 [35984] dbg: config: warning: score set for non-existent rule OBFU_JPG_ATTACH Sep 15 14:13:46.868 [35984] dbg: config: warning: score set for non-existent rule RCVD_IN_MSPIKE_L3 Sep 15 14:13:46.869 [35984] dbg: config: warning: score set for non-existent rule VANITY Sep 15 14:13:48.455 [35984] dbg: rules: __HAS_LIST_ID merged duplicates: __ML2 Sep 15 14:13:48.455 [35984] dbg: rules: __DOS_HAS_MAILING_LIST merged duplicates: __ML4 Sep 15 14:13:48.455 [35984] dbg: rules: __AXB_XM_OL_024C2 merged duplicates: __AXB_XM_OL_2600 Sep 15 14:13:48.455 [35984] dbg: rules: __JM_REACTOR_DATE merged duplicates: __RATWARE_0_TZ_DATE Sep 15 14:13:48.455 [35984] dbg: rules: __DOS_HAS_ANY_URI merged duplicates: __HAS_ANY_URI __HAS_URI Sep 15 14:13:48.455 [35984] dbg: rules: __HAS_REPLY_TO merged duplicates: __REPLYTO_EXISTS Sep 15 14:13:48.456 [35984] dbg: rules: CTYPE_001C_A merged duplicates: DATE_IN_FUTURE_96_XX JM_SOUGHT_1 JM_SOUGHT_FRAUD_1 Sep 15 14:13:48.456 [35984] dbg: rules: __HAS_XMAIL merged duplicates: __HAS_X_MAILER Sep 15 14:13:48.456 [35984] dbg: rules: __THEBAT_MUA merged duplicates: __XM_THEBAT Sep 15 14:13:48.456 [35984] dbg: rules: NAME_EMAIL_DIFF merged duplicates: __NAME_EMAIL_DIFF Sep 15 14:13:48.456 [35984] dbg: rules: __AXB_MO_OL_024C2 merged duplicates: __AXB_MO_OL_2600 Sep 15 14:13:48.456 [35984] dbg: rules: __LARGE_PERCENT_AFTER merged duplicates: __LCL__KAM_BODY_LENGTH_LT_1024 __LCL__KAM_BODY_LENGTH_LT_512 __MIME_CTYPE_IN_BODY __MIME_MALF __PILL_PRICE_01 __PILL_PRICE_02 Sep 15 14:13:48.456 [35984] dbg: rules: __DOS_HAS_LIST_UNSUB merged duplicates: __FSL_HAS_LIST_UNSUB Sep 15 14:13:48.457 [35984] dbg: config: finish parsing Sep 15 14:13:48.483 [35984] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x40239e8) implements 'finish_parsing_end', priority 0 Sep 15 14:13:48.484 [35984] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x443e518) implements 'finish_parsing_end', priority 0 Sep 15 14:13:48.484 [35984] dbg: replacetags: replacing tags Sep 15 14:13:48.508 [35984] dbg: replacetags: done replacing tags Sep 15 14:13:48.509 [35984] dbg: FreeMail: loaded freemail_domains entries: 2532 normal, 31 wildcard Sep 15 14:13:48.511 [35984] dbg: config: score set 0 chosen. Sep 15 14:13:48.512 [35984] dbg: message: main message type: text/plain Sep 15 14:13:48.512 [35984] dbg: message: ---- MIME PARSER START ---- Sep 15 14:13:48.512 [35984] dbg: message: parsing normal part Sep 15 14:13:48.512 [35984] dbg: message: ---- MIME PARSER END ---- Sep 15 14:13:48.512 [35984] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x4261008) implements 'check_start', priority 0 Sep 15 14:13:48.514 [35984] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x413af48) implements 'check_main', priority 0 Sep 15 14:13:48.514 [35984] dbg: config: trusted_networks are not configured; it is recommended that you configure trusted_networks manually Sep 15 14:13:48.514 [35984] dbg: metadata: X-Spam-Relays-Trusted: Sep 15 14:13:48.514 [35984] dbg: metadata: X-Spam-Relays-Untrusted: Sep 15 14:13:48.514 [35984] dbg: metadata: X-Spam-Relays-Internal: Sep 15 14:13:48.514 [35984] dbg: metadata: X-Spam-Relays-External: Sep 15 14:13:48.515 [35984] dbg: message: no encoding detected Sep 15 14:13:48.515 [35984] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3ef1328) implements 'parsed_metadata', priority 0 Sep 15 14:13:48.515 [35984] dbg: dns: is_dns_available() last checked 1442297628.5 seconds ago; re-checking Sep 15 14:13:48.516 [35984] dbg: dns: is DNS available? 0 Sep 15 14:13:48.516 [35984] dbg: rules: local tests only, ignoring RBL eval Sep 15 14:13:48.518 [35984] dbg: check: running tests for priority: -1000 Sep 15 14:13:48.518 [35984] dbg: rules: running head tests; score so far=0 Sep 15 14:13:48.518 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 282 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg1000_1 Sep 15 14:13:48.519 [35984] dbg: rules: run_generic_tests - compiling eval code: head, priority -1000 Sep 15 14:13:48.519 [35984] dbg: rules: compiled head tests Sep 15 14:13:48.519 [35984] dbg: rules: running head_eval tests; score so far=0 Sep 15 14:13:48.520 [35984] dbg: rules: run_eval_tests - compiling eval code: 9, priority -1000 Sep 15 14:13:48.521 [35984] dbg: eval: all '*From' addrs: ign...@compiling.spamassassin.taint.org Sep 15 14:13:48.522 [35984] dbg: eval: all '*To' addrs: Sep 15 14:13:48.523 [35984] dbg: rules: running body tests; score so far=0 Sep 15 14:13:48.523 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg1000_1 Sep 15 14:13:48.524 [35984] dbg: rules: run_generic_tests - compiling eval code: body, priority -1000 Sep 15 14:13:48.524 [35984] dbg: rules: compiled body tests Sep 15 14:13:48.524 [35984] dbg: rules: running uri tests; score so far=0 Sep 15 14:13:48.524 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg1000_1 Sep 15 14:13:48.525 [35984] dbg: rules: run_generic_tests - compiling eval code: uri, priority -1000 Sep 15 14:13:48.525 [35984] dbg: rules: compiled uri tests Sep 15 14:13:48.525 [35984] dbg: rules: running rawbody tests; score so far=0 Sep 15 14:13:48.525 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 231 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg1000_1 Sep 15 14:13:48.526 [35984] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -1000 Sep 15 14:13:48.526 [35984] dbg: rules: compiled rawbody tests Sep 15 14:13:48.526 [35984] dbg: rules: running full tests; score so far=0 Sep 15 14:13:48.526 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 260 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg1000_1 Sep 15 14:13:48.527 [35984] dbg: rules: run_generic_tests - compiling eval code: full, priority -1000 Sep 15 14:13:48.527 [35984] dbg: rules: compiled full tests Sep 15 14:13:48.527 [35984] dbg: rules: running meta tests; score so far=0 Sep 15 14:13:48.527 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 285 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg1000_1 Sep 15 14:13:48.528 [35984] dbg: rules: run_generic_tests - compiling eval code: meta, priority -1000 Sep 15 14:13:48.528 [35984] dbg: rules: compiled meta tests Sep 15 14:13:48.528 [35984] dbg: check: running tests for priority: -950 Sep 15 14:13:48.529 [35984] dbg: rules: running head tests; score so far=0 Sep 15 14:13:48.529 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg950_1 Sep 15 14:13:48.529 [35984] dbg: rules: run_generic_tests - compiling eval code: head, priority -950 Sep 15 14:13:48.529 [35984] dbg: rules: compiled head tests Sep 15 14:13:48.530 [35984] dbg: rules: running head_eval tests; score so far=0 Sep 15 14:13:48.530 [35984] dbg: rules: run_eval_tests - compiling eval code: 9, priority -950 Sep 15 14:13:48.530 [35984] dbg: rules: running body tests; score so far=0 Sep 15 14:13:48.531 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg950_1 Sep 15 14:13:48.531 [35984] dbg: rules: run_generic_tests - compiling eval code: body, priority -950 Sep 15 14:13:48.531 [35984] dbg: rules: compiled body tests Sep 15 14:13:48.531 [35984] dbg: rules: running uri tests; score so far=0 Sep 15 14:13:48.532 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg950_1 Sep 15 14:13:48.532 [35984] dbg: rules: run_generic_tests - compiling eval code: uri, priority -950 Sep 15 14:13:48.532 [35984] dbg: rules: compiled uri tests Sep 15 14:13:48.532 [35984] dbg: rules: running rawbody tests; score so far=0 Sep 15 14:13:48.533 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg950_1 Sep 15 14:13:48.533 [35984] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -950 Sep 15 14:13:48.533 [35984] dbg: rules: compiled rawbody tests Sep 15 14:13:48.533 [35984] dbg: rules: running full tests; score so far=0 Sep 15 14:13:48.534 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg950_1 Sep 15 14:13:48.534 [35984] dbg: rules: run_generic_tests - compiling eval code: full, priority -950 Sep 15 14:13:48.534 [35984] dbg: rules: compiled full tests Sep 15 14:13:48.534 [35984] dbg: rules: running meta tests; score so far=0 Sep 15 14:13:48.535 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg950_1 Sep 15 14:13:48.535 [35984] dbg: rules: run_generic_tests - compiling eval code: meta, priority -950 Sep 15 14:13:48.535 [35984] dbg: rules: compiled meta tests Sep 15 14:13:48.535 [35984] dbg: check: running tests for priority: -900 Sep 15 14:13:48.536 [35984] dbg: rules: running head tests; score so far=0 Sep 15 14:13:48.536 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg900_1 Sep 15 14:13:48.536 [35984] dbg: rules: run_generic_tests - compiling eval code: head, priority -900 Sep 15 14:13:48.536 [35984] dbg: rules: compiled head tests Sep 15 14:13:48.537 [35984] dbg: rules: running head_eval tests; score so far=0 Sep 15 14:13:48.537 [35984] dbg: rules: run_eval_tests - compiling eval code: 9, priority -900 Sep 15 14:13:48.538 [35984] dbg: rules: running body tests; score so far=0 Sep 15 14:13:48.539 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg900_1 Sep 15 14:13:48.539 [35984] dbg: rules: run_generic_tests - compiling eval code: body, priority -900 Sep 15 14:13:48.539 [35984] dbg: rules: compiled body tests Sep 15 14:13:48.539 [35984] dbg: rules: running uri tests; score so far=0 Sep 15 14:13:48.540 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg900_1 Sep 15 14:13:48.540 [35984] dbg: rules: run_generic_tests - compiling eval code: uri, priority -900 Sep 15 14:13:48.540 [35984] dbg: rules: compiled uri tests Sep 15 14:13:48.540 [35984] dbg: rules: running rawbody tests; score so far=0 Sep 15 14:13:48.541 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg900_1 Sep 15 14:13:48.541 [35984] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -900 Sep 15 14:13:48.541 [35984] dbg: rules: compiled rawbody tests Sep 15 14:13:48.541 [35984] dbg: rules: running full tests; score so far=0 Sep 15 14:13:48.542 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg900_1 Sep 15 14:13:48.542 [35984] dbg: rules: run_generic_tests - compiling eval code: full, priority -900 Sep 15 14:13:48.542 [35984] dbg: rules: compiled full tests Sep 15 14:13:48.542 [35984] dbg: rules: running meta tests; score so far=0 Sep 15 14:13:48.543 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg900_1 Sep 15 14:13:48.543 [35984] dbg: rules: run_generic_tests - compiling eval code: meta, priority -900 Sep 15 14:13:48.543 [35984] dbg: rules: compiled meta tests Sep 15 14:13:48.544 [35984] dbg: check: running tests for priority: -400 Sep 15 14:13:48.544 [35984] dbg: rules: running head tests; score so far=0 Sep 15 14:13:48.544 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg400_1 Sep 15 14:13:48.544 [35984] dbg: rules: run_generic_tests - compiling eval code: head, priority -400 Sep 15 14:13:48.545 [35984] dbg: rules: compiled head tests Sep 15 14:13:48.545 [35984] dbg: rules: running body tests; score so far=0 Sep 15 14:13:48.545 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg400_1 Sep 15 14:13:48.545 [35984] dbg: rules: run_generic_tests - compiling eval code: body, priority -400 Sep 15 14:13:48.546 [35984] dbg: rules: compiled body tests Sep 15 14:13:48.546 [35984] dbg: rules: running uri tests; score so far=0 Sep 15 14:13:48.546 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg400_1 Sep 15 14:13:48.546 [35984] dbg: rules: run_generic_tests - compiling eval code: uri, priority -400 Sep 15 14:13:48.547 [35984] dbg: rules: compiled uri tests Sep 15 14:13:48.547 [35984] dbg: rules: running body_eval tests; score so far=0 Sep 15 14:13:48.547 [35984] dbg: rules: run_eval_tests - compiling eval code: 11, priority -400 Sep 15 14:13:48.548 [35984] dbg: rules: running rawbody tests; score so far=0 Sep 15 14:13:48.548 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg400_1 Sep 15 14:13:48.548 [35984] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -400 Sep 15 14:13:48.548 [35984] dbg: rules: compiled rawbody tests Sep 15 14:13:48.548 [35984] dbg: rules: running full tests; score so far=0 Sep 15 14:13:48.549 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg400_1 Sep 15 14:13:48.549 [35984] dbg: rules: run_generic_tests - compiling eval code: full, priority -400 Sep 15 14:13:48.549 [35984] dbg: rules: compiled full tests Sep 15 14:13:48.550 [35984] dbg: rules: running meta tests; score so far=0 Sep 15 14:13:48.550 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg400_1 Sep 15 14:13:48.550 [35984] dbg: rules: run_generic_tests - compiling eval code: meta, priority -400 Sep 15 14:13:48.550 [35984] dbg: rules: compiled meta tests Sep 15 14:13:48.551 [35984] dbg: check: running tests for priority: 0 Sep 15 14:13:48.551 [35984] dbg: rules: running head tests; score so far=0 Sep 15 14:13:48.575 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60069 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_1 Sep 15 14:13:48.588 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60291 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_2 Sep 15 14:13:48.600 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60028 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_3 Sep 15 14:13:48.611 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60212 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_4 Sep 15 14:13:48.623 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60421 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_5 Sep 15 14:13:48.632 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 26182 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_6 Sep 15 14:13:48.636 [35984] dbg: rules: run_generic_tests - compiling eval code: head, priority 0 Sep 15 14:13:48.636 [35984] dbg: rules: compiled head tests Sep 15 14:13:48.638 [35984] dbg: rules: ran header rule __MISSING_REF ======> got hit: "UNSET" Sep 15 14:13:48.638 [35984] dbg: rules: ran header rule __MISSING_REPLY ======> got hit: "UNSET" Sep 15 14:13:48.639 [35984] dbg: rules: ran header rule __MSGID_OK_HOST ======> got hit: "@lint_rules>" Sep 15 14:13:48.639 [35984] dbg: rules: ran header rule __MSGID_OK_DIGITS ======> got hit: "1442297616" Sep 15 14:13:48.639 [35984] dbg: rules: ran header rule __HAS_MESSAGE_ID ======> got hit: "<YES>" Sep 15 14:13:48.640 [35984] dbg: rules: ran header rule __HAS_SUBJECT ======> got hit: "<YES>" Sep 15 14:13:48.641 [35984] dbg: rules: ran header rule __MSOE_MID_WRONG_CASE ======> got hit: " Sep 15 14:13:48.641 [35984] dbg: rules: [...] Message-Id: " Sep 15 14:13:48.641 [35984] dbg: rules: ran header rule __HAS_MSGID ======> got hit: "<" Sep 15 14:13:48.641 [35984] dbg: rules: ran header rule __SANE_MSGID ======> got hit: "<1442297616@lint_rules> Sep 15 14:13:48.642 [35984] dbg: rules: [...] " Sep 15 14:13:48.642 [35984] dbg: rules: ran header rule __TO_NO_ARROWS_R ======> got hit: "negative match" Sep 15 14:13:48.642 [35984] dbg: rules: ran header rule __HAS_FROM ======> got hit: "<YES>" Sep 15 14:13:48.643 [35984] dbg: rules: running head_eval tests; score so far=0 Sep 15 14:13:48.645 [35984] dbg: rules: run_eval_tests - compiling eval code: 9, priority 0 Sep 15 14:13:48.652 [35984] dbg: FreeMail: RULE (FREEMAIL_FROM) check_freemail_from Sep 15 14:13:48.652 [35984] dbg: FreeMail: all from-addresses: ign...@compiling.spamassassin.taint.org Sep 15 14:13:48.653 [35984] dbg: FreeMail: RULE (__freemail_reply) check_freemail_replyto Sep 15 14:13:48.653 [35984] dbg: FreeMail: From address: ign...@compiling.spamassassin.taint.org Sep 15 14:13:48.653 [35984] dbg: FreeMail: No Reply-To and From is not freemail, skipping check Sep 15 14:13:48.654 [35984] dbg: rules: ran eval rule NO_RELAYS ======> got hit (1) Sep 15 14:13:48.655 [35984] dbg: rules: ran eval rule __GATED_THROUGH_RCVD_REMOVER ======> got hit (1) Sep 15 14:13:48.655 [35984] dbg: FreeMail: RULE (__smf_freemail_hdr_replyto) check_freemail_header Sep 15 14:13:48.655 [35984] dbg: FreeMail: header Reply-To:addr not found from mail Sep 15 14:13:48.656 [35984] dbg: FreeMail: RULE (FREEMAIL_REPLYTO_END_DIGIT) check_freemail_header regex:\d@ Sep 15 14:13:48.656 [35984] dbg: FreeMail: header Reply-To not found from mail Sep 15 14:13:48.657 [35984] dbg: FreeMail: RULE (__freemail_replyto) check_freemail_replyto Sep 15 14:13:48.657 [35984] dbg: FreeMail: From address: ign...@compiling.spamassassin.taint.org Sep 15 14:13:48.657 [35984] dbg: FreeMail: Reply-To is not freemail, skipping check Sep 15 14:13:48.658 [35984] dbg: FreeMail: RULE (__freemail_hdr_replyto) check_freemail_header Sep 15 14:13:48.658 [35984] dbg: FreeMail: header Reply-To not found from mail Sep 15 14:13:48.658 [35984] dbg: spf: cannot get Envelope-From, cannot use SPF Sep 15 14:13:48.658 [35984] dbg: spf: def_spf_whitelist_from: could not find useable envelope sender Sep 15 14:13:48.658 [35984] dbg: FreeMail: RULE (FREEMAIL_ENVFROM_END_DIGIT) check_freemail_header regex:\d@ Sep 15 14:13:48.658 [35984] dbg: FreeMail: header EnvelopeFrom not found from mail Sep 15 14:13:48.659 [35984] dbg: spf: spf_whitelist_from: could not find useable envelope sender Sep 15 14:13:48.660 [35984] dbg: rules: ran eval rule MISSING_HEADERS ======> got hit (1) Sep 15 14:13:48.660 [35984] dbg: rules: running body tests; score so far=0.914 Sep 15 14:13:48.665 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 61650 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_1 Sep 15 14:13:48.680 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60346 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_2 Sep 15 14:13:48.694 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60111 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_3 Sep 15 14:13:48.708 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60618 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_4 Sep 15 14:13:48.723 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60863 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_5 Sep 15 14:13:48.737 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 61592 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_6 Sep 15 14:13:48.751 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60235 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_7 Sep 15 14:13:48.766 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 61104 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_8 Sep 15 14:13:48.783 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60820 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_9 Sep 15 14:13:48.794 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 6031 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_10 Sep 15 14:13:48.795 [35984] dbg: rules: run_generic_tests - compiling eval code: body, priority 0 Sep 15 14:13:48.796 [35984] dbg: rules: compiled body tests Sep 15 14:13:48.836 [35984] dbg: rules: ran body rule __NONEMPTY_BODY ======> got hit: "I" Sep 15 14:13:48.874 [35984] dbg: rules: ran body rule __BODY_TEXT_LINE ======> got hit: "I" Sep 15 14:13:48.913 [35984] dbg: rules: running uri tests; score so far=0.914 Sep 15 14:13:48.918 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 37437 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_0_1 Sep 15 14:13:48.927 [35984] dbg: rules: run_generic_tests - compiling eval code: uri, priority 0 Sep 15 14:13:48.928 [35984] dbg: rules: compiled uri tests Sep 15 14:13:48.928 [35984] dbg: rules: running body_eval tests; score so far=0.914 Sep 15 14:13:48.932 [35984] dbg: rules: run_eval_tests - compiling eval code: 11, priority 0 Sep 15 14:13:48.951 [35984] dbg: https_http_mismatch: anchors 0 Sep 15 14:13:48.961 [35984] dbg: rules: running rawbody tests; score so far=0.914 Sep 15 14:13:48.964 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 21972 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_0_1 Sep 15 14:13:48.968 [35984] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 0 Sep 15 14:13:48.968 [35984] dbg: rules: compiled rawbody tests Sep 15 14:13:48.969 [35984] dbg: rules: running rawbody_eval tests; score so far=0.914 Sep 15 14:13:48.970 [35984] dbg: rules: run_eval_tests - compiling eval code: 15, priority 0 Sep 15 14:13:48.971 [35984] dbg: rules: running full tests; score so far=0.914 Sep 15 14:13:48.971 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 1167 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_0_1 Sep 15 14:13:48.972 [35984] dbg: rules: run_generic_tests - compiling eval code: full, priority 0 Sep 15 14:13:48.972 [35984] dbg: rules: compiled full tests Sep 15 14:13:48.972 [35984] dbg: rules: running full_eval tests; score so far=0.914 Sep 15 14:13:48.972 [35984] dbg: rules: run_eval_tests - compiling eval code: 13, priority 0 Sep 15 14:13:48.973 [35984] dbg: dkim: signature verification disabled, DNS resolving not available Sep 15 14:13:48.973 [35984] dbg: rules: running meta tests; score so far=0.914 Sep 15 14:13:48.974 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 275 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_0_1 Sep 15 14:13:48.974 [35984] dbg: rules: run_generic_tests - compiling eval code: meta, priority 0 Sep 15 14:13:48.974 [35984] dbg: rules: compiled meta tests Sep 15 14:13:48.975 [35984] dbg: check: running tests for priority: 500 Sep 15 14:13:48.975 [35984] dbg: dns: harvest_dnsbl_queries Sep 15 14:13:48.975 [35984] dbg: rules: running head tests; score so far=0.914 Sep 15 14:13:48.975 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 276 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_500_1 Sep 15 14:13:48.976 [35984] dbg: rules: run_generic_tests - compiling eval code: head, priority 500 Sep 15 14:13:48.976 [35984] dbg: rules: compiled head tests Sep 15 14:13:48.976 [35984] dbg: rules: running body tests; score so far=0.914 Sep 15 14:13:48.976 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 219 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_500_1 Sep 15 14:13:48.977 [35984] dbg: rules: run_generic_tests - compiling eval code: body, priority 500 Sep 15 14:13:48.977 [35984] dbg: rules: compiled body tests Sep 15 14:13:48.977 [35984] dbg: rules: running uri tests; score so far=0.914 Sep 15 14:13:48.977 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 217 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_500_1 Sep 15 14:13:48.978 [35984] dbg: rules: run_generic_tests - compiling eval code: uri, priority 500 Sep 15 14:13:48.978 [35984] dbg: rules: compiled uri tests Sep 15 14:13:48.978 [35984] dbg: rules: running rawbody tests; score so far=0.914 Sep 15 14:13:48.978 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_500_1 Sep 15 14:13:48.979 [35984] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 500 Sep 15 14:13:48.979 [35984] dbg: rules: compiled rawbody tests Sep 15 14:13:48.979 [35984] dbg: rules: running full tests; score so far=0.914 Sep 15 14:13:48.979 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 254 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_500_1 Sep 15 14:13:48.980 [35984] dbg: rules: run_generic_tests - compiling eval code: full, priority 500 Sep 15 14:13:48.980 [35984] dbg: rules: compiled full tests Sep 15 14:13:48.980 [35984] dbg: rules: running meta tests; score so far=0.914 Sep 15 14:13:48.981 [35984] dbg: rules: meta test DIGEST_MULTIPLE has undefined dependency 'DCC_CHECK' Sep 15 14:13:48.995 [35984] dbg: rules: meta test GOOG_REDIR_SHORT has undefined dependency '__KAM_BODY_LENGTH_LT_512' Sep 15 14:13:49.011 [35984] dbg: rules: meta test FSL_BULK_SIG has undefined dependency 'DCC_CHECK' Sep 15 14:13:49.030 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60098 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_1 Sep 15 14:13:49.046 [35984] dbg: rules: flush_evalstr (add_evalstr) compiling 60007 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_2 Sep 15 14:13:49.074 [35984] dbg: rules: flush_evalstr (run_generic_tests) compiling 59592 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_3 Sep 15 14:13:49.087 [35984] dbg: rules: run_generic_tests - compiling eval code: meta, priority 500 Sep 15 14:13:49.087 [35984] dbg: rules: compiled meta tests Sep 15 14:13:49.091 [35984] dbg: check: is spam? score=3.652 required=5.3 Sep 15 14:13:49.092 [35984] dbg: check: tests=MISSING_DATE,MISSING_HEADERS,NO_RECEIVED,NO_RELAYS Sep 15 14:13:49.092 [35984] dbg: check: subtests=__BODY_TEXT_LINE,__EMPTY_BODY,__GATED_THROUGH_RCVD_REMOVER,__HAS_FROM,__HAS_MESSAGE_ID,__HAS_MSGID,__HAS_SUBJECT,__KHOP_NO_FULL_NAME,__MISSING_REF,__MISSING_REPLY,__MSGID_OK_DIGITS,__MSGID_OK_HOST,__MSOE_MID_WRONG_CASE,__NONEMPTY_BODY,__NOT_SPOOFED,__SANE_MSGID,__TO_NO_ARROWS_R,__UNUSABLE_MSGID Sep 15 14:13:49.092 [35984] dbg: timing: total 12832 ms - init: 12252 (95.5%), parse: 1.39 (0.0%), extract_message_metadata: 1.76 (0.0%), get_uri_detail_list: 1.45 (0.0%), tests_pri_-1000: 11 (0.1%), compile_gen: 186 (1.4%), compile_eval: 26 (0.2%), tests_pri_-950: 7 (0.1%), tests_pri_-900: 8 (0.1%), tests_pri_-400: 7 (0.1%), tests_pri_0: 424 (3.3%), tests_pri_500: 117 (0.9%) Thanks Nathan
