Oct 17 12:23:44.211 [12466] dbg: logger: adding facilities: all Oct 17 12:23:44.211 [12466] dbg: logger: logging level is DBG Oct 17 12:23:44.211 [12466] dbg: generic: SpamAssassin version 3.3.1 Oct 17 12:23:44.211 [12466] dbg: generic: Perl 5.010001, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin Oct 17 12:23:44.211 [12466] dbg: config: timing enabled Oct 17 12:23:44.212 [12466] dbg: config: score set 0 chosen. Oct 17 12:23:44.212 [12466] dbg: util: running in taint mode? yes Oct 17 12:23:44.212 [12466] dbg: util: taint mode: deleting unsafe environment variables, resetting PATH Oct 17 12:23:44.212 [12466] dbg: util: PATH included '/usr/local/sbin', keeping Oct 17 12:23:44.212 [12466] dbg: util: PATH included '/usr/local/bin', keeping Oct 17 12:23:44.212 [12466] dbg: util: PATH included '/usr/sbin', keeping Oct 17 12:23:44.212 [12466] dbg: util: PATH included '/usr/bin', keeping Oct 17 12:23:44.213 [12466] dbg: util: PATH included '/sbin', keeping Oct 17 12:23:44.213 [12466] dbg: util: PATH included '/bin', keeping Oct 17 12:23:44.213 [12466] dbg: util: PATH included '/usr/games', keeping Oct 17 12:23:44.213 [12466] dbg: util: PATH included '/home/ichudov/bin', keeping Oct 17 12:23:44.213 [12466] dbg: util: PATH included '/home/ichudov/net-ebay', keeping Oct 17 12:23:44.213 [12466] dbg: util: PATH included '/home/ichudov/configs/bling', keeping Oct 17 12:23:44.213 [12466] dbg: util: final PATH set to: /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 Oct 17 12:23:44.215 [12466] dbg: dns: is Net::DNS::Resolver available? yes Oct 17 12:23:44.215 [12466] dbg: dns: Net::DNS version: 0.65 Oct 17 12:23:44.215 [12466] dbg: config: using "/etc/spamassassin" for site rules pre files Oct 17 12:23:44.215 [12466] dbg: config: read file /etc/spamassassin/init.pre Oct 17 12:23:44.215 [12466] dbg: config: read file /etc/spamassassin/v310.pre Oct 17 12:23:44.215 [12466] dbg: config: read file /etc/spamassassin/v312.pre Oct 17 12:23:44.215 [12466] dbg: config: read file /etc/spamassassin/v320.pre Oct 17 12:23:44.216 [12466] dbg: config: read file /etc/spamassassin/v330.pre Oct 17 12:23:44.216 [12466] dbg: config: using "/var/lib/spamassassin/3.003001" for sys rules pre files Oct 17 12:23:44.216 [12466] dbg: config: using "/var/lib/spamassassin/3.003001" for default rules dir Oct 17 12:23:44.216 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org.cf Oct 17 12:23:44.216 [12466] dbg: config: using "/etc/spamassassin" for site rules dir Oct 17 12:23:44.216 [12466] dbg: config: read file /etc/spamassassin/65_debian.cf Oct 17 12:23:44.216 [12466] dbg: config: read file /etc/spamassassin/70_sare_evilnum0.cf Oct 17 12:23:44.216 [12466] dbg: config: read file /etc/spamassassin/70_sare_random.cf Oct 17 12:23:44.216 [12466] dbg: config: read file /etc/spamassassin/FuzzyOcr.cf Oct 17 12:23:44.216 [12466] dbg: config: read file /etc/spamassassin/local.cf Oct 17 12:23:44.216 [12466] dbg: config: read file /etc/spamassassin/tripwire.cf Oct 17 12:23:44.216 [12466] dbg: config: using "/home/ichudov/.spamassassin" for user state dir Oct 17 12:23:44.216 [12466] dbg: config: using "/home/ichudov/.spamassassin/user_prefs" for user prefs file Oct 17 12:23:44.216 [12466] dbg: config: read file /home/ichudov/.spamassassin/user_prefs Oct 17 12:23:44.219 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC Oct 17 12:23:44.227 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC Oct 17 12:23:44.235 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC Oct 17 12:23:44.237 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC Oct 17 12:23:44.239 [12466] dbg: pyzor: network tests on, attempting Pyzor Oct 17 12:23:44.239 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC Oct 17 12:23:44.269 [12466] dbg: razor2: razor2 is available, version 2.84 Oct 17 12:23:44.269 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC Oct 17 12:23:44.274 [12466] dbg: reporter: network tests on, attempting SpamCop Oct 17 12:23:44.274 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC Oct 17 12:23:44.275 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC Oct 17 12:23:44.275 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC Oct 17 12:23:44.276 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC Oct 17 12:23:44.277 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::DKIM from @INC Oct 17 12:23:44.279 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC Oct 17 12:23:44.282 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC Oct 17 12:23:44.282 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC Oct 17 12:23:44.283 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC Oct 17 12:23:44.287 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC Oct 17 12:23:44.288 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC Oct 17 12:23:44.289 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC Oct 17 12:23:44.290 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC Oct 17 12:23:44.296 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC Oct 17 12:23:44.300 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC Oct 17 12:23:44.302 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC Oct 17 12:23:44.303 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC Oct 17 12:23:44.304 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC Oct 17 12:23:44.305 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC Oct 17 12:23:44.306 [12466] dbg: plugin: loading Mail::SpamAssassin::Plugin::FreeMail from @INC Oct 17 12:23:44.307 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf Oct 17 12:23:44.307 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf" for included file Oct 17 12:23:44.307 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_default_prefs.cf Oct 17 12:23:44.309 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_hasbase.cf Oct 17 12:23:44.309 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/10_hasbase.cf" for included file Oct 17 12:23:44.309 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/10_hasbase.cf Oct 17 12:23:44.310 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf Oct 17 12:23:44.310 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf" for included file Oct 17 12:23:44.310 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_advance_fee.cf Oct 17 12:23:44.312 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf Oct 17 12:23:44.312 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf" for included file Oct 17 12:23:44.312 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_aux_tlds.cf Oct 17 12:23:44.317 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf Oct 17 12:23:44.317 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf" for included file Oct 17 12:23:44.317 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_body_tests.cf Oct 17 12:23:44.319 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf Oct 17 12:23:44.319 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf" for included file Oct 17 12:23:44.319 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_compensate.cf Oct 17 12:23:44.320 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf Oct 17 12:23:44.320 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf" for included file Oct 17 12:23:44.320 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dnsbl_tests.cf Oct 17 12:23:44.322 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf Oct 17 12:23:44.322 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf" for included file Oct 17 12:23:44.322 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_drugs.cf Oct 17 12:23:44.327 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf Oct 17 12:23:44.327 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf" for included file Oct 17 12:23:44.327 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_dynrdns.cf Oct 17 12:23:44.330 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_fake_helo_tests.cf Oct 17 12:23:44.330 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_fake_helo_tests.cf" for included file Oct 17 12:23:44.330 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_fake_helo_tests.cf Oct 17 12:23:44.331 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf Oct 17 12:23:44.331 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf" for included file Oct 17 12:23:44.331 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail.cf Oct 17 12:23:44.332 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_domains.cf Oct 17 12:23:44.332 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_domains.cf" for included file Oct 17 12:23:44.333 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_freemail_domains.cf Oct 17 12:23:44.333 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0xa0b0240) implements 'parse_config', priority 0 Oct 17 12:23:44.347 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf Oct 17 12:23:44.347 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf" for included file Oct 17 12:23:44.347 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_head_tests.cf Oct 17 12:23:44.360 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf Oct 17 12:23:44.360 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf" for included file Oct 17 12:23:44.360 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_html_tests.cf Oct 17 12:23:44.363 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf Oct 17 12:23:44.363 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf" for included file Oct 17 12:23:44.363 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_imageinfo.cf Oct 17 12:23:44.364 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_mailspike.cf Oct 17 12:23:44.364 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_mailspike.cf" for included file Oct 17 12:23:44.364 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_mailspike.cf Oct 17 12:23:44.365 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf Oct 17 12:23:44.365 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf" for included file Oct 17 12:23:44.365 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_meta_tests.cf Oct 17 12:23:44.366 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf Oct 17 12:23:44.366 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf" for included file Oct 17 12:23:44.366 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_net_tests.cf Oct 17 12:23:44.367 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf Oct 17 12:23:44.367 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf" for included file Oct 17 12:23:44.367 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_phrases.cf Oct 17 12:23:44.370 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf Oct 17 12:23:44.370 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf" for included file Oct 17 12:23:44.370 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_porn.cf Oct 17 12:23:44.371 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf Oct 17 12:23:44.371 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf" for included file Oct 17 12:23:44.371 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_ratware.cf Oct 17 12:23:44.380 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf Oct 17 12:23:44.380 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf" for included file Oct 17 12:23:44.380 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_uri_tests.cf Oct 17 12:23:44.385 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf Oct 17 12:23:44.385 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf" for included file Oct 17 12:23:44.385 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/20_vbounce.cf Oct 17 12:23:44.393 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf Oct 17 12:23:44.393 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf" for included file Oct 17 12:23:44.393 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/23_bayes.cf Oct 17 12:23:44.394 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf Oct 17 12:23:44.394 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf" for included file Oct 17 12:23:44.394 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_accessdb.cf Oct 17 12:23:44.394 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf Oct 17 12:23:44.394 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf" for included file Oct 17 12:23:44.394 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_antivirus.cf Oct 17 12:23:44.395 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf Oct 17 12:23:44.395 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf" for included file Oct 17 12:23:44.395 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_asn.cf Oct 17 12:23:44.395 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf Oct 17 12:23:44.395 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf" for included file Oct 17 12:23:44.395 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dcc.cf Oct 17 12:23:44.395 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf Oct 17 12:23:44.395 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf" for included file Oct 17 12:23:44.395 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_dkim.cf Oct 17 12:23:44.397 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf Oct 17 12:23:44.397 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf" for included file Oct 17 12:23:44.397 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_hashcash.cf Oct 17 12:23:44.397 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf Oct 17 12:23:44.397 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf" for included file Oct 17 12:23:44.397 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_pyzor.cf Oct 17 12:23:44.398 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf Oct 17 12:23:44.398 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf" for included file Oct 17 12:23:44.398 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_razor2.cf Oct 17 12:23:44.398 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf Oct 17 12:23:44.398 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf" for included file Oct 17 12:23:44.399 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_replace.cf Oct 17 12:23:44.402 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf Oct 17 12:23:44.402 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf" for included file Oct 17 12:23:44.402 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_spf.cf Oct 17 12:23:44.403 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf Oct 17 12:23:44.403 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf" for included file Oct 17 12:23:44.403 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_textcat.cf Oct 17 12:23:44.403 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf Oct 17 12:23:44.403 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf" for included file Oct 17 12:23:44.403 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/25_uribl.cf Oct 17 12:23:44.406 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf Oct 17 12:23:44.406 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf" for included file Oct 17 12:23:44.406 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_de.cf Oct 17 12:23:44.408 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf Oct 17 12:23:44.408 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf" for included file Oct 17 12:23:44.408 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_fr.cf Oct 17 12:23:44.409 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf Oct 17 12:23:44.409 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf" for included file Oct 17 12:23:44.409 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_it.cf Oct 17 12:23:44.410 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf Oct 17 12:23:44.410 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf" for included file Oct 17 12:23:44.410 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_nl.cf Oct 17 12:23:44.411 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf Oct 17 12:23:44.411 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf" for included file Oct 17 12:23:44.411 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pl.cf Oct 17 12:23:44.415 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf Oct 17 12:23:44.415 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf" for included file Oct 17 12:23:44.416 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/30_text_pt_br.cf Oct 17 12:23:44.423 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf Oct 17 12:23:44.423 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf" for included file Oct 17 12:23:44.423 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/50_scores.cf Oct 17 12:23:44.442 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_adsp_override_dkim.cf Oct 17 12:23:44.442 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_adsp_override_dkim.cf" for included file Oct 17 12:23:44.442 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_adsp_override_dkim.cf Oct 17 12:23:44.443 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf Oct 17 12:23:44.443 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf" for included file Oct 17 12:23:44.443 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_awl.cf Oct 17 12:23:44.444 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf Oct 17 12:23:44.444 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf" for included file Oct 17 12:23:44.444 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_shortcircuit.cf Oct 17 12:23:44.444 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf Oct 17 12:23:44.444 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf" for included file Oct 17 12:23:44.444 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist.cf Oct 17 12:23:44.445 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf Oct 17 12:23:44.445 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf" for included file Oct 17 12:23:44.445 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_dkim.cf Oct 17 12:23:44.447 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf Oct 17 12:23:44.447 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf" for included file Oct 17 12:23:44.447 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_spf.cf Oct 17 12:23:44.448 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_subject.cf Oct 17 12:23:44.448 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_subject.cf" for included file Oct 17 12:23:44.448 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/60_whitelist_subject.cf Oct 17 12:23:44.448 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf Oct 17 12:23:44.448 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf" for included file Oct 17 12:23:44.449 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_active.cf Oct 17 12:23:44.518 [12466] 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 Oct 17 12:23:44.518 [12466] dbg: config: uri_detail added T_KHOP_FOREIGN_CLICK Oct 17 12:23:44.520 [12466] dbg: config: uri_detail adding (raw !~ /(?-xism:canadapost\.)/) to T_AJB_CANPOST_BADLINK Oct 17 12:23:44.520 [12466] dbg: config: uri_detail adding (text =~ /(?-xism:(?:https?:\/\/|www\.)canadapost\.)/) to T_AJB_CANPOST_BADLINK Oct 17 12:23:44.520 [12466] dbg: config: uri_detail adding (type =~ /(?-xism:^a$)/) to T_AJB_CANPOST_BADLINK Oct 17 12:23:44.520 [12466] dbg: config: uri_detail added T_AJB_CANPOST_BADLINK Oct 17 12:23:44.520 [12466] dbg: config: uri_detail adding (raw !~ /(?-xism:youtube\.)/) to T_AJB_UTUBE_BADLINK Oct 17 12:23:44.520 [12466] dbg: config: uri_detail adding (text =~ /(?-xism:(?:https?:\/\/|www\.)youtube\.)/) to T_AJB_UTUBE_BADLINK Oct 17 12:23:44.520 [12466] dbg: config: uri_detail adding (type =~ /(?-xism:^a$)/) to T_AJB_UTUBE_BADLINK Oct 17 12:23:44.520 [12466] dbg: config: uri_detail added T_AJB_UTUBE_BADLINK Oct 17 12:23:44.562 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf Oct 17 12:23:44.562 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf" for included file Oct 17 12:23:44.562 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/72_scores.cf Oct 17 12:23:44.566 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/73_sandbox_manual_scores.cf Oct 17 12:23:44.566 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/73_sandbox_manual_scores.cf" for included file Oct 17 12:23:44.567 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/73_sandbox_manual_scores.cf Oct 17 12:23:44.567 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf Oct 17 12:23:44.567 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf" for included file Oct 17 12:23:44.567 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/local.cf Oct 17 12:23:44.567 [12466] dbg: config: fixed relative path: /var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf Oct 17 12:23:44.567 [12466] dbg: config: using "/var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf" for included file Oct 17 12:23:44.567 [12466] dbg: config: read file /var/lib/spamassassin/3.003001/updates_spamassassin_org/regression_tests.cf Oct 17 12:23:44.584 [12466] dbg: plugin: loading FuzzyOcr from @INC Oct 17 12:23:44.638 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0xa0b0240) implements 'parse_config', priority 0 Oct 17 12:23:44.638 [12466] dbg: plugin: FuzzyOcr=HASH(0xa71e9a8) implements 'parse_config', priority 0 Oct 17 12:23:44.638 [12466] dbg: FuzzyOcr: focr_bin_helper: 'pnmnorm,pnminvert,ppmtopgm' Oct 17 12:23:44.638 [12466] info: FuzzyOcr: Adding <3> new helper apps Oct 17 12:23:44.638 [12466] dbg: FuzzyOcr: focr_bin_helper: 'tesseract' Oct 17 12:23:44.638 [12466] info: FuzzyOcr: Adding <1> new helper apps Oct 17 12:23:44.638 [12466] info: FuzzyOcr: Starting preprocessor parser for file "/etc/mail/spamassassin/FuzzyOcr.preps"... Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: preprocessor normalize { Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: command = pnmnorm Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: } Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: preprocessor invert { Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: command = pnminvert Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: } Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: preprocessor ppmtopgm { Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: command = ppmtopgm Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: } Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: preprocessor maketiff { Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: command = pnmtotiff Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: args = -color -truecolor Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line: } Oct 17 12:23:44.639 [12466] info: FuzzyOcr: Starting scanset parser for file "/etc/mail/spamassassin/FuzzyOcr.scansets"... Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line scanset ocrad { Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line command = $ocrad Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line args = -s5 $input Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line } Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line scanset ocrad-invert { Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line command = $ocrad Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line args = -s5 -i $input Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line } Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line scanset ocrad-decolorize-invert { Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line preprocessors = ppmtopgm Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line command = $ocrad Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line args = -s5 -i $input Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line } Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line scanset ocrad-decolorize { Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line preprocessors = ppmtopgm Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line command = $ocrad Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line args = -s5 $input Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line } Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line scanset gocr { Oct 17 12:23:44.639 [12466] dbg: FuzzyOcr: line command = $gocr Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line args = -i $input Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line } Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line scanset gocr-180 { Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line command = $gocr Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line args = -l 180 -d 2 -i $input Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line } Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line scanset tesseract { Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line preprocessors = maketiff Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line command = $tesseract Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line args = $input $output Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line force_output_in = $output.txt Oct 17 12:23:44.640 [12466] dbg: FuzzyOcr: line } Oct 17 12:23:44.640 [12466] dbg: config: allowing user rules! Oct 17 12:23:44.701 [12466] warn: netset: cannot include 127.0.0.1/32 as it has already been included Oct 17 12:23:44.701 [12466] info: config: failed to parse line, skipping, in "/home/ichudov/.spamassassin/user_prefs": use_auto_whitelist 0 Oct 17 12:23:45.054 [12466] dbg: config: finish parsing Oct 17 12:23:45.065 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x9e41120) implements 'finish_parsing_end', priority 0 Oct 17 12:23:45.065 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0xa0b0240) implements 'finish_parsing_end', priority 0 Oct 17 12:23:45.065 [12466] dbg: plugin: FuzzyOcr=HASH(0xa71e9a8) implements 'finish_parsing_end', priority 0 Oct 17 12:23:45.065 [12466] dbg: replacetags: replacing tags Oct 17 12:23:45.078 [12466] dbg: replacetags: done replacing tags Oct 17 12:23:45.078 [12466] dbg: FreeMail: loaded freemail_domains entries: 2112 normal, 26 wildcard Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Searching in: /usr/local/netpbm/bin Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Searching in: /usr/local/bin Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Searching in: /usr/bin Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using gifsicle => /usr/bin/gifsicle Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using giffix => /usr/bin/giffix Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using giftext => /usr/bin/giftext Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using gifinter => /usr/bin/gifinter Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using giftopnm => /usr/bin/giftopnm Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using jpegtopnm => /usr/bin/jpegtopnm Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using pngtopnm => /usr/bin/pngtopnm Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using bmptopnm => /usr/bin/bmptopnm Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using tifftopnm => /usr/bin/tifftopnm Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using ppmhist => /usr/bin/ppmhist Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using pamfile => /usr/bin/pamfile Oct 17 12:23:45.078 [12466] dbg: FuzzyOcr: Cannot find executable for ocrad Oct 17 12:23:45.078 [12466] info: FuzzyOcr: Using gocr => /usr/bin/gocr Oct 17 12:23:45.079 [12466] info: FuzzyOcr: Using pnmnorm => /usr/bin/pnmnorm Oct 17 12:23:45.079 [12466] info: FuzzyOcr: Using pnminvert => /usr/bin/pnminvert Oct 17 12:23:45.079 [12466] info: FuzzyOcr: Using ppmtopgm => /usr/bin/ppmtopgm Oct 17 12:23:45.079 [12466] info: FuzzyOcr: Using tesseract => /usr/bin/tesseract Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: Threshold[max_hash] => 5 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: Threshold[c] => 5 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: Threshold[s] => 0.01 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: Threshold[w] => 0.01 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: Threshold[h] => 0.01 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: Threshold[cn] => 0.01 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_add_score => 1 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_autodisable_negative_score => -5 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_autodisable_score => 1000 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_autosort_buffer => 10 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_autosort_scanset => 1 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_base_score => 5 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_corrupt_score => 2.5 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_corrupt_unfixable_score => 5 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_counts_required => 2 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_db_hash => /etc/mail/spamassassin/FuzzyOcr.db Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_db_max_days => 35 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_db_safe => /etc/mail/spamassassin/FuzzyOcr.safe.db Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_digest_db => /etc/mail/spamassassin/FuzzyOcr.hashdb Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_enable_image_hashing => 0 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_global_timeout => 0 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_global_wordlist => /etc/mail/spamassassin/FuzzyOcr.words Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_hashing_learn_scanned => 1 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_keep_bad_images => 0 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_log_pmsinfo => 1 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_log_stderr => 1 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_max_height => 800 Oct 17 12:23:45.079 [12466] dbg: FuzzyOcr: focr_max_width => 800 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_min_height => 4 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_min_width => 4 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_minimal_scanset => 1 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_mysql_db => FuzzyOcr Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_mysql_hash => Hash Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_mysql_host => localhost Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_mysql_port => 3306 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_mysql_safe => Safe Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_mysql_update_hash => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_mysql_user => fuzzyocr Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_no_homedirs => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_path_bin => /usr/local/netpbm/bin:/usr/local/bin:/usr/bin Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_pdf_maxpages => 1 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_personal_wordlist => __userstate__/FuzzyOcr.words Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_preprocessor_file => /etc/mail/spamassassin/FuzzyOcr.preps Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_scan_pdfs => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_scanset_file => /etc/mail/spamassassin/FuzzyOcr.scansets Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_score_ham => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_skip_bmp => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_skip_gif => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_skip_jpeg => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_skip_png => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_skip_tiff => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_skip_updates => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_strip_numbers => 1 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_threshold => 0.25 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_timeout => 10 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_twopass_scoring_factor => 1.5 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_unique_matches => 0 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_verbose => 1 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_wrongctype_score => 1.5 Oct 17 12:23:45.080 [12466] dbg: FuzzyOcr: focr_wrongext_score => 1.5 Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Loaded preprocessor normalize: /usr/bin/pnmnorm Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Loaded preprocessor invert: /usr/bin/pnminvert Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Loaded preprocessor ppmtopgm: /usr/bin/ppmtopgm Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Loaded preprocessor maketiff: pnmtotiff -color -truecolor Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Using scan ocrad: $ocrad -s5 $input Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Using scan ocrad-invert: $ocrad -s5 -i $input Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Using scan ocrad-decolorize-invert: $ocrad -s5 -i $input Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Using scan ocrad-decolorize: $ocrad -s5 $input Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Using scan gocr: /usr/bin/gocr -i $input Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Using scan gocr-180: /usr/bin/gocr -l 180 -d 2 -i $input Oct 17 12:23:45.080 [12466] info: FuzzyOcr: Using scan tesseract: /usr/bin/tesseract $input $output Oct 17 12:23:45.081 [12466] info: FuzzyOcr: Added <44> words from "/etc/mail/spamassassin/FuzzyOcr.words" Oct 17 12:23:45.081 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0x9f09110) implements 'learner_new', priority 0 Oct 17 12:23:45.081 [12466] dbg: bayes: learner_new self=Mail::SpamAssassin::Plugin::Bayes=HASH(0x9f09110), bayes_store_module=Mail::SpamAssassin::BayesStore::DBM Oct 17 12:23:45.087 [12466] dbg: bayes: learner_new: got store=Mail::SpamAssassin::BayesStore::DBM=HASH(0xa7dd8e0) Oct 17 12:23:45.087 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0x9f09110) implements 'learner_is_scan_available', priority 0 Oct 17 12:23:45.087 [12466] dbg: config: using "/home/ichudov/.spamassassin" for user state dir Oct 17 12:23:45.087 [12466] dbg: bayes: tie-ing to DB file R/O /home/ichudov/.spamassassin/bayes_toks Oct 17 12:23:45.087 [12466] dbg: bayes: tie-ing to DB file R/O /home/ichudov/.spamassassin/bayes_seen Oct 17 12:23:45.088 [12466] dbg: bayes: found bayes db version 3 Oct 17 12:23:45.088 [12466] dbg: bayes: DB journal sync: last sync: 1350493906 Oct 17 12:23:45.088 [12466] dbg: config: using "/home/ichudov/.spamassassin" for user state dir Oct 17 12:23:45.088 [12466] dbg: config: score set 3 chosen. Oct 17 12:23:45.088 [12466] dbg: config: time limit 300.0 s Oct 17 12:23:45.089 [12466] dbg: message: main message type: text/html Oct 17 12:23:45.089 [12466] dbg: check: pms new, time limit in 299.126 s Oct 17 12:23:45.089 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x9f9af50) implements 'check_start', priority 0 Oct 17 12:23:45.089 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x9ee73a8) implements 'check_main', priority 0 Oct 17 12:23:45.089 [12466] 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 Oct 17 12:23:45.090 [12466] 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=q9HHGdrN001467 auth= msa=0 ] Oct 17 12:23:45.090 [12466] dbg: received-header: relay 65.182.171.171 trusted? yes internal? yes msa? no Oct 17 12:23:45.091 [12466] dbg: received-header: parsed as [ ip=176.57.182.157 rdns= helo=mx3c38.moavex.com by=orca.chudov.com ident= envfrom= intl=0 id=q9HHGYQ8007076 auth= msa=0 ] Oct 17 12:23:45.091 [12466] dbg: received-header: relay 176.57.182.157 trusted? no internal? no msa? no Oct 17 12:23:45.091 [12466] 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=q9HHGdrN001467 auth= msa=0 ] Oct 17 12:23:45.092 [12466] dbg: metadata: X-Spam-Relays-Untrusted: [ ip=176.57.182.157 rdns= helo=mx3c38.moavex.com by=orca.chudov.com ident= envfrom= intl=0 id=q9HHGYQ8007076 auth= msa=0 ] Oct 17 12:23:45.092 [12466] 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=q9HHGdrN001467 auth= msa=0 ] Oct 17 12:23:45.092 [12466] dbg: metadata: X-Spam-Relays-External: [ ip=176.57.182.157 rdns= helo=mx3c38.moavex.com by=orca.chudov.com ident= envfrom= intl=0 id=q9HHGYQ8007076 auth= msa=0 ] Oct 17 12:23:45.092 [12466] dbg: message: ---- MIME PARSER START ---- Oct 17 12:23:45.092 [12466] dbg: message: parsing normal part Oct 17 12:23:45.092 [12466] dbg: message: ---- MIME PARSER END ---- Oct 17 12:23:45.092 [12466] dbg: message: decoding other encoding type (7bit), ignoring Oct 17 12:23:45.094 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x9a3dfe0) implements 'parsed_metadata', priority 0 Oct 17 12:23:45.094 [12466] dbg: dns: is_dns_available() last checked 1350494625.1 seconds ago; re-checking Oct 17 12:23:45.095 [12466] dbg: dns: is Net::DNS::Resolver available? yes Oct 17 12:23:45.095 [12466] dbg: dns: Net::DNS version: 0.65 Oct 17 12:23:45.095 [12466] dbg: dns: name server: 127.0.0.1, LocalAddr: 0.0.0.0 Oct 17 12:23:45.095 [12466] dbg: dns: resolver socket rx buffer size is 112640 bytes Oct 17 12:23:45.095 [12466] dbg: dns: testing resolver nameservers: 127.0.0.1, 8.8.8.8, 8.8.4.4 Oct 17 12:23:45.095 [12466] dbg: dns: trying (3) apache.org... Oct 17 12:23:45.095 [12466] dbg: dns: looking up NS for 'apache.org' Oct 17 12:23:45.095 [12466] dbg: dns: providing a callback for id: 41008/apache.org/NS/IN Oct 17 12:23:45.096 [12466] dbg: dns: NS lookup of apache.org using 127.0.0.1 succeeded => DNS available (set dns_available to override) Oct 17 12:23:45.096 [12466] dbg: dns: name server: 8.8.8.8, LocalAddr: 0.0.0.0 Oct 17 12:23:45.096 [12466] dbg: dns: resolver socket rx buffer size is 112640 bytes Oct 17 12:23:45.096 [12466] dbg: dns: trying (3) cingular.com... Oct 17 12:23:45.096 [12466] dbg: dns: looking up NS for 'cingular.com' Oct 17 12:23:45.096 [12466] dbg: dns: providing a callback for id: 8653/cingular.com/NS/IN Oct 17 12:23:45.199 [12466] dbg: dns: NS lookup of cingular.com using 8.8.8.8 succeeded => DNS available (set dns_available to override) Oct 17 12:23:45.199 [12466] dbg: dns: name server: 8.8.4.4, LocalAddr: 0.0.0.0 Oct 17 12:23:45.199 [12466] dbg: dns: resolver socket rx buffer size is 112640 bytes Oct 17 12:23:45.199 [12466] dbg: dns: trying (3) adelphia.net... Oct 17 12:23:45.199 [12466] dbg: dns: looking up NS for 'adelphia.net' Oct 17 12:23:45.200 [12466] dbg: dns: providing a callback for id: 3512/adelphia.net/NS/IN Oct 17 12:23:45.228 [12466] dbg: dns: NS lookup of adelphia.net using 8.8.4.4 succeeded => DNS available (set dns_available to override) Oct 17 12:23:45.228 [12466] dbg: dns: name server: 8.8.4.4, LocalAddr: 0.0.0.0 Oct 17 12:23:45.228 [12466] dbg: dns: resolver socket rx buffer size is 112640 bytes Oct 17 12:23:45.228 [12466] dbg: dns: NS list: 127.0.0.1, 8.8.8.8, 8.8.4.4 Oct 17 12:23:45.229 [12466] dbg: dns: name server: 127.0.0.1, LocalAddr: 0.0.0.0 Oct 17 12:23:45.229 [12466] dbg: dns: resolver socket rx buffer size is 112640 bytes Oct 17 12:23:45.229 [12466] dbg: dns: is DNS available? 1 Oct 17 12:23:45.232 [12466] dbg: uridnsbl: domains to query: moavex.com Oct 17 12:23:45.233 [12466] dbg: dns: providing a callback for id: 49495/moavex.com.multi.surbl.org/A/IN Oct 17 12:23:45.233 [12466] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:moavex.com (timeout 15.0s, min 3.0s) Oct 17 12:23:45.233 [12466] dbg: dns: URIBL_PH_SURBL lookup start Oct 17 12:23:45.233 [12466] dbg: dns: providing a callback for id: 17245/moavex.com.multi.uribl.com/A/IN Oct 17 12:23:45.234 [12466] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:moavex.com (timeout 15.0s, min 3.0s) Oct 17 12:23:45.234 [12466] dbg: dns: URIBL_BLACK lookup start Oct 17 12:23:45.234 [12466] dbg: dns: URIBL_RED lookup start Oct 17 12:23:45.234 [12466] dbg: dns: URIBL_GREY lookup start Oct 17 12:23:45.234 [12466] dbg: dns: providing a callback for id: 15316/moavex.com.dob.sibl.support-intelligence.net/A/IN Oct 17 12:23:45.234 [12466] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:moavex.com (timeout 15.0s, min 3.0s) Oct 17 12:23:45.235 [12466] dbg: dns: URIBL_RHS_DOB lookup start Oct 17 12:23:45.235 [12466] dbg: dns: URIBL_SC_SURBL lookup start Oct 17 12:23:45.235 [12466] dbg: dns: URIBL_JP_SURBL lookup start Oct 17 12:23:45.235 [12466] dbg: dns: URIBL_AB_SURBL lookup start Oct 17 12:23:45.235 [12466] dbg: dns: URIBL_WS_SURBL lookup start Oct 17 12:23:45.235 [12466] dbg: dns: URIBL_OB_SURBL lookup start Oct 17 12:23:45.235 [12466] dbg: dns: URIBL_BLOCKED lookup start Oct 17 12:23:45.235 [12466] dbg: dns: providing a callback for id: 34830/moavex.com.dbl.spamhaus.org/A/IN Oct 17 12:23:45.236 [12466] dbg: async: starting: URI-DNSBL, DNSBL:dbl.spamhaus.org.:moavex.com (timeout 15.0s, min 3.0s) Oct 17 12:23:45.236 [12466] dbg: dns: URIBL_DBL_ERROR lookup start Oct 17 12:23:45.236 [12466] dbg: dns: URIBL_DBL_SPAM lookup start Oct 17 12:23:45.236 [12466] dbg: dns: URIBL_DBL_REDIR lookup start Oct 17 12:23:45.236 [12466] dbg: dns: providing a callback for id: 231/moavex.com/NS/IN Oct 17 12:23:45.237 [12466] dbg: async: starting: URI-NS, NS:moavex.com (timeout 15.0s, min 3.0s) Oct 17 12:23:45.237 [12466] dbg: dns: URIBL_SBL lookup start Oct 17 12:23:45.237 [12466] dbg: dns: checking RBL combined.njabl.org., set njabl Oct 17 12:23:45.237 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.238 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.238 [12466] dbg: dns: launching DNS A query for 157.182.57.176.combined.njabl.org. in background Oct 17 12:23:45.238 [12466] dbg: dns: providing a callback for id: 38356/157.182.57.176.combined.njabl.org/A/IN Oct 17 12:23:45.238 [12466] dbg: async: starting: DNSBL-A, dns:A:157.182.57.176.combined.njabl.org. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.239 [12466] dbg: dns: checking RBL bl.spamcop.net., set spamcop Oct 17 12:23:45.239 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.239 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.239 [12466] dbg: dns: launching DNS TXT query for 157.182.57.176.bl.spamcop.net. in background Oct 17 12:23:45.239 [12466] dbg: dns: providing a callback for id: 16686/157.182.57.176.bl.spamcop.net/TXT/IN Oct 17 12:23:45.239 [12466] dbg: async: starting: DNSBL-TXT, dns:TXT:157.182.57.176.bl.spamcop.net. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.240 [12466] dbg: dns: _check_rbl_addresses RBL rhsbl.ahbl.org., set ahbl Oct 17 12:23:45.240 [12466] dbg: dns: launching DNS A query for mx3c38.moavex.com.rhsbl.ahbl.org. in background Oct 17 12:23:45.240 [12466] dbg: dns: providing a callback for id: 3118/mx3c38.moavex.com.rhsbl.ahbl.org/A/IN Oct 17 12:23:45.240 [12466] dbg: async: starting: DNSBL-A, dns:A:mx3c38.moavex.com.rhsbl.ahbl.org. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.240 [12466] dbg: dns: checking A and MX for host mx3c38.moavex.com Oct 17 12:23:45.240 [12466] dbg: dns: launching DNS A query for mx3c38.moavex.com in background Oct 17 12:23:45.240 [12466] dbg: dns: providing a callback for id: 20228/mx3c38.moavex.com/A/IN Oct 17 12:23:45.240 [12466] dbg: async: starting: NO_DNS_FOR_FROM, DNSBL-A, dns:A:mx3c38.moavex.com (timeout 15.0s, min 3.0s) Oct 17 12:23:45.240 [12466] dbg: dns: launching DNS MX query for mx3c38.moavex.com in background Oct 17 12:23:45.240 [12466] dbg: dns: providing a callback for id: 31270/mx3c38.moavex.com/MX/IN Oct 17 12:23:45.241 [12466] dbg: async: starting: NO_DNS_FOR_FROM, DNSBL-MX, dns:MX:mx3c38.moavex.com (timeout 15.0s, min 3.0s) Oct 17 12:23:45.241 [12466] dbg: dns: checking RBL zen.spamhaus.org., set zen-lastexternal Oct 17 12:23:45.241 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.241 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.241 [12466] dbg: dns: launching DNS A query for 157.182.57.176.zen.spamhaus.org. in background Oct 17 12:23:45.241 [12466] dbg: dns: providing a callback for id: 7807/157.182.57.176.zen.spamhaus.org/A/IN Oct 17 12:23:45.241 [12466] dbg: async: starting: DNSBL-A, dns:A:157.182.57.176.zen.spamhaus.org. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.241 [12466] dbg: dns: checking RBL dnsbl.sorbs.net., set sorbs-lastexternal Oct 17 12:23:45.241 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.241 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.241 [12466] dbg: dns: launching DNS A query for 157.182.57.176.dnsbl.sorbs.net. in background Oct 17 12:23:45.242 [12466] dbg: dns: providing a callback for id: 26676/157.182.57.176.dnsbl.sorbs.net/A/IN Oct 17 12:23:45.242 [12466] dbg: async: starting: DNSBL-A, dns:A:157.182.57.176.dnsbl.sorbs.net. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.242 [12466] dbg: dns: checking RBL dnsbl.sorbs.net., set sorbs Oct 17 12:23:45.242 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.242 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.242 [12466] dbg: dns: checking RBL bl.score.senderscore.com., set rnbl-lastexternal Oct 17 12:23:45.242 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.242 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.242 [12466] dbg: dns: launching DNS A query for 157.182.57.176.bl.score.senderscore.com. in background Oct 17 12:23:45.242 [12466] dbg: dns: providing a callback for id: 50821/157.182.57.176.bl.score.senderscore.com/A/IN Oct 17 12:23:45.242 [12466] dbg: async: starting: DNSBL-A, dns:A:157.182.57.176.bl.score.senderscore.com. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.243 [12466] dbg: dns: checking RBL zen.spamhaus.org., set zen-lastexternal Oct 17 12:23:45.243 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.243 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.243 [12466] dbg: dns: checking RBL psbl.surriel.com., set psbl-lastexternal Oct 17 12:23:45.243 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.243 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.243 [12466] dbg: dns: launching DNS A query for 157.182.57.176.psbl.surriel.com. in background Oct 17 12:23:45.243 [12466] dbg: dns: providing a callback for id: 29734/157.182.57.176.psbl.surriel.com/A/IN Oct 17 12:23:45.243 [12466] dbg: async: starting: DNSBL-A, dns:A:157.182.57.176.psbl.surriel.com. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.243 [12466] dbg: dns: checking RBL list.dnswl.org., set dnswl-firsttrusted Oct 17 12:23:45.243 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.243 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.243 [12466] dbg: dns: launching DNS A query for 157.182.57.176.list.dnswl.org. in background Oct 17 12:23:45.244 [12466] dbg: dns: providing a callback for id: 39833/157.182.57.176.list.dnswl.org/A/IN Oct 17 12:23:45.244 [12466] dbg: async: starting: DNSBL-A, dns:A:157.182.57.176.list.dnswl.org. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.244 [12466] dbg: dns: checking RBL bb.barracudacentral.org., set brbl-lastexternal Oct 17 12:23:45.244 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.244 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.244 [12466] dbg: dns: launching DNS A query for 157.182.57.176.bb.barracudacentral.org. in background Oct 17 12:23:45.244 [12466] dbg: dns: providing a callback for id: 47722/157.182.57.176.bb.barracudacentral.org/A/IN Oct 17 12:23:45.244 [12466] dbg: async: starting: DNSBL-A, dns:A:157.182.57.176.bb.barracudacentral.org. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.244 [12466] dbg: dns: checking RBL sa-trusted.bondedsender.org., set ssc-firsttrusted Oct 17 12:23:45.244 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.244 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.245 [12466] dbg: dns: launching DNS TXT query for 157.182.57.176.sa-trusted.bondedsender.org. in background Oct 17 12:23:45.245 [12466] dbg: dns: providing a callback for id: 21715/157.182.57.176.sa-trusted.bondedsender.org/TXT/IN Oct 17 12:23:45.245 [12466] dbg: async: starting: DNSBL-TXT, dns:TXT:157.182.57.176.sa-trusted.bondedsender.org. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.245 [12466] dbg: dns: checking RBL zen.spamhaus.org., set zen Oct 17 12:23:45.245 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.245 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.245 [12466] dbg: dns: checking RBL sa-accredit.habeas.com., set ssc-firsttrusted Oct 17 12:23:45.245 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.245 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.245 [12466] dbg: dns: launching DNS TXT query for 157.182.57.176.sa-accredit.habeas.com. in background Oct 17 12:23:45.246 [12466] dbg: dns: providing a callback for id: 61586/157.182.57.176.sa-accredit.habeas.com/TXT/IN Oct 17 12:23:45.246 [12466] dbg: async: starting: DNSBL-TXT, dns:TXT:157.182.57.176.sa-accredit.habeas.com. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.246 [12466] dbg: dns: checking RBL iadb.isipp.com., set iadb-firsttrusted Oct 17 12:23:45.246 [12466] dbg: dns: IPs found: full-external: 176.57.182.157 untrusted: 176.57.182.157 originating: Oct 17 12:23:45.246 [12466] dbg: dns: only inspecting the following IPs: 176.57.182.157 Oct 17 12:23:45.246 [12466] dbg: dns: launching DNS A query for 157.182.57.176.iadb.isipp.com. in background Oct 17 12:23:45.246 [12466] dbg: dns: providing a callback for id: 37072/157.182.57.176.iadb.isipp.com/A/IN Oct 17 12:23:45.246 [12466] dbg: async: starting: DNSBL-A, dns:A:157.182.57.176.iadb.isipp.com. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.246 [12466] dbg: check: check_main, time limit in 298.969 s Oct 17 12:23:45.246 [12466] dbg: check: running tests for priority: -1000 Oct 17 12:23:45.248 [12466] dbg: dns: hit 176.57.182.157 Oct 17 12:23:45.249 [12466] dbg: async: select found 16 responses ready (t.o.=0.0) Oct 17 12:23:45.249 [12466] dbg: async: completed in 0.006 s: DNSBL-A, dns:A:157.182.57.176.bl.score.senderscore.com. Oct 17 12:23:45.249 [12466] dbg: async: completed in 0.011 s: URI-NS, NS:moavex.com Oct 17 12:23:45.249 [12466] dbg: dns: providing a callback for id: 43339/dns1.name-services.com/A/IN Oct 17 12:23:45.249 [12466] dbg: async: starting: URI-A, A:dns1.name-services.com. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.249 [12466] dbg: dns: providing a callback for id: 30084/dns4.name-services.com/A/IN Oct 17 12:23:45.250 [12466] dbg: async: starting: URI-A, A:dns4.name-services.com. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.250 [12466] dbg: dns: providing a callback for id: 8733/dns3.name-services.com/A/IN Oct 17 12:23:45.250 [12466] dbg: async: starting: URI-A, A:dns3.name-services.com. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.250 [12466] dbg: dns: providing a callback for id: 55672/dns2.name-services.com/A/IN Oct 17 12:23:45.250 [12466] dbg: async: starting: URI-A, A:dns2.name-services.com. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.250 [12466] dbg: dns: providing a callback for id: 29042/dns5.name-services.com/A/IN Oct 17 12:23:45.250 [12466] dbg: async: starting: URI-A, A:dns5.name-services.com. (timeout 15.0s, min 3.0s) Oct 17 12:23:45.250 [12466] dbg: async: completed in 0.003 s: DNSBL-A, dns:A:157.182.57.176.iadb.isipp.com. Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.005 s: DNSBL-A, dns:A:157.182.57.176.psbl.surriel.com. Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.013 s: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:moavex.com Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.007 s: DNSBL-A, dns:A:157.182.57.176.dnsbl.sorbs.net. Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.014 s: URI-DNSBL, DNSBL:multi.surbl.org.:moavex.com Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.007 s: DNSBL-A, dns:A:157.182.57.176.zen.spamhaus.org. Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.012 s: URI-DNSBL, DNSBL:dbl.spamhaus.org.:moavex.com Oct 17 12:23:45.251 [12466] dbg: uridnsbl: domain "moavex.com" listed (URIBL_DBL_SPAM): 127.0.1.2 Oct 17 12:23:45.251 [12466] dbg: dns: URIBL_DBL_SPAM lookup finished Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.008 s: NO_DNS_FOR_FROM, DNSBL-A, dns:A:mx3c38.moavex.com Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.008 s: DNSBL-A, dns:A:mx3c38.moavex.com.rhsbl.ahbl.org. Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.004 s: DNSBL-A, dns:A:157.182.57.176.bb.barracudacentral.org. Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.009 s: DNSBL-A, dns:A:157.182.57.176.combined.njabl.org. Oct 17 12:23:45.251 [12466] dbg: async: completed in 0.013 s: URI-DNSBL, DNSBL:multi.uribl.com.:moavex.com Oct 17 12:23:45.251 [12466] dbg: uridnsbl: domain "moavex.com" listed (URIBL_BLACK): 127.0.0.2 Oct 17 12:23:45.252 [12466] dbg: dns: URIBL_BLACK lookup finished Oct 17 12:23:45.252 [12466] dbg: async: completed in 0.008 s: NO_DNS_FOR_FROM, DNSBL-MX, dns:MX:mx3c38.moavex.com Oct 17 12:23:45.252 [12466] dbg: async: completed in 0.005 s: DNSBL-A, dns:A:157.182.57.176.list.dnswl.org. Oct 17 12:23:45.252 [12466] dbg: async: queries completed: 16, started: 5 Oct 17 12:23:45.252 [12466] dbg: async: queries active: DNSBL-TXT=3 URI-A=5 at Wed Oct 17 12:23:45 2012 Oct 17 12:23:45.252 [12466] dbg: dns: harvested completed queries Oct 17 12:23:45.252 [12466] dbg: rules: running head tests; score so far=3.425 Oct 17 12:23:45.252 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 282 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg1000_1 Oct 17 12:23:45.252 [12466] dbg: rules: run_generic_tests - compiling eval code: head, priority -1000 Oct 17 12:23:45.252 [12466] dbg: rules: compiled head tests Oct 17 12:23:45.252 [12466] dbg: rules: running head_eval tests; score so far=3.425 Oct 17 12:23:45.252 [12466] dbg: rules: run_eval_tests - compiling eval code: 9, priority -1000 Oct 17 12:23:45.253 [12466] dbg: eval: all '*From' addrs: MollyKelly@mx3c38.moavex.com Oct 17 12:23:45.253 [12466] dbg: eval: all '*To' addrs: ichudov@bling.chudov.com ichudov@algebra.com ichudov@chudov.com Oct 17 12:23:45.253 [12466] dbg: rules: running body tests; score so far=3.425 Oct 17 12:23:45.253 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg1000_1 Oct 17 12:23:45.254 [12466] dbg: rules: run_generic_tests - compiling eval code: body, priority -1000 Oct 17 12:23:45.254 [12466] dbg: rules: compiled body tests Oct 17 12:23:45.254 [12466] dbg: rules: running uri tests; score so far=3.425 Oct 17 12:23:45.254 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg1000_1 Oct 17 12:23:45.254 [12466] dbg: rules: run_generic_tests - compiling eval code: uri, priority -1000 Oct 17 12:23:45.254 [12466] dbg: rules: compiled uri tests Oct 17 12:23:45.254 [12466] dbg: rules: running body_eval tests; score so far=3.425 Oct 17 12:23:45.254 [12466] dbg: rules: run_eval_tests - compiling eval code: 11, priority -1000 Oct 17 12:23:45.254 [12466] dbg: rules: running rawbody tests; score so far=3.425 Oct 17 12:23:45.254 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 231 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg1000_1 Oct 17 12:23:45.254 [12466] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -1000 Oct 17 12:23:45.254 [12466] dbg: rules: compiled rawbody tests Oct 17 12:23:45.255 [12466] dbg: rules: running full tests; score so far=3.425 Oct 17 12:23:45.255 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 260 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg1000_1 Oct 17 12:23:45.255 [12466] dbg: rules: run_generic_tests - compiling eval code: full, priority -1000 Oct 17 12:23:45.255 [12466] dbg: rules: compiled full tests Oct 17 12:23:45.255 [12466] dbg: rules: running meta tests; score so far=3.425 Oct 17 12:23:45.255 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 285 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg1000_1 Oct 17 12:23:45.255 [12466] dbg: rules: run_generic_tests - compiling eval code: meta, priority -1000 Oct 17 12:23:45.255 [12466] dbg: rules: compiled meta tests Oct 17 12:23:45.255 [12466] dbg: check: running tests for priority: -950 Oct 17 12:23:45.255 [12466] dbg: rules: running head tests; score so far=3.425 Oct 17 12:23:45.255 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg950_1 Oct 17 12:23:45.256 [12466] dbg: rules: run_generic_tests - compiling eval code: head, priority -950 Oct 17 12:23:45.256 [12466] dbg: rules: compiled head tests Oct 17 12:23:45.256 [12466] dbg: rules: running head_eval tests; score so far=3.425 Oct 17 12:23:45.256 [12466] dbg: rules: run_eval_tests - compiling eval code: 9, priority -950 Oct 17 12:23:45.256 [12466] dbg: rules: running body tests; score so far=3.425 Oct 17 12:23:45.256 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg950_1 Oct 17 12:23:45.256 [12466] dbg: rules: run_generic_tests - compiling eval code: body, priority -950 Oct 17 12:23:45.256 [12466] dbg: rules: compiled body tests Oct 17 12:23:45.256 [12466] dbg: rules: running uri tests; score so far=3.425 Oct 17 12:23:45.256 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg950_1 Oct 17 12:23:45.256 [12466] dbg: rules: run_generic_tests - compiling eval code: uri, priority -950 Oct 17 12:23:45.256 [12466] dbg: rules: compiled uri tests Oct 17 12:23:45.256 [12466] dbg: rules: running body_eval tests; score so far=3.425 Oct 17 12:23:45.257 [12466] dbg: rules: run_eval_tests - compiling eval code: 11, priority -950 Oct 17 12:23:45.257 [12466] dbg: rules: running rawbody tests; score so far=3.425 Oct 17 12:23:45.257 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg950_1 Oct 17 12:23:45.257 [12466] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -950 Oct 17 12:23:45.257 [12466] dbg: rules: compiled rawbody tests Oct 17 12:23:45.257 [12466] dbg: rules: running full tests; score so far=3.425 Oct 17 12:23:45.257 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg950_1 Oct 17 12:23:45.257 [12466] dbg: rules: run_generic_tests - compiling eval code: full, priority -950 Oct 17 12:23:45.257 [12466] dbg: rules: compiled full tests Oct 17 12:23:45.257 [12466] dbg: rules: running meta tests; score so far=3.425 Oct 17 12:23:45.257 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg950_1 Oct 17 12:23:45.257 [12466] dbg: rules: run_generic_tests - compiling eval code: meta, priority -950 Oct 17 12:23:45.257 [12466] dbg: rules: compiled meta tests Oct 17 12:23:45.257 [12466] dbg: check: running tests for priority: -900 Oct 17 12:23:45.258 [12466] dbg: rules: running head tests; score so far=3.425 Oct 17 12:23:45.258 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg900_1 Oct 17 12:23:45.258 [12466] dbg: rules: run_generic_tests - compiling eval code: head, priority -900 Oct 17 12:23:45.258 [12466] dbg: rules: compiled head tests Oct 17 12:23:45.258 [12466] dbg: rules: running head_eval tests; score so far=3.425 Oct 17 12:23:45.258 [12466] dbg: rules: run_eval_tests - compiling eval code: 9, priority -900 Oct 17 12:23:45.258 [12466] dbg: rules: running body tests; score so far=3.425 Oct 17 12:23:45.258 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg900_1 Oct 17 12:23:45.258 [12466] dbg: rules: run_generic_tests - compiling eval code: body, priority -900 Oct 17 12:23:45.259 [12466] dbg: rules: compiled body tests Oct 17 12:23:45.259 [12466] dbg: rules: running uri tests; score so far=3.425 Oct 17 12:23:45.259 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg900_1 Oct 17 12:23:45.259 [12466] dbg: rules: run_generic_tests - compiling eval code: uri, priority -900 Oct 17 12:23:45.259 [12466] dbg: rules: compiled uri tests Oct 17 12:23:45.259 [12466] dbg: rules: running body_eval tests; score so far=3.425 Oct 17 12:23:45.259 [12466] dbg: rules: run_eval_tests - compiling eval code: 11, priority -900 Oct 17 12:23:45.259 [12466] dbg: rules: running rawbody tests; score so far=3.425 Oct 17 12:23:45.259 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg900_1 Oct 17 12:23:45.259 [12466] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -900 Oct 17 12:23:45.259 [12466] dbg: rules: compiled rawbody tests Oct 17 12:23:45.259 [12466] dbg: rules: running full tests; score so far=3.425 Oct 17 12:23:45.259 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg900_1 Oct 17 12:23:45.260 [12466] dbg: rules: run_generic_tests - compiling eval code: full, priority -900 Oct 17 12:23:45.260 [12466] dbg: rules: compiled full tests Oct 17 12:23:45.260 [12466] dbg: rules: running meta tests; score so far=3.425 Oct 17 12:23:45.260 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg900_1 Oct 17 12:23:45.260 [12466] dbg: rules: run_generic_tests - compiling eval code: meta, priority -900 Oct 17 12:23:45.260 [12466] dbg: rules: compiled meta tests Oct 17 12:23:45.260 [12466] dbg: check: running tests for priority: -400 Oct 17 12:23:45.260 [12466] dbg: rules: running head tests; score so far=3.425 Oct 17 12:23:45.260 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 280 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_neg400_1 Oct 17 12:23:45.260 [12466] dbg: rules: run_generic_tests - compiling eval code: head, priority -400 Oct 17 12:23:45.260 [12466] dbg: rules: compiled head tests Oct 17 12:23:45.260 [12466] dbg: rules: running body tests; score so far=3.425 Oct 17 12:23:45.260 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 223 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_neg400_1 Oct 17 12:23:45.260 [12466] dbg: rules: run_generic_tests - compiling eval code: body, priority -400 Oct 17 12:23:45.261 [12466] dbg: rules: compiled body tests Oct 17 12:23:45.261 [12466] dbg: rules: running uri tests; score so far=3.425 Oct 17 12:23:45.261 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 221 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_neg400_1 Oct 17 12:23:45.261 [12466] dbg: rules: run_generic_tests - compiling eval code: uri, priority -400 Oct 17 12:23:45.261 [12466] dbg: rules: compiled uri tests Oct 17 12:23:45.261 [12466] dbg: rules: running body_eval tests; score so far=3.425 Oct 17 12:23:45.261 [12466] dbg: rules: run_eval_tests - compiling eval code: 11, priority -400 Oct 17 12:23:45.261 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::WLBLEval=HASH(0xa064048) implements 'check_wb_list', priority 0 Oct 17 12:23:45.261 [12466] dbg: bayes: DB journal sync: last sync: 1350493906 Oct 17 12:23:45.262 [12466] dbg: bayes: corpus size: nspam = 917948, nham = 544386 Oct 17 12:23:45.273 [12466] dbg: bayes: score = 0.502917612185652 Oct 17 12:23:45.273 [12466] dbg: bayes: DB expiry: tokens in DB: 146020, Expiry max size: 150000, Oldest atime: 1350034672, Newest atime: 1350494607, Last expire: 1350380093, Current time: 1350494625 Oct 17 12:23:45.273 [12466] dbg: bayes: DB journal sync: last sync: 1350493906 Oct 17 12:23:45.273 [12466] dbg: bayes: untie-ing Oct 17 12:23:45.274 [12466] dbg: rules: running rawbody tests; score so far=3.425 Oct 17 12:23:45.274 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 229 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_neg400_1 Oct 17 12:23:45.274 [12466] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority -400 Oct 17 12:23:45.274 [12466] dbg: rules: compiled rawbody tests Oct 17 12:23:45.274 [12466] dbg: rules: running full tests; score so far=3.425 Oct 17 12:23:45.274 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 258 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_neg400_1 Oct 17 12:23:45.274 [12466] dbg: rules: run_generic_tests - compiling eval code: full, priority -400 Oct 17 12:23:45.274 [12466] dbg: rules: compiled full tests Oct 17 12:23:45.274 [12466] dbg: rules: running meta tests; score so far=3.425 Oct 17 12:23:45.274 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 283 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_neg400_1 Oct 17 12:23:45.274 [12466] dbg: rules: run_generic_tests - compiling eval code: meta, priority -400 Oct 17 12:23:45.274 [12466] dbg: rules: compiled meta tests Oct 17 12:23:45.275 [12466] dbg: check: running tests for priority: 0 Oct 17 12:23:45.275 [12466] dbg: rules: running head tests; score so far=3.425 Oct 17 12:23:45.286 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60216 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_1 Oct 17 12:23:45.291 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60448 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_2 Oct 17 12:23:45.295 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60488 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_3 Oct 17 12:23:45.299 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60336 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_4 Oct 17 12:23:45.310 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60295 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_5 Oct 17 12:23:45.315 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60034 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_6 Oct 17 12:23:45.320 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60236 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_7 Oct 17 12:23:45.325 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 27410 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_0_8 Oct 17 12:23:45.326 [12466] dbg: rules: run_generic_tests - compiling eval code: head, priority 0 Oct 17 12:23:45.326 [12466] dbg: rules: compiled head tests Oct 17 12:23:45.327 [12466] dbg: rules: ran header rule __LAST_EXTERNAL_RELAY_NO_AUTH ======> got hit: "[ ip=176.57.182.157 rdns= helo=mx3c38.moavex.com by=orca.chudov.com ident= envfrom= intl=0 id=q9HHGYQ8007076 auth= " Oct 17 12:23:45.327 [12466] dbg: rules: ran header rule __RDNS_NONE ======> got hit: "[ ip=176.57.182.157 rdns= " Oct 17 12:23:45.327 [12466] dbg: rules: ran header rule __DOS_SINGLE_EXT_RELAY ======> got hit: "[ ip=176.57.182.157 rdns= helo=mx3c38.moavex.com by=orca.chudov.com ident= envfrom= intl=0 id=q9HHGYQ8007076 auth= msa=0 ]" Oct 17 12:23:45.327 [12466] dbg: rules: ran header rule __CTYPE_HTML ======> got hit: "text/html" Oct 17 12:23:45.327 [12466] dbg: rules: ran header rule __CT ======> got hit: "" Oct 17 12:23:45.327 [12466] dbg: rules: ran header rule __CTYPE_CHARSET_QUOTED ======> got hit: "charset="" Oct 17 12:23:45.328 [12466] dbg: rules: ran header rule __MISSING_REF ======> got hit: "UNSET" Oct 17 12:23:45.328 [12466] dbg: rules: ran header rule __MISSING_REPLY ======> got hit: "UNSET" Oct 17 12:23:45.328 [12466] dbg: rules: ran header rule __HAS_DATE ======> got hit: "" Oct 17 12:23:45.328 [12466] dbg: rules: ran header rule __DATE_700 ======> got hit: "-0700" Oct 17 12:23:45.328 [12466] dbg: rules: ran header rule __LAST_UNTRUSTED_RELAY_NO_AUTH ======> got hit: "[ ip=176.57.182.157 rdns= helo=mx3c38.moavex.com by=orca.chudov.com ident= envfrom= intl=0 id=q9HHGYQ8007076 auth= " Oct 17 12:23:45.328 [12466] dbg: rules: ran header rule __HAS_MESSAGE_ID ======> got hit: "" Oct 17 12:23:45.328 [12466] dbg: rules: ran header rule __MIME_VERSION ======> got hit: "" Oct 17 12:23:45.329 [12466] dbg: rules: ran header rule __TOCC_EXISTS ======> got hit: "" Oct 17 12:23:45.329 [12466] dbg: rules: ran header rule __DOS_RCVD_WED ======> got hit: " Wed, " Oct 17 12:23:45.329 [12466] dbg: rules: ran header rule __HAS_RCVD ======> got hit: "" Oct 17 12:23:45.330 [12466] dbg: rules: ran header rule __HAS_SUBJECT ======> got hit: "" Oct 17 12:23:45.330 [12466] dbg: rules: ran header rule __HAS_MSGID ======> got hit: "<" Oct 17 12:23:45.330 [12466] dbg: rules: ran header rule __SANE_MSGID ======> got hit: " Oct 17 12:23:45.330 [12466] dbg: rules: [...] " Oct 17 12:23:45.330 [12466] dbg: rules: ran header rule __HAS_TO ======> got hit: "" Oct 17 12:23:45.330 [12466] dbg: rules: ran header rule __CTE ======> got hit: "" Oct 17 12:23:45.330 [12466] dbg: rules: ran header rule __HAS_FROM ======> got hit: "" Oct 17 12:23:45.331 [12466] dbg: rules: running head_eval tests; score so far=3.425 Oct 17 12:23:45.331 [12466] dbg: rules: run_eval_tests - compiling eval code: 9, priority 0 Oct 17 12:23:45.334 [12466] dbg: FreeMail: RULE (FREEMAIL_FROM) check_freemail_from Oct 17 12:23:45.334 [12466] dbg: FreeMail: all from-addresses: mollykelly@mx3c38.moavex.com Oct 17 12:23:45.335 [12466] dbg: dkim: cannot load Mail::DKIM module, DKIM checks disabled: Can't locate Mail/DKIM/Verifier.pm in @INC (@INC contains: .. /etc/spamassassin lib /usr/share/perl5 /etc/perl /usr/local/lib/perl/5.10.1 /usr/local/share/perl/5.10.1 /usr/lib/perl5 /usr/lib/perl/5.10 /usr/share/perl/5.10 /usr/local/lib/site_perl) at /usr/share/perl5/Mail/SpamAssassin/Plugin/DKIM.pm line 584. Oct 17 12:23:45.335 [12466] dbg: spf: checking to see if the message has a Received-SPF header that we can use Oct 17 12:23:45.348 [12466] dbg: spf: using Mail::SPF for SPF checks Oct 17 12:23:45.348 [12466] dbg: spf: checking HELO (helo=mx3c38.moavex.com, ip=176.57.182.157) Oct 17 12:23:45.349 [12466] dbg: dns: providing a callback for id: 45570/mx3c38.moavex.com/SPF/IN Oct 17 12:23:45.350 [12466] dbg: dns: providing a callback for id: 60843/mx3c38.moavex.com/TXT/IN Oct 17 12:23:45.350 [12466] dbg: spf: query for /176.57.182.157/mx3c38.moavex.com: result: none, comment: , text: No applicable sender policy available Oct 17 12:23:45.351 [12466] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks Oct 17 12:23:45.351 [12466] dbg: spf: relayed through one or more trusted relays, cannot use header-based Envelope-From, skipping Oct 17 12:23:45.351 [12466] dbg: FreeMail: RULE (__freemail_reply) check_freemail_replyto Oct 17 12:23:45.352 [12466] dbg: FreeMail: From address: mollykelly@mx3c38.moavex.com Oct 17 12:23:45.352 [12466] dbg: FreeMail: No Reply-To and From is not freemail, skipping check Oct 17 12:23:45.353 [12466] dbg: FreeMail: RULE (FREEMAIL_REPLYTO_END_DIGIT) check_freemail_header regex:\d@ Oct 17 12:23:45.353 [12466] dbg: FreeMail: header Reply-To not found from mail Oct 17 12:23:45.353 [12466] dbg: FreeMail: RULE (__freemail_replyto) check_freemail_replyto Oct 17 12:23:45.353 [12466] dbg: FreeMail: From address: mollykelly@mx3c38.moavex.com Oct 17 12:23:45.353 [12466] dbg: FreeMail: Reply-To is not freemail, skipping check Oct 17 12:23:45.353 [12466] dbg: rules: ran eval rule __ENV_AND_HDR_FROM_MATCH ======> got hit (1) Oct 17 12:23:45.353 [12466] dbg: FreeMail: RULE (__freemail_hdr_replyto) check_freemail_header Oct 17 12:23:45.353 [12466] dbg: FreeMail: header Reply-To not found from mail Oct 17 12:23:45.353 [12466] dbg: spf: def_spf_whitelist_from: already checked spf and didn't get pass, skipping whitelist check Oct 17 12:23:45.354 [12466] dbg: FreeMail: RULE (FREEMAIL_ENVFROM_END_DIGIT) check_freemail_header regex:\d@ Oct 17 12:23:45.354 [12466] dbg: FreeMail: address from header EnvelopeFrom: mollykelly@mx3c38.moavex.com Oct 17 12:23:45.354 [12466] dbg: spf: whitelist_from_spf: already checked spf and didn't get pass, skipping whitelist check Oct 17 12:23:45.354 [12466] dbg: async: select found no responses ready (t.o.=0.0) Oct 17 12:23:45.354 [12466] dbg: async: completed in 0.099 s: URI-A, A:dns4.name-services.com. Oct 17 12:23:45.354 [12466] dbg: dns: providing a callback for id: 44599/1.194.124.98.zen.spamhaus.org/A/IN Oct 17 12:23:45.354 [12466] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.194.124.98 (timeout 15.0s, min 3.0s) Oct 17 12:23:45.354 [12466] dbg: async: completed in 0.110 s: DNSBL-TXT, dns:TXT:157.182.57.176.bl.spamcop.net. Oct 17 12:23:45.354 [12466] dbg: async: completed in 0.099 s: URI-A, A:dns3.name-services.com. Oct 17 12:23:45.355 [12466] dbg: dns: providing a callback for id: 33353/1.193.124.98.zen.spamhaus.org/A/IN Oct 17 12:23:45.355 [12466] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.193.124.98 (timeout 15.0s, min 3.0s) Oct 17 12:23:45.355 [12466] dbg: async: completed in 0.104 s: DNSBL-TXT, dns:TXT:157.182.57.176.sa-accredit.habeas.com. Oct 17 12:23:45.355 [12466] dbg: async: completed in 0.099 s: URI-A, A:dns5.name-services.com. Oct 17 12:23:45.355 [12466] dbg: dns: providing a callback for id: 22264/1.196.124.98.zen.spamhaus.org/A/IN Oct 17 12:23:45.355 [12466] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.196.124.98 (timeout 15.0s, min 3.0s) Oct 17 12:23:45.355 [12466] dbg: async: completed in 0.099 s: URI-A, A:dns1.name-services.com. Oct 17 12:23:45.355 [12466] dbg: dns: providing a callback for id: 48310/1.192.124.98.zen.spamhaus.org/A/IN Oct 17 12:23:45.356 [12466] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.192.124.98 (timeout 15.0s, min 3.0s) Oct 17 12:23:45.356 [12466] dbg: async: completed in 0.099 s: URI-A, A:dns2.name-services.com. Oct 17 12:23:45.356 [12466] dbg: dns: providing a callback for id: 710/1.197.124.98.zen.spamhaus.org/A/IN Oct 17 12:23:45.356 [12466] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.197.124.98 (timeout 15.0s, min 3.0s) Oct 17 12:23:45.356 [12466] dbg: async: queries completed: 7, started: 5 Oct 17 12:23:45.356 [12466] dbg: async: queries active: DNSBL-TXT=1 URI-DNSBL=5 at Wed Oct 17 12:23:45 2012 Oct 17 12:23:45.356 [12466] dbg: dns: harvested completed queries Oct 17 12:23:45.356 [12466] dbg: rules: running body tests; score so far=3.425 Oct 17 12:23:45.358 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60222 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_1 Oct 17 12:23:45.365 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60381 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_2 Oct 17 12:23:45.371 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60022 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_3 Oct 17 12:23:45.384 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60176 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_4 Oct 17 12:23:45.390 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60632 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_5 Oct 17 12:23:45.397 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60300 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_6 Oct 17 12:23:45.403 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60296 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_7 Oct 17 12:23:45.409 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60092 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_8 Oct 17 12:23:45.415 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60194 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_9 Oct 17 12:23:45.424 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 49772 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_0_10 Oct 17 12:23:45.432 [12466] dbg: rules: run_generic_tests - compiling eval code: body, priority 0 Oct 17 12:23:45.432 [12466] dbg: rules: compiled body tests Oct 17 12:23:45.463 [12466] dbg: rules: ran body rule __DOS_LINK ======> got hit: "link" Oct 17 12:23:45.470 [12466] dbg: rules: ran body rule __NONEMPTY_BODY ======> got hit: "S" Oct 17 12:23:45.491 [12466] dbg: rules: ran body rule __FRAUD_DBI ======> got hit: "dollars" Oct 17 12:23:45.494 [12466] dbg: rules: ran body rule __HUSH_HUSH ======> got hit: "CONFIDENTIALITY" Oct 17 12:23:45.496 [12466] dbg: async: select found 6 responses ready (t.o.=0.0) Oct 17 12:23:45.496 [12466] dbg: async: completed in 0.251 s: DNSBL-TXT, dns:TXT:157.182.57.176.sa-trusted.bondedsender.org. Oct 17 12:23:45.496 [12466] dbg: async: completed in 0.141 s: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.194.124.98 Oct 17 12:23:45.496 [12466] dbg: async: completed in 0.141 s: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.193.124.98 Oct 17 12:23:45.496 [12466] dbg: async: completed in 0.140 s: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.197.124.98 Oct 17 12:23:45.497 [12466] dbg: async: completed in 0.141 s: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.196.124.98 Oct 17 12:23:45.497 [12466] dbg: async: completed in 0.140 s: URI-DNSBL, DNSBL:zen.spamhaus.org.:1.192.124.98 Oct 17 12:23:45.497 [12466] dbg: dns: harvested completed queries Oct 17 12:23:45.497 [12466] dbg: rules: running uri tests; score so far=3.425 Oct 17 12:23:45.498 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 25726 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_0_1 Oct 17 12:23:45.499 [12466] dbg: rules: run_generic_tests - compiling eval code: uri, priority 0 Oct 17 12:23:45.499 [12466] dbg: rules: compiled uri tests Oct 17 12:23:45.500 [12466] dbg: rules: ran uri rule __DOS_HAS_ANY_URI ======> got hit: "h" Oct 17 12:23:45.500 [12466] dbg: rules: ran uri rule __HAS_URI ======> got hit: "h" Oct 17 12:23:45.500 [12466] dbg: rules: ran uri rule __HAS_ANY_URI ======> got hit: "h" Oct 17 12:23:45.500 [12466] dbg: rules: running body_eval tests; score so far=3.425 Oct 17 12:23:45.501 [12466] dbg: rules: run_eval_tests - compiling eval code: 11, priority 0 Oct 17 12:23:45.516 [12466] dbg: https_http_mismatch: anchors 2 Oct 17 12:23:45.516 [12466] dbg: uri: running T_AJB_CANPOST_BADLINK Oct 17 12:23:45.516 [12466] dbg: uri: raw matched: 'http://www.moavex.com/options-994637/home.aspx?8966-96=HAftBwKiKPduudUHkf2hSH=n_ia&pages-987=ZJn' !~ /(?-xism:canadapost\.)/ Oct 17 12:23:45.517 [12466] dbg: uri: type matched: 'a' =~ /(?-xism:^a$)/ Oct 17 12:23:45.517 [12466] dbg: uri: running T_AJB_CANPOST_BADLINK Oct 17 12:23:45.517 [12466] dbg: uri: raw matched: 'http://www.moavex.com/pipe/98du86q445ssjm987s-95/p/index.asp?rtYkxc2dmhob5DiVvm8kCiGe=true&enter=ZJn8ujjf8787&ui-' !~ /(?-xism:canadapost\.)/ Oct 17 12:23:45.517 [12466] dbg: uri: type matched: 'a' =~ /(?-xism:^a$)/ Oct 17 12:23:45.517 [12466] dbg: rules: ran eval rule __TAG_EXISTS_BODY ======> got hit (1) Oct 17 12:23:45.518 [12466] dbg: uri: running T_AJB_UTUBE_BADLINK Oct 17 12:23:45.518 [12466] dbg: uri: raw matched: 'http://www.moavex.com/options-994637/home.aspx?8966-96=HAftBwKiKPduudUHkf2hSH=n_ia&pages-987=ZJn' !~ /(?-xism:youtube\.)/ Oct 17 12:23:45.518 [12466] dbg: uri: type matched: 'a' =~ /(?-xism:^a$)/ Oct 17 12:23:45.518 [12466] dbg: uri: running T_AJB_UTUBE_BADLINK Oct 17 12:23:45.518 [12466] dbg: uri: raw matched: 'http://www.moavex.com/pipe/98du86q445ssjm987s-95/p/index.asp?rtYkxc2dmhob5DiVvm8kCiGe=true&enter=ZJn8ujjf8787&ui-' !~ /(?-xism:youtube\.)/ Oct 17 12:23:45.518 [12466] dbg: uri: type matched: 'a' =~ /(?-xism:^a$)/ Oct 17 12:23:45.518 [12466] dbg: rules: ran eval rule __ANY_TEXT_ATTACH_DOC ======> got hit (1) Oct 17 12:23:45.518 [12466] dbg: rules: ran eval rule __MIME_HTML ======> got hit (1) Oct 17 12:23:45.519 [12466] dbg: rules: ran eval rule HTML_MESSAGE ======> got hit (1) Oct 17 12:23:45.519 [12466] dbg: rules: ran eval rule __TAG_EXISTS_HTML ======> got hit (1) Oct 17 12:23:45.520 [12466] dbg: rules: ran eval rule BAYES_50 ======> got hit (1) Oct 17 12:23:45.520 [12466] dbg: rules: ran eval rule __ANY_TEXT_ATTACH ======> got hit (1) Oct 17 12:23:45.520 [12466] dbg: uri: running T_KHOP_FOREIGN_CLICK Oct 17 12:23:45.520 [12466] dbg: uri: running T_KHOP_FOREIGN_CLICK Oct 17 12:23:45.521 [12466] dbg: rules: ran eval rule MIME_HTML_ONLY ======> got hit (1) Oct 17 12:23:45.521 [12466] dbg: rules: ran eval rule __TAG_EXISTS_HEAD ======> got hit (1) Oct 17 12:23:45.521 [12466] dbg: rules: ran eval rule __TAG_EXISTS_META ======> got hit (1) Oct 17 12:23:45.521 [12466] dbg: rules: running rawbody tests; score so far=4.949 Oct 17 12:23:45.524 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60143 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_0_1 Oct 17 12:23:45.528 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 1792 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_0_2 Oct 17 12:23:45.528 [12466] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 0 Oct 17 12:23:45.528 [12466] dbg: rules: compiled rawbody tests Oct 17 12:23:45.529 [12466] dbg: rules: running rawbody_eval tests; score so far=4.949 Oct 17 12:23:45.529 [12466] dbg: rules: run_eval_tests - compiling eval code: 15, priority 0 Oct 17 12:23:45.530 [12466] dbg: rules: running full tests; score so far=4.949 Oct 17 12:23:45.530 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 2710 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_0_1 Oct 17 12:23:45.530 [12466] dbg: rules: run_generic_tests - compiling eval code: full, priority 0 Oct 17 12:23:45.530 [12466] dbg: rules: compiled full tests Oct 17 12:23:45.531 [12466] dbg: rules: running full_eval tests; score so far=4.949 Oct 17 12:23:45.531 [12466] dbg: rules: run_eval_tests - compiling eval code: 13, priority 0 Oct 17 12:23:45.532 [12466] dbg: dns: entering helper-app run mode Oct 17 12:23:46.344 [12466] dbg: dns: leaving helper-app run mode Oct 17 12:23:46.344 [12466] dbg: razor2: part=0 engine=8 contested=0 confidence=0 Oct 17 12:23:46.345 [12466] dbg: razor2: results: spam? 0 Oct 17 12:23:46.345 [12466] dbg: razor2: results: engine 8, highest cf score: 0 Oct 17 12:23:46.345 [12466] dbg: razor2: results: engine 4, highest cf score: 0 Oct 17 12:23:46.345 [12466] dbg: util: current PATH is: /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 Oct 17 12:23:46.346 [12466] dbg: pyzor: pyzor is not available: no pyzor executable found Oct 17 12:23:46.346 [12466] dbg: pyzor: no pyzor found, disabling Pyzor Oct 17 12:23:46.346 [12466] dbg: rules: running meta tests; score so far=4.949 Oct 17 12:23:46.346 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 275 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_0_1 Oct 17 12:23:46.347 [12466] dbg: rules: run_generic_tests - compiling eval code: meta, priority 0 Oct 17 12:23:46.347 [12466] dbg: rules: compiled meta tests Oct 17 12:23:46.347 [12466] dbg: check: running tests for priority: 500 Oct 17 12:23:46.347 [12466] dbg: dns: harvest_dnsbl_queries Oct 17 12:23:46.347 [12466] dbg: async: timing: 0.003 . dns:A:157.182.57.176.iadb.isipp.com. Oct 17 12:23:46.347 [12466] dbg: async: timing: 0.004 . dns:A:157.182.57.176.bb.barracudacentral.org. Oct 17 12:23:46.347 [12466] dbg: async: timing: 0.005 . dns:A:157.182.57.176.list.dnswl.org. Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.005 . dns:A:157.182.57.176.psbl.surriel.com. Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.006 . dns:A:157.182.57.176.bl.score.senderscore.com. Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.007 . dns:A:157.182.57.176.dnsbl.sorbs.net. Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.007 . dns:A:157.182.57.176.zen.spamhaus.org. Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.008 . dns:MX:mx3c38.moavex.com Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.008 . dns:A:mx3c38.moavex.com Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.008 . dns:A:mx3c38.moavex.com.rhsbl.ahbl.org. Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.009 . dns:A:157.182.57.176.combined.njabl.org. Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.011 . NS:moavex.com Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.012 . DNSBL:dbl.spamhaus.org.:moavex.com Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.013 . DNSBL:dob.sibl.support-intelligence.net:moavex.com Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.013 . DNSBL:multi.uribl.com.:moavex.com Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.014 . DNSBL:multi.surbl.org.:moavex.com Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.099 . A:dns5.name-services.com. Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.099 . A:dns2.name-services.com. Oct 17 12:23:46.348 [12466] dbg: async: timing: 0.099 . A:dns3.name-services.com. Oct 17 12:23:46.349 [12466] dbg: async: timing: 0.099 . A:dns4.name-services.com. Oct 17 12:23:46.349 [12466] dbg: async: timing: 0.099 . A:dns1.name-services.com. Oct 17 12:23:46.349 [12466] dbg: async: timing: 0.104 . dns:TXT:157.182.57.176.sa-accredit.habeas.com. Oct 17 12:23:46.349 [12466] dbg: async: timing: 0.110 . dns:TXT:157.182.57.176.bl.spamcop.net. Oct 17 12:23:46.349 [12466] dbg: async: timing: 0.140 . DNSBL:zen.spamhaus.org.:1.197.124.98 Oct 17 12:23:46.349 [12466] dbg: async: timing: 0.140 . DNSBL:zen.spamhaus.org.:1.192.124.98 Oct 17 12:23:46.349 [12466] dbg: async: timing: 0.141 . DNSBL:zen.spamhaus.org.:1.196.124.98 Oct 17 12:23:46.349 [12466] dbg: async: timing: 0.141 . DNSBL:zen.spamhaus.org.:1.193.124.98 Oct 17 12:23:46.349 [12466] dbg: async: timing: 0.141 . DNSBL:zen.spamhaus.org.:1.194.124.98 Oct 17 12:23:46.349 [12466] dbg: async: timing: 0.251 . dns:TXT:157.182.57.176.sa-trusted.bondedsender.org. Oct 17 12:23:46.350 [12466] dbg: rules: running head tests; score so far=4.949 Oct 17 12:23:46.350 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 276 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_500_1 Oct 17 12:23:46.350 [12466] dbg: rules: run_generic_tests - compiling eval code: head, priority 500 Oct 17 12:23:46.350 [12466] dbg: rules: compiled head tests Oct 17 12:23:46.351 [12466] dbg: rules: running body tests; score so far=4.949 Oct 17 12:23:46.351 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 219 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_500_1 Oct 17 12:23:46.351 [12466] dbg: rules: run_generic_tests - compiling eval code: body, priority 500 Oct 17 12:23:46.351 [12466] dbg: rules: compiled body tests Oct 17 12:23:46.351 [12466] dbg: rules: running uri tests; score so far=4.949 Oct 17 12:23:46.352 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 217 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_500_1 Oct 17 12:23:46.352 [12466] dbg: rules: run_generic_tests - compiling eval code: uri, priority 500 Oct 17 12:23:46.352 [12466] dbg: rules: compiled uri tests Oct 17 12:23:46.352 [12466] dbg: rules: running rawbody tests; score so far=4.949 Oct 17 12:23:46.352 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_500_1 Oct 17 12:23:46.353 [12466] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 500 Oct 17 12:23:46.353 [12466] dbg: rules: compiled rawbody tests Oct 17 12:23:46.353 [12466] dbg: rules: running full tests; score so far=4.949 Oct 17 12:23:46.353 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 254 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_500_1 Oct 17 12:23:46.353 [12466] dbg: rules: run_generic_tests - compiling eval code: full, priority 500 Oct 17 12:23:46.354 [12466] dbg: rules: compiled full tests Oct 17 12:23:46.354 [12466] dbg: rules: running meta tests; score so far=4.949 Oct 17 12:23:46.354 [12466] dbg: rules: meta test DIGEST_MULTIPLE has undefined dependency 'DCC_CHECK' Oct 17 12:23:46.378 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60168 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_1 Oct 17 12:23:46.383 [12466] dbg: rules: flush_evalstr (add_evalstr) compiling 60752 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_2 Oct 17 12:23:46.388 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 38149 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_500_3 Oct 17 12:23:46.391 [12466] dbg: rules: run_generic_tests - compiling eval code: meta, priority 500 Oct 17 12:23:46.391 [12466] dbg: rules: compiled meta tests Oct 17 12:23:46.393 [12466] dbg: check: running tests for priority: 900 Oct 17 12:23:46.393 [12466] dbg: rules: running head tests; score so far=5.742 Oct 17 12:23:46.393 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 276 chars of Mail::SpamAssassin::Plugin::Check::_head_tests_900_1 Oct 17 12:23:46.394 [12466] dbg: rules: run_generic_tests - compiling eval code: head, priority 900 Oct 17 12:23:46.394 [12466] dbg: rules: compiled head tests Oct 17 12:23:46.394 [12466] dbg: rules: running body tests; score so far=5.742 Oct 17 12:23:46.395 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 219 chars of Mail::SpamAssassin::Plugin::Check::_body_tests_900_1 Oct 17 12:23:46.395 [12466] dbg: rules: run_generic_tests - compiling eval code: body, priority 900 Oct 17 12:23:46.395 [12466] dbg: rules: compiled body tests Oct 17 12:23:46.395 [12466] dbg: rules: running uri tests; score so far=5.742 Oct 17 12:23:46.395 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 217 chars of Mail::SpamAssassin::Plugin::Check::_uri_tests_900_1 Oct 17 12:23:46.396 [12466] dbg: rules: run_generic_tests - compiling eval code: uri, priority 900 Oct 17 12:23:46.396 [12466] dbg: rules: compiled uri tests Oct 17 12:23:46.396 [12466] dbg: rules: running body_eval tests; score so far=5.742 Oct 17 12:23:46.396 [12466] dbg: rules: run_eval_tests - compiling eval code: 11, priority 900 Oct 17 12:23:46.397 [12466] dbg: FuzzyOcr: Starting FuzzyOcr... Oct 17 12:23:46.397 [12466] info: FuzzyOcr: Processing Message with ID "" ("Excellent Tax Settlments" -> ) Oct 17 12:23:46.397 [12466] dbg: FuzzyOcr: Skipping OCR, no image files found... Oct 17 12:23:46.397 [12466] dbg: FuzzyOcr: Processed in 0.000364 sec. Oct 17 12:23:46.397 [12466] dbg: rules: running rawbody tests; score so far=5.742 Oct 17 12:23:46.397 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 225 chars of Mail::SpamAssassin::Plugin::Check::_rawbody_tests_900_1 Oct 17 12:23:46.398 [12466] dbg: rules: run_generic_tests - compiling eval code: rawbody, priority 900 Oct 17 12:23:46.398 [12466] dbg: rules: compiled rawbody tests Oct 17 12:23:46.398 [12466] dbg: rules: running full tests; score so far=5.742 Oct 17 12:23:46.398 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 254 chars of Mail::SpamAssassin::Plugin::Check::_full_tests_900_1 Oct 17 12:23:46.398 [12466] dbg: rules: run_generic_tests - compiling eval code: full, priority 900 Oct 17 12:23:46.399 [12466] dbg: rules: compiled full tests Oct 17 12:23:46.399 [12466] dbg: rules: running meta tests; score so far=5.742 Oct 17 12:23:46.399 [12466] dbg: rules: flush_evalstr (run_generic_tests) compiling 279 chars of Mail::SpamAssassin::Plugin::Check::_meta_tests_900_1 Oct 17 12:23:46.399 [12466] dbg: rules: run_generic_tests - compiling eval code: meta, priority 900 Oct 17 12:23:46.399 [12466] dbg: rules: compiled meta tests Oct 17 12:23:46.400 [12466] dbg: plugin: Mail::SpamAssassin::Plugin::AutoLearnThreshold=HASH(0x9e23788) implements 'autolearn_discriminator', priority 0 Oct 17 12:23:46.400 [12466] dbg: learn: auto-learn: currently using scoreset 3, recomputing score based on scoreset 1 Oct 17 12:23:46.400 [12466] dbg: learn: auto-learn: message score: 5.742, computed score for autolearn: 5.855 Oct 17 12:23:46.400 [12466] dbg: learn: auto-learn? ham=0.1, spam=12, body-points=4.581, head-points=0, learned-points=0.8 Oct 17 12:23:46.400 [12466] dbg: learn: auto-learn? no: inside auto-learn thresholds, not considered ham or spam Oct 17 12:23:46.401 [12466] dbg: check: is spam? score=5.742 required=3 Oct 17 12:23:46.401 [12466] dbg: check: tests=BAYES_50,HTML_MESSAGE,MIME_HTML_ONLY,RDNS_NONE,URIBL_BLACK,URIBL_DBL_SPAM Oct 17 12:23:46.401 [12466] dbg: check: subtests=__ANY_TEXT_ATTACH,__ANY_TEXT_ATTACH_DOC,__CT,__CTE,__CTYPE_CHARSET_QUOTED,__CTYPE_HTML,__DATE_700,__DOS_DIRECT_TO_MX,__DOS_HAS_ANY_URI,__DOS_LINK,__DOS_RCVD_WED,__DOS_SINGLE_EXT_RELAY,__ENV_AND_HDR_FROM_MATCH,__FRAUD_DBI,__HAS_ANY_URI,__HAS_DATE,__HAS_FROM,__HAS_MESSAGE_ID,__HAS_MSGID,__HAS_RCVD,__HAS_SUBJECT,__HAS_TO,__HAS_URI,__HUSH_HUSH,__LAST_EXTERNAL_RELAY_NO_AUTH,__LAST_UNTRUSTED_RELAY_NO_AUTH,__MIME_HTML,__MIME_VERSION,__MISSING_REF,__MISSING_REPLY,__NONEMPTY_BODY,__RDNS_NONE,__SANE_MSGID,__TAG_EXISTS_BODY,__TAG_EXISTS_HEAD,__TAG_EXISTS_HTML,__TAG_EXISTS_META,__TOCC_EXISTS Oct 17 12:23:46.401 [12466] dbg: timing: total 2184 ms - init: 873 (40.0%), parse: 0.66 (0.0%), extract_message_metadata: 148 (6.8%), poll_dns_idle: 129 (5.9%), get_uri_detail_list: 3 (0.1%), tests_pri_-1000: 9 (0.4%), compile_gen: 94 (4.3%), compile_eval: 19 (0.9%), tests_pri_-950: 2 (0.1%), tests_pri_-900: 2 (0.1%), tests_pri_-400: 15 (0.7%), check_bayes: 12 (0.6%), tests_pri_0: 1072 (49.1%), dkim_load_modules: 0.09 (0.0%), check_spf: 16 (0.7%), check_razor2: 813 (37.2%), check_pyzor: 0.54 (0.0%), tests_pri_500: 46 (2.1%), tests_pri_900: 7 (0.3%) From MollyKelly@mx3c38.moavex.com Wed Oct 17 12:16:41 2012 Received: from localhost by bling with SpamAssassin (version 3.3.1); Wed, 17 Oct 2012 12:23:46 -0500 From: "Excellent Tax Settlments" To: Subject: Stressed out about your back taxes? Let us help you today. Date: Wed, 17 Oct 2012 10:15:01 -0700 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.7 required=3.0 tests=BAYES_50,HTML_MESSAGE, MIME_HTML_ONLY,RDNS_NONE,URIBL_BLACK,URIBL_DBL_SPAM autolearn=no version=3.3.1 MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----------=_507EE9A2.E89B2EDB" This is a multi-part message in MIME format. ------------=_507EE9A2.E89B2EDB 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: In many cases you would be absolutely amazed at what the IRS is willing to settle for on your back taxes. They don't like chasing you down. They don't like harrassing you. They simply have a job to do. They collect our tax dollars due. And when people get behind, they often just want to settle and move on. We know how to navigate through the red tape. How to push the buttons. How to rapidly expidite your issues so that you can take care of them and get on with your life. [...] Content analysis details: (5.7 points, 3.0 required) pts rule name description ---- ---------------------- -------------------------------------------------- 1.7 URIBL_DBL_SPAM Contains an URL listed in the DBL blocklist [URIs: moavex.com] 1.7 URIBL_BLACK Contains an URL listed in the URIBL blacklist [URIs: moavex.com] 0.0 HTML_MESSAGE BODY: HTML included in message 0.8 BAYES_50 BODY: Bayes spam probability is 40 to 60% [score: 0.5029] 0.7 MIME_HTML_ONLY BODY: Message only has text/html MIME parts 0.8 RDNS_NONE Delivered to internal network by a host with no rDNS The original message was not completely plain text, and may be unsafe to open with some email clients; in particular, it may contain a virus, or confirm that your address can receive spam. If you wish to view it, it may be safer to save it to a file and open it with an editor. ------------=_507EE9A2.E89B2EDB Content-Type: message/rfc822; x-spam-type=original Content-Description: original message before SpamAssassin Content-Disposition: attachment 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=2.3 required=3.0 tests=BAYES_50,HTML_MESSAGE, MIME_HTML_ONLY,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 q9HHGdrN001467 for ; Wed, 17 Oct 2012 12:16:41 -0500 Received: from mx3c38.moavex.com ([176.57.182.157]) by orca.chudov.com (8.14.2/8.14.2/Debian-2build1) with ESMTP id q9HHGYQ8007076 for ; Wed, 17 Oct 2012 12:16:36 -0500 Content-Type: text/html; charset="us-ascii" MIME-Version: 1.0 Content-Transfer-Encoding: 7bit To: From: "Excellent Tax Settlments" Subject: Stressed out about your back taxes? Let us help you today. Date: Wed, 17 Oct 2012 10:15:01 -0700 Message-ID: Status: RO Content-Length: 3485 Lines: 52

In many cases you would be absolutely amazed at what the IRS is willing to settle for on your back taxes. They don't like chasing you down. They don't like harrassing you.

They simply have a job to do. They collect our tax dollars due. And when people get behind, they often just want to settle and move on. We know how to navigate through the red tape. How to push the buttons. How to rapidly expidite your issues so that you can take care of them and get on with your life.

Get on with it now - Check your options here

CONFIDENTIALITY NOTICE. This email and its attachments may be privileged and confidential. They are intended solely for the use of the intended recipient. If you are not the intended recipient, you must take no action based upon them, and you must not copy or show them to anyone. Please contact the sender if you believe you have received this email in error.
Tired of getting messages from Dalton, Eckler, Feldman Associates? Please use this link
Or use the following address to reach us: 48881 Highway One.One.Two, Port Angelus, Washington, 98363
 

Bubba regularly brought his wifes sex tapes into work at the radio station and would show them to the staff, says an insider at Bubbas office. When the Hulk tape was leaked none of his staff were surprised because theyd all seen it already! And that wasnt the only tape they saw with Heather having sex with a celebrity. The source went on to claim that she got into bed while the cameras rolled with at least two other big names. It was just sort of a regular thing when it came to Heather and Bubba. They were known for taping Heathers sexscapades and it just so happened to involve famous people sometimes.
 
     

Bristol Palin and
While we'd like to put money down on a bet that hubby Jay-Z will drop in for a few
bars - perhaps echoing
 15236 Strelna, Coordinates: 595113N 30335E / 59.85361N 30.05972E / 59.85361; 30.05972Upper terrace of the Constantine Palace
------------=_507EE9A2.E89B2EDB--