Mar 23 12:38:45.496 [24152] dbg: logger: adding facilities: all
Mar 23 12:38:45.497 [24152] dbg: logger: logging level is DBG
Mar 23 12:38:45.497 [24152] dbg: generic: SpamAssassin version 3.3.0
Mar 23 12:38:45.497 [24152] dbg: generic: Perl 5.010000, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/mail/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin
Mar 23 12:38:45.497 [24152] dbg: config: timing enabled
Mar 23 12:38:45.498 [24152] dbg: config: score set 0 chosen.
Mar 23 12:38:45.501 [24152] dbg: util: running in taint mode? yes
Mar 23 12:38:45.502 [24152] dbg: util: taint mode: deleting unsafe environment variables, resetting PATH
Mar 23 12:38:45.502 [24152] dbg: util: PATH included '/usr/kerberos/sbin', keeping
Mar 23 12:38:45.502 [24152] dbg: util: PATH included '/usr/kerberos/bin', keeping
Mar 23 12:38:45.502 [24152] dbg: util: PATH included '/usr/local/bin', keeping
Mar 23 12:38:45.502 [24152] dbg: util: PATH included '/bin', keeping
Mar 23 12:38:45.503 [24152] dbg: util: PATH included '/usr/bin', keeping
Mar 23 12:38:45.503 [24152] dbg: util: PATH included '/usr/local/sbin', keeping
Mar 23 12:38:45.503 [24152] dbg: util: PATH included '/usr/sbin', keeping
Mar 23 12:38:45.503 [24152] dbg: util: PATH included '/sbin', keeping
Mar 23 12:38:45.504 [24152] dbg: util: PATH included '/home/sbuysse/bin', which is unusable, dropping: No such file or directory
Mar 23 12:38:45.504 [24152] dbg: util: final PATH set to: /usr/kerberos/sbin:/usr/kerberos/bin:/usr/local/bin:/bin:/usr/bin:/usr/local/sb- in:/usr/sbin:/sbin
Mar 23 12:38:45.513 [24152] dbg: dns: is Net::DNS::Resolver available? yes
Mar 23 12:38:45.513 [24152] dbg: dns: Net::DNS version: 0.65
Mar 23 12:38:45.518 [24152] dbg: config: using "/etc/mail/spamassassin" for site rules pre files
Mar 23 12:38:45.519 [24152] dbg: config: read file /etc/mail/spamassassin/init.pre
Mar 23 12:38:45.519 [24152] dbg: config: read file /etc/mail/spamassassin/v310.pre
Mar 23 12:38:45.519 [24152] dbg: config: read file /etc/mail/spamassassin/v312.pre
Mar 23 12:38:45.520 [24152] dbg: config: read file /etc/mail/spamassassin/v320.pre
Mar 23 12:38:45.520 [24152] dbg: config: read file /etc/mail/spamassassin/v330.pre
Mar 23 12:38:45.520 [24152] dbg: config: using "/var/lib/spamassassin/3.003000" for sys rules pre files
Mar 23 12:38:45.520 [24152] dbg: config: using "/var/lib/spamassassin/3.003000" for default rules dir
Mar 23 12:38:45.521 [24152] dbg: config: read file /var/lib/spamassassin/3.003000/sought_rules_yerp_org.cf
Mar 23 12:38:45.521 [24152] dbg: config: using "/etc/mail/spamassassin" for site rules dir
Mar 23 12:38:45.522 [24152] dbg: config: read file /etc/mail/spamassassin/70_sare_adult.cf
Mar 23 12:38:45.523 [24152] dbg: config: read file /etc/mail/spamassassin/70_sare_evilnum0.cf
Mar 23 12:38:45.523 [24152] dbg: config: read file /etc/mail/spamassassin/70_sc_top200.cf
Mar 23 12:38:45.523 [24152] dbg: config: read file /etc/mail/spamassassin/90_ FreeMail.cf
Mar 23 12:38:45.524 [24152] dbg: config: read file /etc/mail/spamassassin/90_2tld.cf
Mar 23 12:38:45.524 [24152] dbg: config: read file /etc/mail/spamassassin/90_sare_freemail.cf
Mar 23 12:38:45.524 [24152] dbg: config: read file /etc/mail/spamassassin/FreeMail.cf
Mar 23 12:38:45.524 [24152] dbg: config: read file /etc/mail/spamassassin/blacklist.cf
Mar 23 12:38:45.525 [24152] dbg: config: read file /etc/mail/spamassassin/local.cf
Mar 23 12:38:45.525 [24152] dbg: config: read file /etc/mail/spamassassin/whitelist.cf
Mar 23 12:38:45.526 [24152] dbg: config: using "/root/.spamassassin" for user state dir
Mar 23 12:38:45.526 [24152] dbg: config: using "/root/.spamassassin/user_prefs" for user prefs file
Mar 23 12:38:45.526 [24152] dbg: config: read file /root/.spamassassin/user_prefs
Mar 23 12:38:45.532 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
Mar 23 12:38:45.542 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC
Mar 23 12:38:45.559 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
Mar 23 12:38:45.568 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC
Mar 23 12:38:45.573 [24152] dbg: pyzor: network tests on, attempting Pyzor
Mar 23 12:38:45.574 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC
Mar 23 12:38:45.688 [24152] dbg: razor2: razor2 is available, version 2.84
Mar 23 12:38:45.689 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC
Mar 23 12:38:45.716 [24152] dbg: reporter: network tests on, attempting SpamCop
Mar 23 12:38:45.716 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC
Mar 23 12:38:45.719 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC
Mar 23 12:38:45.722 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC
Mar 23 12:38:45.725 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC
Mar 23 12:38:45.729 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::DKIM from @INC
Mar 23 12:38:45.740 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC
Mar 23 12:38:45.755 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC
Mar 23 12:38:45.758 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC
Mar 23 12:38:45.762 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC
Mar 23 12:38:45.783 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC
Mar 23 12:38:45.788 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC
Mar 23 12:38:45.793 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC
Mar 23 12:38:45.798 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC
Mar 23 12:38:45.813 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC
Mar 23 12:38:45.820 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC
Mar 23 12:38:45.825 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC
Mar 23 12:38:45.827 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC
Mar 23 12:38:45.832 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC
Mar 23 12:38:45.835 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC
Mar 23 12:38:45.841 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::FreeMail from @INC
Mar 23 12:38:45.848 [24152] dbg: config: fixed relative path: /var/lib/spamassassin/3.003000/sought_rules_yerp_org/20_sought.cf
Mar 23 12:38:45.848 [24152] dbg: config: using "/var/lib/spamassassin/3.003000/sought_rules_yerp_org/20_sought.cf" for included file
Mar 23 12:38:45.849 [24152] dbg: config: read file /var/lib/spamassassin/3.003000/sought_rules_yerp_org/20_sought.cf
Mar 23 12:38:45.925 [24152] dbg: config: fixed relative path: /var/lib/spamassassin/3.003000/sought_rules_yerp_org/20_sought_fraud.cf
Mar 23 12:38:45.925 [24152] dbg: config: using "/var/lib/spamassassin/3.003000/sought_rules_yerp_org/20_sought_fraud.cf" for included file
Mar 23 12:38:45.926 [24152] dbg: config: read file /var/lib/spamassassin/3.003000/sought_rules_yerp_org/20_sought_fraud.cf
Mar 23 12:38:46.134 [24152] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x9e11304) implements 'parse_config', priority 0
Mar 23 12:38:46.144 [24152] dbg: plugin: did not register Mail::SpamAssassin::Plugin::FreeMail, already registered
Mar 23 12:38:46.151 [24152] dbg: plugin: loading Mail::SpamAssassin::Plugin::DCC from @INC
Mar 23 12:38:46.165 [24152] dbg: dcc: network tests on, registering DCC
Mar 23 12:38:46.393 [24152] dbg: rules: __SEEK_FRAUD_YM7Q1U merged duplicates: __SEEK_YM7Q1U
Mar 23 12:38:46.394 [24152] dbg: rules: SARE_SUBJ_SLUT merged duplicates: __FPS_SLUT
Mar 23 12:38:46.394 [24152] dbg: rules: __SEEK_2811F9 merged duplicates: __SEEK_FRAUD_2811F9
Mar 23 12:38:46.394 [24152] dbg: rules: __SEEK_FRAUD_HMVYSR merged duplicates: __SEEK_HMVYSR
Mar 23 12:38:46.394 [24152] dbg: rules: __SEEK_FRAUD_NN_XIN merged duplicates: __SEEK_NN_XIN
Mar 23 12:38:46.395 [24152] dbg: config: finish parsing
Mar 23 12:38:46.409 [24152] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x9b4b1ac) implements 'finish_parsing_end', priority 0
Mar 23 12:38:46.409 [24152] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x9e11304) implements 'finish_parsing_end', priority 0
Mar 23 12:38:46.409 [24152] dbg: replacetags: replacing tags
Mar 23 12:38:46.415 [24152] dbg: replacetags: done replacing tags
Mar 23 12:38:46.416 [24152] dbg: FreeMail: loaded freemail_domains entries: 23 normal, 1 wildcard
Mar 23 12:38:46.420 [24152] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0x9c23ef4) implements 'learner_new', priority 0
Mar 23 12:38:46.420 [24152] dbg: bayes: learner_new self=Mail::SpamAssassin::Plugin::Bayes=HASH(0x9c23ef4), bayes_store_module=Mail::SpamAssassin::BayesStore::DBM
Mar 23 12:38:46.448 [24152] dbg: bayes: learner_new: got store=Mail::SpamAssassin::BayesStore::DBM=HASH(0x97642a4)
Mar 23 12:38:46.449 [24152] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0x9c23ef4) implements 'learner_is_scan_available', priority 0
Mar 23 12:38:46.449 [24152] dbg: config: using "/root/.spamassassin" for user state dir
Mar 23 12:38:46.449 [24152] dbg: bayes: tie-ing to DB file R/O /root/.spamassassin/bayes_toks
Mar 23 12:38:46.451 [24152] dbg: bayes: tie-ing to DB file R/O /root/.spamassassin/bayes_seen
Mar 23 12:38:46.453 [24152] dbg: bayes: found bayes db version 3
Mar 23 12:38:46.454 [24152] dbg: bayes: DB journal sync: last sync: 0
Mar 23 12:38:46.454 [24152] dbg: config: using "/root/.spamassassin" for user state dir
Mar 23 12:38:46.454 [24152] dbg: bayes: not available for scanning, only 0 spam(s) in bayes DB < 200
Mar 23 12:38:46.455 [24152] dbg: bayes: untie-ing
Mar 23 12:38:46.455 [24152] dbg: config: score set 1 chosen.
Mar 23 12:38:46.460 [24152] dbg: message: main message type: multipart/alternative
Mar 23 12:38:46.460 [24152] dbg: config: time limit 300.0 s
Mar 23 12:38:46.461 [24152] dbg: check: pms new, time limit in 299.057 s
Mar 23 12:38:46.461 [24152] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x9ccead4) implements 'check_start', priority 0
Mar 23 12:38:46.463 [24152] dbg: bayes: tie-ing to DB file R/O /root/.spamassassin/bayes_toks
Mar 23 12:38:46.464 [24152] dbg: bayes: tie-ing to DB file R/O /root/.spamassassin/bayes_seen
Mar 23 12:38:46.466 [24152] dbg: bayes: found bayes db version 3
Mar 23 12:38:46.467 [24152] dbg: bayes: DB journal sync: last sync: 0
Mar 23 12:38:46.467 [24152] dbg: bayes: not available for scanning, only 0 spam(s) in bayes DB < 200
Mar 23 12:38:46.467 [24152] dbg: bayes: untie-ing
Mar 23 12:38:46.468 [24152] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x9bea784) implements 'check_main', priority 0
Mar 23 12:38:46.468 [24152] dbg: config: internal_networks not configured, using trusted_networks configuration for internal_networks; if you really want internal_networks to only contain the required 127/8 add 'internal_networks !0/0' to your configuration
Mar 23 12:38:46.469 [24152] dbg: received-header: parsed as [ ip=115.118.196.190 rdns= helo=arb1 by=firewall.akretio.be ident= envfrom= intl=0 id=E8387E0FFB auth= msa=0 ]
Mar 23 12:38:46.470 [24152] dbg: received-header: relay 115.118.196.190 trusted? no internal? no msa? no
Mar 23 12:38:46.471 [24152] dbg: metadata: X-Spam-Relays-Trusted:
Mar 23 12:38:46.471 [24152] dbg: metadata: X-Spam-Relays-Untrusted: [ ip=115.118.196.190 rdns= helo=arb1 by=firewall.akretio.be ident= envfrom= intl=0 id=E8387E0FFB auth= msa=0 ]
Mar 23 12:38:46.471 [24152] dbg: metadata: X-Spam-Relays-Internal:
Mar 23 12:38:46.472 [24152] dbg: metadata: X-Spam-Relays-External: [ ip=115.118.196.190 rdns= helo=arb1 by=firewall.akretio.be ident= envfrom= intl=0 id=E8387E0FFB auth= msa=0 ]
Mar 23 12:38:46.472 [24152] dbg: message: ---- MIME PARSER START ----
Mar 23 12:38:46.472 [24152] dbg: message: parsing multipart, got boundary: ----=_NextPart_000_002D_01CACAA4.978D0810
Mar 23 12:38:46.473 [24152] dbg: message: found part of type text/plain, boundary: ----=_NextPart_000_002D_01CACAA4.978D0810
Mar 23 12:38:46.473 [24152] dbg: message: added part, type: text/plain
Mar 23 12:38:46.475 [24152] dbg: message: found part of type text/html, boundary: ----=_NextPart_000_002D_01CACAA4.978D0810
Mar 23 12:38:46.475 [24152] dbg: message: added part, type: text/html
Mar 23 12:38:46.476 [24152] dbg: message: parsing normal part
Mar 23 12:38:46.476 [24152] dbg: message: parsing normal part
Mar 23 12:38:46.476 [24152] dbg: message: ---- MIME PARSER END ----
Mar 23 12:38:46.476 [24152] dbg: message: decoding other encoding type (7bit), ignoring
Mar 23 12:38:46.477 [24152] dbg: message: decoding quoted-printable
Mar 23 12:38:46.481 [24152] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x973dc4c) implements 'parsed_metadata', priority 0
Mar 23 12:38:46.481 [24152] dbg: dns: is_dns_available() last checked 1269344326.5 seconds ago; re-checking
Mar 23 12:38:46.483 [24152] dbg: dns: is Net::DNS::Resolver available? yes
Mar 23 12:38:46.483 [24152] dbg: dns: Net::DNS version: 0.65
Mar 23 12:38:46.484 [24152] dbg: dns: name server: 212.71.8.10, LocalAddr: 0.0.0.0
Mar 23 12:38:46.485 [24152] dbg: dns: resolver socket rx buffer size is 112640 bytes
Mar 23 12:38:46.485 [24152] dbg: dns: testing resolver nameservers: 212.71.8.10
Mar 23 12:38:46.485 [24152] dbg: dns: trying (3) w3.org...
Mar 23 12:38:46.485 [24152] dbg: dns: looking up NS for 'w3.org'
Mar 23 12:38:46.486 [24152] dbg: dns: providing a callback for id: 20662/w3.org/NS/IN
Mar 23 12:38:46.491 [24152] dbg: dns: NS lookup of w3.org using 212.71.8.10 succeeded => DNS available (set dns_available to override)
Mar 23 12:38:46.491 [24152] dbg: dns: name server: 212.71.8.10, LocalAddr: 0.0.0.0
Mar 23 12:38:46.492 [24152] dbg: dns: resolver socket rx buffer size is 112640 bytes
Mar 23 12:38:46.492 [24152] dbg: dns: NS list: 212.71.8.10
Mar 23 12:38:46.492 [24152] dbg: dns: name server: 212.71.8.10, LocalAddr: 0.0.0.0
Mar 23 12:38:46.493 [24152] dbg: dns: resolver socket rx buffer size is 112640 bytes
Mar 23 12:38:46.493 [24152] dbg: dns: is DNS available? 1
Mar 23 12:38:46.495 [24152] dbg: uridnsbl: domains to query: differhelp.com
Mar 23 12:38:46.496 [24152] dbg: check: check_main, time limit in 299.022 s
Mar 23 12:38:46.496 [24152] dbg: check: running tests for priority: 0
Mar 23 12:38:46.497 [24152] dbg: rules: running head tests; score so far=0
Mar 23 12:38:46.508 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 37857 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_1
Mar 23 12:38:46.520 [24152] dbg: rules: run_generic_tests - compiling eval code: head, priority 0
Mar 23 12:38:46.521 [24152] dbg: rules: compiled head tests
Mar 23 12:38:46.522 [24152] dbg: rules: running head_eval tests; score so far=0
Mar 23 12:38:46.522 [24152] dbg: rules: run_eval_tests - compiling eval code: 9, priority 0
Mar 23 12:38:46.524 [24152] dbg: FreeMail: RULE (FREEMAIL_REPLYTO) check_freemail_replyto
Mar 23 12:38:46.525 [24152] dbg: FreeMail: From address: bryanxf@worldatlas.com
Mar 23 12:38:46.525 [24152] dbg: FreeMail: Reply-To is not freemail, skipping check
Mar 23 12:38:46.525 [24152] dbg: FreeMail: RULE (FREEMAIL_FROM) check_freemail_from
Mar 23 12:38:46.526 [24152] dbg: eval: all '*From' addrs: bryanxF@worldatlas.com
Mar 23 12:38:46.526 [24152] dbg: FreeMail: all from-addresses: bryanxf@worldatlas.com
Mar 23 12:38:46.526 [24152] dbg: rules: running body tests; score so far=0
Mar 23 12:38:46.538 [24152] dbg: rules: flush_evalstr (add_evalstr) compiling 60166 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_1
Mar 23 12:38:46.568 [24152] dbg: rules: flush_evalstr (add_evalstr) compiling 60608 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_2
Mar 23 12:38:46.597 [24152] dbg: rules: flush_evalstr (add_evalstr) compiling 60136 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_3
Mar 23 12:38:46.627 [24152] dbg: rules: flush_evalstr (add_evalstr) compiling 60293 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_4
Mar 23 12:38:46.657 [24152] dbg: rules: flush_evalstr (add_evalstr) compiling 60213 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_5
Mar 23 12:38:46.686 [24152] dbg: rules: flush_evalstr (add_evalstr) compiling 60178 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_6
Mar 23 12:38:46.716 [24152] dbg: rules: flush_evalstr (add_evalstr) compiling 60142 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_7
Mar 23 12:38:46.746 [24152] dbg: rules: flush_evalstr (add_evalstr) compiling 60387 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_8
Mar 23 12:38:46.776 [24152] dbg: rules: flush_evalstr (add_evalstr) compiling 60135 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_9
Mar 23 12:38:46.805 [24152] dbg: rules: flush_evalstr (add_evalstr) compiling 60395 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_10
Mar 23 12:38:46.828 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 25322 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_11
Mar 23 12:38:46.837 [24152] dbg: rules: run_generic_tests - compiling eval code: body, priority 0
Mar 23 12:38:46.838 [24152] dbg: rules: compiled body tests
Mar 23 12:38:46.845 [24152] dbg: rules: running uri tests; score so far=0
Mar 23 12:38:46.845 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 213 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_0_1
Mar 23 12:38:46.846 [24152] dbg: rules: run_generic_tests - compiling eval code: uri, priority 0
Mar 23 12:38:46.846 [24152] dbg: rules: compiled uri tests
Mar 23 12:38:46.847 [24152] dbg: rules: running rawbody tests; score so far=0
Mar 23 12:38:46.847 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_0_1
Mar 23 12:38:46.848 [24152] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 0
Mar 23 12:38:46.848 [24152] dbg: rules: compiled rawbody tests
Mar 23 12:38:46.848 [24152] dbg: rules: running full tests; score so far=0
Mar 23 12:38:46.849 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 250 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_0_1
Mar 23 12:38:46.849 [24152] dbg: rules: run_generic_tests - compiling eval code: full, priority 0
Mar 23 12:38:46.850 [24152] dbg: rules: compiled full tests
Mar 23 12:38:46.850 [24152] dbg: rules: running meta tests; score so far=0
Mar 23 12:38:46.850 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 275 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_0_1
Mar 23 12:38:46.851 [24152] dbg: rules: run_generic_tests - compiling eval code: meta, priority 0
Mar 23 12:38:46.851 [24152] dbg: rules: compiled meta tests
Mar 23 12:38:46.852 [24152] dbg: check: running tests for priority: 500
Mar 23 12:38:46.852 [24152] dbg: dns: harvest_dnsbl_queries
Mar 23 12:38:46.853 [24152] dbg: rules: running head tests; score so far=0
Mar 23 12:38:46.853 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 276 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_500_1
Mar 23 12:38:46.854 [24152] dbg: rules: run_generic_tests - compiling eval code: head, priority 500
Mar 23 12:38:46.854 [24152] dbg: rules: compiled head tests
Mar 23 12:38:46.855 [24152] dbg: rules: running body tests; score so far=0
Mar 23 12:38:46.855 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 219 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_500_1
Mar 23 12:38:46.856 [24152] dbg: rules: run_generic_tests - compiling eval code: body, priority 500
Mar 23 12:38:46.856 [24152] dbg: rules: compiled body tests
Mar 23 12:38:46.856 [24152] dbg: rules: running uri tests; score so far=0
Mar 23 12:38:46.857 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 217 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_500_1
Mar 23 12:38:46.857 [24152] dbg: rules: run_generic_tests - compiling eval code: uri, priority 500
Mar 23 12:38:46.857 [24152] dbg: rules: compiled uri tests
Mar 23 12:38:46.858 [24152] dbg: rules: running rawbody tests; score so far=0
Mar 23 12:38:46.858 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_500_1
Mar 23 12:38:46.859 [24152] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 500
Mar 23 12:38:46.859 [24152] dbg: rules: compiled rawbody tests
Mar 23 12:38:46.860 [24152] dbg: rules: running full tests; score so far=0
Mar 23 12:38:46.860 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 254 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_500_1
Mar 23 12:38:46.860 [24152] dbg: rules: run_generic_tests - compiling eval code: full, priority 500
Mar 23 12:38:46.861 [24152] dbg: rules: compiled full tests
Mar 23 12:38:46.861 [24152] dbg: rules: running meta tests; score so far=0
Mar 23 12:38:46.890 [24152] dbg: rules: flush_evalstr (run_generic_tests) compiling 41172 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_1
Mar 23 12:38:46.904 [24152] dbg: rules: run_generic_tests - compiling eval code: meta, priority 500
Mar 23 12:38:46.907 [24152] dbg: rules: compiled meta tests
Mar 23 12:38:46.908 [24152] dbg: check: is spam? score=0 required=5
Mar 23 12:38:46.910 [24152] dbg: check: tests=
Mar 23 12:38:46.910 [24152] dbg: check: subtests=
Mar 23 12:38:46.911 [24152] dbg: timing: total 1390 ms - init: 937 (67.4%), parse: 5 (0.4%), extract_message_metadata: 27 (2.0%), poll_dns_idle: 1.73 (0.1%), get_uri_detail_list: 1.12 (0.1%), tests_pri_0: 356 (25.6%), compile_gen: 217 (15.6%), compile_eval: 0.58 (0.0%), tests_pri_500: 56 (4.0%)
Mar 23 12:38:46.927 [24152] dbg: plugin: Mail::SpamAssassin::Plugin::MIMEHeader=HASH(0x9b4191c) implements 'finish_tests', priority 0
Mar 23 12:38:46.927 [24152] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x9bea784) implements 'finish_tests', priority 0