Jun 7 22:12:56.231 [2438] dbg: logger: adding facilities: all Jun 7 22:12:56.231 [2438] dbg: logger: logging level is DBG Jun 7 22:12:56.231 [2438] dbg: generic: SpamAssassin version 3.3.1 Jun 7 22:12:56.231 [2438] dbg: generic: Perl 5.010001, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin Jun 7 22:12:56.231 [2438] dbg: config: timing enabled Jun 7 22:12:56.232 [2438] dbg: config: score set 0 chosen. Jun 7 22:12:56.234 [2438] dbg: util: running in taint mode? yes Jun 7 22:12:56.234 [2438] dbg: util: taint mode: deleting unsafe environment variables, resetting PATH Jun 7 22:12:56.235 [2438] dbg: util: PATH included '/usr/local/bin', keeping Jun 7 22:12:56.235 [2438] dbg: util: PATH included '/usr/bin', keeping Jun 7 22:12:56.235 [2438] dbg: util: PATH included '/bin', keeping Jun 7 22:12:56.235 [2438] dbg: util: PATH included '/usr/local/games', keeping Jun 7 22:12:56.235 [2438] dbg: util: PATH included '/usr/games', keeping Jun 7 22:12:56.235 [2438] dbg: util: final PATH set to: /usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games Jun 7 22:12:56.242 [2438] dbg: dns: is Net::DNS::Resolver available? yes Jun 7 22:12:56.242 [2438] dbg: dns: Net::DNS version: 0.65 Jun 7 22:12:56.536 [2438] dbg: diag: perl platform: 5.010001 linux Jun 7 22:12:56.536 [2438] dbg: diag: [...] module installed: Digest::SHA1, version 2.12 Jun 7 22:12:56.536 [2438] dbg: diag: [...] module installed: HTML::Parser, version 3.64 Jun 7 22:12:56.536 [2438] dbg: diag: [...] module installed: Net::DNS, version 0.65 Jun 7 22:12:56.536 [2438] dbg: diag: [...] module installed: NetAddr::IP, version 4.024 Jun 7 22:12:56.536 [2438] dbg: diag: [...] module installed: Time::HiRes, version 1.9719 Jun 7 22:12:56.536 [2438] dbg: diag: [...] module installed: Archive::Tar, version 1.52 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: IO::Zlib, version 1.09 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: Digest::SHA1, version 2.12 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: MIME::Base64, version 3.08 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: DB_File, version 1.82 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: Net::SMTP, version 2.31 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: Mail::SPF, version v2.007 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module not installed: IP::Country::Fast ('require' failed) Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: Razor2::Client::Agent, version 2.84 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module not installed: Net::Ident ('require' failed) Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: IO::Socket::INET6, version 2.54 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module not installed: IO::Socket::SSL ('require' failed) Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: Compress::Zlib, version 2.02 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: Mail::DKIM, version 0.38 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: DBI, version 1.609 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: Getopt::Long, version 2.38 Jun 7 22:12:56.537 [2438] dbg: diag: [...] module installed: LWP::UserAgent, version 5.834 Jun 7 22:12:56.538 [2438] dbg: diag: [...] module installed: HTTP::Date, version 5.831 Jun 7 22:12:56.538 [2438] dbg: diag: [...] module not installed: Encode::Detect ('require' failed) Jun 7 22:12:56.538 [2438] dbg: ignore: using a test message to lint rules Jun 7 22:12:56.538 [2438] dbg: config: using "/etc/spamassassin" for site rules pre files Jun 7 22:12:56.538 [2438] dbg: config: read file /etc/spamassassin/init.pre Jun 7 22:12:56.539 [2438] dbg: config: read file /etc/spamassassin/v310.pre Jun 7 22:12:56.539 [2438] dbg: config: read file /etc/spamassassin/v312.pre Jun 7 22:12:56.539 [2438] dbg: config: read file /etc/spamassassin/v320.pre Jun 7 22:12:56.539 [2438] dbg: config: read file /etc/spamassassin/v330.pre Jun 7 22:12:56.539 [2438] dbg: config: using "/usr/share/spamassassin" for sys rules pre files Jun 7 22:12:56.540 [2438] dbg: config: using "/usr/share/spamassassin" for default rules dir Jun 7 22:12:56.541 [2438] dbg: config: read file /usr/share/spamassassin/10_default_prefs.cf Jun 7 22:12:56.541 [2438] dbg: config: read file /usr/share/spamassassin/20_advance_fee.cf Jun 7 22:12:56.541 [2438] dbg: config: read file /usr/share/spamassassin/20_aux_tlds.cf Jun 7 22:12:56.541 [2438] dbg: config: read file /usr/share/spamassassin/20_body_tests.cf Jun 7 22:12:56.541 [2438] dbg: config: read file /usr/share/spamassassin/20_compensate.cf Jun 7 22:12:56.542 [2438] dbg: config: read file /usr/share/spamassassin/20_dnsbl_tests.cf Jun 7 22:12:56.542 [2438] dbg: config: read file /usr/share/spamassassin/20_drugs.cf Jun 7 22:12:56.542 [2438] dbg: config: read file /usr/share/spamassassin/20_dynrdns.cf Jun 7 22:12:56.542 [2438] dbg: config: read file /usr/share/spamassassin/20_fake_helo_tests.cf Jun 7 22:12:56.543 [2438] dbg: config: read file /usr/share/spamassassin/20_freemail.cf Jun 7 22:12:56.543 [2438] dbg: config: read file /usr/share/spamassassin/20_freemail_domains.cf Jun 7 22:12:56.543 [2438] dbg: config: read file /usr/share/spamassassin/20_head_tests.cf Jun 7 22:12:56.544 [2438] dbg: config: read file /usr/share/spamassassin/20_html_tests.cf Jun 7 22:12:56.544 [2438] dbg: config: read file /usr/share/spamassassin/20_imageinfo.cf Jun 7 22:12:56.544 [2438] dbg: config: read file /usr/share/spamassassin/20_meta_tests.cf Jun 7 22:12:56.544 [2438] dbg: config: read file /usr/share/spamassassin/20_net_tests.cf Jun 7 22:12:56.544 [2438] dbg: config: read file /usr/share/spamassassin/20_phrases.cf Jun 7 22:12:56.545 [2438] dbg: config: read file /usr/share/spamassassin/20_porn.cf Jun 7 22:12:56.545 [2438] dbg: config: read file /usr/share/spamassassin/20_ratware.cf Jun 7 22:12:56.545 [2438] dbg: config: read file /usr/share/spamassassin/20_uri_tests.cf Jun 7 22:12:56.545 [2438] dbg: config: read file /usr/share/spamassassin/20_vbounce.cf Jun 7 22:12:56.545 [2438] dbg: config: read file /usr/share/spamassassin/23_bayes.cf Jun 7 22:12:56.546 [2438] dbg: config: read file /usr/share/spamassassin/25_accessdb.cf Jun 7 22:12:56.546 [2438] dbg: config: read file /usr/share/spamassassin/25_antivirus.cf Jun 7 22:12:56.546 [2438] dbg: config: read file /usr/share/spamassassin/25_asn.cf Jun 7 22:12:56.546 [2438] dbg: config: read file /usr/share/spamassassin/25_dcc.cf Jun 7 22:12:56.546 [2438] dbg: config: read file /usr/share/spamassassin/25_dkim.cf Jun 7 22:12:56.546 [2438] dbg: config: read file /usr/share/spamassassin/25_hashcash.cf Jun 7 22:12:56.547 [2438] dbg: config: read file /usr/share/spamassassin/25_pyzor.cf Jun 7 22:12:56.547 [2438] dbg: config: read file /usr/share/spamassassin/25_razor2.cf Jun 7 22:12:56.547 [2438] dbg: config: read file /usr/share/spamassassin/25_replace.cf Jun 7 22:12:56.547 [2438] dbg: config: read file /usr/share/spamassassin/25_spf.cf Jun 7 22:12:56.548 [2438] dbg: config: read file /usr/share/spamassassin/25_textcat.cf Jun 7 22:12:56.548 [2438] dbg: config: read file /usr/share/spamassassin/25_uribl.cf Jun 7 22:12:56.548 [2438] dbg: config: read file /usr/share/spamassassin/30_text_de.cf Jun 7 22:12:56.549 [2438] dbg: config: read file /usr/share/spamassassin/30_text_fr.cf Jun 7 22:12:56.549 [2438] dbg: config: read file /usr/share/spamassassin/30_text_it.cf Jun 7 22:12:56.549 [2438] dbg: config: read file /usr/share/spamassassin/30_text_nl.cf Jun 7 22:12:56.549 [2438] dbg: config: read file /usr/share/spamassassin/30_text_pl.cf Jun 7 22:12:56.549 [2438] dbg: config: read file /usr/share/spamassassin/30_text_pt_br.cf Jun 7 22:12:56.550 [2438] dbg: config: read file /usr/share/spamassassin/50_scores.cf Jun 7 22:12:56.550 [2438] dbg: config: read file /usr/share/spamassassin/60_adsp_override_dkim.cf Jun 7 22:12:56.550 [2438] dbg: config: read file /usr/share/spamassassin/60_awl.cf Jun 7 22:12:56.550 [2438] dbg: config: read file /usr/share/spamassassin/60_shortcircuit.cf Jun 7 22:12:56.551 [2438] dbg: config: read file /usr/share/spamassassin/60_whitelist.cf Jun 7 22:12:56.551 [2438] dbg: config: read file /usr/share/spamassassin/60_whitelist_dkim.cf Jun 7 22:12:56.551 [2438] dbg: config: read file /usr/share/spamassassin/60_whitelist_spf.cf Jun 7 22:12:56.551 [2438] dbg: config: read file /usr/share/spamassassin/60_whitelist_subject.cf Jun 7 22:12:56.552 [2438] dbg: config: read file /usr/share/spamassassin/72_active.cf Jun 7 22:12:56.556 [2438] dbg: config: using "/etc/spamassassin" for site rules dir Jun 7 22:12:56.556 [2438] dbg: config: read file /etc/spamassassin/65_debian.cf Jun 7 22:12:56.557 [2438] dbg: config: read file /etc/spamassassin/local.cf Jun 7 22:12:56.581 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC Jun 7 22:12:56.590 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC Jun 7 22:12:56.593 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC Jun 7 22:12:56.599 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC Jun 7 22:12:56.602 [2438] dbg: pyzor: local tests only, disabling Pyzor Jun 7 22:12:56.603 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC Jun 7 22:12:56.606 [2438] dbg: razor2: local tests only, skipping Razor Jun 7 22:12:56.606 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC Jun 7 22:12:56.610 [2438] dbg: reporter: local tests only, disabling SpamCop Jun 7 22:12:56.610 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC Jun 7 22:12:56.612 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC Jun 7 22:12:56.613 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC Jun 7 22:12:56.615 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC Jun 7 22:12:56.618 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::DKIM from @INC Jun 7 22:12:56.625 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC Jun 7 22:12:56.635 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC Jun 7 22:12:56.637 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC Jun 7 22:12:56.639 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC Jun 7 22:12:56.653 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC Jun 7 22:12:56.656 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC Jun 7 22:12:56.660 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC Jun 7 22:12:56.663 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC Jun 7 22:12:56.673 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC Jun 7 22:12:56.677 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC Jun 7 22:12:56.681 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC Jun 7 22:12:56.682 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC Jun 7 22:12:56.686 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC Jun 7 22:12:56.687 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC Jun 7 22:12:56.691 [2438] dbg: plugin: loading Mail::SpamAssassin::Plugin::FreeMail from @INC Jun 7 22:12:56.769 [2438] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0xa75ecc8) implements 'parse_config', priority 0 Jun 7 22:12:57.226 [2438] dbg: config: uri_detail adding (text =~ /(?-xism:(?i)\b(?:cliquez\Wici\b|clic aqu[^<.,a ]))/) to T_KHOP_FOREIGN_CLICK Jun 7 22:12:57.226 [2438] dbg: config: uri_detail added T_KHOP_FOREIGN_CLICK Jun 7 22:12:57.406 [2438] dbg: config: warning: score set for non-existent rule SHORTCIRCUIT Jun 7 22:12:57.407 [2438] dbg: config: warning: score set for non-existent rule SUBJ_RE_NUM Jun 7 22:12:57.407 [2438] dbg: config: warning: score set for non-existent rule FM_VIAGRA_SPAM1114 Jun 7 22:12:57.407 [2438] dbg: config: warning: score set for non-existent rule AXB_HELO_LH_HOME Jun 7 22:12:57.408 [2438] dbg: config: warning: score set for non-existent rule ACCESSDB Jun 7 22:12:57.731 [2438] dbg: rules: __MO_OL_9B90B merged duplicates: __MO_OL_C65FA Jun 7 22:12:57.731 [2438] dbg: rules: __XM_OL_22B61 merged duplicates: __XM_OL_A842E Jun 7 22:12:57.732 [2438] dbg: rules: __MO_OL_07794 merged duplicates: __MO_OL_8627E __MO_OL_F3B05 Jun 7 22:12:57.732 [2438] 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 Jun 7 22:12:57.732 [2438] dbg: rules: FH_MSGID_01C67 merged duplicates: __MSGID_VGA Jun 7 22:12:57.732 [2438] dbg: rules: FS_NEW_SOFT_UPLOAD merged duplicates: HS_SUBJ_NEW_SOFTWARE Jun 7 22:12:57.732 [2438] dbg: rules: __MO_OL_91287 merged duplicates: __MO_OL_B30D1 __MO_OL_CF0C0 Jun 7 22:12:57.732 [2438] dbg: rules: KAM_STOCKOTC merged duplicates: KAM_STOCKTIP15 KAM_STOCKTIP20 KAM_STOCKTIP21 KAM_STOCKTIP4 KAM_STOCKTIP6 Jun 7 22:12:57.733 [2438] 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 Jun 7 22:12:57.733 [2438] dbg: rules: __MO_OL_015D5 merged duplicates: __MO_OL_6554A Jun 7 22:12:57.733 [2438] dbg: rules: __MO_OL_22B61 merged duplicates: __MO_OL_4F240 __MO_OL_ADFF7 Jun 7 22:12:57.733 [2438] dbg: rules: __MO_OL_812FF merged duplicates: __MO_OL_BC7E6 Jun 7 22:12:57.733 [2438] dbg: rules: __MO_OL_25340 merged duplicates: __MO_OL_4EEDB __MO_OL_7533E Jun 7 22:12:57.733 [2438] dbg: rules: __DOS_HAS_MAILING_LIST merged duplicates: __ML4 __freemail_safe_ml2 Jun 7 22:12:57.734 [2438] dbg: rules: __ML2 merged duplicates: __freemail_safe_ml1 Jun 7 22:12:57.734 [2438] dbg: rules: __MO_OL_58CB5 merged duplicates: __MO_OL_B4B40 Jun 7 22:12:57.734 [2438] dbg: rules: __DOS_HAS_ANY_URI merged duplicates: __HAS_ANY_URI Jun 7 22:12:57.734 [2438] dbg: rules: __FH_HAS_XMSMAIL merged duplicates: __HAS_MSMAIL_PRI Jun 7 22:12:57.734 [2438] dbg: rules: __MO_OL_72641 merged duplicates: __MO_OL_A842E Jun 7 22:12:57.734 [2438] dbg: rules: __HAS_X_MAILING_LIST merged duplicates: __freemail_safe_ml3 Jun 7 22:12:57.734 [2438] dbg: rules: __MO_OL_F475E merged duplicates: __MO_OL_FF5C8 Jun 7 22:12:57.734 [2438] dbg: rules: __MO_OL_4BF4C merged duplicates: __MO_OL_F6D01 Jun 7 22:12:57.735 [2438] dbg: config: finish parsing Jun 7 22:12:57.777 [2438] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0xa2c09b8) implements 'finish_parsing_end', priority 0 Jun 7 22:12:57.777 [2438] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0xa75ecc8) implements 'finish_parsing_end', priority 0 Jun 7 22:12:57.777 [2438] dbg: replacetags: replacing tags Jun 7 22:12:57.820 [2438] dbg: replacetags: done replacing tags Jun 7 22:12:57.821 [2438] dbg: FreeMail: loaded freemail_domains entries: 2068 normal, 21 wildcard Jun 7 22:12:57.825 [2438] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0xa352508) implements 'learner_new', priority 0 Jun 7 22:12:57.825 [2438] dbg: bayes: learner_new self=Mail::SpamAssassin::Plugin::Bayes=HASH(0xa352508), bayes_store_module=Mail::SpamAssassin::BayesStore::DBM Jun 7 22:12:57.845 [2438] dbg: bayes: learner_new: got store=Mail::SpamAssassin::BayesStore::DBM=HASH(0xa7c0eb8) Jun 7 22:12:57.845 [2438] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0xa352508) implements 'learner_is_scan_available', priority 0 Jun 7 22:12:57.845 [2438] dbg: bayes: tie-ing to DB file R/O /var/lib/amavis/.spamassassin/bayes_toks Jun 7 22:12:57.846 [2438] dbg: bayes: tie-ing to DB file R/O /var/lib/amavis/.spamassassin/bayes_seen Jun 7 22:12:57.847 [2438] dbg: bayes: found bayes db version 3 Jun 7 22:12:57.848 [2438] dbg: bayes: DB journal sync: last sync: 0 Jun 7 22:12:57.848 [2438] dbg: bayes: not available for scanning, only 0 spam(s) in bayes DB < 200 Jun 7 22:12:57.848 [2438] dbg: bayes: untie-ing Jun 7 22:12:57.849 [2438] dbg: config: score set 0 chosen. Jun 7 22:12:57.849 [2438] dbg: message: main message type: text/plain Jun 7 22:12:57.849 [2438] dbg: message: ---- MIME PARSER START ---- Jun 7 22:12:57.849 [2438] dbg: message: parsing normal part Jun 7 22:12:57.849 [2438] dbg: message: ---- MIME PARSER END ---- Jun 7 22:12:57.850 [2438] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0xa64b948) implements 'check_start', priority 0 Jun 7 22:12:57.851 [2438] dbg: bayes: tie-ing to DB file R/O /var/lib/amavis/.spamassassin/bayes_toks Jun 7 22:12:57.852 [2438] dbg: bayes: tie-ing to DB file R/O /var/lib/amavis/.spamassassin/bayes_seen Jun 7 22:12:57.853 [2438] dbg: bayes: found bayes db version 3 Jun 7 22:12:57.853 [2438] dbg: bayes: DB journal sync: last sync: 0 Jun 7 22:12:57.853 [2438] dbg: bayes: not available for scanning, only 0 spam(s) in bayes DB < 200 Jun 7 22:12:57.854 [2438] dbg: bayes: untie-ing Jun 7 22:12:57.854 [2438] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0xa333630) implements 'check_main', priority 0 Jun 7 22:12:57.854 [2438] dbg: config: trusted_networks are not configured; it is recommended that you configure trusted_networks manually Jun 7 22:12:57.855 [2438] dbg: metadata: X-Spam-Relays-Trusted: Jun 7 22:12:57.855 [2438] dbg: metadata: X-Spam-Relays-Untrusted: Jun 7 22:12:57.855 [2438] dbg: metadata: X-Spam-Relays-Internal: Jun 7 22:12:57.855 [2438] dbg: metadata: X-Spam-Relays-External: Jun 7 22:12:57.855 [2438] dbg: message: no encoding detected Jun 7 22:12:57.856 [2438] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa20ef60) implements 'parsed_metadata', priority 0 Jun 7 22:12:57.856 [2438] dbg: dns: is_dns_available() last checked 1275941577.9 seconds ago; re-checking Jun 7 22:12:57.856 [2438] dbg: dns: is DNS available? 0 Jun 7 22:12:57.856 [2438] dbg: rules: local tests only, ignoring RBL eval Jun 7 22:12:57.858 [2438] dbg: check: running tests for priority: -1000 Jun 7 22:12:57.859 [2438] dbg: rules: running head tests; score so far=0 Jun 7 22:12:57.859 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 282 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg1000_1 Jun 7 22:12:57.859 [2438] dbg: rules: run_generic_tests - compiling eval code: head, priority -1000 Jun 7 22:12:57.860 [2438] dbg: rules: compiled head tests Jun 7 22:12:57.860 [2438] dbg: rules: running head_eval tests; score so far=0 Jun 7 22:12:57.861 [2438] dbg: rules: run_eval_tests - compiling eval code: 9, priority -1000 Jun 7 22:12:57.862 [2438] dbg: eval: all '*From' addrs: ignore@compiling.spamassassin.taint.org Jun 7 22:12:57.862 [2438] dbg: eval: all '*To' addrs: Jun 7 22:12:57.863 [2438] dbg: rules: running body tests; score so far=0 Jun 7 22:12:57.863 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg1000_1 Jun 7 22:12:57.864 [2438] dbg: rules: run_generic_tests - compiling eval code: body, priority -1000 Jun 7 22:12:57.864 [2438] dbg: rules: compiled body tests Jun 7 22:12:57.864 [2438] dbg: rules: running uri tests; score so far=0 Jun 7 22:12:57.864 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg1000_1 Jun 7 22:12:57.865 [2438] dbg: rules: run_generic_tests - compiling eval code: uri, priority -1000 Jun 7 22:12:57.865 [2438] dbg: rules: compiled uri tests Jun 7 22:12:57.865 [2438] dbg: rules: running rawbody tests; score so far=0 Jun 7 22:12:57.865 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 231 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg1000_1 Jun 7 22:12:57.865 [2438] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -1000 Jun 7 22:12:57.866 [2438] dbg: rules: compiled rawbody tests Jun 7 22:12:57.866 [2438] dbg: rules: running full tests; score so far=0 Jun 7 22:12:57.866 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 260 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg1000_1 Jun 7 22:12:57.866 [2438] dbg: rules: run_generic_tests - compiling eval code: full, priority -1000 Jun 7 22:12:57.867 [2438] dbg: rules: compiled full tests Jun 7 22:12:57.867 [2438] dbg: rules: running meta tests; score so far=0 Jun 7 22:12:57.867 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 285 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg1000_1 Jun 7 22:12:57.868 [2438] dbg: rules: run_generic_tests - compiling eval code: meta, priority -1000 Jun 7 22:12:57.868 [2438] dbg: rules: compiled meta tests Jun 7 22:12:57.868 [2438] dbg: check: running tests for priority: -950 Jun 7 22:12:57.868 [2438] dbg: rules: running head tests; score so far=0 Jun 7 22:12:57.869 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg950_1 Jun 7 22:12:57.869 [2438] dbg: rules: run_generic_tests - compiling eval code: head, priority -950 Jun 7 22:12:57.869 [2438] dbg: rules: compiled head tests Jun 7 22:12:57.869 [2438] dbg: rules: running head_eval tests; score so far=0 Jun 7 22:12:57.870 [2438] dbg: rules: run_eval_tests - compiling eval code: 9, priority -950 Jun 7 22:12:57.870 [2438] dbg: rules: running body tests; score so far=0 Jun 7 22:12:57.871 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg950_1 Jun 7 22:12:57.871 [2438] dbg: rules: run_generic_tests - compiling eval code: body, priority -950 Jun 7 22:12:57.871 [2438] dbg: rules: compiled body tests Jun 7 22:12:57.871 [2438] dbg: rules: running uri tests; score so far=0 Jun 7 22:12:57.872 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg950_1 Jun 7 22:12:57.872 [2438] dbg: rules: run_generic_tests - compiling eval code: uri, priority -950 Jun 7 22:12:57.872 [2438] dbg: rules: compiled uri tests Jun 7 22:12:57.872 [2438] dbg: rules: running rawbody tests; score so far=0 Jun 7 22:12:57.872 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg950_1 Jun 7 22:12:57.873 [2438] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -950 Jun 7 22:12:57.873 [2438] dbg: rules: compiled rawbody tests Jun 7 22:12:57.873 [2438] dbg: rules: running full tests; score so far=0 Jun 7 22:12:57.873 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg950_1 Jun 7 22:12:57.874 [2438] dbg: rules: run_generic_tests - compiling eval code: full, priority -950 Jun 7 22:12:57.874 [2438] dbg: rules: compiled full tests Jun 7 22:12:57.874 [2438] dbg: rules: running meta tests; score so far=0 Jun 7 22:12:57.874 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg950_1 Jun 7 22:12:57.875 [2438] dbg: rules: run_generic_tests - compiling eval code: meta, priority -950 Jun 7 22:12:57.875 [2438] dbg: rules: compiled meta tests Jun 7 22:12:57.875 [2438] dbg: check: running tests for priority: -900 Jun 7 22:12:57.876 [2438] dbg: rules: running head tests; score so far=0 Jun 7 22:12:57.876 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg900_1 Jun 7 22:12:57.876 [2438] dbg: rules: run_generic_tests - compiling eval code: head, priority -900 Jun 7 22:12:57.876 [2438] dbg: rules: compiled head tests Jun 7 22:12:57.877 [2438] dbg: rules: running head_eval tests; score so far=0 Jun 7 22:12:57.877 [2438] dbg: rules: run_eval_tests - compiling eval code: 9, priority -900 Jun 7 22:12:57.878 [2438] dbg: rules: running body tests; score so far=0 Jun 7 22:12:57.878 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg900_1 Jun 7 22:12:57.878 [2438] dbg: rules: run_generic_tests - compiling eval code: body, priority -900 Jun 7 22:12:57.879 [2438] dbg: rules: compiled body tests Jun 7 22:12:57.879 [2438] dbg: rules: running uri tests; score so far=0 Jun 7 22:12:57.879 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg900_1 Jun 7 22:12:57.879 [2438] dbg: rules: run_generic_tests - compiling eval code: uri, priority -900 Jun 7 22:12:57.880 [2438] dbg: rules: compiled uri tests Jun 7 22:12:57.880 [2438] dbg: rules: running rawbody tests; score so far=0 Jun 7 22:12:57.880 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg900_1 Jun 7 22:12:57.880 [2438] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -900 Jun 7 22:12:57.881 [2438] dbg: rules: compiled rawbody tests Jun 7 22:12:57.881 [2438] dbg: rules: running full tests; score so far=0 Jun 7 22:12:57.881 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg900_1 Jun 7 22:12:57.881 [2438] dbg: rules: run_generic_tests - compiling eval code: full, priority -900 Jun 7 22:12:57.882 [2438] dbg: rules: compiled full tests Jun 7 22:12:57.882 [2438] dbg: rules: running meta tests; score so far=0 Jun 7 22:12:57.882 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg900_1 Jun 7 22:12:57.882 [2438] dbg: rules: run_generic_tests - compiling eval code: meta, priority -900 Jun 7 22:12:57.883 [2438] dbg: rules: compiled meta tests Jun 7 22:12:57.883 [2438] dbg: check: running tests for priority: -400 Jun 7 22:12:57.883 [2438] dbg: rules: running head tests; score so far=0 Jun 7 22:12:57.883 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg400_1 Jun 7 22:12:57.884 [2438] dbg: rules: run_generic_tests - compiling eval code: head, priority -400 Jun 7 22:12:57.884 [2438] dbg: rules: compiled head tests Jun 7 22:12:57.884 [2438] dbg: rules: running body tests; score so far=0 Jun 7 22:12:57.884 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg400_1 Jun 7 22:12:57.885 [2438] dbg: rules: run_generic_tests - compiling eval code: body, priority -400 Jun 7 22:12:57.885 [2438] dbg: rules: compiled body tests Jun 7 22:12:57.885 [2438] dbg: rules: running uri tests; score so far=0 Jun 7 22:12:57.885 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg400_1 Jun 7 22:12:57.886 [2438] dbg: rules: run_generic_tests - compiling eval code: uri, priority -400 Jun 7 22:12:57.886 [2438] dbg: rules: compiled uri tests Jun 7 22:12:57.886 [2438] dbg: rules: running body_eval tests; score so far=0 Jun 7 22:12:57.886 [2438] dbg: rules: run_eval_tests - compiling eval code: 11, priority -400 Jun 7 22:12:57.887 [2438] dbg: rules: running rawbody tests; score so far=0 Jun 7 22:12:57.887 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg400_1 Jun 7 22:12:57.887 [2438] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -400 Jun 7 22:12:57.887 [2438] dbg: rules: compiled rawbody tests Jun 7 22:12:57.888 [2438] dbg: rules: running full tests; score so far=0 Jun 7 22:12:57.888 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg400_1 Jun 7 22:12:57.888 [2438] dbg: rules: run_generic_tests - compiling eval code: full, priority -400 Jun 7 22:12:57.888 [2438] dbg: rules: compiled full tests Jun 7 22:12:57.889 [2438] dbg: rules: running meta tests; score so far=0 Jun 7 22:12:57.889 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg400_1 Jun 7 22:12:57.889 [2438] dbg: rules: run_generic_tests - compiling eval code: meta, priority -400 Jun 7 22:12:57.889 [2438] dbg: rules: compiled meta tests Jun 7 22:12:57.890 [2438] dbg: check: running tests for priority: 0 Jun 7 22:12:57.890 [2438] dbg: rules: running head tests; score so far=0 Jun 7 22:12:57.925 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60372 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_1 Jun 7 22:12:57.944 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60416 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_2 Jun 7 22:12:57.962 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60205 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_3 Jun 7 22:12:57.979 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60137 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_4 Jun 7 22:12:57.996 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 55473 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_5 Jun 7 22:12:58.006 [2438] dbg: rules: run_generic_tests - compiling eval code: head, priority 0 Jun 7 22:12:58.007 [2438] dbg: rules: compiled head tests Jun 7 22:12:58.008 [2438] dbg: rules: ran header rule __MISSING_REF ======> got hit: "UNSET" Jun 7 22:12:58.009 [2438] dbg: rules: ran header rule __MSGID_OK_HOST ======> got hit: "@lint_rules>" Jun 7 22:12:58.009 [2438] dbg: rules: ran header rule __MSGID_OK_DIGITS ======> got hit: "1275941576" Jun 7 22:12:58.009 [2438] dbg: rules: ran header rule __HAS_MESSAGE_ID ======> got hit: "" Jun 7 22:12:58.011 [2438] dbg: rules: ran header rule __HAS_SUBJECT ======> got hit: "" Jun 7 22:12:58.011 [2438] dbg: rules: ran header rule __MSOE_MID_WRONG_CASE ======> got hit: " Jun 7 22:12:58.011 [2438] dbg: rules: [...] Message-Id: " Jun 7 22:12:58.011 [2438] dbg: rules: ran header rule __HAS_MSGID ======> got hit: "<" Jun 7 22:12:58.012 [2438] dbg: rules: ran header rule __SANE_MSGID ======> got hit: "<1275941576@lint_rules> Jun 7 22:12:58.012 [2438] dbg: rules: [...] " Jun 7 22:12:58.012 [2438] dbg: rules: ran header rule __TO_NO_ARROWS_R ======> got hit: "negative match" Jun 7 22:12:58.013 [2438] dbg: rules: running head_eval tests; score so far=0 Jun 7 22:12:58.015 [2438] dbg: rules: run_eval_tests - compiling eval code: 9, priority 0 Jun 7 22:12:58.024 [2438] dbg: FreeMail: RULE (FREEMAIL_FROM) check_freemail_from Jun 7 22:12:58.024 [2438] dbg: FreeMail: all from-addresses: ignore@compiling.spamassassin.taint.org Jun 7 22:12:58.025 [2438] dbg: FreeMail: RULE (__freemail_reply) check_freemail_replyto Jun 7 22:12:58.025 [2438] dbg: FreeMail: From address: ignore@compiling.spamassassin.taint.org Jun 7 22:12:58.025 [2438] dbg: FreeMail: No Reply-To and From is not freemail, skipping check Jun 7 22:12:58.026 [2438] dbg: rules: ran eval rule NO_RELAYS ======> got hit (1) Jun 7 22:12:58.027 [2438] dbg: rules: ran eval rule __GATED_THROUGH_RCVD_REMOVER ======> got hit (1) Jun 7 22:12:58.028 [2438] dbg: FreeMail: RULE (FREEMAIL_REPLYTO_END_DIGIT) check_freemail_header regex:\d@ Jun 7 22:12:58.028 [2438] dbg: FreeMail: header Reply-To not found from mail Jun 7 22:12:58.029 [2438] dbg: FreeMail: RULE (__freemail_replyto) check_freemail_replyto Jun 7 22:12:58.029 [2438] dbg: FreeMail: From address: ignore@compiling.spamassassin.taint.org Jun 7 22:12:58.029 [2438] dbg: FreeMail: Reply-To is not freemail, skipping check Jun 7 22:12:58.030 [2438] dbg: FreeMail: RULE (__freemail_hdr_replyto) check_freemail_header Jun 7 22:12:58.030 [2438] dbg: FreeMail: header Reply-To not found from mail Jun 7 22:12:58.030 [2438] dbg: spf: cannot get Envelope-From, cannot use SPF Jun 7 22:12:58.030 [2438] dbg: spf: def_spf_whitelist_from: could not find useable envelope sender Jun 7 22:12:58.030 [2438] dbg: FreeMail: RULE (FREEMAIL_ENVFROM_END_DIGIT) check_freemail_header regex:\d@ Jun 7 22:12:58.030 [2438] dbg: FreeMail: header EnvelopeFrom not found from mail Jun 7 22:12:58.031 [2438] dbg: spf: spf_whitelist_from: could not find useable envelope sender Jun 7 22:12:58.032 [2438] dbg: rules: ran eval rule MISSING_HEADERS ======> got hit (1) Jun 7 22:12:58.032 [2438] dbg: rules: running body tests; score so far=0.914 Jun 7 22:12:58.041 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60464 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_1 Jun 7 22:12:58.064 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60160 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_2 Jun 7 22:12:58.087 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60288 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_3 Jun 7 22:12:58.110 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60457 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_4 Jun 7 22:12:58.133 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60421 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_5 Jun 7 22:12:58.156 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60061 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_6 Jun 7 22:12:58.178 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60121 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_7 Jun 7 22:12:58.201 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60048 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_8 Jun 7 22:12:58.224 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60312 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_9 Jun 7 22:12:58.247 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60706 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_10 Jun 7 22:12:58.262 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 1801 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_11 Jun 7 22:12:58.263 [2438] dbg: rules: run_generic_tests - compiling eval code: body, priority 0 Jun 7 22:12:58.264 [2438] dbg: rules: compiled body tests Jun 7 22:12:58.328 [2438] dbg: rules: ran body rule __NONEMPTY_BODY ======> got hit: "I" Jun 7 22:12:58.347 [2438] dbg: rules: running uri tests; score so far=0.914 Jun 7 22:12:58.351 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 19850 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_0_1 Jun 7 22:12:58.356 [2438] dbg: rules: run_generic_tests - compiling eval code: uri, priority 0 Jun 7 22:12:58.356 [2438] dbg: rules: compiled uri tests Jun 7 22:12:58.356 [2438] dbg: rules: running body_eval tests; score so far=0.914 Jun 7 22:12:58.360 [2438] dbg: rules: run_eval_tests - compiling eval code: 11, priority 0 Jun 7 22:12:58.376 [2438] dbg: https_http_mismatch: anchors 0 Jun 7 22:12:58.382 [2438] dbg: rules: running rawbody tests; score so far=0.914 Jun 7 22:12:58.384 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 14179 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_0_1 Jun 7 22:12:58.387 [2438] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 0 Jun 7 22:12:58.388 [2438] dbg: rules: compiled rawbody tests Jun 7 22:12:58.388 [2438] dbg: rules: running rawbody_eval tests; score so far=0.914 Jun 7 22:12:58.389 [2438] dbg: rules: run_eval_tests - compiling eval code: 15, priority 0 Jun 7 22:12:58.390 [2438] dbg: rules: running full tests; score so far=0.914 Jun 7 22:12:58.390 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 670 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_0_1 Jun 7 22:12:58.391 [2438] dbg: rules: run_generic_tests - compiling eval code: full, priority 0 Jun 7 22:12:58.391 [2438] dbg: rules: compiled full tests Jun 7 22:12:58.391 [2438] dbg: rules: running full_eval tests; score so far=0.914 Jun 7 22:12:58.392 [2438] dbg: rules: run_eval_tests - compiling eval code: 13, priority 0 Jun 7 22:12:58.392 [2438] dbg: dkim: signature verification disabled, DNS resolving not available Jun 7 22:12:58.393 [2438] dbg: rules: running meta tests; score so far=0.914 Jun 7 22:12:58.393 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 275 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_0_1 Jun 7 22:12:58.393 [2438] dbg: rules: run_generic_tests - compiling eval code: meta, priority 0 Jun 7 22:12:58.393 [2438] dbg: rules: compiled meta tests Jun 7 22:12:58.394 [2438] dbg: check: running tests for priority: 500 Jun 7 22:12:58.394 [2438] dbg: dns: harvest_dnsbl_queries Jun 7 22:12:58.394 [2438] dbg: rules: running head tests; score so far=0.914 Jun 7 22:12:58.395 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 276 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_500_1 Jun 7 22:12:58.395 [2438] dbg: rules: run_generic_tests - compiling eval code: head, priority 500 Jun 7 22:12:58.395 [2438] dbg: rules: compiled head tests Jun 7 22:12:58.396 [2438] dbg: rules: running body tests; score so far=0.914 Jun 7 22:12:58.396 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 219 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_500_1 Jun 7 22:12:58.396 [2438] dbg: rules: run_generic_tests - compiling eval code: body, priority 500 Jun 7 22:12:58.396 [2438] dbg: rules: compiled body tests Jun 7 22:12:58.397 [2438] dbg: rules: running uri tests; score so far=0.914 Jun 7 22:12:58.397 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 217 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_500_1 Jun 7 22:12:58.397 [2438] dbg: rules: run_generic_tests - compiling eval code: uri, priority 500 Jun 7 22:12:58.397 [2438] dbg: rules: compiled uri tests Jun 7 22:12:58.397 [2438] dbg: rules: running rawbody tests; score so far=0.914 Jun 7 22:12:58.398 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_500_1 Jun 7 22:12:58.398 [2438] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 500 Jun 7 22:12:58.398 [2438] dbg: rules: compiled rawbody tests Jun 7 22:12:58.398 [2438] dbg: rules: running full tests; score so far=0.914 Jun 7 22:12:58.399 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 254 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_500_1 Jun 7 22:12:58.399 [2438] dbg: rules: run_generic_tests - compiling eval code: full, priority 500 Jun 7 22:12:58.399 [2438] dbg: rules: compiled full tests Jun 7 22:12:58.399 [2438] dbg: rules: running meta tests; score so far=0.914 Jun 7 22:12:58.400 [2438] dbg: rules: meta test DIGEST_MULTIPLE has undefined dependency 'DCC_CHECK' Jun 7 22:12:58.440 [2438] dbg: rules: flush_evalstr (add_evalstr) compiling 60007 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_1 Jun 7 22:12:58.455 [2438] dbg: rules: flush_evalstr (run_generic_tests) compiling 42824 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_2 Jun 7 22:12:58.464 [2438] dbg: rules: run_generic_tests - compiling eval code: meta, priority 500 Jun 7 22:12:58.464 [2438] dbg: rules: compiled meta tests Jun 7 22:12:58.467 [2438] dbg: check: is spam? score=3.652 required=5 Jun 7 22:12:58.467 [2438] dbg: check: tests=MISSING_DATE,MISSING_HEADERS,NO_RECEIVED,NO_RELAYS Jun 7 22:12:58.467 [2438] 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,__SANE_MSGID,__TO_NO_ARROWS_R,__UNUSABLE_MSGID Jun 7 22:12:58.468 [2438] dbg: timing: total 1928 ms - init: 1311 (68.0%), parse: 1.12 (0.1%), extract_message_metadata: 1.96 (0.1%), get_uri_detail_list: 1.55 (0.1%), tests_pri_-1000: 10 (0.5%), compile_gen: 226 (11.7%), compile_eval: 24 (1.3%), tests_pri_-950: 7 (0.4%), tests_pri_-900: 8 (0.4%), tests_pri_-400: 7 (0.3%), tests_pri_0: 504 (26.1%), tests_pri_500: 73 (3.8%)