See <https://builds.apache.org/job/SpamAssassin-trunk/8138/changes>
Changes: [khopesh] auto-generated rules [spamassassin_role] updated scores for revision 1403196 active rules added since last mass-check ------------------------------------------ [...truncated 1400 lines...] malformed debug message: Oct 30 21:51:16.756 [6062] dbg: rules: __AXB_MO_OL_28361 merged duplicates: __AXB_MO_OL_5D6C6 malformed debug message: Oct 30 21:51:16.757 [6062] dbg: config: finish parsing malformed debug message: Oct 30 21:51:16.781 [6062] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x8ef6934) implements 'finish_parsing_end', priority 0 malformed debug message: Oct 30 21:51:16.781 [6062] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x91d053c) implements 'finish_parsing_end', priority 0 malformed debug message: Oct 30 21:51:16.781 [6062] dbg: replacetags: replacing tags malformed debug message: Oct 30 21:51:16.804 [6062] dbg: replacetags: done replacing tags malformed debug message: Oct 30 21:51:16.805 [6062] dbg: FreeMail: loaded freemail_domains entries: 2112 normal, 26 wildcard malformed debug message: Oct 30 21:51:16.808 [6062] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0x905e218) implements 'learner_new', priority 0 malformed debug message: Oct 30 21:51:16.808 [6062] dbg: bayes: learner_new self=Mail::SpamAssassin::Plugin::Bayes=HASH(0x905e218), bayes_store_module=Mail::SpamAssassin::BayesStore::DBM malformed debug message: Oct 30 21:51:16.819 [6062] dbg: bayes: learner_new: got store=Mail::SpamAssassin::BayesStore::DBM=HASH(0x94615e8) malformed debug message: Oct 30 21:51:16.819 [6062] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0x905e218) implements 'learner_is_scan_available', priority 0 malformed debug message: Oct 30 21:51:16.819 [6062] dbg: bayes: DB_File module not installed, cannot use bayes malformed debug message: Oct 30 21:51:16.819 [6062] dbg: config: score set 1 chosen. malformed debug message: Oct 30 21:51:16.819 [6062] dbg: config: time limit 300.0 s malformed debug message: Oct 30 21:51:16.820 [6062] dbg: message: main message type: text/plain malformed debug message: Oct 30 21:51:16.820 [6062] dbg: check: pms new, time limit in 298.372 s malformed debug message: Oct 30 21:51:16.821 [6062] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x8f92f80) implements 'check_start', priority 0 malformed debug message: Oct 30 21:51:16.821 [6062] dbg: bayes: DB_File module not installed, cannot use bayes malformed debug message: Oct 30 21:51:16.822 [6062] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x9054a74) implements 'check_main', priority 0 malformed debug message: Oct 30 21:51:16.822 [6062] dbg: config: trusted_networks are not configured; it is recommended that you configure trusted_networks manually malformed debug message: Oct 30 21:51:16.829 [6062] dbg: received-header: parsed as [ ip=127.0.0.1 rdns= helo=internal.example.com by=localhost ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.829 [6062] dbg: received-header: 'from' 127.0.0.1 has private IP malformed debug message: Oct 30 21:51:16.829 [6062] dbg: received-header: relay 127.0.0.1 trusted? yes internal? yes msa? no malformed debug message: Oct 30 21:51:16.829 [6062] dbg: received-header: parsed as [ ip=150.51.53.1 rdns= helo=dmz.example.com by=internal.example.com ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.829 [6062] dbg: received-header: do not trust any hosts from here on malformed debug message: Oct 30 21:51:16.829 [6062] dbg: received-header: relay 150.51.53.1 trusted? no internal? no msa? no malformed debug message: Oct 30 21:51:16.829 [6062] dbg: received-header: parsed as [ ip=212.17.35.14 rdns= helo=friend.example.com by=dmz.example.com ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.829 [6062] dbg: received-header: relay 212.17.35.14 trusted? no internal? no msa? no malformed debug message: Oct 30 21:51:16.830 [6062] dbg: received-header: parsed as [ ip=193.120.149.226 rdns= helo=notrust.example.com by=friend.example.com ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.830 [6062] dbg: received-header: relay 193.120.149.226 trusted? no internal? no msa? no malformed debug message: Oct 30 21:51:16.830 [6062] dbg: received-header: parsed as [ ip=61.119.13.18 rdns= helo=loser.example.org by=notrust.example.com ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.830 [6062] dbg: received-header: relay 61.119.13.18 trusted? no internal? no msa? no malformed debug message: Oct 30 21:51:16.830 [6062] dbg: received-header: parsed as [ ip=210.73.88.134 rdns= helo=chaos.example.net by=loser.example.org ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.830 [6062] dbg: received-header: relay 210.73.88.134 trusted? no internal? no msa? no malformed debug message: Oct 30 21:51:16.831 [6062] dbg: received-header: parsed as [ ip=144.137.3.98 rdns= helo=evil.example.net by=chaos.example.net ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.831 [6062] dbg: received-header: relay 144.137.3.98 trusted? no internal? no msa? no malformed debug message: Oct 30 21:51:16.831 [6062] dbg: metadata: X-Spam-Relays-Trusted: [ ip=127.0.0.1 rdns= helo=internal.example.com by=localhost ident= envfrom= intl=1 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.831 [6062] dbg: metadata: X-Spam-Relays-Untrusted: [ ip=150.51.53.1 rdns= helo=dmz.example.com by=internal.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=212.17.35.14 rdns= helo=friend.example.com by=dmz.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=193.120.149.226 rdns= helo=notrust.example.com by=friend.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=61.119.13.18 rdns= helo=loser.example.org by=notrust.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=210.73.88.134 rdns= helo=chaos.example.net by=loser.example.org ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=144.137.3.98 rdns= helo=evil.example.net by=chaos.example.net ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.831 [6062] dbg: metadata: X-Spam-Relays-Internal: [ ip=127.0.0.1 rdns= helo=internal.example.com by=localhost ident= envfrom= intl=1 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.831 [6062] dbg: metadata: X-Spam-Relays-External: [ ip=150.51.53.1 rdns= helo=dmz.example.com by=internal.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=212.17.35.14 rdns= helo=friend.example.com by=dmz.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=193.120.149.226 rdns= helo=notrust.example.com by=friend.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=61.119.13.18 rdns= helo=loser.example.org by=notrust.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=210.73.88.134 rdns= helo=chaos.example.net by=loser.example.org ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=144.137.3.98 rdns= helo=evil.example.net by=chaos.example.net ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.831 [6062] dbg: check: tagrun - tag LASTEXTERNALREVIP is now ready, value: 1.53.51.150 malformed debug message: Oct 30 21:51:16.831 [6062] dbg: check: tagrun - tag FIRSTTRUSTEDREVIP is now ready, value: 1.53.51.150 malformed debug message: Oct 30 21:51:16.831 [6062] dbg: plugin: Mail::SpamAssassin::Plugin::AskDNS=HASH(0x8fe945c) implements 'extract_metadata', priority 0 malformed debug message: Oct 30 21:51:16.832 [6062] dbg: dns: socket module for IPv6 support not available malformed debug message: Oct 30 21:51:16.832 [6062] dbg: dns: using socket module: IO::Socket::INET, forced IPv4 malformed debug message: Oct 30 21:51:16.832 [6062] dbg: dns: is Net::DNS::Resolver available? yes malformed debug message: Oct 30 21:51:16.832 [6062] dbg: dns: Net::DNS version: 0.49 malformed debug message: Oct 30 21:51:16.832 [6062] dbg: dns: dns_available set to yes in config file, skipping test malformed debug message: Oct 30 21:51:16.832 [6062] dbg: check: tagrun - action 0 blocking on tags DKIMDOMAIN malformed debug message: Oct 30 21:51:16.832 [6062] dbg: check: tagrun - tag RELAYSTRUSTED is now ready, value: [ ip=127.0.0.1 rdns= helo=internal.example.com by=localhost ident= envfrom= intl=1 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.832 [6062] dbg: check: tagrun - tag RELAYSUNTRUSTED is now ready, value: [ ip=150.51.53.1 rdns= helo=dmz.example.com by=internal.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=212.17.35.14 rdns= helo=friend.example.com by=dmz.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=193.120.149.226 rdns= helo=notrust.example.com by=friend.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=61.119.13.18 rdns= helo=loser.example.org by=notrust.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=210.73.88.134 rdns= helo=chaos.example.net by=loser.example.org ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=144.137.3.98 rdns= helo=evil.example.net by=chaos.example.net ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.832 [6062] dbg: check: tagrun - tag RELAYSINTERNAL is now ready, value: [ ip=127.0.0.1 rdns= helo=internal.example.com by=localhost ident= envfrom= intl=1 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.832 [6062] dbg: check: tagrun - tag RELAYSEXTERNAL is now ready, value: [ ip=150.51.53.1 rdns= helo=dmz.example.com by=internal.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=212.17.35.14 rdns= helo=friend.example.com by=dmz.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=193.120.149.226 rdns= helo=notrust.example.com by=friend.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=61.119.13.18 rdns= helo=loser.example.org by=notrust.example.com ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=210.73.88.134 rdns= helo=chaos.example.net by=loser.example.org ident= envfrom= intl=0 id= auth= msa=0 ] [ ip=144.137.3.98 rdns= helo=evil.example.net by=chaos.example.net ident= envfrom= intl=0 id= auth= msa=0 ] malformed debug message: Oct 30 21:51:16.832 [6062] dbg: check: tagrun - tag LANGUAGES is now ready, value: <UNDEF> malformed debug message: Oct 30 21:51:16.832 [6062] dbg: message: ---- MIME PARSER START ---- malformed debug message: Oct 30 21:51:16.832 [6062] dbg: message: parsing normal part malformed debug message: Oct 30 21:51:16.832 [6062] dbg: message: storing a body to memory malformed debug message: Oct 30 21:51:16.832 [6062] dbg: message: ---- MIME PARSER END ---- malformed debug message: Oct 30 21:51:16.833 [6062] dbg: message: no encoding detected malformed debug message: Oct 30 21:51:16.833 [6062] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x8e9a78c) implements 'parsed_metadata', priority 0 malformed debug message: Oct 30 21:51:16.834 [6062] dbg: check: tagrun - tag URIHOSTS is now ready, value: ARY:[uribl-example-b.com,foo.bar.baz.uribl-example-a.com,144.137.3.98,uribl-example-c.com,spamassassin.org] malformed debug message: Oct 30 21:51:16.834 [6062] dbg: check: tagrun - tag URIDOMAINS is now ready, value: ARY:[uribl-example-b.com,uribl-example-a.com,144.137.3.98,uribl-example-c.com,spamassassin.org] malformed debug message: Oct 30 21:51:16.834 [6062] dbg: uridnsbl: considering host=uribl-example-b.com, domain=uribl-example-b.com malformed debug message: Oct 30 21:51:16.835 [6062] dbg: dns: servers obtained from Net::DNS : [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.835 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.835 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.835 [6062] dbg: dns: connect_sock, resolver: yes malformed debug message: Oct 30 21:51:16.835 [6062] dbg: dns: LocalAddr: 0.0.0.0, name server(s): [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.841 [6062] dbg: dns: 53959 configured local ports for DNS queries malformed debug message: Oct 30 21:51:16.842 [6062] dbg: dns: resolver socket rx buffer size is 57344 bytes, local port 43845 malformed debug message: Oct 30 21:51:16.842 [6062] dbg: dns: providing a callback for id: 52260/uribl-example-b.com.multi.surbl.org/A/IN malformed debug message: Oct 30 21:51:16.842 [6062] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:uribl-example-b.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.843 [6062] dbg: dns: URIBL_PH_SURBL lookup start malformed debug message: Oct 30 21:51:16.843 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.843 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.843 [6062] dbg: dns: providing a callback for id: 52261/uribl-example-b.com.multi.uribl.com/A/IN malformed debug message: Oct 30 21:51:16.843 [6062] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:uribl-example-b.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.843 [6062] dbg: dns: URIBL_BLACK lookup start malformed debug message: Oct 30 21:51:16.843 [6062] dbg: dns: URIBL_RED lookup start malformed debug message: Oct 30 21:51:16.843 [6062] dbg: dns: URIBL_GREY lookup start malformed debug message: Oct 30 21:51:16.843 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.843 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.843 [6062] dbg: dns: providing a callback for id: 52262/uribl-example-b.com.dob.sibl.support-intelligence.net/A/IN malformed debug message: Oct 30 21:51:16.844 [6062] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:uribl-example-b.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: URIBL_RHS_DOB lookup start malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: URIBL_SC_SURBL lookup start malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: URIBL_JP_SURBL lookup start malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: URIBL_AB_SURBL lookup start malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: URIBL_WS_SURBL lookup start malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: URIBL_BLOCKED lookup start malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: providing a callback for id: 52263/uribl-example-b.com.dbl.spamhaus.org/A/IN malformed debug message: Oct 30 21:51:16.844 [6062] dbg: async: starting: URI-DNSBL, DNSBL:dbl.spamhaus.org.:uribl-example-b.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: URIBL_DBL_ERROR lookup start malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: URIBL_DBL_SPAM lookup start malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: URIBL_DBL_REDIR lookup start malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.844 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.845 [6062] dbg: dns: providing a callback for id: 52264/uribl-example-b.com/NS/IN malformed debug message: Oct 30 21:51:16.845 [6062] dbg: async: starting: URI-NS, NS:uribl-example-b.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.845 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.845 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.845 [6062] dbg: dns: providing a callback for id: 52265/uribl-example-b.com/A/IN malformed debug message: Oct 30 21:51:16.845 [6062] dbg: async: starting: URI-A, A:uribl-example-b.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.845 [6062] dbg: dns: URIBL_SBL_A lookup start malformed debug message: Oct 30 21:51:16.845 [6062] dbg: uridnsbl: considering host=foo.bar.baz.uribl-example-a.com, domain=uribl-example-a.com malformed debug message: Oct 30 21:51:16.845 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.845 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.845 [6062] dbg: dns: providing a callback for id: 52266/uribl-example-a.com.multi.surbl.org/A/IN malformed debug message: Oct 30 21:51:16.845 [6062] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:uribl-example-a.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: URIBL_PH_SURBL lookup start malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: providing a callback for id: 52267/uribl-example-a.com.multi.uribl.com/A/IN malformed debug message: Oct 30 21:51:16.846 [6062] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:uribl-example-a.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: URIBL_BLACK lookup start malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: URIBL_RED lookup start malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: URIBL_GREY lookup start malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: providing a callback for id: 52268/uribl-example-a.com.dob.sibl.support-intelligence.net/A/IN malformed debug message: Oct 30 21:51:16.846 [6062] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:uribl-example-a.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.846 [6062] dbg: dns: URIBL_RHS_DOB lookup start malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: URIBL_SC_SURBL lookup start malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: URIBL_JP_SURBL lookup start malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: URIBL_AB_SURBL lookup start malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: URIBL_WS_SURBL lookup start malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: URIBL_BLOCKED lookup start malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: providing a callback for id: 52269/uribl-example-a.com.dbl.spamhaus.org/A/IN malformed debug message: Oct 30 21:51:16.847 [6062] dbg: async: starting: URI-DNSBL, DNSBL:dbl.spamhaus.org.:uribl-example-a.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: URIBL_DBL_ERROR lookup start malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: URIBL_DBL_SPAM lookup start malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: URIBL_DBL_REDIR lookup start malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.847 [6062] dbg: dns: providing a callback for id: 52270/uribl-example-a.com/NS/IN malformed debug message: Oct 30 21:51:16.848 [6062] dbg: async: starting: URI-NS, NS:uribl-example-a.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.848 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.848 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.848 [6062] dbg: dns: providing a callback for id: 52271/foo.bar.baz.uribl-example-a.com/A/IN malformed debug message: Oct 30 21:51:16.848 [6062] dbg: async: starting: URI-A, A:foo.bar.baz.uribl-example-a.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.848 [6062] dbg: dns: URIBL_SBL_A lookup start malformed debug message: Oct 30 21:51:16.848 [6062] dbg: uridnsbl: considering host=144.137.3.98, domain=144.137.3.98 malformed debug message: Oct 30 21:51:16.848 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.848 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.849 [6062] dbg: dns: providing a callback for id: 52272/98.3.137.144.sbl.spamhaus.org/A/IN malformed debug message: Oct 30 21:51:16.849 [6062] dbg: async: starting: URI-DNSBL, DNSBL:sbl.spamhaus.org.:98.3.137.144 (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.849 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.849 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.849 [6062] dbg: dns: providing a callback for id: 52273/98.3.137.144.zen.spamhaus.org/A/IN malformed debug message: Oct 30 21:51:16.849 [6062] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:98.3.137.144 (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.849 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.849 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.849 [6062] dbg: dns: providing a callback for id: 52274/98.3.137.144.multi.surbl.org/A/IN malformed debug message: Oct 30 21:51:16.849 [6062] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:98.3.137.144 (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.850 [6062] dbg: dns: URIBL_PH_SURBL lookup start malformed debug message: Oct 30 21:51:16.850 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.850 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.850 [6062] dbg: dns: providing a callback for id: 52275/98.3.137.144.multi.uribl.com/A/IN malformed debug message: Oct 30 21:51:16.850 [6062] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:98.3.137.144 (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.850 [6062] dbg: dns: URIBL_BLACK lookup start malformed debug message: Oct 30 21:51:16.850 [6062] dbg: dns: URIBL_RED lookup start malformed debug message: Oct 30 21:51:16.850 [6062] dbg: dns: URIBL_GREY lookup start malformed debug message: Oct 30 21:51:16.850 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.850 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.850 [6062] dbg: dns: providing a callback for id: 52276/98.3.137.144.dob.sibl.support-intelligence.net/A/IN malformed debug message: Oct 30 21:51:16.851 [6062] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:98.3.137.144 (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: URIBL_RHS_DOB lookup start malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: URIBL_SC_SURBL lookup start malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: URIBL_JP_SURBL lookup start malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: URIBL_AB_SURBL lookup start malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: URIBL_WS_SURBL lookup start malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: URIBL_BLOCKED lookup start malformed debug message: Oct 30 21:51:16.851 [6062] dbg: uridnsbl: considering host=uribl-example-c.com, domain=uribl-example-c.com malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: providing a callback for id: 52277/uribl-example-c.com.multi.surbl.org/A/IN malformed debug message: Oct 30 21:51:16.851 [6062] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:uribl-example-c.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: URIBL_PH_SURBL lookup start malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.851 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: providing a callback for id: 52278/uribl-example-c.com.multi.uribl.com/A/IN malformed debug message: Oct 30 21:51:16.852 [6062] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:uribl-example-c.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: URIBL_BLACK lookup start malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: URIBL_RED lookup start malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: URIBL_GREY lookup start malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: providing a callback for id: 52279/uribl-example-c.com.dob.sibl.support-intelligence.net/A/IN malformed debug message: Oct 30 21:51:16.852 [6062] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:uribl-example-c.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: URIBL_RHS_DOB lookup start malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: URIBL_SC_SURBL lookup start malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: URIBL_JP_SURBL lookup start malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: URIBL_AB_SURBL lookup start malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: URIBL_WS_SURBL lookup start malformed debug message: Oct 30 21:51:16.852 [6062] dbg: dns: URIBL_BLOCKED lookup start malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: providing a callback for id: 52280/uribl-example-c.com.dbl.spamhaus.org/A/IN malformed debug message: Oct 30 21:51:16.853 [6062] dbg: async: starting: URI-DNSBL, DNSBL:dbl.spamhaus.org.:uribl-example-c.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: URIBL_DBL_ERROR lookup start malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: URIBL_DBL_SPAM lookup start malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: URIBL_DBL_REDIR lookup start malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: providing a callback for id: 52281/uribl-example-c.com/NS/IN malformed debug message: Oct 30 21:51:16.853 [6062] dbg: async: starting: URI-NS, NS:uribl-example-c.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.853 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.854 [6062] dbg: dns: providing a callback for id: 52282/uribl-example-c.com/A/IN malformed debug message: Oct 30 21:51:16.854 [6062] dbg: async: starting: URI-A, A:uribl-example-c.com (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.854 [6062] dbg: dns: URIBL_SBL_A lookup start malformed debug message: Oct 30 21:51:16.854 [6062] dbg: uridnsbl: considering host=spamassassin.org, domain=spamassassin.org malformed debug message: Oct 30 21:51:16.854 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.854 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.854 [6062] dbg: dns: providing a callback for id: 52283/spamassassin.org.multi.surbl.org/A/IN malformed debug message: Oct 30 21:51:16.854 [6062] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:spamassassin.org (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.854 [6062] dbg: dns: URIBL_PH_SURBL lookup start malformed debug message: Oct 30 21:51:16.854 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.854 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.854 [6062] dbg: dns: providing a callback for id: 52284/spamassassin.org.multi.uribl.com/A/IN malformed debug message: Oct 30 21:51:16.855 [6062] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:spamassassin.org (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: URIBL_BLACK lookup start malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: URIBL_RED lookup start malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: URIBL_GREY lookup start malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: providing a callback for id: 52285/spamassassin.org.dob.sibl.support-intelligence.net/A/IN malformed debug message: Oct 30 21:51:16.855 [6062] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:spamassassin.org (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: URIBL_RHS_DOB lookup start malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: URIBL_SC_SURBL lookup start malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: URIBL_JP_SURBL lookup start malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: URIBL_AB_SURBL lookup start malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: URIBL_WS_SURBL lookup start malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: URIBL_BLOCKED lookup start malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.855 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.856 [6062] dbg: dns: providing a callback for id: 52286/spamassassin.org.dbl.spamhaus.org/A/IN malformed debug message: Oct 30 21:51:16.856 [6062] dbg: async: starting: URI-DNSBL, DNSBL:dbl.spamhaus.org.:spamassassin.org (timeout 15.0s, min 3.0s) malformed debug message: Oct 30 21:51:16.856 [6062] dbg: dns: URIBL_DBL_ERROR lookup start malformed debug message: Oct 30 21:51:16.856 [6062] dbg: dns: URIBL_DBL_SPAM lookup start malformed debug message: Oct 30 21:51:16.856 [6062] dbg: dns: URIBL_DBL_REDIR lookup start malformed debug message: Oct 30 21:51:16.856 [6062] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.856 [6062] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53 malformed debug message: Oct 30 21:51:16.856 [6062] dbg: dns: providing a callback for id: 52287/spamassassin.org/NS/IN malformed debug message: Oct 30 21:51:16.856 [6062] dbg: async: starting: URI-NS, NS:spamassassinRecording test results
