mx1# /usr/local/bin/spamd --allow-tell --vpopmail --username=spamd --socketpath=/tmp/spamd.sock --debug all [96985] dbg: logger: adding facilities: all [96985] dbg: logger: logging level is DBG [96985] dbg: logger: trying to connect to syslog/unix... [96985] dbg: logger: opening syslog with unix socket [96985] dbg: logger: successfully connected to syslog/unix [96985] dbg: logger: successfully added syslog method [96985] dbg: spamd: will perform setuids? 0 [96985] dbg: spamd: creating UNIX socket: [96985] dbg: spamd: Listen: 128 [96985] dbg: spamd: Local: /tmp/spamd.sock [96985] dbg: spamd: Type: 1 [96985] dbg: logger: adding facilities: all [96985] dbg: logger: logging level is DBG [96985] dbg: generic: SpamAssassin version 3.2.5 [96985] dbg: config: score set 0 chosen. [96985] dbg: dns: no ipv6 [96985] dbg: dns: is Net::DNS::Resolver available? yes [96985] dbg: dns: Net::DNS version: 0.65 [96985] dbg: spamd: Preloading modules with HOME=/tmp/spamd-96985-init [96985] dbg: ignore: test message to precompile patterns and load modules [96985] dbg: config: using "/etc/mail/spamassassin" for site rules pre files [96985] dbg: config: read file /etc/mail/spamassassin/init.pre [96985] dbg: config: read file /etc/mail/spamassassin/v310.pre [96985] dbg: config: read file /etc/mail/spamassassin/v312.pre [96985] dbg: config: read file /etc/mail/spamassassin/v320.pre [96985] dbg: config: using "/usr/local/share/spamassassin" for sys rules pre files [96985] dbg: config: using "/usr/local/share/spamassassin" for default rules dir [96985] dbg: config: read file /usr/local/share/spamassassin/10_default_prefs.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_advance_fee.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_body_tests.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_compensate.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_dnsbl_tests.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_drugs.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_dynrdns.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_fake_helo_tests.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_head_tests.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_html_tests.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_imageinfo.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_meta_tests.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_net_tests.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_phrases.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_porn.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_ratware.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_uri_tests.cf [96985] dbg: config: read file /usr/local/share/spamassassin/20_vbounce.cf [96985] dbg: config: read file /usr/local/share/spamassassin/23_bayes.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_accessdb.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_antivirus.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_asn.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_dcc.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_dkim.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_domainkeys.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_hashcash.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_pyzor.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_razor2.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_replace.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_spf.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_textcat.cf [96985] dbg: config: read file /usr/local/share/spamassassin/25_uribl.cf [96985] dbg: config: read file /usr/local/share/spamassassin/30_text_de.cf [96985] dbg: config: read file /usr/local/share/spamassassin/30_text_fr.cf [96985] dbg: config: read file /usr/local/share/spamassassin/30_text_it.cf [96985] dbg: config: read file /usr/local/share/spamassassin/30_text_nl.cf [96985] dbg: config: read file /usr/local/share/spamassassin/30_text_pl.cf [96985] dbg: config: read file /usr/local/share/spamassassin/30_text_pt_br.cf [96985] dbg: config: read file /usr/local/share/spamassassin/50_scores.cf [96985] dbg: config: read file /usr/local/share/spamassassin/60_awl.cf [96985] dbg: config: read file /usr/local/share/spamassassin/60_shortcircuit.cf [96985] dbg: config: read file /usr/local/share/spamassassin/60_whitelist.cf [96985] dbg: config: read file /usr/local/share/spamassassin/60_whitelist_dk.cf [96985] dbg: config: read file /usr/local/share/spamassassin/60_whitelist_dkim.cf [96985] dbg: config: read file /usr/local/share/spamassassin/60_whitelist_spf.cf [96985] dbg: config: read file /usr/local/share/spamassassin/60_whitelist_subject.cf [96985] dbg: config: read file /usr/local/share/spamassassin/72_active.cf [96985] dbg: config: read file /usr/local/share/spamassassin/72_removed.cf [96985] dbg: config: using "/etc/mail/spamassassin" for site rules dir [96985] dbg: config: read file /etc/mail/spamassassin/local.cf [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC [96985] dbg: pyzor: network tests on, attempting Pyzor [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC [96985] dbg: razor2: razor2 is not available [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC [96985] dbg: reporter: network tests on, attempting SpamCop [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::AWL from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC [96985] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC [96985] dbg: rules: __MO_OL_9B90B merged duplicates: __MO_OL_C65FA [96985] dbg: rules: __XM_OL_22B61 merged duplicates: __XM_OL_A842E [96985] dbg: rules: __MO_OL_07794 merged duplicates: __MO_OL_8627E __MO_OL_F3B05 [96985] dbg: rules: __XM_OL_07794 merged duplicates: __XM_OL_25340 __XM_OL_3857F __XM_OL_4F240 __XM_OL_58CB5 __XM_OL_6554A __XM_OL_812FF __XM_OL_C65FA __XM_OL_CF0C0 __XM_OL_F475E __XM_OL_F6D01 [96985] dbg: rules: FH_MSGID_01C67 merged duplicates: __MSGID_VGA [96985] dbg: rules: FS_NEW_SOFT_UPLOAD merged duplicates: HS_SUBJ_NEW_SOFTWARE [96985] dbg: rules: __FH_HAS_XMSMAIL merged duplicates: __HAS_MSMAIL_PRI [96985] dbg: rules: __MO_OL_015D5 merged duplicates: __MO_OL_6554A [96985] dbg: rules: __XM_OL_015D5 merged duplicates: __XM_OL_4BF4C __XM_OL_4EEDB __XM_OL_5B79A __XM_OL_9B90B __XM_OL_ADFF7 __XM_OL_B30D1 __XM_OL_B4B40 __XM_OL_BC7E6 __XM_OL_F3B05 __XM_OL_FF5C8 [96985] dbg: rules: __MO_OL_91287 merged duplicates: __MO_OL_B30D1 __MO_OL_CF0C0 [96985] dbg: rules: KAM_STOCKOTC merged duplicates: KAM_STOCKTIP15 KAM_STOCKTIP20 KAM_STOCKTIP21 KAM_STOCKTIP4 KAM_STOCKTIP6 [96985] dbg: rules: __MO_OL_22B61 merged duplicates: __MO_OL_4F240 __MO_OL_ADFF7 [96985] dbg: rules: __MO_OL_812FF merged duplicates: __MO_OL_BC7E6 [96985] dbg: rules: __MO_OL_25340 merged duplicates: __MO_OL_4EEDB __MO_OL_7533E [96985] dbg: rules: __MO_OL_58CB5 merged duplicates: __MO_OL_B4B40 [96985] dbg: rules: __DOS_HAS_ANY_URI merged duplicates: __HAS_ANY_URI [96985] dbg: rules: AXB_RCVD_ZOOBSEND merged duplicates: BROKEN_RATWARE_BOM CTYPE_001C_A DEAR_HOMEOWNER DIV_CENTER_A_HREF DRUG_RA_PRICE FM_DDDD_TIMES_2 FM_SEX_HOSTDDDD HG_HORMONE HS_PHARMA_1 HS_UPLOADED_SOFTWARE OEBOUND STOX_RCVD_N_NN_N URIBL_RHS_ABUSE URIBL_RHS_BOGUSMX URIBL_RHS_DSN URIBL_RHS_POST URIBL_RHS_TLD_WHOIS URIBL_RHS_WHOIS URIBL_XS_SURBL URI_L_PHP XMAILER_MIMEOLE_OL_5E7ED XMAILER_MIMEOLE_OL_C7C33 XMAILER_MIMEOLE_OL_D03AB X_LIBRARY YOUR_CRD_RATING [96985] dbg: rules: __MO_OL_72641 merged duplicates: __MO_OL_A842E [96985] dbg: rules: __MO_OL_F475E merged duplicates: __MO_OL_FF5C8 [96985] dbg: rules: __MO_OL_4BF4C merged duplicates: __MO_OL_F6D01 [96985] dbg: conf: finish parsing [96985] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x80330fd10) implements 'finish_parsing_end', priority 0 [96985] dbg: replacetags: replacing tags [96985] dbg: replacetags: done replacing tags [96985] dbg: bayes: no dbs present, cannot tie DB R/O: /tmp/spamd-96985-init/.spamassassin/bayes_toks [96985] dbg: config: score set 1 chosen. [96985] dbg: message: main message type: text/plain [96985] dbg: message: ---- MIME PARSER START ---- [96985] dbg: message: parsing normal part [96985] dbg: message: ---- MIME PARSER END ---- [96985] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x8035cd870) implements 'check_start', priority 0 [96985] dbg: bayes: no dbs present, cannot tie DB R/O: /tmp/spamd-96985-init/.spamassassin/bayes_toks [96985] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x8035875d0) implements 'check_main', priority 0 [96985] dbg: conf: trusted_networks are not configured; it is recommended that you configure trusted_networks manually [96985] dbg: metadata: X-Spam-Relays-Trusted: [96985] dbg: metadata: X-Spam-Relays-Untrusted: [96985] dbg: metadata: X-Spam-Relays-Internal: [96985] dbg: metadata: X-Spam-Relays-External: [96985] dbg: message: no encoding detected [96985] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x80324cb10) implements 'parsed_metadata', priority 0 [96985] dbg: dns: is_dns_available() last checked 1238100715.0 seconds ago; re-checking [96985] dbg: dns: no ipv6 [96985] dbg: dns: is Net::DNS::Resolver available? yes [96985] dbg: dns: Net::DNS version: 0.65 [96985] dbg: dns: name server: 208.67.222.222, LocalAddr: 0.0.0.0 [96985] dbg: dns: resolver socket rx buffer size is 42080 bytes [96985] dbg: dns: testing resolver nameservers: 208.67.222.222, 208.67.220.220 [96985] dbg: dns: trying (3) apache.org... [96985] dbg: dns: looking up NS for 'apache.org' [96985] dbg: dns: NS lookup of apache.org using 208.67.222.222 succeeded => DNS available (set dns_available to override) [96985] dbg: dns: name server: 208.67.220.220, LocalAddr: 0.0.0.0 [96985] dbg: dns: resolver socket rx buffer size is 42080 bytes [96985] dbg: dns: trying (3) doubleclick.com... [96985] dbg: dns: looking up NS for 'doubleclick.com' [96985] dbg: dns: NS lookup of doubleclick.com using 208.67.220.220 succeeded => DNS available (set dns_available to override) [96985] dbg: dns: name server: 208.67.220.220, LocalAddr: 0.0.0.0 [96985] dbg: dns: resolver socket rx buffer size is 42080 bytes [96985] dbg: dns: NS list: 208.67.222.222, 208.67.220.220 [96985] dbg: dns: name server: 208.67.222.222, LocalAddr: 0.0.0.0 [96985] dbg: dns: resolver socket rx buffer size is 42080 bytes [96985] dbg: dns: is DNS available? 1 [96985] dbg: uridnsbl: domains to query: [96985] dbg: dns: checking RBL sa-other.bondedsender.org., set bsp-untrusted [96985] dbg: dns: checking RBL plus.bondedsender.org., set ssc-firsttrusted [96985] dbg: dns: checking RBL combined.njabl.org., set njabl [96985] dbg: dns: checking RBL bl.spamcop.net., set spamcop [96985] dbg: dns: checking RBL zen.spamhaus.org., set zen-lastexternal [96985] dbg: dns: checking RBL dnsbl.sorbs.net., set sorbs-lastexternal [96985] dbg: dns: checking RBL dnsbl.sorbs.net., set sorbs [96985] dbg: dns: checking RBL zen.spamhaus.org., set zen-lastexternal [96985] dbg: dns: checking RBL sa-accredit.habeas.com., set habeas-firsttrusted [96985] dbg: dns: checking RBL list.dnswl.org., set dnswl-firsttrusted [96985] dbg: dns: checking RBL list.dsbl.org., set dsbl-lastexternal [96985] dbg: dns: checking RBL sa-trusted.bondedsender.org., set bsp-firsttrusted [96985] dbg: dns: checking RBL zen.spamhaus.org., set zen [96985] dbg: dns: checking RBL iadb.isipp.com., set iadb-firsttrusted [96985] dbg: check: running tests for priority: -1000 [96985] dbg: rules: running head tests; score so far=0 [96985] dbg: rules: compiled head tests [96985] dbg: eval: all '*From' addrs: ign...@compiling.spamassassin.taint.org [96985] dbg: eval: all '*To' addrs: [96985] dbg: rules: running body tests; score so far=0 [96985] dbg: rules: compiled body tests [96985] dbg: rules: running uri tests; score so far=0 [96985] dbg: rules: compiled uri tests [96985] dbg: rules: running rawbody tests; score so far=0 [96985] dbg: rules: compiled rawbody tests [96985] dbg: rules: running full tests; score so far=0 [96985] dbg: rules: compiled full tests [96985] dbg: rules: running meta tests; score so far=0 [96985] dbg: rules: compiled meta tests [96985] dbg: check: running tests for priority: -950 [96985] dbg: rules: running head tests; score so far=0 [96985] dbg: rules: compiled head tests [96985] dbg: rules: running body tests; score so far=0 [96985] dbg: rules: compiled body tests [96985] dbg: rules: running uri tests; score so far=0 [96985] dbg: rules: compiled uri tests [96985] dbg: rules: running rawbody tests; score so far=0 [96985] dbg: rules: compiled rawbody tests [96985] dbg: rules: running full tests; score so far=0 [96985] dbg: rules: compiled full tests [96985] dbg: rules: running meta tests; score so far=0 [96985] dbg: rules: compiled meta tests [96985] dbg: check: running tests for priority: -900 [96985] dbg: rules: running head tests; score so far=0 [96985] dbg: rules: compiled head tests [96985] dbg: rules: running body tests; score so far=0 [96985] dbg: rules: compiled body tests [96985] dbg: rules: running uri tests; score so far=0 [96985] dbg: rules: compiled uri tests [96985] dbg: rules: running rawbody tests; score so far=0 [96985] dbg: rules: compiled rawbody tests [96985] dbg: rules: running full tests; score so far=0 [96985] dbg: rules: compiled full tests [96985] dbg: rules: running meta tests; score so far=0 [96985] dbg: rules: compiled meta tests [96985] dbg: check: running tests for priority: -400 [96985] dbg: rules: running head tests; score so far=0 [96985] dbg: rules: compiled head tests [96985] dbg: rules: running body tests; score so far=0 [96985] dbg: rules: compiled body tests [96985] dbg: rules: running uri tests; score so far=0 [96985] dbg: rules: compiled uri tests [96985] dbg: rules: running rawbody tests; score so far=0 [96985] dbg: rules: compiled rawbody tests [96985] dbg: rules: running full tests; score so far=0 [96985] dbg: rules: compiled full tests [96985] dbg: rules: running meta tests; score so far=0 [96985] dbg: rules: compiled meta tests [96985] dbg: check: running tests for priority: 0 [96985] dbg: rules: running head tests; score so far=0 [96985] dbg: rules: compiled head tests [96985] dbg: rules: ran header rule __MISSING_REF ======> got hit: "UNSET" [96985] dbg: rules: ran header rule __MSGID_OK_HOST ======> got hit: "@spamassassin_spamd_init>" [96985] dbg: rules: ran header rule __MSGID_OK_DIGITS ======> got hit: "1238100682" [96985] dbg: rules: ran header rule __MSOE_MID_WRONG_CASE ======> got hit: " [96985] dbg: rules: Message-Id: " [96985] dbg: rules: ran header rule __HAS_MSGID ======> got hit: "<" [96985] dbg: rules: ran header rule __SANE_MSGID ======> got hit: "<1238100682.98...@spamassassin_spamd_init> [96985] dbg: rules: " [96985] dbg: rules: ran header rule MISSING_DATE ======> got hit: "UNSET" [96985] dbg: spf: checking to see if the message has a Received-SPF header that we can use [96985] dbg: spf: using Mail::SPF for SPF checks [96985] dbg: spf: no suitable relay for spf use found, skipping SPF-helo check [96985] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks [96985] dbg: spf: no suitable relay for spf use found, skipping SPF check [96985] dbg: rules: ran eval rule NO_RELAYS ======> got hit (1) [96985] dbg: rules: ran eval rule __GATED_THROUGH_RCVD_REMOVER ======> got hit (1) [96985] dbg: spf: def_spf_whitelist_from: already checked spf and didn't get pass, skipping whitelist check [96985] dbg: rules: ran eval rule MISSING_HEADERS ======> got hit (1) [96985] dbg: spf: whitelist_from_spf: already checked spf and didn't get pass, skipping whitelist check [96985] dbg: rules: running body tests; score so far=1.581 [96985] dbg: rules: compiled body tests [96985] dbg: rules: ran body rule __NONEMPTY_BODY ======> got hit: "I" [96985] dbg: rules: running uri tests; score so far=1.581 [96985] dbg: rules: compiled uri tests [96985] dbg: eval: stock info total: 0 [96985] dbg: rules: running rawbody tests; score so far=1.581 [96985] dbg: rules: compiled rawbody tests [96985] dbg: rules: running full tests; score so far=1.581 [96985] dbg: rules: compiled full tests [96985] dbg: util: current PATH is: /sbin:/bin:/usr/sbin:/usr/bin:/usr/games:/usr/local/sbin:/usr/local/bin [96985] dbg: pyzor: pyzor is not available: no pyzor executable found [96985] dbg: pyzor: no pyzor found, disabling Pyzor [96985] dbg: rules: running meta tests; score so far=1.581 [96985] dbg: rules: compiled meta tests [96985] dbg: check: running tests for priority: 500 [96985] dbg: dns: harvest_dnsbl_queries [96985] dbg: rules: running head tests; score so far=1.581 [96985] dbg: rules: compiled head tests [96985] dbg: rules: running body tests; score so far=1.581 [96985] dbg: rules: compiled body tests [96985] dbg: rules: running uri tests; score so far=1.581 [96985] dbg: rules: compiled uri tests [96985] dbg: rules: running rawbody tests; score so far=1.581 [96985] dbg: rules: compiled rawbody tests [96985] dbg: rules: running full tests; score so far=1.581 [96985] dbg: rules: compiled full tests [96985] dbg: rules: running meta tests; score so far=1.581 [96985] dbg: rules: meta test DIGEST_MULTIPLE has undefined dependency 'DCC_CHECK' [96985] dbg: rules: compiled meta tests [96985] dbg: check: running tests for priority: 1000 [96985] dbg: rules: running head tests; score so far=2.865 [96985] dbg: rules: compiled head tests [96985] dbg: locker: safe_lock: created /tmp/spamd-96985-init/.spamassassin/auto-whitelist.lock.mx1.alexus.biz.96985 [96985] dbg: locker: safe_lock: trying to get lock on /tmp/spamd-96985-init/.spamassassin/auto-whitelist with 0 retries [96985] dbg: locker: safe_lock: link to /tmp/spamd-96985-init/.spamassassin/auto-whitelist.lock: link ok [96985] dbg: auto-whitelist: tie-ing to DB file of type DB_File R/W in /tmp/spamd-96985-init/.spamassassin/auto-whitelist [96985] dbg: auto-whitelist: db-based ign...@compiling.spamassassin.taint.org|ip=none scores 0/0 [96985] dbg: auto-whitelist: AWL active, pre-score: 2.865, autolearn score: 2.865, mean: undef, IP: undef [96985] dbg: auto-whitelist: DB addr list: untie-ing and unlocking [96985] dbg: auto-whitelist: DB addr list: file locked, breaking lock [96985] dbg: locker: safe_unlock: unlink /tmp/spamd-96985-init/.spamassassin/auto-whitelist.lock [96985] dbg: auto-whitelist: post auto-whitelist score: 2.865 [96985] dbg: rules: running body tests; score so far=2.865 [96985] dbg: rules: compiled body tests [96985] dbg: rules: running uri tests; score so far=2.865 [96985] dbg: rules: compiled uri tests [96985] dbg: rules: running rawbody tests; score so far=2.865 [96985] dbg: rules: compiled rawbody tests [96985] dbg: rules: running full tests; score so far=2.865 [96985] dbg: rules: compiled full tests [96985] dbg: rules: running meta tests; score so far=2.865 [96985] dbg: rules: compiled meta tests [96985] dbg: check: is spam? score=2.865 required=5 [96985] dbg: check: tests=MISSING_DATE,MISSING_HEADERS,MISSING_SUBJECT,NO_RECEIVED,NO_RELAYS [96985] dbg: check: subtests=__GATED_THROUGH_RCVD_REMOVER,__HAS_MSGID,__MISSING_REF,__MSGID_OK_DIGITS,__MSGID_OK_HOST,__MSOE_MID_WRONG_CASE,__NONEMPTY_BODY,__SANE_MSGID,__UNUSABLE_MSGID [96985] dbg: learn: initializing learner [96985] dbg: config: copying current conf to backup [96985] info: spamd: server started on UNIX domain socket /tmp/spamd.sock (running version 3.2.5) [96985] info: spamd: server pid: 96985 [96985] info: spamd: server successfully spawned child process, pid 97034 [97034] dbg: prefork: sysread(8) not ready, wait max 300 secs [96985] dbg: prefork: child 97034: entering state 0 [96985] dbg: prefork: new lowest idle kid: none [96985] info: spamd: server successfully spawned child process, pid 97035 [97035] dbg: prefork: sysread(9) not ready, wait max 300 secs [96985] dbg: prefork: child 97035: entering state 0 [96985] dbg: prefork: new lowest idle kid: none [96985] dbg: prefork: child 97034: entering state 1 [96985] dbg: prefork: new lowest idle kid: 97034 [96985] dbg: prefork: child reports idle [96985] dbg: prefork: child 97035: entering state 1 [96985] dbg: prefork: new lowest idle kid: 97034 [96985] dbg: prefork: child reports idle [96985] info: prefork: child states: II
sending myself an email from my laptop... [96985] dbg: prefork: ordered 97034 to accept [97034] info: spamd: got connection over /tmp/spamd.sock [96985] dbg: prefork: child 97034: entering state 2 [97034] warn: Use of uninitialized value $dir in scalar chomp at /usr/local/bin/spamd line 2118, <GEN8> line 2. [96985] dbg: prefork: new lowest idle kid: 97035 [97034] dbg: info: user has changed [97034] dbg: bayes: no dbs present, cannot tie DB R/O: /home/spamd/.spamassassin/bayes_toks [97034] dbg: config: score set 1 chosen. [97034] dbg: spamd: running as uid 58 [97034] dbg: dns: name server: 208.67.222.222, LocalAddr: 0.0.0.0 [97034] dbg: dns: resolver socket rx buffer size is 42080 bytes [97034] dbg: message: main message type: text/plain [97034] info: spamd: processing message <20090326205319.9252c69c...@mbp.local> for simscan:58 [97034] dbg: bayes: no dbs present, cannot tie DB R/O: /home/spamd/.spamassassin/bayes_toks [97034] dbg: conf: trusted_networks are not configured; it is recommended that you configure trusted_networks manually [97034] dbg: received-header: parsed as [ ip=69.10.67.106 rdns= helo=mbp.local by=alexus.org ident= envfrom= intl=0 id= auth= msa=0 ] [97034] dbg: received-header: do not trust any hosts from here on [97034] dbg: received-header: relay 69.10.67.106 trusted? no internal? no msa? no [97034] dbg: metadata: X-Spam-Relays-Trusted: [97034] dbg: metadata: X-Spam-Relays-Untrusted: [ ip=69.10.67.106 rdns= helo=mbp.local by=alexus.org ident= envfrom= intl=0 id= auth= msa=0 ] [97034] dbg: metadata: X-Spam-Relays-Internal: [97034] dbg: metadata: X-Spam-Relays-External: [ ip=69.10.67.106 rdns= helo=mbp.local by=alexus.org ident= envfrom= intl=0 id= auth= msa=0 ] [97034] dbg: message: ---- MIME PARSER START ---- [97034] dbg: message: parsing normal part [97034] dbg: message: ---- MIME PARSER END ---- [97034] dbg: message: no encoding detected [97034] dbg: uridnsbl: domains to query: [97034] dbg: dns: checking RBL sa-other.bondedsender.org., set bsp-untrusted [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: [97034] dbg: dns: checking RBL plus.bondedsender.org., set ssc-firsttrusted [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: launching DNS A query for 106.67.10.69.plus.bondedsender.org. in background [97034] dbg: async: starting: DNSBL-A, dns:A:106.67.10.69.plus.bondedsender.org. (timeout 15.0s, min 3.0s) [97034] dbg: dns: checking RBL combined.njabl.org., set njabl [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: launching DNS A query for 106.67.10.69.combined.njabl.org. in background [97034] dbg: async: starting: DNSBL-A, dns:A:106.67.10.69.combined.njabl.org. (timeout 15.0s, min 3.0s) [97034] dbg: dns: checking RBL bl.spamcop.net., set spamcop [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: launching DNS TXT query for 106.67.10.69.bl.spamcop.net. in background [97034] dbg: async: starting: DNSBL-TXT, dns:TXT:106.67.10.69.bl.spamcop.net. (timeout 15.0s, min 3.0s) [97034] dbg: dns: checking RBL zen.spamhaus.org., set zen-lastexternal [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: launching DNS A query for 106.67.10.69.zen.spamhaus.org. in background [97034] dbg: async: starting: DNSBL-A, dns:A:106.67.10.69.zen.spamhaus.org. (timeout 15.0s, min 3.0s) [97034] dbg: dns: checking RBL dnsbl.sorbs.net., set sorbs-lastexternal [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: launching DNS A query for 106.67.10.69.dnsbl.sorbs.net. in background [97034] dbg: async: starting: DNSBL-A, dns:A:106.67.10.69.dnsbl.sorbs.net. (timeout 15.0s, min 3.0s) [97034] dbg: dns: checking RBL dnsbl.sorbs.net., set sorbs [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: checking RBL zen.spamhaus.org., set zen-lastexternal [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: checking RBL sa-accredit.habeas.com., set habeas-firsttrusted [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: launching DNS A query for 106.67.10.69.sa-accredit.habeas.com. in background [97034] dbg: async: starting: DNSBL-A, dns:A:106.67.10.69.sa-accredit.habeas.com. (timeout 15.0s, min 3.0s) [97034] dbg: dns: checking RBL list.dnswl.org., set dnswl-firsttrusted [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: launching DNS A query for 106.67.10.69.list.dnswl.org. in background [97034] dbg: async: starting: DNSBL-A, dns:A:106.67.10.69.list.dnswl.org. (timeout 15.0s, min 3.0s) [97034] dbg: dns: checking RBL list.dsbl.org., set dsbl-lastexternal [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: launching DNS TXT query for 106.67.10.69.list.dsbl.org. in background [97034] dbg: async: starting: DNSBL-TXT, dns:TXT:106.67.10.69.list.dsbl.org. (timeout 15.0s, min 3.0s) [97034] dbg: dns: checking RBL sa-trusted.bondedsender.org., set bsp-firsttrusted [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: launching DNS TXT query for 106.67.10.69.sa-trusted.bondedsender.org. in background [97034] dbg: async: starting: DNSBL-TXT, dns:TXT:106.67.10.69.sa-trusted.bondedsender.org. (timeout 15.0s, min 3.0s) [97034] dbg: dns: checking RBL zen.spamhaus.org., set zen [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: checking RBL iadb.isipp.com., set iadb-firsttrusted [97034] dbg: dns: IPs found: full-external: 69.10.67.106 untrusted: 69.10.67.106 originating: [97034] dbg: dns: only inspecting the following IPs: 69.10.67.106 [97034] dbg: dns: launching DNS A query for 106.67.10.69.iadb.isipp.com. in background [97034] dbg: async: starting: DNSBL-A, dns:A:106.67.10.69.iadb.isipp.com. (timeout 15.0s, min 3.0s) [97034] dbg: check: running tests for priority: -1000 [97034] dbg: async: select found 9 responses ready (t.o.=0.0) [97034] dbg: async: completed in 6.405 s: DNSBL-A, dns:A:106.67.10.69.sa-accredit.habeas.com. [97034] dbg: async: completed in 2.564 s: DNSBL-TXT, dns:TXT:106.67.10.69.sa-trusted.bondedsender.org. [97034] dbg: async: completed in 10.504 s: DNSBL-A, dns:A:106.67.10.69.zen.spamhaus.org. [97034] dbg: async: completed in 9.223 s: DNSBL-A, dns:A:106.67.10.69.dnsbl.sorbs.net. [97034] dbg: async: completed in 11.784 s: DNSBL-TXT, dns:TXT:106.67.10.69.bl.spamcop.net. [97034] dbg: async: completed in 0.515 s: DNSBL-A, dns:A:106.67.10.69.iadb.isipp.com. [97034] dbg: async: completed in 5.125 s: DNSBL-A, dns:A:106.67.10.69.list.dnswl.org. [97034] dbg: async: completed in 13.065 s: DNSBL-A, dns:A:106.67.10.69.combined.njabl.org. [97034] dbg: async: completed in 14.345 s: DNSBL-A, dns:A:106.67.10.69.plus.bondedsender.org. [97034] dbg: async: queries completed: 9, started: 0 [97034] dbg: async: queries active: DNSBL-TXT=1, all expired at Thu Mar 26 20:53:44 2009 [97034] dbg: dns: harvested completed queries [97034] dbg: rules: running head tests; score so far=0 [97034] dbg: async: select found 1 responses ready (t.o.=0.0) [97034] dbg: async: completed in 7.431 s: DNSBL-TXT, dns:TXT:106.67.10.69.list.dsbl.org. [97034] dbg: dns: harvested completed queries [97034] dbg: eval: all '*From' addrs: ale...@mbp.local [97034] dbg: eval: all '*To' addrs: postmas...@8au.net [97034] dbg: rules: running body tests; score so far=0 [97034] dbg: rules: running uri tests; score so far=0 [97034] dbg: rules: running rawbody tests; score so far=0 [97034] dbg: rules: running full tests; score so far=0 [97034] dbg: rules: running meta tests; score so far=0 [97034] dbg: check: running tests for priority: -950 [97034] dbg: rules: running head tests; score so far=0 [97034] dbg: rules: running body tests; score so far=0 [97034] dbg: rules: running uri tests; score so far=0 [97034] dbg: rules: running rawbody tests; score so far=0 [97034] dbg: rules: running full tests; score so far=0 [97034] dbg: rules: running meta tests; score so far=0 [97034] dbg: check: running tests for priority: -900 [97034] dbg: rules: running head tests; score so far=0 [97034] dbg: rules: running body tests; score so far=0 [97034] dbg: rules: running uri tests; score so far=0 [97034] dbg: rules: running rawbody tests; score so far=0 [97035] dbg: prefork: periodic ping from spamd parent [97034] dbg: rules: running full tests; score so far=0 [97035] dbg: prefork: sysread(9) not ready, wait max 300 secs [97034] dbg: rules: running meta tests; score so far=0 [97034] dbg: check: running tests for priority: -400 [97034] dbg: rules: running head tests; score so far=0 [97034] dbg: rules: running body tests; score so far=0 [97034] dbg: rules: running uri tests; score so far=0 [97034] dbg: rules: running rawbody tests; score so far=0 [97034] dbg: rules: running full tests; score so far=0 [97034] dbg: rules: running meta tests; score so far=0 [97034] dbg: check: running tests for priority: 0 [97034] dbg: rules: running head tests; score so far=0 [97034] dbg: rules: ran header rule __RDNS_NONE ======> got hit: "[ ip=69.10.67.106 rdns= " [97034] dbg: rules: ran header rule __LAST_UNTRUSTED_RELAY_NO_AUTH ======> got hit: "[ ip=69.10.67.106 rdns= helo=mbp.local by=alexus.org ident= envfrom= intl=0 id= auth= " [97034] dbg: rules: ran header rule __DOS_SINGLE_EXT_RELAY ======> got hit: "[ ip=69.10.67.106 rdns= helo=mbp.local by=alexus.org ident= envfrom= intl=0 id= auth= msa=0 ]" [97034] dbg: rules: ran header rule __MISSING_REF ======> got hit: "UNSET" [97034] dbg: rules: ran header rule __NAKED_TO ======> got hit: "postmas...@8au.net" [97034] dbg: rules: ran header rule __HAS_RCVD ======> got hit: "f" [97034] dbg: rules: ran header rule __DOS_RCVD_THU ======> got hit: " Thu, " [97034] dbg: rules: ran header rule __TOCC_EXISTS ======> got hit: "p" [97034] dbg: rules: ran header rule __MSGID_OK_HOST ======> got hit: "@mbp.local>" [97034] dbg: rules: ran header rule __MSGID_OK_DIGITS ======> got hit: "2009032620" [97034] dbg: rules: ran header rule __MSOE_MID_WRONG_CASE ======> got hit: " [97034] dbg: rules: Message-Id: " [97034] dbg: rules: ran header rule __HAS_MSGID ======> got hit: "<" [97034] dbg: rules: ran header rule __SANE_MSGID ======> got hit: "<20090326205319.9252c69c...@mbp.local> [97034] dbg: rules: " [97034] dbg: spf: checking to see if the message has a Received-SPF header that we can use [97034] dbg: spf: checking HELO (helo=mbp.local, ip=69.10.67.106) [97034] dbg: spf: query for /69.10.67.106/mbp.local: result: none, comment: , text: No applicable sender policy available [97034] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks [97034] dbg: spf: cannot get Envelope-From, cannot use SPF [97034] dbg: spf: def_spf_whitelist_from: already checked spf and didn't get pass, skipping whitelist check [97034] dbg: spf: whitelist_from_spf: already checked spf and didn't get pass, skipping whitelist check [97034] dbg: rules: running body tests; score so far=0 [97034] dbg: rules: ran body rule __NONEMPTY_BODY ======> got hit: "1" [97034] dbg: rules: running uri tests; score so far=0 [97034] dbg: eval: stock info total: 0 [97034] dbg: rules: ran eval rule TVD_SPACE_RATIO ======> got hit (1) [97034] dbg: rules: running rawbody tests; score so far=2.899 [97034] dbg: rules: running full tests; score so far=2.899 [97034] dbg: pyzor: pyzor is not available: no pyzor executable found [97034] dbg: pyzor: no pyzor found, disabling Pyzor [97034] dbg: rules: running meta tests; score so far=2.899 [97034] dbg: check: running tests for priority: 500 [97034] dbg: dns: harvest_dnsbl_queries [97034] dbg: async: timing: 0.515 . dns:A:106.67.10.69.iadb.isipp.com. [97034] dbg: async: timing: 2.564 . dns:TXT:106.67.10.69.sa-trusted.bondedsender.org. [97034] dbg: async: timing: 5.125 . dns:A:106.67.10.69.list.dnswl.org. [97034] dbg: async: timing: 6.405 . dns:A:106.67.10.69.sa-accredit.habeas.com. [97034] dbg: async: timing: 7.431 . dns:TXT:106.67.10.69.list.dsbl.org. [97034] dbg: async: timing: 9.223 . dns:A:106.67.10.69.dnsbl.sorbs.net. [97034] dbg: async: timing: 10.504 . dns:A:106.67.10.69.zen.spamhaus.org. [97034] dbg: async: timing: 11.784 . dns:TXT:106.67.10.69.bl.spamcop.net. [97034] dbg: async: timing: 13.065 . dns:A:106.67.10.69.combined.njabl.org. [97034] dbg: async: timing: 14.345 . dns:A:106.67.10.69.plus.bondedsender.org. [97034] dbg: rules: running head tests; score so far=2.899 [97034] dbg: rules: running body tests; score so far=2.899 [97034] dbg: rules: running uri tests; score so far=2.899 [97034] dbg: rules: running rawbody tests; score so far=2.899 [97034] dbg: rules: running full tests; score so far=2.899 [97034] dbg: rules: running meta tests; score so far=2.899 [97034] dbg: check: running tests for priority: 1000 [97034] dbg: rules: running head tests; score so far=4.284 [97034] dbg: locker: safe_lock: created /home/spamd/.spamassassin/auto-whitelist.lock.mx1.alexus.biz.97034 [97034] dbg: locker: safe_lock: trying to get lock on /home/spamd/.spamassassin/auto-whitelist with 0 retries [97034] dbg: locker: safe_lock: link to /home/spamd/.spamassassin/auto-whitelist.lock: link ok [97034] dbg: auto-whitelist: tie-ing to DB file of type DB_File R/W in /home/spamd/.spamassassin/auto-whitelist [97034] dbg: auto-whitelist: db-based ale...@mbp.local|ip=69.10 scores 18/77.112 [97034] dbg: auto-whitelist: AWL active, pre-score: 4.284, autolearn score: 4.284, mean: 4.284, IP: 69.10.67.106 [97034] dbg: auto-whitelist: add_score: new count: 19, new totscore: 81.396 [97034] dbg: auto-whitelist: DB addr list: untie-ing and unlocking [97034] dbg: auto-whitelist: DB addr list: file locked, breaking lock [97034] dbg: locker: safe_unlock: unlink /home/spamd/.spamassassin/auto-whitelist.lock [97034] dbg: auto-whitelist: post auto-whitelist score: 4.284 [97034] dbg: rules: running body tests; score so far=4.284 [97034] dbg: rules: running uri tests; score so far=4.284 [97034] dbg: rules: running rawbody tests; score so far=4.284 [97034] dbg: rules: running full tests; score so far=4.284 [97034] dbg: rules: running meta tests; score so far=4.284 [97034] dbg: plugin: Mail::SpamAssassin::Plugin::AutoLearnThreshold=HASH(0x80330b018) implements 'autolearn_discriminator', priority 0 [97034] dbg: learn: auto-learn: currently using scoreset 1 [97034] dbg: learn: auto-learn: message score: 4.284, computed score for autolearn: 4.284 [97034] dbg: learn: auto-learn? ham=0.1, spam=12, body-points=4.284, head-points=4.284, learned-points=0 [97034] dbg: learn: auto-learn? no: inside auto-learn thresholds, not considered ham or spam [97034] dbg: check: is spam? score=4.284 required=5 [97034] dbg: check: tests=MISSING_SUBJECT,RDNS_NONE,TVD_SPACE_RATIO [97034] dbg: check: subtests=__DOS_RCVD_THU,__DOS_SINGLE_EXT_RELAY,__HAS_MSGID,__HAS_RCVD,__LAST_UNTRUSTED_RELAY_NO_AUTH,__MISSING_REF,__MSGID_OK_DIGITS,__MSGID_OK_HOST,__MSOE_MID_WRONG_CASE,__NAKED_TO,__NONEMPTY_BODY,__RDNS_NONE,__SANE_MSGID,__TOCC_EXISTS [97034] info: spamd: clean message (4.3/5.0) for simscan:58 in 53.8 seconds, 366 bytes. [97034] info: spamd: result: . 4 - MISSING_SUBJECT,RDNS_NONE,TVD_SPACE_RATIO scantime=53.8,size=366,user=simscan,uid=58,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=/tmp/spamd.sock,mid=<20090326205319.9252c69c...@mbp.local>,autolearn=no [97034] dbg: config: copying current conf from backup [97034] dbg: prefork: periodic ping from spamd parent [96985] dbg: prefork: child 97034: entering state 1 [96985] dbg: prefork: new lowest idle kid: 97034 [97034] dbg: prefork: sysread(8) not ready, wait max 300 secs [96985] dbg: prefork: child reports idle [96985] info: prefork: child states: II [97035] dbg: prefork: periodic ping from spamd parent [97034] dbg: prefork: periodic ping from spamd parent [97035] dbg: prefork: sysread(9) not ready, wait max 300 secs [97034] dbg: prefork: sysread(8) not ready, wait max 300 secs -- http://alexus.org/
