[3096] dbg: logger: adding facilities: all
[3096] dbg: logger: logging level is DBG
[3096] dbg: spamd: creating INET socket:
[3096] dbg: spamd: Listen: 2147483647
[3096] dbg: spamd: LocalAddr: 127.0.0.1
[3096] dbg: spamd: LocalPort: 33976
[3096] dbg: spamd: Proto: 6
[3096] dbg: spamd: ReuseAddr: 1
[3096] dbg: spamd: Type: 1
[3096] dbg: logger: adding facilities: all
[3096] dbg: logger: logging level is DBG
[3096] dbg: generic: SpamAssassin version 3.2.0-r492202
[3096] dbg: config: score set 0 chosen.
[3096] dbg: dns: no ipv6
[3096] dbg: dns: is Net::DNS::Resolver available? yes
[3096] dbg: dns: Net::DNS version: 0.57
[3096] dbg: learn: initializing learner
[3096] dbg: config: using "/cygdrive/e/Temp/spamassassin-trunk/t/log/localrules.tmp" for site rules pre files
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/localrules.tmp/init.pre
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/localrules.tmp/svn_only.pre
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/localrules.tmp/v310.pre
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/localrules.tmp/v312.pre
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/localrules.tmp/v320.pre
[3096] dbg: config: using "/cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy" for sys rules pre files
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/init.pre
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/svn_only.pre
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/v310.pre
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/v312.pre
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/v320.pre
[3096] dbg: config: using "/cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy" for default rules dir
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/01_test_rules.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/10_default_prefs.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_advance_fee.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_body_tests.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_compensate.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_dnsbl_tests.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_drugs.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_fake_helo_tests.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_head_tests.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_html_tests.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_meta_tests.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_net_tests.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_phrases.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_porn.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_ratware.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_uri_tests.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/20_vbounce.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/23_bayes.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_accessdb.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_antivirus.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_dcc.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_dkim.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_domainkeys.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_hashcash.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_pyzor.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_razor2.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_replace.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_spf.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_textcat.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/25_uribl.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/30_text_de.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/30_text_fr.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/30_text_it.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/30_text_nl.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/30_text_pl.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/30_text_pt_br.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/50_scores.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/60_awl.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/60_shortcircuit.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/60_whitelist.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/60_whitelist_dk.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/60_whitelist_dkim.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/60_whitelist_spf.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/60_whitelist_subject.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/70_sandbox.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/72_active.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/99_test_default.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/local.cf
[3096] dbg: config: read file /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/regression_tests.cf
[3096] dbg: config: using "/cygdrive/e/Temp/spamassassin-trunk/t/log/localrules.tmp" for site rules dir
[3096] dbg: config: using "/cygdrive/c/Documents and Settings/frog357/.spamassassin/user_prefs" for user prefs file
[3096] dbg: config: read file /cygdrive/c/Documents and Settings/frog357/.spamassassin/user_prefs
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC
[3096] dbg: pyzor: local tests only, disabling Pyzor
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC
[3096] dbg: razor2: local tests only, skipping Razor
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC
[3096] dbg: reporter: local tests only, disabling SpamCop
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::AWL from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x10d67c38), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Hashcash=HASH(0x10d67c80), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::SPF=HASH(0x10d67c8c), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC
[3096] dbg: pyzor: local tests only, disabling Pyzor
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Pyzor=HASH(0x10d67e78), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC
[3096] dbg: razor2: local tests only, skipping Razor
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Razor2=HASH(0x10d67ec0), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC
[3096] dbg: reporter: local tests only, disabling SpamCop
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::SpamCop=HASH(0x10d67ecc), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::AWL from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::AWL=HASH(0x11156fe4), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::AutoLearnThreshold=HASH(0x10d67f8c), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::WhiteListSubject=HASH(0x10d67ea8), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::MIMEHeader=HASH(0x11157320), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x10d680e8), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Check=HASH(0x10d681b4), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::HTTPSMismatch=HASH(0x10d5f16c), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::URIDetail=HASH(0x10d5f148), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Bayes=HASH(0x10d5f220), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::BodyEval=HASH(0x10d5f1fc), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::DNSEval=HASH(0x10d5f208), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::HTMLEval=HASH(0x111578e4), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::HeaderEval=HASH(0x111578fc), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::MIMEEval=HASH(0x11157920), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::RelayEval=HASH(0x11157944), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::URIEval=HASH(0x11157968), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::WLBLEval=HASH(0x1115798c), already registered
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC
[3096] dbg: plugin: did not register Mail::SpamAssassin::Plugin::VBounce=HASH(0x111578c0), already registered
[3096] dbg: config: fixed relative path: /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/sandbox-hstern.pm
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::Sandbox::hstern from /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/sandbox-hstern.pm
[3096] dbg: config: fixed relative path: /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/ImageInfo.pm
[3096] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from /cygdrive/e/Temp/spamassassin-trunk/t/log/test_rules_copy/ImageInfo.pm
[3096] dbg: rules: __MO_OL_72641 merged duplicates: __MO_OL_A842E
[3096] dbg: rules: TEST_ENDSNUMS merged duplicates: __FROM_ENDS_IN_NUMS
[3096] dbg: rules: __FH_HAS_XMSMAIL merged duplicates: __HAS_MSMAIL_PRI
[3096] dbg: rules: __XM_OL_5E7ED merged duplicates: __XM_OL_60256 __XM_OL_D03AB
[3096] dbg: rules: __MO_OL_F475E merged duplicates: __MO_OL_FF5C8
[3096] dbg: rules: __XM_OL_62E6A merged duplicates: __XM_OL_CE8A4
[3096] dbg: rules: __MO_OL_9B90B merged duplicates: __MO_OL_C65FA
[3096] dbg: rules: EXCUSE_4 merged duplicates: TEST_EXCUSE_4
[3096] dbg: rules: T__FRAUD_DBI merged duplicates: __FRAUD_DBI
[3096] dbg: rules: __XM_OL_43480 merged duplicates: __XM_OL_B9B11
[3096] dbg: rules: T_STOCK_WATCH_THIS_3 merged duplicates: T_STOCK_WATCH_THIS_4
[3096] dbg: rules: __XM_OL_22B61 merged duplicates: __XM_OL_2B374 __XM_OL_A842E
[3096] dbg: rules: __MO_OL_5EDD4 merged duplicates: __MO_OL_8CB6D __MO_OL_D03AB
[3096] dbg: rules: __XM_OL_20CC2 merged duplicates: __XM_OL_5EDD4 __XM_OL_C7C33 __XM_OL_C9068 __XM_OL_EF20B __XM_OL_F8BA4
[3096] dbg: rules: __MO_OL_22B61 merged duplicates: __MO_OL_4F240 __MO_OL_ADFF7
[3096] dbg: rules: __XM_OL_8CB6D merged duplicates: __XM_OL_F1004
[3096] dbg: rules: NORMAL_HTTP_TO_IP merged duplicates: TEST_NORMAL_HTTP_TO_IP
[3096] dbg: rules: T_HS_INDEX_PARAM merged duplicates: T_HS_INDEX_PARAM_5
[3096] dbg: rules: FH_MSGID_01C67 merged duplicates: __MSGID_VGA
[3096] dbg: rules: FS_NEW_SOFT_UPLOAD merged duplicates: HS_SUBJ_NEW_SOFTWARE
[3096] 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
[3096] dbg: rules: __MO_OL_25340 merged duplicates: __MO_OL_4EEDB __MO_OL_7533E
[3096] dbg: rules: __MO_OL_62E6A merged duplicates: __MO_OL_C9068
[3096] dbg: rules: __MO_OL_4BF4C merged duplicates: __MO_OL_F6D01
[3096] dbg: rules: __MO_OL_58CB5 merged duplicates: __MO_OL_B4B40
[3096] 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
[3096] dbg: rules: FORGED_YAHOO_RCVD merged duplicates: TEST_FORGED_YAHOO_RCVD
[3096] dbg: rules: __MO_OL_07794 merged duplicates: __MO_OL_8627E __MO_OL_F3B05
[3096] dbg: rules: __MO_OL_5E7ED merged duplicates: __MO_OL_C7C33 __MO_OL_F1004
[3096] dbg: rules: __XM_OL_9B607 merged duplicates: __XM_OL_DC995
[3096] dbg: rules: __MO_OL_812FF merged duplicates: __MO_OL_BC7E6
[3096] dbg: rules: __MO_OL_91287 merged duplicates: __MO_OL_B30D1 __MO_OL_CF0C0
[3096] dbg: rules: __XM_OL_8627E merged duplicates: __XM_OL_95A76
[3096] dbg: rules: __MO_OL_015D5 merged duplicates: __MO_OL_6554A
[3096] dbg: conf: finish parsing
[3096] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x10d22658) implements 'finish_parsing_end', priority 0
[3096] dbg: replacetags: replacing tags
[3096] dbg: replacetags: done replacing tags
[3096] dbg: bayes: no dbs present, cannot tie DB R/O: ./log/user_state/bayes_toks
[3096] dbg: config: score set 0 chosen.
[3096] dbg: spamd: Preloading modules with HOME=/tmp/spamd-3096-init
[3096] dbg: plugin: Mail::SpamAssassin::Plugin::HeaderEval=HASH(0x110d0f74) implements 'compile_now_start', priority 0
[3096] dbg: eval: failed to locate the triplets.txt file
[3096] dbg: ignore: test message to precompile patterns and load modules
[3096] dbg: message: main message type: text/plain
[3096] dbg: message: ---- MIME PARSER START ----
[3096] dbg: message: parsing normal part
[3096] dbg: message: ---- MIME PARSER END ----
[3096] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x11053a54) implements 'check_start', priority 0
[3096] dbg: bayes: no dbs present, cannot tie DB R/O: ./log/user_state/bayes_toks
[3096] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x10d49b78) implements 'check_main', priority 0
[3096] dbg: metadata: X-Spam-Relays-Trusted: 
[3096] dbg: metadata: X-Spam-Relays-Untrusted: 
[3096] dbg: metadata: X-Spam-Relays-Internal: 
[3096] dbg: metadata: X-Spam-Relays-External: 
[3096] dbg: message: no encoding detected
[3096] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x10237688) implements 'parsed_metadata', priority 0
[3096] dbg: dns: is_dns_available() last checked 1168035122 seconds ago; re-checking
[3096] dbg: dns: is DNS available? 0
[3096] dbg: rules: local tests only, ignoring RBL eval
[3096] dbg: check: running tests for priority: -1000
[3096] dbg: rules: running head tests; score so far=0
[3096] dbg: eval: all '*From' addrs: ignore@compiling.spamassassin.taint.org
[3096] dbg: eval: all '*To' addrs: 
[3096] dbg: rules: running body tests; score so far=0
[3096] dbg: rules: running uri tests; score so far=0
[3096] dbg: rules: running rawbody tests; score so far=0
[3096] dbg: rules: running full tests; score so far=0
[3096] dbg: rules: running meta tests; score so far=0
[3096] dbg: check: running tests for priority: -950
[3096] dbg: rules: running head tests; score so far=0
[3096] dbg: rules: running body tests; score so far=0
[3096] dbg: rules: running uri tests; score so far=0
[3096] dbg: rules: running rawbody tests; score so far=0
[3096] dbg: rules: running full tests; score so far=0
[3096] dbg: rules: running meta tests; score so far=0
[3096] dbg: check: running tests for priority: -900
[3096] dbg: rules: running head tests; score so far=0
[3096] dbg: rules: running body tests; score so far=0
[3096] dbg: rules: running uri tests; score so far=0
[3096] dbg: rules: running rawbody tests; score so far=0
[3096] dbg: rules: running full tests; score so far=0
[3096] dbg: rules: running meta tests; score so far=0
[3096] dbg: check: running tests for priority: -400
[3096] dbg: rules: running head tests; score so far=0
[3096] dbg: rules: running body tests; score so far=0
[3096] dbg: rules: running uri tests; score so far=0
[3096] dbg: rules: running rawbody tests; score so far=0
[3096] dbg: rules: running full tests; score so far=0
[3096] dbg: rules: running meta tests; score so far=0
[3096] dbg: check: running tests for priority: 0
[3096] dbg: rules: running head tests; score so far=0
[3096] dbg: rules: ran header rule __MISSING_THREAD ======> got hit: "UNSET"
[3096] dbg: rules: ran header rule __MISSING_REF ======> got hit: "UNSET"
[3096] dbg: rules: ran header rule __MISSING_REPLY ======> got hit: "UNSET"
[3096] dbg: rules: ran header rule __MSOE_MID_WRONG_CASE ======> got hit: "
[3096] dbg: rules: Message-Id: "
[3096] dbg: rules: ran header rule MISSING_DATE ======> got hit: "UNSET"
[3096] dbg: rules: ran header rule TEST_NOREALNAME ======> got hit: "ignore@compiling.spamassassin.taint.org
[3096] dbg: rules: "
[3096] dbg: rules: ran header rule __MSGID_OK_HOST ======> got hit: "@spamassassin_spamd_init>"
[3096] dbg: rules: ran header rule __MSGID_OK_DIGITS ======> got hit: "1168035122"
[3096] dbg: rules: ran header rule __HAS_MSGID ======> got hit: "<"
[3096] dbg: rules: ran header rule __SANE_MSGID ======> got hit: "<1168035122.24568@spamassassin_spamd_init>
[3096] dbg: rules: "
[3096] dbg: spf: checking to see if the message has a Received-SPF header that we can use
[3096] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[3096] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[3096] dbg: rules: ran eval rule NO_RELAYS ======> got hit (1)
[3096] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[3096] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[3096] dbg: spf: cannot get Envelope-From, cannot use SPF
[3096] dbg: spf: def_spf_whitelist_from: could not find useable envelope sender
[3096] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[3096] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[3096] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[3096] dbg: rules: ran eval rule __UNUSABLE_MSGID ======> got hit (1)
[3096] dbg: spf: spf_whitelist_from: could not find useable envelope sender
[3096] dbg: rules: ran eval rule MISSING_HEADERS ======> got hit (1)
[3096] dbg: rules: running body tests; score so far=5.001
[3096] dbg: rules: ran body rule __NONEMPTY_BODY ======> got hit: "I"
[3096] dbg: rules: running uri tests; score so far=5.001
[3096] dbg: eval: stock info total: 0
[3096] dbg: https_http_mismatch: anchors 0
[3096] dbg: rules: running rawbody tests; score so far=5.001
[3096] dbg: rules: running full tests; score so far=5.001
[3096] dbg: rules: running meta tests; score so far=5.001
[3096] dbg: check: running tests for priority: 500
[3096] dbg: rules: running head tests; score so far=5.001
[3096] dbg: rules: running body tests; score so far=5.001
[3096] dbg: rules: running uri tests; score so far=5.001
[3096] dbg: rules: running rawbody tests; score so far=5.001
[3096] dbg: rules: running full tests; score so far=5.001
[3096] dbg: rules: running meta tests; score so far=5.001
[3096] dbg: rules: meta test DIGEST_MULTIPLE has undefined dependency 'DCC_CHECK'
[3096] dbg: rules: meta test T_FAKE_REPLY_SURE_B has undefined dependency 'FAKE_REPLY_A'
[3096] dbg: rules: meta test T_FAKE_REPLY_SURE_B has undefined dependency 'FAKE_REPLY_A2'
[3096] dbg: rules: meta test T_KAM_NUMBER has undefined dependency 'HTML_SHORT_LENGTH'
[3096] dbg: rules: meta test T_FAKE_REPLY_SURE_A has undefined dependency 'FAKE_REPLY_A'
[3096] dbg: rules: meta test T_FAKE_REPLY_SURE_A has undefined dependency 'FAKE_REPLY_A2'
[3096] dbg: check: running tests for priority: 1000
[3096] dbg: rules: running head tests; score so far=6.729
[3096] dbg: locker: safe_lock: created ./log/user_state/auto-whitelist.lock.BRR.i-is.net.3096
[3096] dbg: locker: safe_lock: trying to get lock on ./log/user_state/auto-whitelist with 0 retries
[3096] dbg: locker: safe_lock: link to ./log/user_state/auto-whitelist.lock: link ok
[3096] dbg: auto-whitelist: tie-ing to DB file of type DB_File R/W in ./log/user_state/auto-whitelist
[3096] dbg: auto-whitelist: db-based ignore@compiling.spamassassin.taint.org|ip=none scores 0/0
[3096] dbg: auto-whitelist: AWL active, pre-score: 6.729, autolearn score: 6.729, mean: undef, IP: undef
[3096] dbg: auto-whitelist: DB addr list: untie-ing and unlocking
[3096] dbg: auto-whitelist: DB addr list: file locked, breaking lock
[3096] dbg: locker: safe_unlock: unlink ./log/user_state/auto-whitelist.lock
[3096] dbg: auto-whitelist: post auto-whitelist score: 6.729
[3096] dbg: rules: running body tests; score so far=6.729
[3096] dbg: rules: running uri tests; score so far=6.729
[3096] dbg: rules: running rawbody tests; score so far=6.729
[3096] dbg: rules: running full tests; score so far=6.729
[3096] dbg: rules: running meta tests; score so far=6.729
[3096] dbg: check: is spam? score=6.729 required=5
[3096] dbg: check: tests=MISSING_DATE,MISSING_HEADERS,MISSING_SUBJECT,NO_RECEIVED,NO_RELAYS,TEST_NOREALNAME
[3096] dbg: check: subtests=__HAS_MSGID,__MISSING_REF,__MISSING_REPLY,__MISSING_THREAD,__MSGID_OK_DIGITS,__MSGID_OK_HOST,__MSOE_MID_WRONG_CASE,__NONEMPTY_BODY,__SANE_MSGID,__UNUSABLE_MSGID
[3096] dbg: config: copying current conf to backup
[3096] info: spamd: server started on port 33976/tcp (running version 3.2.0-r492202)
[3096] info: spamd: server pid: 3096
[3096] info: spamd: server successfully spawned child process, pid 556
[3096] info: spamd: server successfully spawned child process, pid 4024
[3096] info: spamd: server successfully spawned child process, pid 816
[3096] info: spamd: server successfully spawned child process, pid 3728
[3096] info: spamd: server killed by SIGINT, shutting down