Nov 9 22:27:02.935 [4818] dbg: logger: adding facilities: all Nov 9 22:27:02.935 [4818] dbg: logger: logging level is DBG Nov 9 22:27:02.935 [4818] dbg: generic: SpamAssassin version 3.3.1 Nov 9 22:27:02.935 [4818] dbg: generic: Perl 5.010001, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin Nov 9 22:27:02.935 [4818] dbg: config: timing enabled Nov 9 22:27:02.936 [4818] dbg: config: score set 0 chosen. Nov 9 22:27:02.936 [4818] dbg: util: running in taint mode? yes Nov 9 22:27:02.936 [4818] dbg: util: taint mode: deleting unsafe environment variables, resetting PATH Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/home/ichudov/bin', keeping Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/usr/local/sbin', keeping Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/usr/local/bin', keeping Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/usr/sbin', keeping Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/usr/bin', keeping Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/sbin', keeping Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/bin', keeping Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/usr/games', keeping Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/home/ichudov/bin', keeping Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/home/ichudov/net-ebay', keeping Nov 9 22:27:02.937 [4818] dbg: util: PATH included '/home/ichudov/configs/bling', keeping Nov 9 22:27:02.937 [4818] dbg: util: final PATH set to: /home/ichudov/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/home/ichudov/bin:/home/ichudov/net-ebay:/home/ichudov/configs/bling Nov 9 22:27:02.939 [4818] dbg: dns: is Net::DNS::Resolver available? yes Nov 9 22:27:02.939 [4818] dbg: dns: Net::DNS version: 0.65 Nov 9 22:27:02.940 [4818] dbg: config: using "/etc/spamassassin" for site rules pre files Nov 9 22:27:02.940 [4818] dbg: config: read file /etc/spamassassin/init.pre Nov 9 22:27:02.940 [4818] dbg: config: read file /etc/spamassassin/v310.pre Nov 9 22:27:02.940 [4818] dbg: config: read file /etc/spamassassin/v312.pre Nov 9 22:27:02.940 [4818] dbg: config: read file /etc/spamassassin/v320.pre Nov 9 22:27:02.940 [4818] dbg: config: read file /etc/spamassassin/v330.pre Nov 9 22:27:02.940 [4818] dbg: config: using "/var/lib/spamassassin/3.003001" for sys rules pre files Nov 9 22:27:02.940 [4818] dbg: config: using "/var/lib/spamassassin/3.003001" for default rules dir Nov 9 22:27:02.940 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org.cf Nov 9 22:27:02.940 [4818] dbg: config: using "/etc/spamassassin" for site rules dir Nov 9 22:27:02.940 [4818] dbg: config: read file /etc/spamassassin/65_debian.cf Nov 9 22:27:02.940 [4818] dbg: config: read file /etc/spamassassin/70_sare_evilnum0.cf Nov 9 22:27:02.940 [4818] dbg: config: read file /etc/spamassassin/70_sare_random.cf Nov 9 22:27:02.940 [4818] dbg: config: read file /etc/spamassassin/FuzzyOcr.cf Nov 9 22:27:02.940 [4818] dbg: config: read file /etc/spamassassin/local.cf Nov 9 22:27:02.941 [4818] dbg: config: read file /etc/spamassassin/tripwire.cf Nov 9 22:27:02.941 [4818] dbg: config: using "/home/ichudov/.spamassassin" for user state dir Nov 9 22:27:02.941 [4818] dbg: config: using "/home/ichudov/.spamassassin/user_prefs" for user prefs file Nov 9 22:27:02.941 [4818] dbg: config: read file /home/ichudov/.spamassassin/user_prefs Nov 9 22:27:02.942 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC Nov 9 22:27:02.945 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC Nov 9 22:27:02.948 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC Nov 9 22:27:02.950 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC Nov 9 22:27:02.951 [4818] dbg: pyzor: network tests on, attempting Pyzor Nov 9 22:27:02.951 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC Nov 9 22:27:02.974 [4818] dbg: razor2: razor2 is available, version 2.84 Nov 9 22:27:02.974 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC Nov 9 22:27:02.979 [4818] dbg: reporter: network tests on, attempting SpamCop Nov 9 22:27:02.979 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC Nov 9 22:27:02.980 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC Nov 9 22:27:02.980 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC Nov 9 22:27:02.981 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC Nov 9 22:27:02.981 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::DKIM from @INC Nov 9 22:27:02.984 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC Nov 9 22:27:02.987 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC Nov 9 22:27:02.988 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC Nov 9 22:27:02.988 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC Nov 9 22:27:02.992 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC Nov 9 22:27:02.993 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC Nov 9 22:27:02.994 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC Nov 9 22:27:02.995 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC Nov 9 22:27:02.998 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC Nov 9 22:27:02.999 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC Nov 9 22:27:03.000 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC Nov 9 22:27:03.001 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC Nov 9 22:27:03.002 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC Nov 9 22:27:03.002 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC Nov 9 22:27:03.003 [4818] dbg: plugin: loading Mail::SpamAssassin::Plugin::FreeMail from @INC Nov 9 22:27:03.005 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf Nov 9 22:27:03.005 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf" for included file Nov 9 22:27:03.005 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf Nov 9 22:27:03.006 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_hasbase.cf Nov 9 22:27:03.006 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/10_hasbase.cf" for included file Nov 9 22:27:03.006 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_hasbase.cf Nov 9 22:27:03.007 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf Nov 9 22:27:03.008 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf" for included file Nov 9 22:27:03.008 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf Nov 9 22:27:03.009 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf Nov 9 22:27:03.009 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf" for included file Nov 9 22:27:03.010 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf Nov 9 22:27:03.013 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf Nov 9 22:27:03.013 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf" for included file Nov 9 22:27:03.013 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf Nov 9 22:27:03.015 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf Nov 9 22:27:03.015 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf" for included file Nov 9 22:27:03.015 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf Nov 9 22:27:03.015 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf Nov 9 22:27:03.015 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf" for included file Nov 9 22:27:03.015 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf Nov 9 22:27:03.018 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf Nov 9 22:27:03.018 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf" for included file Nov 9 22:27:03.018 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf Nov 9 22:27:03.023 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf Nov 9 22:27:03.023 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf" for included file Nov 9 22:27:03.023 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf Nov 9 22:27:03.026 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_fake_helo_tests.cf Nov 9 22:27:03.026 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_fake_helo_tests.cf" for included file Nov 9 22:27:03.026 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_fake_helo_tests.cf Nov 9 22:27:03.027 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf Nov 9 22:27:03.027 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf" for included file Nov 9 22:27:03.027 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf Nov 9 22:27:03.028 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_domains.cf Nov 9 22:27:03.028 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_domains.cf" for included file Nov 9 22:27:03.028 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_domains.cf Nov 9 22:27:03.029 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x94a7288) implements 'parse_config', priority 0 Nov 9 22:27:03.038 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf Nov 9 22:27:03.038 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf" for included file Nov 9 22:27:03.038 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf Nov 9 22:27:03.048 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf Nov 9 22:27:03.048 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf" for included file Nov 9 22:27:03.048 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf Nov 9 22:27:03.052 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf Nov 9 22:27:03.052 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf" for included file Nov 9 22:27:03.052 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf Nov 9 22:27:03.053 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_mailspike.cf Nov 9 22:27:03.053 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_mailspike.cf" for included file Nov 9 22:27:03.053 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_mailspike.cf Nov 9 22:27:03.054 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf Nov 9 22:27:03.054 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf" for included file Nov 9 22:27:03.054 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf Nov 9 22:27:03.055 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf Nov 9 22:27:03.055 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf" for included file Nov 9 22:27:03.055 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf Nov 9 22:27:03.056 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf Nov 9 22:27:03.056 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf" for included file Nov 9 22:27:03.056 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf Nov 9 22:27:03.059 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf Nov 9 22:27:03.059 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf" for included file Nov 9 22:27:03.059 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf Nov 9 22:27:03.060 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf Nov 9 22:27:03.060 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf" for included file Nov 9 22:27:03.060 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf Nov 9 22:27:03.066 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf Nov 9 22:27:03.066 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf" for included file Nov 9 22:27:03.066 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf Nov 9 22:27:03.068 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf Nov 9 22:27:03.068 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf" for included file Nov 9 22:27:03.068 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf Nov 9 22:27:03.074 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf Nov 9 22:27:03.074 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf" for included file Nov 9 22:27:03.074 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf Nov 9 22:27:03.075 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf Nov 9 22:27:03.075 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf" for included file Nov 9 22:27:03.075 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf Nov 9 22:27:03.075 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf Nov 9 22:27:03.075 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf" for included file Nov 9 22:27:03.075 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf Nov 9 22:27:03.076 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf Nov 9 22:27:03.076 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf" for included file Nov 9 22:27:03.076 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf Nov 9 22:27:03.076 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf Nov 9 22:27:03.076 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf" for included file Nov 9 22:27:03.076 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf Nov 9 22:27:03.076 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf Nov 9 22:27:03.076 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf" for included file Nov 9 22:27:03.076 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf Nov 9 22:27:03.078 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf Nov 9 22:27:03.078 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf" for included file Nov 9 22:27:03.078 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf Nov 9 22:27:03.079 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf Nov 9 22:27:03.079 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf" for included file Nov 9 22:27:03.079 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf Nov 9 22:27:03.079 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf Nov 9 22:27:03.079 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf" for included file Nov 9 22:27:03.079 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf Nov 9 22:27:03.080 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf Nov 9 22:27:03.080 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf" for included file Nov 9 22:27:03.080 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf Nov 9 22:27:03.083 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf Nov 9 22:27:03.083 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf" for included file Nov 9 22:27:03.083 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf Nov 9 22:27:03.084 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf Nov 9 22:27:03.084 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf" for included file Nov 9 22:27:03.084 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf Nov 9 22:27:03.084 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf Nov 9 22:27:03.084 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf" for included file Nov 9 22:27:03.084 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf Nov 9 22:27:03.087 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf Nov 9 22:27:03.087 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf" for included file Nov 9 22:27:03.087 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf Nov 9 22:27:03.089 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf Nov 9 22:27:03.089 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf" for included file Nov 9 22:27:03.089 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf Nov 9 22:27:03.091 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf Nov 9 22:27:03.091 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf" for included file Nov 9 22:27:03.091 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf Nov 9 22:27:03.091 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf Nov 9 22:27:03.091 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf" for included file Nov 9 22:27:03.091 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf Nov 9 22:27:03.092 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf Nov 9 22:27:03.092 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf" for included file Nov 9 22:27:03.092 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf Nov 9 22:27:03.094 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf Nov 9 22:27:03.094 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf" for included file Nov 9 22:27:03.094 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf Nov 9 22:27:03.097 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf Nov 9 22:27:03.097 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf" for included file Nov 9 22:27:03.097 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf Nov 9 22:27:03.115 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_adsp_override_dkim.cf Nov 9 22:27:03.116 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_adsp_override_dkim.cf" for included file Nov 9 22:27:03.116 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_adsp_override_dkim.cf Nov 9 22:27:03.117 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf Nov 9 22:27:03.117 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf" for included file Nov 9 22:27:03.117 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf Nov 9 22:27:03.117 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf Nov 9 22:27:03.117 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf" for included file Nov 9 22:27:03.118 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf Nov 9 22:27:03.118 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf Nov 9 22:27:03.118 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf" for included file Nov 9 22:27:03.118 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf Nov 9 22:27:03.119 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf Nov 9 22:27:03.119 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf" for included file Nov 9 22:27:03.119 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf Nov 9 22:27:03.121 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf Nov 9 22:27:03.121 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf" for included file Nov 9 22:27:03.121 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf Nov 9 22:27:03.122 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_subject.cf Nov 9 22:27:03.122 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_subject.cf" for included file Nov 9 22:27:03.122 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_subject.cf Nov 9 22:27:03.123 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf Nov 9 22:27:03.123 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf" for included file Nov 9 22:27:03.123 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf Nov 9 22:27:03.188 [4818] dbg: config: uri_detail adding (text =~ /(?-xism:(?i)\b(?:cli(?:quez\W|ck\Wa)ici\b|cli(?:cca\W|c\Wa|que\Wa)qu[^<.,a ]|klie?k(?:\Whi?er|ni(?:j|nite)\Wtu[tk]aj)\b))/) to T_KHOP_FOREIGN_CLICK Nov 9 22:27:03.188 [4818] dbg: config: uri_detail added T_KHOP_FOREIGN_CLICK Nov 9 22:27:03.189 [4818] dbg: config: uri_detail adding (raw !~ /(?-xism:canadapost\.)/) to T_AJB_CANPOST_BADLINK Nov 9 22:27:03.189 [4818] dbg: config: uri_detail adding (text =~ /(?-xism:(?:https?:\/\/|www\.)canadapost\.)/) to T_AJB_CANPOST_BADLINK Nov 9 22:27:03.190 [4818] dbg: config: uri_detail adding (type =~ /(?-xism:^a$)/) to T_AJB_CANPOST_BADLINK Nov 9 22:27:03.190 [4818] dbg: config: uri_detail added T_AJB_CANPOST_BADLINK Nov 9 22:27:03.190 [4818] dbg: config: uri_detail adding (raw !~ /(?-xism:youtube\.)/) to T_AJB_UTUBE_BADLINK Nov 9 22:27:03.190 [4818] dbg: config: uri_detail adding (text =~ /(?-xism:(?:https?:\/\/|www\.)youtube\.)/) to T_AJB_UTUBE_BADLINK Nov 9 22:27:03.190 [4818] dbg: config: uri_detail adding (type =~ /(?-xism:^a$)/) to T_AJB_UTUBE_BADLINK Nov 9 22:27:03.190 [4818] dbg: config: uri_detail added T_AJB_UTUBE_BADLINK Nov 9 22:27:03.223 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf Nov 9 22:27:03.223 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf" for included file Nov 9 22:27:03.223 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf Nov 9 22:27:03.227 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/73_sandbox_manual_scores.cf Nov 9 22:27:03.227 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/73_sandbox_manual_scores.cf" for included file Nov 9 22:27:03.227 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/73_sandbox_manual_scores.cf Nov 9 22:27:03.227 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf Nov 9 22:27:03.227 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf" for included file Nov 9 22:27:03.227 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf Nov 9 22:27:03.227 [4818] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf Nov 9 22:27:03.227 [4818] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf" for included file Nov 9 22:27:03.227 [4818] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf Nov 9 22:27:03.246 [4818] dbg: plugin: loading FuzzyOcr from @INC Nov 9 22:27:03.295 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x94a7288) implements 'parse_config', priority 0 Nov 9 22:27:03.295 [4818] dbg: plugin: FuzzyOcr=HASH(0x9ad03d0) implements 'parse_config', priority 0 Nov 9 22:27:03.295 [4818] dbg: FuzzyOcr: focr_bin_helper: 'pnmnorm,pnminvert,ppmtopgm' Nov 9 22:27:03.295 [4818] info: FuzzyOcr: Adding <3> new helper apps Nov 9 22:27:03.295 [4818] dbg: FuzzyOcr: focr_bin_helper: 'tesseract' Nov 9 22:27:03.295 [4818] info: FuzzyOcr: Adding <1> new helper apps Nov 9 22:27:03.295 [4818] info: FuzzyOcr: Starting preprocessor parser for file "/etc/mail/spamassassin/FuzzyOcr.preps"... Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: preprocessor normalize { Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: command = pnmnorm Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: } Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: preprocessor invert { Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: command = pnminvert Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: } Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: preprocessor ppmtopgm { Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: command = ppmtopgm Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: } Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: preprocessor maketiff { Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: command = pnmtotiff Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: args = -color -truecolor Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line: } Nov 9 22:27:03.296 [4818] info: FuzzyOcr: Starting scanset parser for file "/etc/mail/spamassassin/FuzzyOcr.scansets"... Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line scanset ocrad { Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line command = $ocrad Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line args = -s5 $input Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line } Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line scanset ocrad-invert { Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line command = $ocrad Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line args = -s5 -i $input Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line } Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line scanset ocrad-decolorize-invert { Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line preprocessors = ppmtopgm Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line command = $ocrad Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line args = -s5 -i $input Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line } Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line scanset ocrad-decolorize { Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line preprocessors = ppmtopgm Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line command = $ocrad Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line args = -s5 $input Nov 9 22:27:03.296 [4818] dbg: FuzzyOcr: line } Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line scanset gocr { Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line command = $gocr Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line args = -i $input Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line } Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line scanset gocr-180 { Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line command = $gocr Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line args = -l 180 -d 2 -i $input Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line } Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line scanset tesseract { Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line preprocessors = maketiff Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line command = $tesseract Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line args = $input $output Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line force_output_in = $output.txt Nov 9 22:27:03.297 [4818] dbg: FuzzyOcr: line } Nov 9 22:27:03.297 [4818] dbg: config: allowing user rules! Nov 9 22:27:03.358 [4818] warn: netset: cannot include 127.0.0.1/32 as it has already been included Nov 9 22:27:03.358 [4818] info: config: failed to parse line, skipping, in "/home/ichudov/.spamassassin/user_prefs": use_auto_whitelist 0 Nov 9 22:27:03.669 [4818] dbg: config: finish parsing Nov 9 22:27:03.680 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x9237de0) implements 'finish_parsing_end', priority 0 Nov 9 22:27:03.680 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x94a7288) implements 'finish_parsing_end', priority 0 Nov 9 22:27:03.680 [4818] dbg: plugin: FuzzyOcr=HASH(0x9ad03d0) implements 'finish_parsing_end', priority 0 Nov 9 22:27:03.680 [4818] dbg: replacetags: replacing tags Nov 9 22:27:03.693 [4818] dbg: replacetags: done replacing tags Nov 9 22:27:03.693 [4818] dbg: FreeMail: loaded freemail_domains entries: 2112 normal, 26 wildcard Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Searching in: /usr/local/netpbm/bin Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Searching in: /usr/local/bin Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Searching in: /usr/bin Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using gifsicle => /usr/bin/gifsicle Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using giffix => /usr/bin/giffix Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using giftext => /usr/bin/giftext Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using gifinter => /usr/bin/gifinter Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using giftopnm => /usr/bin/giftopnm Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using jpegtopnm => /usr/bin/jpegtopnm Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using pngtopnm => /usr/bin/pngtopnm Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using bmptopnm => /usr/bin/bmptopnm Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using tifftopnm => /usr/bin/tifftopnm Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using ppmhist => /usr/bin/ppmhist Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using pamfile => /usr/bin/pamfile Nov 9 22:27:03.693 [4818] dbg: FuzzyOcr: Cannot find executable for ocrad Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using gocr => /usr/bin/gocr Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using pnmnorm => /usr/bin/pnmnorm Nov 9 22:27:03.693 [4818] info: FuzzyOcr: Using pnminvert => /usr/bin/pnminvert Nov 9 22:27:03.694 [4818] info: FuzzyOcr: Using ppmtopgm => /usr/bin/ppmtopgm Nov 9 22:27:03.694 [4818] info: FuzzyOcr: Using tesseract => /usr/bin/tesseract Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: Threshold[max_hash] => 5 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: Threshold[c] => 5 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: Threshold[s] => 0.01 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: Threshold[w] => 0.01 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: Threshold[h] => 0.01 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: Threshold[cn] => 0.01 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_add_score => 1 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_autodisable_negative_score => -5 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_autodisable_score => 1000 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_autosort_buffer => 10 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_autosort_scanset => 1 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_base_score => 5 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_corrupt_score => 2.5 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_corrupt_unfixable_score => 5 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_counts_required => 2 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_db_hash => /etc/mail/spamassassin/FuzzyOcr.db Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_db_max_days => 35 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_db_safe => /etc/mail/spamassassin/FuzzyOcr.safe.db Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_digest_db => /etc/mail/spamassassin/FuzzyOcr.hashdb Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_enable_image_hashing => 0 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_global_timeout => 0 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_global_wordlist => /etc/mail/spamassassin/FuzzyOcr.words Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_hashing_learn_scanned => 1 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_keep_bad_images => 0 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_log_pmsinfo => 1 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_log_stderr => 1 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_max_height => 800 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_max_width => 800 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_min_height => 4 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_min_width => 4 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_minimal_scanset => 1 Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_mysql_db => FuzzyOcr Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_mysql_hash => Hash Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_mysql_host => localhost Nov 9 22:27:03.694 [4818] dbg: FuzzyOcr: focr_mysql_port => 3306 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_mysql_safe => Safe Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_mysql_update_hash => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_mysql_user => fuzzyocr Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_no_homedirs => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_path_bin => /usr/local/netpbm/bin:/usr/local/bin:/usr/bin Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_pdf_maxpages => 1 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_personal_wordlist => __userstate__/FuzzyOcr.words Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_preprocessor_file => /etc/mail/spamassassin/FuzzyOcr.preps Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_scan_pdfs => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_scanset_file => /etc/mail/spamassassin/FuzzyOcr.scansets Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_score_ham => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_skip_bmp => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_skip_gif => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_skip_jpeg => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_skip_png => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_skip_tiff => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_skip_updates => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_strip_numbers => 1 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_threshold => 0.25 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_timeout => 10 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_twopass_scoring_factor => 1.5 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_unique_matches => 0 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_verbose => 1 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_wrongctype_score => 1.5 Nov 9 22:27:03.695 [4818] dbg: FuzzyOcr: focr_wrongext_score => 1.5 Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Loaded preprocessor normalize: /usr/bin/pnmnorm Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Loaded preprocessor invert: /usr/bin/pnminvert Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Loaded preprocessor ppmtopgm: /usr/bin/ppmtopgm Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Loaded preprocessor maketiff: pnmtotiff -color -truecolor Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Using scan ocrad: $ocrad -s5 $input Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Using scan ocrad-invert: $ocrad -s5 -i $input Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Using scan ocrad-decolorize-invert: $ocrad -s5 -i $input Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Using scan ocrad-decolorize: $ocrad -s5 $input Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Using scan gocr: /usr/bin/gocr -i $input Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Using scan gocr-180: /usr/bin/gocr -l 180 -d 2 -i $input Nov 9 22:27:03.695 [4818] info: FuzzyOcr: Using scan tesseract: /usr/bin/tesseract $input $output Nov 9 22:27:03.696 [4818] info: FuzzyOcr: Added <44> words from "/etc/mail/spamassassin/FuzzyOcr.words" Nov 9 22:27:03.696 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0x92ffc78) implements 'learner_new', priority 0 Nov 9 22:27:03.696 [4818] dbg: bayes: learner_new self=Mail::SpamAssassin::Plugin::Bayes=HASH(0x92ffc78), bayes_store_module=Mail::SpamAssassin::BayesStore::DBM Nov 9 22:27:03.702 [4818] dbg: bayes: learner_new: got store=Mail::SpamAssassin::BayesStore::DBM=HASH(0x9b77f90) Nov 9 22:27:03.702 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0x92ffc78) implements 'learner_is_scan_available', priority 0 Nov 9 22:27:03.702 [4818] dbg: config: using "/home/ichudov/.spamassassin" for user state dir Nov 9 22:27:03.702 [4818] dbg: bayes: tie-ing to DB file R/O /home/ichudov/.spamassassin/bayes_toks Nov 9 22:27:03.702 [4818] dbg: bayes: tie-ing to DB file R/O /home/ichudov/.spamassassin/bayes_seen Nov 9 22:27:03.703 [4818] dbg: bayes: found bayes db version 3 Nov 9 22:27:03.703 [4818] dbg: bayes: DB journal sync: last sync: 1352519342 Nov 9 22:27:03.703 [4818] dbg: config: using "/home/ichudov/.spamassassin" for user state dir Nov 9 22:27:03.703 [4818] dbg: config: score set 3 chosen. Nov 9 22:27:03.703 [4818] dbg: config: time limit 300.0 s Nov 9 22:27:03.704 [4818] dbg: message: main message type: text/plain Nov 9 22:27:03.704 [4818] dbg: check: pms new, time limit in 299.236 s Nov 9 22:27:03.704 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x9391ce8) implements 'check_start', priority 0 Nov 9 22:27:03.704 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x92de6c8) implements 'check_main', priority 0 Nov 9 22:27:03.704 [4818] 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 Nov 9 22:27:03.705 [4818] dbg: received-header: parsed as [ ip=65.182.171.171 rdns=netsource-hosting.chudov.com helo=orca.chudov.com by=bling.chudov.com ident= envfrom= intl=0 id=qAA4HJLY020244 auth= msa=0 ] Nov 9 22:27:03.705 [4818] dbg: received-header: relay 65.182.171.171 trusted? yes internal? yes msa? no Nov 9 22:27:03.707 [4818] dbg: received-header: parsed as [ ip=213.109.177.160 rdns= helo=mx02.northomeau.com by=orca.chudov.com ident= envfrom= intl=0 id=qAA4HD1H028112 auth= msa=0 ] Nov 9 22:27:03.707 [4818] dbg: received-header: relay 213.109.177.160 trusted? no internal? no msa? no Nov 9 22:27:03.707 [4818] dbg: metadata: X-Spam-Relays-Trusted: [ ip=65.182.171.171 rdns=netsource-hosting.chudov.com helo=orca.chudov.com by=bling.chudov.com ident= envfrom= intl=1 id=qAA4HJLY020244 auth= msa=0 ] Nov 9 22:27:03.707 [4818] dbg: metadata: X-Spam-Relays-Untrusted: [ ip=213.109.177.160 rdns= helo=mx02.northomeau.com by=orca.chudov.com ident= envfrom= intl=0 id=qAA4HD1H028112 auth= msa=0 ] Nov 9 22:27:03.707 [4818] dbg: metadata: X-Spam-Relays-Internal: [ ip=65.182.171.171 rdns=netsource-hosting.chudov.com helo=orca.chudov.com by=bling.chudov.com ident= envfrom= intl=1 id=qAA4HJLY020244 auth= msa=0 ] Nov 9 22:27:03.707 [4818] dbg: metadata: X-Spam-Relays-External: [ ip=213.109.177.160 rdns= helo=mx02.northomeau.com by=orca.chudov.com ident= envfrom= intl=0 id=qAA4HD1H028112 auth= msa=0 ] Nov 9 22:27:03.707 [4818] dbg: message: ---- MIME PARSER START ---- Nov 9 22:27:03.707 [4818] dbg: message: parsing normal part Nov 9 22:27:03.707 [4818] dbg: message: ---- MIME PARSER END ---- Nov 9 22:27:03.707 [4818] dbg: message: decoding other encoding type (7bit), ignoring Nov 9 22:27:03.707 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x8e35650) implements 'parsed_metadata', priority 0 Nov 9 22:27:03.707 [4818] dbg: dns: is_dns_available() last checked 1352521623.7 seconds ago; re-checking Nov 9 22:27:03.708 [4818] dbg: dns: is Net::DNS::Resolver available? yes Nov 9 22:27:03.708 [4818] dbg: dns: Net::DNS version: 0.65 Nov 9 22:27:03.708 [4818] dbg: dns: name server: 127.0.0.1, LocalAddr: 0.0.0.0 Nov 9 22:27:03.708 [4818] dbg: dns: resolver socket rx buffer size is 112640 bytes Nov 9 22:27:03.708 [4818] dbg: dns: testing resolver nameservers: 127.0.0.1, 8.8.8.8, 8.8.4.4 Nov 9 22:27:03.708 [4818] dbg: dns: trying (3) mit.edu... Nov 9 22:27:03.708 [4818] dbg: dns: looking up NS for 'mit.edu' Nov 9 22:27:03.708 [4818] dbg: dns: providing a callback for id: 32163/mit.edu/NS/IN Nov 9 22:27:03.709 [4818] dbg: dns: NS lookup of mit.edu using 127.0.0.1 succeeded => DNS available (set dns_available to override) Nov 9 22:27:03.709 [4818] dbg: dns: name server: 8.8.8.8, LocalAddr: 0.0.0.0 Nov 9 22:27:03.709 [4818] dbg: dns: resolver socket rx buffer size is 112640 bytes Nov 9 22:27:03.709 [4818] dbg: dns: trying (3) google.com... Nov 9 22:27:03.709 [4818] dbg: dns: looking up NS for 'google.com' Nov 9 22:27:03.710 [4818] dbg: dns: providing a callback for id: 55639/google.com/NS/IN Nov 9 22:27:03.754 [4818] dbg: dns: NS lookup of google.com using 8.8.8.8 succeeded => DNS available (set dns_available to override) Nov 9 22:27:03.754 [4818] dbg: dns: name server: 8.8.4.4, LocalAddr: 0.0.0.0 Nov 9 22:27:03.754 [4818] dbg: dns: resolver socket rx buffer size is 112640 bytes Nov 9 22:27:03.755 [4818] dbg: dns: trying (3) colorado.edu... Nov 9 22:27:03.755 [4818] dbg: dns: looking up NS for 'colorado.edu' Nov 9 22:27:03.755 [4818] dbg: dns: providing a callback for id: 42018/colorado.edu/NS/IN Nov 9 22:27:03.782 [4818] dbg: dns: NS lookup of colorado.edu using 8.8.4.4 succeeded => DNS available (set dns_available to override) Nov 9 22:27:03.782 [4818] dbg: dns: name server: 8.8.4.4, LocalAddr: 0.0.0.0 Nov 9 22:27:03.783 [4818] dbg: dns: resolver socket rx buffer size is 112640 bytes Nov 9 22:27:03.783 [4818] dbg: dns: NS list: 127.0.0.1, 8.8.8.8, 8.8.4.4 Nov 9 22:27:03.783 [4818] dbg: dns: name server: 127.0.0.1, LocalAddr: 0.0.0.0 Nov 9 22:27:03.784 [4818] dbg: dns: resolver socket rx buffer size is 112640 bytes Nov 9 22:27:03.784 [4818] dbg: dns: is DNS available? 1 Nov 9 22:27:03.787 [4818] dbg: uridnsbl: domains to query: northomeau.com Nov 9 22:27:03.787 [4818] dbg: dns: providing a callback for id: 60141/northomeau.com.multi.surbl.org/A/IN Nov 9 22:27:03.788 [4818] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:northomeau.com (timeout 15.0s, min 3.0s) Nov 9 22:27:03.788 [4818] dbg: dns: URIBL_PH_SURBL lookup start Nov 9 22:27:03.788 [4818] dbg: dns: providing a callback for id: 2787/northomeau.com.multi.uribl.com/A/IN Nov 9 22:27:03.788 [4818] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:northomeau.com (timeout 15.0s, min 3.0s) Nov 9 22:27:03.788 [4818] dbg: dns: URIBL_BLACK lookup start Nov 9 22:27:03.789 [4818] dbg: dns: URIBL_RED lookup start Nov 9 22:27:03.789 [4818] dbg: dns: URIBL_GREY lookup start Nov 9 22:27:03.789 [4818] dbg: dns: providing a callback for id: 44936/northomeau.com.dob.sibl.support-intelligence.net/A/IN Nov 9 22:27:03.789 [4818] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:northomeau.com (timeout 15.0s, min 3.0s) Nov 9 22:27:03.789 [4818] dbg: dns: URIBL_RHS_DOB lookup start Nov 9 22:27:03.789 [4818] dbg: dns: URIBL_SC_SURBL lookup start Nov 9 22:27:03.790 [4818] dbg: dns: URIBL_JP_SURBL lookup start Nov 9 22:27:03.790 [4818] dbg: dns: URIBL_AB_SURBL lookup start Nov 9 22:27:03.790 [4818] dbg: dns: URIBL_WS_SURBL lookup start Nov 9 22:27:03.790 [4818] dbg: dns: URIBL_BLOCKED lookup start Nov 9 22:27:03.791 [4818] dbg: dns: providing a callback for id: 38480/northomeau.com.dbl.spamhaus.org/A/IN Nov 9 22:27:03.791 [4818] dbg: async: starting: URI-DNSBL, DNSBL:dbl.spamhaus.org.:northomeau.com (timeout 15.0s, min 3.0s) Nov 9 22:27:03.791 [4818] dbg: dns: URIBL_DBL_ERROR lookup start Nov 9 22:27:03.791 [4818] dbg: dns: URIBL_DBL_SPAM lookup start Nov 9 22:27:03.791 [4818] dbg: dns: URIBL_DBL_REDIR lookup start Nov 9 22:27:03.791 [4818] dbg: dns: providing a callback for id: 21670/northomeau.com/NS/IN Nov 9 22:27:03.792 [4818] dbg: async: starting: URI-NS, NS:northomeau.com (timeout 15.0s, min 3.0s) Nov 9 22:27:03.792 [4818] dbg: dns: URIBL_SBL lookup start Nov 9 22:27:03.792 [4818] dbg: dns: checking RBL combined.njabl.org., set njabl Nov 9 22:27:03.792 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.793 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.793 [4818] dbg: dns: launching DNS A query for 160.177.109.213.combined.njabl.org. in background Nov 9 22:27:03.793 [4818] dbg: dns: providing a callback for id: 62715/160.177.109.213.combined.njabl.org/A/IN Nov 9 22:27:03.793 [4818] dbg: async: starting: DNSBL-A, dns:A:160.177.109.213.combined.njabl.org. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.794 [4818] dbg: dns: checking RBL bl.spamcop.net., set spamcop Nov 9 22:27:03.794 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.794 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.794 [4818] dbg: dns: launching DNS TXT query for 160.177.109.213.bl.spamcop.net. in background Nov 9 22:27:03.795 [4818] dbg: dns: providing a callback for id: 49231/160.177.109.213.bl.spamcop.net/TXT/IN Nov 9 22:27:03.795 [4818] dbg: async: starting: DNSBL-TXT, dns:TXT:160.177.109.213.bl.spamcop.net. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.795 [4818] dbg: dns: _check_rbl_addresses RBL rhsbl.ahbl.org., set ahbl Nov 9 22:27:03.796 [4818] dbg: dns: launching DNS A query for mx02.northomeau.com.rhsbl.ahbl.org. in background Nov 9 22:27:03.796 [4818] dbg: dns: providing a callback for id: 40092/mx02.northomeau.com.rhsbl.ahbl.org/A/IN Nov 9 22:27:03.796 [4818] dbg: async: starting: DNSBL-A, dns:A:mx02.northomeau.com.rhsbl.ahbl.org. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.796 [4818] dbg: dns: checking A and MX for host mx02.northomeau.com Nov 9 22:27:03.796 [4818] dbg: dns: launching DNS A query for mx02.northomeau.com in background Nov 9 22:27:03.797 [4818] dbg: dns: providing a callback for id: 63171/mx02.northomeau.com/A/IN Nov 9 22:27:03.797 [4818] dbg: async: starting: NO_DNS_FOR_FROM, DNSBL-A, dns:A:mx02.northomeau.com (timeout 15.0s, min 3.0s) Nov 9 22:27:03.797 [4818] dbg: dns: launching DNS MX query for mx02.northomeau.com in background Nov 9 22:27:03.797 [4818] dbg: dns: providing a callback for id: 15130/mx02.northomeau.com/MX/IN Nov 9 22:27:03.798 [4818] dbg: async: starting: NO_DNS_FOR_FROM, DNSBL-MX, dns:MX:mx02.northomeau.com (timeout 15.0s, min 3.0s) Nov 9 22:27:03.798 [4818] dbg: dns: checking RBL zen.spamhaus.org., set zen-lastexternal Nov 9 22:27:03.798 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.798 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.798 [4818] dbg: dns: launching DNS A query for 160.177.109.213.zen.spamhaus.org. in background Nov 9 22:27:03.798 [4818] dbg: dns: providing a callback for id: 50120/160.177.109.213.zen.spamhaus.org/A/IN Nov 9 22:27:03.798 [4818] dbg: async: starting: DNSBL-A, dns:A:160.177.109.213.zen.spamhaus.org. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.798 [4818] dbg: dns: checking RBL dnsbl.sorbs.net., set sorbs-lastexternal Nov 9 22:27:03.798 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.798 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.798 [4818] dbg: dns: launching DNS A query for 160.177.109.213.dnsbl.sorbs.net. in background Nov 9 22:27:03.799 [4818] dbg: dns: providing a callback for id: 40457/160.177.109.213.dnsbl.sorbs.net/A/IN Nov 9 22:27:03.799 [4818] dbg: async: starting: DNSBL-A, dns:A:160.177.109.213.dnsbl.sorbs.net. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.799 [4818] dbg: dns: checking RBL dnsbl.sorbs.net., set sorbs Nov 9 22:27:03.799 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.799 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.799 [4818] dbg: dns: checking RBL bl.score.senderscore.com., set rnbl-lastexternal Nov 9 22:27:03.799 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.799 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.799 [4818] dbg: dns: launching DNS A query for 160.177.109.213.bl.score.senderscore.com. in background Nov 9 22:27:03.799 [4818] dbg: dns: providing a callback for id: 35844/160.177.109.213.bl.score.senderscore.com/A/IN Nov 9 22:27:03.800 [4818] dbg: async: starting: DNSBL-A, dns:A:160.177.109.213.bl.score.senderscore.com. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.800 [4818] dbg: dns: checking RBL zen.spamhaus.org., set zen-lastexternal Nov 9 22:27:03.800 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.800 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.800 [4818] dbg: dns: checking RBL psbl.surriel.com., set psbl-lastexternal Nov 9 22:27:03.800 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.800 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.800 [4818] dbg: dns: launching DNS A query for 160.177.109.213.psbl.surriel.com. in background Nov 9 22:27:03.800 [4818] dbg: dns: providing a callback for id: 57445/160.177.109.213.psbl.surriel.com/A/IN Nov 9 22:27:03.800 [4818] dbg: async: starting: DNSBL-A, dns:A:160.177.109.213.psbl.surriel.com. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.800 [4818] dbg: dns: checking RBL list.dnswl.org., set dnswl-firsttrusted Nov 9 22:27:03.800 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.801 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.801 [4818] dbg: dns: launching DNS A query for 160.177.109.213.list.dnswl.org. in background Nov 9 22:27:03.801 [4818] dbg: dns: providing a callback for id: 4226/160.177.109.213.list.dnswl.org/A/IN Nov 9 22:27:03.801 [4818] dbg: async: starting: DNSBL-A, dns:A:160.177.109.213.list.dnswl.org. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.801 [4818] dbg: dns: checking RBL bb.barracudacentral.org., set brbl-lastexternal Nov 9 22:27:03.801 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.801 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.801 [4818] dbg: dns: launching DNS A query for 160.177.109.213.bb.barracudacentral.org. in background Nov 9 22:27:03.801 [4818] dbg: dns: providing a callback for id: 11605/160.177.109.213.bb.barracudacentral.org/A/IN Nov 9 22:27:03.801 [4818] dbg: async: starting: DNSBL-A, dns:A:160.177.109.213.bb.barracudacentral.org. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.802 [4818] dbg: dns: checking RBL sa-trusted.bondedsender.org., set ssc-firsttrusted Nov 9 22:27:03.802 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.802 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.802 [4818] dbg: dns: launching DNS TXT query for 160.177.109.213.sa-trusted.bondedsender.org. in background Nov 9 22:27:03.802 [4818] dbg: dns: providing a callback for id: 57935/160.177.109.213.sa-trusted.bondedsender.org/TXT/IN Nov 9 22:27:03.802 [4818] dbg: async: starting: DNSBL-TXT, dns:TXT:160.177.109.213.sa-trusted.bondedsender.org. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.802 [4818] dbg: dns: checking RBL zen.spamhaus.org., set zen Nov 9 22:27:03.802 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.802 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.802 [4818] dbg: dns: checking RBL sa-accredit.habeas.com., set ssc-firsttrusted Nov 9 22:27:03.802 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.802 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.802 [4818] dbg: dns: launching DNS TXT query for 160.177.109.213.sa-accredit.habeas.com. in background Nov 9 22:27:03.803 [4818] dbg: dns: providing a callback for id: 31579/160.177.109.213.sa-accredit.habeas.com/TXT/IN Nov 9 22:27:03.803 [4818] dbg: async: starting: DNSBL-TXT, dns:TXT:160.177.109.213.sa-accredit.habeas.com. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.803 [4818] dbg: dns: checking RBL iadb.isipp.com., set iadb-firsttrusted Nov 9 22:27:03.803 [4818] dbg: dns: IPs found: full-external: 213.109.177.160 untrusted: 213.109.177.160 originating: Nov 9 22:27:03.803 [4818] dbg: dns: only inspecting the following IPs: 213.109.177.160 Nov 9 22:27:03.803 [4818] dbg: dns: launching DNS A query for 160.177.109.213.iadb.isipp.com. in background Nov 9 22:27:03.803 [4818] dbg: dns: providing a callback for id: 42374/160.177.109.213.iadb.isipp.com/A/IN Nov 9 22:27:03.803 [4818] dbg: async: starting: DNSBL-A, dns:A:160.177.109.213.iadb.isipp.com. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.803 [4818] dbg: check: check_main, time limit in 299.136 s Nov 9 22:27:03.804 [4818] dbg: check: running tests for priority: -1000 Nov 9 22:27:03.805 [4818] dbg: dns: hit 213.109.177.160 Nov 9 22:27:03.806 [4818] dbg: async: select found 16 responses ready (t.o.=0.0) Nov 9 22:27:03.806 [4818] dbg: async: completed in 0.008 s: NO_DNS_FOR_FROM, DNSBL-MX, dns:MX:mx02.northomeau.com Nov 9 22:27:03.806 [4818] dbg: async: completed in 0.014 s: URI-DNSBL, DNSBL:dbl.spamhaus.org.:northomeau.com Nov 9 22:27:03.806 [4818] dbg: uridnsbl: domain "northomeau.com" listed (URIBL_DBL_SPAM): 127.0.1.2 Nov 9 22:27:03.807 [4818] dbg: dns: URIBL_DBL_SPAM lookup finished Nov 9 22:27:03.807 [4818] dbg: async: completed in 0.005 s: DNSBL-A, dns:A:160.177.109.213.bb.barracudacentral.org. Nov 9 22:27:03.807 [4818] dbg: async: completed in 0.008 s: NO_DNS_FOR_FROM, DNSBL-A, dns:A:mx02.northomeau.com Nov 9 22:27:03.807 [4818] dbg: async: completed in 0.007 s: DNSBL-A, dns:A:160.177.109.213.zen.spamhaus.org. Nov 9 22:27:03.807 [4818] dbg: async: completed in 0.016 s: URI-DNSBL, DNSBL:multi.surbl.org.:northomeau.com Nov 9 22:27:03.807 [4818] dbg: async: completed in 0.003 s: DNSBL-A, dns:A:160.177.109.213.iadb.isipp.com. Nov 9 22:27:03.807 [4818] dbg: async: completed in 0.006 s: DNSBL-A, dns:A:160.177.109.213.bl.score.senderscore.com. Nov 9 22:27:03.807 [4818] dbg: async: completed in 0.015 s: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:northomeau.com Nov 9 22:27:03.807 [4818] dbg: async: completed in 0.009 s: DNSBL-A, dns:A:mx02.northomeau.com.rhsbl.ahbl.org. Nov 9 22:27:03.807 [4818] dbg: async: completed in 0.015 s: URI-DNSBL, DNSBL:multi.uribl.com.:northomeau.com Nov 9 22:27:03.807 [4818] dbg: uridnsbl: domain "northomeau.com" listed (URIBL_BLACK): 127.0.0.2 Nov 9 22:27:03.807 [4818] dbg: dns: URIBL_BLACK lookup finished Nov 9 22:27:03.807 [4818] dbg: async: completed in 0.013 s: URI-NS, NS:northomeau.com Nov 9 22:27:03.808 [4818] dbg: dns: providing a callback for id: 33715/dns5.name-services.com/A/IN Nov 9 22:27:03.808 [4818] dbg: async: starting: URI-A, A:dns5.name-services.com. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.808 [4818] dbg: dns: providing a callback for id: 33821/dns4.name-services.com/A/IN Nov 9 22:27:03.808 [4818] dbg: async: starting: URI-A, A:dns4.name-services.com. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.808 [4818] dbg: dns: providing a callback for id: 52630/dns1.name-services.com/A/IN Nov 9 22:27:03.808 [4818] dbg: async: starting: URI-A, A:dns1.name-services.com. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.808 [4818] dbg: dns: providing a callback for id: 8352/dns2.name-services.com/A/IN Nov 9 22:27:03.808 [4818] dbg: async: starting: URI-A, A:dns2.name-services.com. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.809 [4818] dbg: dns: providing a callback for id: 42889/dns3.name-services.com/A/IN Nov 9 22:27:03.809 [4818] dbg: async: starting: URI-A, A:dns3.name-services.com. (timeout 15.0s, min 3.0s) Nov 9 22:27:03.809 [4818] dbg: async: completed in 0.006 s: DNSBL-A, dns:A:160.177.109.213.psbl.surriel.com. Nov 9 22:27:03.809 [4818] dbg: async: completed in 0.007 s: DNSBL-A, dns:A:160.177.109.213.dnsbl.sorbs.net. Nov 9 22:27:03.809 [4818] dbg: async: completed in 0.012 s: DNSBL-A, dns:A:160.177.109.213.combined.njabl.org. Nov 9 22:27:03.809 [4818] dbg: async: completed in 0.005 s: DNSBL-A, dns:A:160.177.109.213.list.dnswl.org. Nov 9 22:27:03.809 [4818] dbg: async: queries completed: 16, started: 5 Nov 9 22:27:03.809 [4818] dbg: async: queries active: DNSBL-TXT=3 URI-A=5 at Fri Nov 9 22:27:03 2012 Nov 9 22:27:03.809 [4818] dbg: dns: harvested completed queries Nov 9 22:27:03.809 [4818] dbg: rules: running head tests; score so far=3.425 Nov 9 22:27:03.809 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 282 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg1000_1 Nov 9 22:27:03.809 [4818] dbg: rules: run_generic_tests - compiling eval code: head, priority -1000 Nov 9 22:27:03.809 [4818] dbg: rules: compiled head tests Nov 9 22:27:03.810 [4818] dbg: rules: running head_eval tests; score so far=3.425 Nov 9 22:27:03.810 [4818] dbg: rules: run_eval_tests - compiling eval code: 9, priority -1000 Nov 9 22:27:03.810 [4818] dbg: eval: all '*From' addrs: ElizabethNunez@mx02.northomeau.com Nov 9 22:27:03.810 [4818] dbg: eval: all '*To' addrs: ichudov@bling.chudov.com ichudov@algebra.com ichudov@chudov.com Nov 9 22:27:03.811 [4818] dbg: rules: running body tests; score so far=3.425 Nov 9 22:27:03.811 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg1000_1 Nov 9 22:27:03.811 [4818] dbg: rules: run_generic_tests - compiling eval code: body, priority -1000 Nov 9 22:27:03.811 [4818] dbg: rules: compiled body tests Nov 9 22:27:03.811 [4818] dbg: rules: running uri tests; score so far=3.425 Nov 9 22:27:03.811 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg1000_1 Nov 9 22:27:03.811 [4818] dbg: rules: run_generic_tests - compiling eval code: uri, priority -1000 Nov 9 22:27:03.811 [4818] dbg: rules: compiled uri tests Nov 9 22:27:03.812 [4818] dbg: rules: running body_eval tests; score so far=3.425 Nov 9 22:27:03.812 [4818] dbg: rules: run_eval_tests - compiling eval code: 11, priority -1000 Nov 9 22:27:03.812 [4818] dbg: rules: running rawbody tests; score so far=3.425 Nov 9 22:27:03.812 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 231 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg1000_1 Nov 9 22:27:03.812 [4818] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -1000 Nov 9 22:27:03.812 [4818] dbg: rules: compiled rawbody tests Nov 9 22:27:03.812 [4818] dbg: rules: running full tests; score so far=3.425 Nov 9 22:27:03.812 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 260 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg1000_1 Nov 9 22:27:03.812 [4818] dbg: rules: run_generic_tests - compiling eval code: full, priority -1000 Nov 9 22:27:03.812 [4818] dbg: rules: compiled full tests Nov 9 22:27:03.812 [4818] dbg: rules: running meta tests; score so far=3.425 Nov 9 22:27:03.812 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 285 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg1000_1 Nov 9 22:27:03.813 [4818] dbg: rules: run_generic_tests - compiling eval code: meta, priority -1000 Nov 9 22:27:03.813 [4818] dbg: rules: compiled meta tests Nov 9 22:27:03.813 [4818] dbg: check: running tests for priority: -950 Nov 9 22:27:03.813 [4818] dbg: rules: running head tests; score so far=3.425 Nov 9 22:27:03.813 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg950_1 Nov 9 22:27:03.813 [4818] dbg: rules: run_generic_tests - compiling eval code: head, priority -950 Nov 9 22:27:03.813 [4818] dbg: rules: compiled head tests Nov 9 22:27:03.813 [4818] dbg: rules: running head_eval tests; score so far=3.425 Nov 9 22:27:03.813 [4818] dbg: rules: run_eval_tests - compiling eval code: 9, priority -950 Nov 9 22:27:03.813 [4818] dbg: rules: running body tests; score so far=3.425 Nov 9 22:27:03.813 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg950_1 Nov 9 22:27:03.814 [4818] dbg: rules: run_generic_tests - compiling eval code: body, priority -950 Nov 9 22:27:03.814 [4818] dbg: rules: compiled body tests Nov 9 22:27:03.814 [4818] dbg: rules: running uri tests; score so far=3.425 Nov 9 22:27:03.814 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg950_1 Nov 9 22:27:03.814 [4818] dbg: rules: run_generic_tests - compiling eval code: uri, priority -950 Nov 9 22:27:03.814 [4818] dbg: rules: compiled uri tests Nov 9 22:27:03.814 [4818] dbg: rules: running body_eval tests; score so far=3.425 Nov 9 22:27:03.814 [4818] dbg: rules: run_eval_tests - compiling eval code: 11, priority -950 Nov 9 22:27:03.814 [4818] dbg: rules: running rawbody tests; score so far=3.425 Nov 9 22:27:03.814 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg950_1 Nov 9 22:27:03.814 [4818] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -950 Nov 9 22:27:03.814 [4818] dbg: rules: compiled rawbody tests Nov 9 22:27:03.814 [4818] dbg: rules: running full tests; score so far=3.425 Nov 9 22:27:03.815 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg950_1 Nov 9 22:27:03.815 [4818] dbg: rules: run_generic_tests - compiling eval code: full, priority -950 Nov 9 22:27:03.815 [4818] dbg: rules: compiled full tests Nov 9 22:27:03.815 [4818] dbg: rules: running meta tests; score so far=3.425 Nov 9 22:27:03.815 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg950_1 Nov 9 22:27:03.815 [4818] dbg: rules: run_generic_tests - compiling eval code: meta, priority -950 Nov 9 22:27:03.815 [4818] dbg: rules: compiled meta tests Nov 9 22:27:03.815 [4818] dbg: check: running tests for priority: -900 Nov 9 22:27:03.815 [4818] dbg: rules: running head tests; score so far=3.425 Nov 9 22:27:03.815 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg900_1 Nov 9 22:27:03.815 [4818] dbg: rules: run_generic_tests - compiling eval code: head, priority -900 Nov 9 22:27:03.815 [4818] dbg: rules: compiled head tests Nov 9 22:27:03.815 [4818] dbg: rules: running head_eval tests; score so far=3.425 Nov 9 22:27:03.816 [4818] dbg: rules: run_eval_tests - compiling eval code: 9, priority -900 Nov 9 22:27:03.816 [4818] dbg: rules: running body tests; score so far=3.425 Nov 9 22:27:03.816 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg900_1 Nov 9 22:27:03.816 [4818] dbg: rules: run_generic_tests - compiling eval code: body, priority -900 Nov 9 22:27:03.816 [4818] dbg: rules: compiled body tests Nov 9 22:27:03.816 [4818] dbg: rules: running uri tests; score so far=3.425 Nov 9 22:27:03.816 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg900_1 Nov 9 22:27:03.816 [4818] dbg: rules: run_generic_tests - compiling eval code: uri, priority -900 Nov 9 22:27:03.817 [4818] dbg: rules: compiled uri tests Nov 9 22:27:03.817 [4818] dbg: rules: running body_eval tests; score so far=3.425 Nov 9 22:27:03.817 [4818] dbg: rules: run_eval_tests - compiling eval code: 11, priority -900 Nov 9 22:27:03.817 [4818] dbg: rules: running rawbody tests; score so far=3.425 Nov 9 22:27:03.817 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg900_1 Nov 9 22:27:03.817 [4818] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -900 Nov 9 22:27:03.817 [4818] dbg: rules: compiled rawbody tests Nov 9 22:27:03.817 [4818] dbg: rules: running full tests; score so far=3.425 Nov 9 22:27:03.818 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg900_1 Nov 9 22:27:03.818 [4818] dbg: rules: run_generic_tests - compiling eval code: full, priority -900 Nov 9 22:27:03.818 [4818] dbg: rules: compiled full tests Nov 9 22:27:03.818 [4818] dbg: rules: running meta tests; score so far=3.425 Nov 9 22:27:03.818 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg900_1 Nov 9 22:27:03.818 [4818] dbg: rules: run_generic_tests - compiling eval code: meta, priority -900 Nov 9 22:27:03.818 [4818] dbg: rules: compiled meta tests Nov 9 22:27:03.818 [4818] dbg: check: running tests for priority: -400 Nov 9 22:27:03.818 [4818] dbg: rules: running head tests; score so far=3.425 Nov 9 22:27:03.818 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg400_1 Nov 9 22:27:03.818 [4818] dbg: rules: run_generic_tests - compiling eval code: head, priority -400 Nov 9 22:27:03.818 [4818] dbg: rules: compiled head tests Nov 9 22:27:03.819 [4818] dbg: rules: running body tests; score so far=3.425 Nov 9 22:27:03.819 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg400_1 Nov 9 22:27:03.819 [4818] dbg: rules: run_generic_tests - compiling eval code: body, priority -400 Nov 9 22:27:03.819 [4818] dbg: rules: compiled body tests Nov 9 22:27:03.819 [4818] dbg: rules: running uri tests; score so far=3.425 Nov 9 22:27:03.819 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg400_1 Nov 9 22:27:03.819 [4818] dbg: rules: run_generic_tests - compiling eval code: uri, priority -400 Nov 9 22:27:03.819 [4818] dbg: rules: compiled uri tests Nov 9 22:27:03.819 [4818] dbg: rules: running body_eval tests; score so far=3.425 Nov 9 22:27:03.819 [4818] dbg: rules: run_eval_tests - compiling eval code: 11, priority -400 Nov 9 22:27:03.819 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::WLBLEval=HASH(0x945a808) implements 'check_wb_list', priority 0 Nov 9 22:27:03.820 [4818] dbg: bayes: DB journal sync: last sync: 1352519342 Nov 9 22:27:03.820 [4818] dbg: bayes: corpus size: nspam = 923214, nham = 548758 Nov 9 22:27:03.829 [4818] dbg: bayes: score = 0.500810209863605 Nov 9 22:27:03.830 [4818] dbg: bayes: DB expiry: tokens in DB: 121196, Expiry max size: 150000, Oldest atime: 1352168916, Newest atime: 1352521480, Last expire: 1352435583, Current time: 1352521623 Nov 9 22:27:03.830 [4818] dbg: bayes: DB journal sync: last sync: 1352519342 Nov 9 22:27:03.830 [4818] dbg: bayes: untie-ing Nov 9 22:27:03.830 [4818] dbg: rules: running rawbody tests; score so far=3.425 Nov 9 22:27:03.830 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg400_1 Nov 9 22:27:03.830 [4818] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -400 Nov 9 22:27:03.830 [4818] dbg: rules: compiled rawbody tests Nov 9 22:27:03.830 [4818] dbg: rules: running full tests; score so far=3.425 Nov 9 22:27:03.830 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg400_1 Nov 9 22:27:03.831 [4818] dbg: rules: run_generic_tests - compiling eval code: full, priority -400 Nov 9 22:27:03.831 [4818] dbg: rules: compiled full tests Nov 9 22:27:03.831 [4818] dbg: rules: running meta tests; score so far=3.425 Nov 9 22:27:03.831 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg400_1 Nov 9 22:27:03.831 [4818] dbg: rules: run_generic_tests - compiling eval code: meta, priority -400 Nov 9 22:27:03.831 [4818] dbg: rules: compiled meta tests Nov 9 22:27:03.831 [4818] dbg: check: running tests for priority: 0 Nov 9 22:27:03.831 [4818] dbg: rules: running head tests; score so far=3.425 Nov 9 22:27:03.842 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60229 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_1 Nov 9 22:27:03.847 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60112 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_2 Nov 9 22:27:03.852 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60436 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_3 Nov 9 22:27:03.856 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60072 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_4 Nov 9 22:27:03.861 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60410 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_5 Nov 9 22:27:03.865 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60213 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_6 Nov 9 22:27:03.869 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 36831 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_7 Nov 9 22:27:03.871 [4818] dbg: rules: run_generic_tests - compiling eval code: head, priority 0 Nov 9 22:27:03.871 [4818] dbg: rules: compiled head tests Nov 9 22:27:03.872 [4818] dbg: rules: ran header rule __LAST_EXTERNAL_RELAY_NO_AUTH ======> got hit: "[ ip=213.109.177.160 rdns= helo=mx02.northomeau.com by=orca.chudov.com ident= envfrom= intl=0 id=qAA4HD1H028112 auth= " Nov 9 22:27:03.872 [4818] dbg: rules: ran header rule __RDNS_NONE ======> got hit: "[ ip=213.109.177.160 rdns= " Nov 9 22:27:03.872 [4818] dbg: rules: ran header rule __DOS_SINGLE_EXT_RELAY ======> got hit: "[ ip=213.109.177.160 rdns= helo=mx02.northomeau.com by=orca.chudov.com ident= envfrom= intl=0 id=qAA4HD1H028112 auth= msa=0 ]" Nov 9 22:27:03.872 [4818] dbg: rules: ran header rule __CT_TEXT_PLAIN ======> got hit: "text/plain" Nov 9 22:27:03.872 [4818] dbg: rules: ran header rule __CT ======> got hit: "" Nov 9 22:27:03.873 [4818] dbg: rules: ran header rule __CTYPE_CHARSET_QUOTED ======> got hit: "charset="" Nov 9 22:27:03.873 [4818] dbg: rules: ran header rule __MISSING_REF ======> got hit: "UNSET" Nov 9 22:27:03.873 [4818] dbg: rules: ran header rule __MISSING_REPLY ======> got hit: "UNSET" Nov 9 22:27:03.873 [4818] dbg: rules: ran header rule __HAS_DATE ======> got hit: "" Nov 9 22:27:03.873 [4818] dbg: rules: ran header rule __LAST_UNTRUSTED_RELAY_NO_AUTH ======> got hit: "[ ip=213.109.177.160 rdns= helo=mx02.northomeau.com by=orca.chudov.com ident= envfrom= intl=0 id=qAA4HD1H028112 auth= " Nov 9 22:27:03.873 [4818] dbg: rules: ran header rule __HAS_MESSAGE_ID ======> got hit: "" Nov 9 22:27:03.873 [4818] dbg: rules: ran header rule __MIME_VERSION ======> got hit: "" Nov 9 22:27:03.874 [4818] dbg: rules: ran header rule __TOCC_EXISTS ======> got hit: "" Nov 9 22:27:03.874 [4818] dbg: rules: ran header rule __HAS_RCVD ======> got hit: "" Nov 9 22:27:03.874 [4818] dbg: rules: ran header rule __DOS_RCVD_FRI ======> got hit: " Fri, " Nov 9 22:27:03.875 [4818] dbg: rules: ran header rule __HAS_SUBJECT ======> got hit: "" Nov 9 22:27:03.875 [4818] dbg: rules: ran header rule __HAS_MSGID ======> got hit: "<" Nov 9 22:27:03.875 [4818] dbg: rules: ran header rule __SANE_MSGID ======> got hit: " Nov 9 22:27:03.875 [4818] dbg: rules: [...] " Nov 9 22:27:03.875 [4818] dbg: rules: ran header rule __HAS_TO ======> got hit: "" Nov 9 22:27:03.875 [4818] dbg: rules: ran header rule __CTE ======> got hit: "" Nov 9 22:27:03.876 [4818] dbg: rules: ran header rule __HAS_FROM ======> got hit: "" Nov 9 22:27:03.876 [4818] dbg: rules: running head_eval tests; score so far=3.425 Nov 9 22:27:03.877 [4818] dbg: rules: run_eval_tests - compiling eval code: 9, priority 0 Nov 9 22:27:03.880 [4818] dbg: FreeMail: RULE (FREEMAIL_FROM) check_freemail_from Nov 9 22:27:03.880 [4818] dbg: FreeMail: all from-addresses: elizabethnunez@mx02.northomeau.com Nov 9 22:27:03.892 [4818] dbg: dkim: using Mail::DKIM version 0.38 Nov 9 22:27:03.892 [4818] dbg: dkim: performing public key lookup and signature verification Nov 9 22:27:03.892 [4818] dbg: dkim: signature verification result: none Nov 9 22:27:03.892 [4818] dbg: dkim: adsp: performing lookup on _adsp._domainkey.mx02.northomeau.com Nov 9 22:27:03.894 [4818] dbg: dkim: adsp result: U/unknown (dns: unknown), author domain 'mx02.northomeau.com' Nov 9 22:27:03.894 [4818] dbg: spf: checking to see if the message has a Received-SPF header that we can use Nov 9 22:27:03.911 [4818] dbg: spf: using Mail::SPF for SPF checks Nov 9 22:27:03.911 [4818] dbg: spf: checking HELO (helo=mx02.northomeau.com, ip=213.109.177.160) Nov 9 22:27:03.911 [4818] dbg: dns: providing a callback for id: 39010/mx02.northomeau.com/SPF/IN Nov 9 22:27:03.913 [4818] dbg: dns: providing a callback for id: 60886/mx02.northomeau.com/TXT/IN Nov 9 22:27:03.914 [4818] dbg: spf: query for /213.109.177.160/mx02.northomeau.com: result: none, comment: , text: No applicable sender policy available Nov 9 22:27:03.915 [4818] dbg: dkim: author ElizabethNunez@mx02.northomeau.com, not in any dkim whitelist Nov 9 22:27:03.916 [4818] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks Nov 9 22:27:03.916 [4818] dbg: spf: relayed through one or more trusted relays, cannot use header-based Envelope-From, skipping Nov 9 22:27:03.916 [4818] dbg: FreeMail: RULE (__freemail_reply) check_freemail_replyto Nov 9 22:27:03.916 [4818] dbg: FreeMail: From address: elizabethnunez@mx02.northomeau.com Nov 9 22:27:03.916 [4818] dbg: FreeMail: No Reply-To and From is not freemail, skipping check Nov 9 22:27:03.918 [4818] dbg: FreeMail: RULE (FREEMAIL_REPLYTO_END_DIGIT) check_freemail_header regex:\d@ Nov 9 22:27:03.918 [4818] dbg: FreeMail: header Reply-To not found from mail Nov 9 22:27:03.919 [4818] dbg: FreeMail: RULE (__freemail_replyto) check_freemail_replyto Nov 9 22:27:03.919 [4818] dbg: FreeMail: From address: elizabethnunez@mx02.northomeau.com Nov 9 22:27:03.919 [4818] dbg: FreeMail: Reply-To is not freemail, skipping check Nov 9 22:27:03.919 [4818] dbg: rules: ran eval rule __ENV_AND_HDR_FROM_MATCH ======> got hit (1) Nov 9 22:27:03.919 [4818] dbg: FreeMail: RULE (__freemail_hdr_replyto) check_freemail_header Nov 9 22:27:03.920 [4818] dbg: FreeMail: header Reply-To not found from mail Nov 9 22:27:03.920 [4818] dbg: spf: def_spf_whitelist_from: already checked spf and didn't get pass, skipping whitelist check Nov 9 22:27:03.920 [4818] dbg: FreeMail: RULE (FREEMAIL_ENVFROM_END_DIGIT) check_freemail_header regex:\d@ Nov 9 22:27:03.920 [4818] dbg: FreeMail: address from header EnvelopeFrom: elizabethnunez@mx02.northomeau.com Nov 9 22:27:03.921 [4818] dbg: spf: whitelist_from_spf: already checked spf and didn't get pass, skipping whitelist check Nov 9 22:27:03.921 [4818] dbg: async: select found 1 responses ready (t.o.=0.0) Nov 9 22:27:03.921 [4818] dbg: async: completed in 0.104 s: URI-A, A:dns4.name-services.com. Nov 9 22:27:03.921 [4818] dbg: dns: providing a callback for id: 52451/1.194.124.98.zen.spamhaus.org/A/IN Nov 9 22:27:03.922 [4818] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.194.124.98 (timeout 15.0s, min 3.0s) Nov 9 22:27:03.922 [4818] dbg: async: completed in 0.104 s: URI-A, A:dns3.name-services.com. Nov 9 22:27:03.922 [4818] dbg: dns: providing a callback for id: 31250/1.193.124.98.zen.spamhaus.org/A/IN Nov 9 22:27:03.922 [4818] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.193.124.98 (timeout 15.0s, min 3.0s) Nov 9 22:27:03.922 [4818] dbg: async: completed in 0.104 s: URI-A, A:dns5.name-services.com. Nov 9 22:27:03.922 [4818] dbg: dns: providing a callback for id: 46514/1.196.124.98.zen.spamhaus.org/A/IN Nov 9 22:27:03.922 [4818] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.196.124.98 (timeout 15.0s, min 3.0s) Nov 9 22:27:03.922 [4818] dbg: async: completed in 0.104 s: URI-A, A:dns1.name-services.com. Nov 9 22:27:03.923 [4818] dbg: dns: providing a callback for id: 4107/1.192.124.98.zen.spamhaus.org/A/IN Nov 9 22:27:03.923 [4818] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.192.124.98 (timeout 15.0s, min 3.0s) Nov 9 22:27:03.923 [4818] dbg: async: completed in 0.104 s: URI-A, A:dns2.name-services.com. Nov 9 22:27:03.923 [4818] dbg: dns: providing a callback for id: 30599/1.197.124.98.zen.spamhaus.org/A/IN Nov 9 22:27:03.923 [4818] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.197.124.98 (timeout 15.0s, min 3.0s) Nov 9 22:27:03.923 [4818] dbg: async: completed in 0.119 s: DNSBL-TXT, dns:TXT:160.177.109.213.sa-trusted.bondedsender.org. Nov 9 22:27:03.923 [4818] dbg: async: completed in 0.110 s: DNSBL-TXT, dns:TXT:160.177.109.213.sa-accredit.habeas.com. Nov 9 22:27:03.923 [4818] dbg: async: completed in 0.118 s: DNSBL-TXT, dns:TXT:160.177.109.213.bl.spamcop.net. Nov 9 22:27:03.923 [4818] dbg: async: queries completed: 8, started: 5 Nov 9 22:27:03.923 [4818] dbg: async: queries active: URI-DNSBL=5 at Fri Nov 9 22:27:03 2012 Nov 9 22:27:03.923 [4818] dbg: dns: harvested completed queries Nov 9 22:27:03.923 [4818] dbg: rules: running body tests; score so far=3.425 Nov 9 22:27:03.926 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60272 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_1 Nov 9 22:27:03.932 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60316 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_2 Nov 9 22:27:03.938 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60043 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_3 Nov 9 22:27:03.945 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60179 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_4 Nov 9 22:27:03.952 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60045 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_5 Nov 9 22:27:03.958 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60273 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_6 Nov 9 22:27:03.964 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60149 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_7 Nov 9 22:27:03.971 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60294 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_8 Nov 9 22:27:03.977 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60298 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_9 Nov 9 22:27:03.983 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 52156 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_10 Nov 9 22:27:03.987 [4818] dbg: rules: run_generic_tests - compiling eval code: body, priority 0 Nov 9 22:27:03.987 [4818] dbg: rules: compiled body tests Nov 9 22:27:04.010 [4818] dbg: rules: ran body rule __MBA ======> got hit: "mba" Nov 9 22:27:04.013 [4818] dbg: rules: ran body rule __DOS_LINK ======> got hit: "link" Nov 9 22:27:04.017 [4818] dbg: rules: ran body rule __NONEMPTY_BODY ======> got hit: "F" Nov 9 22:27:04.035 [4818] dbg: async: select found 5 responses ready (t.o.=0.0) Nov 9 22:27:04.035 [4818] dbg: async: completed in 0.113 s: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.194.124.98 Nov 9 22:27:04.035 [4818] dbg: async: completed in 0.112 s: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.193.124.98 Nov 9 22:27:04.035 [4818] dbg: async: completed in 0.112 s: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.197.124.98 Nov 9 22:27:04.035 [4818] dbg: async: completed in 0.112 s: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.196.124.98 Nov 9 22:27:04.035 [4818] dbg: async: completed in 0.112 s: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.192.124.98 Nov 9 22:27:04.035 [4818] dbg: dns: harvested completed queries Nov 9 22:27:04.035 [4818] dbg: rules: running uri tests; score so far=3.425 Nov 9 22:27:04.036 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 24712 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_0_1 Nov 9 22:27:04.038 [4818] dbg: rules: run_generic_tests - compiling eval code: uri, priority 0 Nov 9 22:27:04.038 [4818] dbg: rules: compiled uri tests Nov 9 22:27:04.038 [4818] dbg: rules: ran uri rule __DOS_HAS_ANY_URI ======> got hit: "h" Nov 9 22:27:04.038 [4818] dbg: rules: ran uri rule __HAS_URI ======> got hit: "h" Nov 9 22:27:04.038 [4818] dbg: rules: ran uri rule __HAS_ANY_URI ======> got hit: "h" Nov 9 22:27:04.038 [4818] dbg: rules: running body_eval tests; score so far=3.425 Nov 9 22:27:04.040 [4818] dbg: rules: run_eval_tests - compiling eval code: 11, priority 0 Nov 9 22:27:04.048 [4818] dbg: https_http_mismatch: anchors 0 Nov 9 22:27:04.049 [4818] dbg: uri: running T_AJB_CANPOST_BADLINK Nov 9 22:27:04.049 [4818] dbg: uri: raw matched: 'http://www.northomeau.com/asset/doc/uW25J4endN4EZGziA3d2iN/rak?b=144465' !~ /(?-xism:canadapost\.)/ Nov 9 22:27:04.049 [4818] dbg: uri: running T_AJB_CANPOST_BADLINK Nov 9 22:27:04.049 [4818] dbg: uri: raw matched: 'http://www.northomeau.com/choose-home-options/kBmTQ8nxApDL3MFnpLerZV8563/home-987578434/rak-act87/start/index.httml' !~ /(?-xism:canadapost\.)/ Nov 9 22:27:04.050 [4818] dbg: uri: running T_AJB_UTUBE_BADLINK Nov 9 22:27:04.050 [4818] dbg: uri: raw matched: 'http://www.northomeau.com/asset/doc/uW25J4endN4EZGziA3d2iN/rak?b=144465' !~ /(?-xism:youtube\.)/ Nov 9 22:27:04.050 [4818] dbg: uri: running T_AJB_UTUBE_BADLINK Nov 9 22:27:04.050 [4818] dbg: uri: raw matched: 'http://www.northomeau.com/choose-home-options/kBmTQ8nxApDL3MFnpLerZV8563/home-987578434/rak-act87/start/index.httml' !~ /(?-xism:youtube\.)/ Nov 9 22:27:04.050 [4818] dbg: rules: ran eval rule __ANY_TEXT_ATTACH_DOC ======> got hit (1) Nov 9 22:27:04.052 [4818] dbg: rules: ran eval rule BAYES_50 ======> got hit (1) Nov 9 22:27:04.052 [4818] dbg: rules: ran eval rule __ANY_TEXT_ATTACH ======> got hit (1) Nov 9 22:27:04.052 [4818] dbg: rules: ran eval rule __TVD_MIME_ATT_TP ======> got hit (1) Nov 9 22:27:04.052 [4818] dbg: uri: running T_KHOP_FOREIGN_CLICK Nov 9 22:27:04.052 [4818] dbg: uri: running T_KHOP_FOREIGN_CLICK Nov 9 22:27:04.053 [4818] dbg: rules: running rawbody tests; score so far=4.225 Nov 9 22:27:04.055 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60143 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_0_1 Nov 9 22:27:04.060 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 1792 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_0_2 Nov 9 22:27:04.060 [4818] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 0 Nov 9 22:27:04.060 [4818] dbg: rules: compiled rawbody tests Nov 9 22:27:04.061 [4818] dbg: rules: running rawbody_eval tests; score so far=4.225 Nov 9 22:27:04.061 [4818] dbg: rules: run_eval_tests - compiling eval code: 15, priority 0 Nov 9 22:27:04.061 [4818] dbg: rules: running full tests; score so far=4.225 Nov 9 22:27:04.061 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 2710 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_0_1 Nov 9 22:27:04.062 [4818] dbg: rules: run_generic_tests - compiling eval code: full, priority 0 Nov 9 22:27:04.062 [4818] dbg: rules: compiled full tests Nov 9 22:27:04.062 [4818] dbg: rules: running full_eval tests; score so far=4.225 Nov 9 22:27:04.062 [4818] dbg: rules: run_eval_tests - compiling eval code: 13, priority 0 Nov 9 22:27:04.063 [4818] dbg: dns: entering helper-app run mode Nov 9 22:27:04.806 [4818] dbg: dns: leaving helper-app run mode Nov 9 22:27:04.931 [4818] dbg: razor2: part=0 engine=8 contested=0 confidence=40 Nov 9 22:27:04.932 [4818] dbg: razor2: results: spam? 1 Nov 9 22:27:04.932 [4818] dbg: razor2: results: engine 8, highest cf score: 40 Nov 9 22:27:04.932 [4818] dbg: razor2: results: engine 4, highest cf score: 0 Nov 9 22:27:04.933 [4818] dbg: rules: ran eval rule RAZOR2_CHECK ======> got hit (1) Nov 9 22:27:04.933 [4818] dbg: util: current PATH is: /home/ichudov/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/home/ichudov/bin:/home/ichudov/net-ebay:/home/ichudov/configs/bling Nov 9 22:27:04.933 [4818] dbg: pyzor: pyzor is not available: no pyzor executable found Nov 9 22:27:04.933 [4818] dbg: pyzor: no pyzor found, disabling Pyzor Nov 9 22:27:04.934 [4818] dbg: rules: ran eval rule __DKIM_DEPENDABLE ======> got hit (1) Nov 9 22:27:04.934 [4818] dbg: rules: running meta tests; score so far=5.147 Nov 9 22:27:04.934 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 275 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_0_1 Nov 9 22:27:04.934 [4818] dbg: rules: run_generic_tests - compiling eval code: meta, priority 0 Nov 9 22:27:04.935 [4818] dbg: rules: compiled meta tests Nov 9 22:27:04.935 [4818] dbg: check: running tests for priority: 500 Nov 9 22:27:04.935 [4818] dbg: dns: harvest_dnsbl_queries Nov 9 22:27:04.935 [4818] dbg: async: timing: 0.003 . dns:A:160.177.109.213.iadb.isipp.com. Nov 9 22:27:04.935 [4818] dbg: async: timing: 0.005 . dns:A:160.177.109.213.bb.barracudacentral.org. Nov 9 22:27:04.935 [4818] dbg: async: timing: 0.005 . dns:A:160.177.109.213.list.dnswl.org. Nov 9 22:27:04.935 [4818] dbg: async: timing: 0.006 . dns:A:160.177.109.213.psbl.surriel.com. Nov 9 22:27:04.935 [4818] dbg: async: timing: 0.006 . dns:A:160.177.109.213.bl.score.senderscore.com. Nov 9 22:27:04.935 [4818] dbg: async: timing: 0.007 . dns:A:160.177.109.213.dnsbl.sorbs.net. Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.007 . dns:A:160.177.109.213.zen.spamhaus.org. Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.008 . dns:MX:mx02.northomeau.com Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.008 . dns:A:mx02.northomeau.com Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.009 . dns:A:mx02.northomeau.com.rhsbl.ahbl.org. Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.012 . dns:A:160.177.109.213.combined.njabl.org. Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.013 . NS:northomeau.com Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.014 . DNSBL:dbl.spamhaus.org.:northomeau.com Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.015 . DNSBL:dob.sibl.support-intelligence.net:northomeau.com Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.015 . DNSBL:multi.uribl.com.:northomeau.com Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.016 . DNSBL:multi.surbl.org.:northomeau.com Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.104 . A:dns3.name-services.com. Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.104 . A:dns2.name-services.com. Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.104 . A:dns1.name-services.com. Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.104 . A:dns4.name-services.com. Nov 9 22:27:04.936 [4818] dbg: async: timing: 0.104 . A:dns5.name-services.com. Nov 9 22:27:04.937 [4818] dbg: async: timing: 0.110 . dns:TXT:160.177.109.213.sa-accredit.habeas.com. Nov 9 22:27:04.937 [4818] dbg: async: timing: 0.112 . DNSBL:zen.spamhaus.org.:1.197.124.98 Nov 9 22:27:04.937 [4818] dbg: async: timing: 0.112 . DNSBL:zen.spamhaus.org.:1.192.124.98 Nov 9 22:27:04.937 [4818] dbg: async: timing: 0.112 . DNSBL:zen.spamhaus.org.:1.196.124.98 Nov 9 22:27:04.937 [4818] dbg: async: timing: 0.112 . DNSBL:zen.spamhaus.org.:1.193.124.98 Nov 9 22:27:04.937 [4818] dbg: async: timing: 0.113 . DNSBL:zen.spamhaus.org.:1.194.124.98 Nov 9 22:27:04.937 [4818] dbg: async: timing: 0.118 . dns:TXT:160.177.109.213.bl.spamcop.net. Nov 9 22:27:04.937 [4818] dbg: async: timing: 0.119 . dns:TXT:160.177.109.213.sa-trusted.bondedsender.org. Nov 9 22:27:04.937 [4818] dbg: rules: running head tests; score so far=5.147 Nov 9 22:27:04.937 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 276 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_500_1 Nov 9 22:27:04.937 [4818] dbg: rules: run_generic_tests - compiling eval code: head, priority 500 Nov 9 22:27:04.937 [4818] dbg: rules: compiled head tests Nov 9 22:27:04.937 [4818] dbg: rules: running body tests; score so far=5.147 Nov 9 22:27:04.937 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 219 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_500_1 Nov 9 22:27:04.938 [4818] dbg: rules: run_generic_tests - compiling eval code: body, priority 500 Nov 9 22:27:04.938 [4818] dbg: rules: compiled body tests Nov 9 22:27:04.938 [4818] dbg: rules: running uri tests; score so far=5.147 Nov 9 22:27:04.938 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 217 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_500_1 Nov 9 22:27:04.938 [4818] dbg: rules: run_generic_tests - compiling eval code: uri, priority 500 Nov 9 22:27:04.938 [4818] dbg: rules: compiled uri tests Nov 9 22:27:04.938 [4818] dbg: rules: running rawbody tests; score so far=5.147 Nov 9 22:27:04.938 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_500_1 Nov 9 22:27:04.938 [4818] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 500 Nov 9 22:27:04.938 [4818] dbg: rules: compiled rawbody tests Nov 9 22:27:04.938 [4818] dbg: rules: running full tests; score so far=5.147 Nov 9 22:27:04.938 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 254 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_500_1 Nov 9 22:27:04.938 [4818] dbg: rules: run_generic_tests - compiling eval code: full, priority 500 Nov 9 22:27:04.939 [4818] dbg: rules: compiled full tests Nov 9 22:27:04.939 [4818] dbg: rules: running meta tests; score so far=5.147 Nov 9 22:27:04.939 [4818] dbg: rules: meta test DIGEST_MULTIPLE has undefined dependency 'DCC_CHECK' Nov 9 22:27:04.955 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 60123 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_1 Nov 9 22:27:04.961 [4818] dbg: rules: flush_evalstr (add_evalstr) compiling 62922 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_2 Nov 9 22:27:04.966 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 24618 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_3 Nov 9 22:27:04.967 [4818] dbg: rules: run_generic_tests - compiling eval code: meta, priority 500 Nov 9 22:27:04.967 [4818] dbg: rules: compiled meta tests Nov 9 22:27:04.968 [4818] dbg: check: running tests for priority: 900 Nov 9 22:27:04.968 [4818] dbg: rules: running head tests; score so far=5.94 Nov 9 22:27:04.968 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 276 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_900_1 Nov 9 22:27:04.969 [4818] dbg: rules: run_generic_tests - compiling eval code: head, priority 900 Nov 9 22:27:04.969 [4818] dbg: rules: compiled head tests Nov 9 22:27:04.969 [4818] dbg: rules: running body tests; score so far=5.94 Nov 9 22:27:04.969 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 219 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_900_1 Nov 9 22:27:04.969 [4818] dbg: rules: run_generic_tests - compiling eval code: body, priority 900 Nov 9 22:27:04.969 [4818] dbg: rules: compiled body tests Nov 9 22:27:04.969 [4818] dbg: rules: running uri tests; score so far=5.94 Nov 9 22:27:04.969 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 217 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_900_1 Nov 9 22:27:04.969 [4818] dbg: rules: run_generic_tests - compiling eval code: uri, priority 900 Nov 9 22:27:04.969 [4818] dbg: rules: compiled uri tests Nov 9 22:27:04.969 [4818] dbg: rules: running body_eval tests; score so far=5.94 Nov 9 22:27:04.969 [4818] dbg: rules: run_eval_tests - compiling eval code: 11, priority 900 Nov 9 22:27:04.970 [4818] dbg: FuzzyOcr: Starting FuzzyOcr... Nov 9 22:27:04.970 [4818] info: FuzzyOcr: Processing Message with ID "" ("Monthly Home Payment Reduction" -> ) Nov 9 22:27:04.970 [4818] dbg: FuzzyOcr: Skipping OCR, no image files found... Nov 9 22:27:04.970 [4818] dbg: FuzzyOcr: Processed in 0.000149 sec. Nov 9 22:27:04.970 [4818] dbg: rules: running rawbody tests; score so far=5.94 Nov 9 22:27:04.970 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_900_1 Nov 9 22:27:04.970 [4818] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 900 Nov 9 22:27:04.970 [4818] dbg: rules: compiled rawbody tests Nov 9 22:27:04.970 [4818] dbg: rules: running full tests; score so far=5.94 Nov 9 22:27:04.970 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 254 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_900_1 Nov 9 22:27:04.970 [4818] dbg: rules: run_generic_tests - compiling eval code: full, priority 900 Nov 9 22:27:04.970 [4818] dbg: rules: compiled full tests Nov 9 22:27:04.971 [4818] dbg: rules: running meta tests; score so far=5.94 Nov 9 22:27:04.971 [4818] dbg: rules: flush_evalstr (run_generic_tests) compiling 279 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_900_1 Nov 9 22:27:04.971 [4818] dbg: rules: run_generic_tests - compiling eval code: meta, priority 900 Nov 9 22:27:04.971 [4818] dbg: rules: compiled meta tests Nov 9 22:27:04.971 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::AutoLearnThreshold=HASH(0x921a7a0) implements 'autolearn_discriminator', priority 0 Nov 9 22:27:04.971 [4818] dbg: learn: auto-learn: currently using scoreset 3, recomputing score based on scoreset 1 Nov 9 22:27:04.971 [4818] dbg: learn: auto-learn: message score: 5.94, computed score for autolearn: 6.478 Nov 9 22:27:04.971 [4818] dbg: learn: auto-learn? ham=0.1, spam=12, body-points=5.204, head-points=1.729, learned-points=0.8 Nov 9 22:27:04.971 [4818] dbg: learn: auto-learn? no: inside auto-learn thresholds, not considered ham or spam Nov 9 22:27:04.971 [4818] dbg: check: is spam? score=5.94 required=3 Nov 9 22:27:04.971 [4818] dbg: check: tests=BAYES_50,RAZOR2_CHECK,RDNS_NONE,URIBL_BLACK,URIBL_DBL_SPAM Nov 9 22:27:04.971 [4818] dbg: check: subtests=__ANY_TEXT_ATTACH,__ANY_TEXT_ATTACH_DOC,__CT,__CTE,__CTYPE_CHARSET_QUOTED,__CT_TEXT_PLAIN,__DKIM_DEPENDABLE,__DOS_DIRECT_TO_MX,__DOS_HAS_ANY_URI,__DOS_LINK,__DOS_RCVD_FRI,__DOS_SINGLE_EXT_RELAY,__ENV_AND_HDR_FROM_MATCH,__HAS_ANY_URI,__HAS_DATE,__HAS_FROM,__HAS_MESSAGE_ID,__HAS_MSGID,__HAS_RCVD,__HAS_SUBJECT,__HAS_TO,__HAS_URI,__LAST_EXTERNAL_RELAY_NO_AUTH,__LAST_UNTRUSTED_RELAY_NO_AUTH,__MBA,__MIME_VERSION,__MISSING_REF,__MISSING_REPLY,__NONEMPTY_BODY,__RDNS_NONE,__SANE_MSGID,__TOCC_EXISTS,__TVD_MIME_ATT_TP Nov 9 22:27:04.972 [4818] dbg: timing: total 2031 ms - init: 764 (37.6%), parse: 0.62 (0.0%), extract_message_metadata: 87 (4.3%), poll_dns_idle: 70 (3.5%), get_uri_detail_list: 3 (0.1%), tests_pri_-1000: 9 (0.5%), compile_gen: 78 (3.8%), compile_eval: 12 (0.6%), tests_pri_-950: 2 (0.1%), tests_pri_-900: 3 (0.1%), tests_pri_-400: 13 (0.6%), check_bayes: 11 (0.5%), tests_pri_0: 1104 (54.3%), dkim_load_modules: 12 (0.6%), check_dkim_signature: 0.34 (0.0%), check_dkim_adsp: 1.78 (0.1%), check_spf: 20 (1.0%), check_razor2: 869 (42.8%), check_pyzor: 0.50 (0.0%), tests_pri_500: 33 (1.6%), tests_pri_900: 3 (0.1%) From ElizabethNunez@mx02.northomeau.com Fri Nov 9 22:17:21 2012 Received: from localhost by bling with SpamAssassin (version 3.3.1); Fri, 09 Nov 2012 22:27:04 -0600 From: "Monthly Home Payment Reduction" To: Subject: Find out how to chop your monthly payment in half Date: Fri, 09 Nov 2012 20:15:21 -0800 Message-Id: X-Spam-Checker-Version: SpamAssassin 3.3.1 (2010-03-16) on bling X-Spam-Flag: YES X-Spam-Level: ***** X-Spam-Status: Yes, score=5.9 required=3.0 tests=BAYES_50,RAZOR2_CHECK, RDNS_NONE,URIBL_BLACK,URIBL_DBL_SPAM autolearn=no version=3.3.1 MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----------=_509DD798.14D208D6" This is a multi-part message in MIME format. ------------=_509DD798.14D208D6 Content-Type: text/plain; charset=iso-8859-1 Content-Disposition: inline Content-Transfer-Encoding: 8bit Spam detection software, running on the system "bling", has identified this incoming email as possible spam. The original message has been attached to this so you can view it (if it isn't spam) or label similar future email. If you have any questions, see the administrator of that system for details. Content preview: The time is now. To take advantage of rates that are so very low that they have not been seen in this range since the 1950's. Between the incredible financial situation we are in, and the fact that the government has many different programs designed to help homeowners get out from under the crushing weight of their current situation, there is no better time to act. [...] Content analysis details: (5.9 points, 3.0 required) pts rule name description ---- ---------------------- -------------------------------------------------- 1.7 URIBL_DBL_SPAM Contains an URL listed in the DBL blocklist [URIs: northomeau.com] 1.7 URIBL_BLACK Contains an URL listed in the URIBL blacklist [URIs: northomeau.com] 0.8 BAYES_50 BODY: Bayes spam probability is 40 to 60% [score: 0.5008] 0.9 RAZOR2_CHECK Listed in Razor2 (http://razor.sf.net/) 0.8 RDNS_NONE Delivered to internal network by a host with no rDNS ------------=_509DD798.14D208D6 Content-Type: message/rfc822; x-spam-type=original Content-Description: original message before SpamAssassin Content-Disposition: inline Content-Transfer-Encoding: 8bit Return-Path: X-Spam-Checker-Version: SpamAssassin 3.3.1 (2010-03-16) on bling X-Spam-Level: * X-Spam-Status: No, score=1.6 required=3.0 tests=BAYES_50,RDNS_NONE autolearn=no version=3.3.1 Received: from orca.chudov.com (netsource-hosting.chudov.com [65.182.171.171]) by bling.chudov.com (8.14.3/8.14.3/Debian-9.1ubuntu1) with ESMTP id qAA4HJLY020244 for ; Fri, 9 Nov 2012 22:17:20 -0600 Received: from mx02.northomeau.com ([213.109.177.160]) by orca.chudov.com (8.14.2/8.14.2/Debian-2build1) with ESMTP id qAA4HD1H028112 for ; Fri, 9 Nov 2012 22:17:14 -0600 Content-Type: text/plain; charset="us-ascii" MIME-Version: 1.0 Content-Transfer-Encoding: 7bit To: From: "Monthly Home Payment Reduction" Subject: Find out how to chop your monthly payment in half Date: Fri, 09 Nov 2012 20:15:21 -0800 Message-ID: Status: RO Content-Length: 1622 Lines: 45 The time is now. To take advantage of rates that are so very low that they have not been seen in this range since the 1950's. Between the incredible financial situation we are in, and the fact that the government has many different programs designed to help homeowners get out from under the crushing weight of their current situation, there is no better time to act. Find out what you can do. http://www.northomeau.com/choose-home-options/kBmTQ8nxApDL3MFnpLerZV8563/home-987578434/rak-act87/start/indexNov 9 22:27:04.974 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::MIMEHeader=HASH(0x922d538) implements 'finish_tests', priority 0 Nov 9 22:27:04.974 [4818] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x92de6c8) implements 'finish_tests', priority 0 .httml You'd rather not see anymore communications from Alstar-Whitley Data Partners? Located at: 2415 Sweet Forest ST San Antonio, Texas, 78251-2413 Then please feel free to either pen us at the address listed above or please use this navigational link. Quick and easy process, expect to be out within twenty four hours. http://www.northomeau.com/asset/doc/uW25J4endN4EZGziA3d2iN/rak?b=144465 Archicembalo, Reproduction of the ArchicembaloThe Archicembalo (?/rkitmblo/) was a musical instrument constructed by Nicola Vicentino in 1555. This was a harpsichord built with many extra keys and strings, enabling experimentation in microtonality and just intonation.Contents1 Construction2 Tuning3 Uses4 Spelling and pronunciation5 Surviving archicembali6 References7 External links ConstructionThe Archicembalo had two manuals, but unlike those on a normal harpsichord these two keyboards were used to provide extra pitches rather than a timbral difference. Both manuals contained all of the usual white and black keys, but in addition each black key was divided into two ------------=_509DD798.14D208D6--