You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@spamassassin.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2012/11/02 16:10:09 UTC

Build failed in Jenkins: SpamAssassin-trunk #8139

See <https://builds.apache.org/job/SpamAssassin-trunk/8139/changes>

Changes:

[spamassassin_role] promotions validated

[khopesh] auto-generated rules

[spamassassin_role] updated scores for revision 1404039 active rules added since last mass-check

[axb] allow Ga manage HK_LOTTO hardwired score

[spamassassin_role] promotions validated

[khopesh] auto-generated rules

[spamassassin_role] updated scores for revision 1403638 active rules added since last mass-check

------------------------------------------
[...truncated 1402 lines...]
malformed debug message: Nov  2 03:08:43.838 [2972] dbg: rules: __AXB_MO_OL_28361 merged duplicates: __AXB_MO_OL_5D6C6
malformed debug message: Nov  2 03:08:43.839 [2972] dbg: config: finish parsing
malformed debug message: Nov  2 03:08:43.863 [2972] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x8ef69d4) implements 'finish_parsing_end', priority 0
malformed debug message: Nov  2 03:08:43.863 [2972] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x91d0ebc) implements 'finish_parsing_end', priority 0
malformed debug message: Nov  2 03:08:43.863 [2972] dbg: replacetags: replacing tags
malformed debug message: Nov  2 03:08:43.887 [2972] dbg: replacetags: done replacing tags
malformed debug message: Nov  2 03:08:43.887 [2972] dbg: FreeMail: loaded freemail_domains entries: 2112 normal, 26 wildcard
malformed debug message: Nov  2 03:08:43.891 [2972] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0x905e380) implements 'learner_new', priority 0
malformed debug message: Nov  2 03:08:43.891 [2972] dbg: bayes: learner_new self=Mail::SpamAssassin::Plugin::Bayes=HASH(0x905e380), bayes_store_module=Mail::SpamAssassin::BayesStore::DBM
malformed debug message: Nov  2 03:08:43.902 [2972] dbg: bayes: learner_new: got store=Mail::SpamAssassin::BayesStore::DBM=HASH(0x94c78c4)
malformed debug message: Nov  2 03:08:43.902 [2972] dbg: plugin: Mail::SpamAssassin::Plugin::Bayes=HASH(0x905e380) implements 'learner_is_scan_available', priority 0
malformed debug message: Nov  2 03:08:43.902 [2972] dbg: bayes: DB_File module not installed, cannot use bayes
malformed debug message: Nov  2 03:08:43.902 [2972] dbg: config: score set 1 chosen.
malformed debug message: Nov  2 03:08:43.902 [2972] dbg: config: time limit 300.0 s
malformed debug message: Nov  2 03:08:43.903 [2972] dbg: message: main message type: text/plain
malformed debug message: Nov  2 03:08:43.903 [2972] dbg: check: pms new, time limit in 298.398 s
malformed debug message: Nov  2 03:08:43.904 [2972] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x8f93000) implements 'check_start', priority 0
malformed debug message: Nov  2 03:08:43.904 [2972] dbg: bayes: DB_File module not installed, cannot use bayes
malformed debug message: Nov  2 03:08:43.904 [2972] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x904366c) implements 'check_main', priority 0
malformed debug message: Nov  2 03:08:43.905 [2972] dbg: config: trusted_networks are not configured; it is recommended that you configure trusted_networks manually
malformed debug message: Nov  2 03:08:43.912 [2972] 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: Nov  2 03:08:43.912 [2972] dbg: received-header: 'from' 127.0.0.1 has private IP
malformed debug message: Nov  2 03:08:43.912 [2972] dbg: received-header: relay 127.0.0.1 trusted? yes internal? yes msa? no
malformed debug message: Nov  2 03:08:43.912 [2972] 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: Nov  2 03:08:43.912 [2972] dbg: received-header: do not trust any hosts from here on
malformed debug message: Nov  2 03:08:43.912 [2972] dbg: received-header: relay 150.51.53.1 trusted? no internal? no msa? no
malformed debug message: Nov  2 03:08:43.912 [2972] 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: Nov  2 03:08:43.913 [2972] dbg: received-header: relay 212.17.35.14 trusted? no internal? no msa? no
malformed debug message: Nov  2 03:08:43.913 [2972] 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: Nov  2 03:08:43.913 [2972] dbg: received-header: relay 193.120.149.226 trusted? no internal? no msa? no
malformed debug message: Nov  2 03:08:43.913 [2972] 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: Nov  2 03:08:43.913 [2972] dbg: received-header: relay 61.119.13.18 trusted? no internal? no msa? no
malformed debug message: Nov  2 03:08:43.913 [2972] 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: Nov  2 03:08:43.913 [2972] dbg: received-header: relay 210.73.88.134 trusted? no internal? no msa? no
malformed debug message: Nov  2 03:08:43.914 [2972] 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: Nov  2 03:08:43.914 [2972] dbg: received-header: relay 144.137.3.98 trusted? no internal? no msa? no
malformed debug message: Nov  2 03:08:43.914 [2972] 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: Nov  2 03:08:43.914 [2972] 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: Nov  2 03:08:43.914 [2972] 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: Nov  2 03:08:43.914 [2972] 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: Nov  2 03:08:43.914 [2972] dbg: check: tagrun - tag LASTEXTERNALREVIP is now ready, value: 1.53.51.150
malformed debug message: Nov  2 03:08:43.914 [2972] dbg: check: tagrun - tag FIRSTTRUSTEDREVIP is now ready, value: 1.53.51.150
malformed debug message: Nov  2 03:08:43.914 [2972] dbg: plugin: Mail::SpamAssassin::Plugin::AskDNS=HASH(0x8fe086c) implements 'extract_metadata', priority 0
malformed debug message: Nov  2 03:08:43.915 [2972] dbg: dns: socket module for IPv6 support not available
malformed debug message: Nov  2 03:08:43.915 [2972] dbg: dns: using socket module: IO::Socket::INET, forced IPv4
malformed debug message: Nov  2 03:08:43.915 [2972] dbg: dns: is Net::DNS::Resolver available? yes
malformed debug message: Nov  2 03:08:43.915 [2972] dbg: dns: Net::DNS version: 0.49
malformed debug message: Nov  2 03:08:43.915 [2972] dbg: dns: dns_available set to yes in config file, skipping test
malformed debug message: Nov  2 03:08:43.915 [2972] dbg: check: tagrun - action 0 blocking on tags DKIMDOMAIN
malformed debug message: Nov  2 03:08:43.915 [2972] 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: Nov  2 03:08:43.915 [2972] 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: Nov  2 03:08:43.915 [2972] 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: Nov  2 03:08:43.915 [2972] 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: Nov  2 03:08:43.915 [2972] dbg: check: tagrun - tag LANGUAGES is now ready, value: <UNDEF>
malformed debug message: Nov  2 03:08:43.915 [2972] dbg: message: ---- MIME PARSER START ----
malformed debug message: Nov  2 03:08:43.916 [2972] dbg: message: parsing normal part
malformed debug message: Nov  2 03:08:43.916 [2972] dbg: message: storing a body to memory
malformed debug message: Nov  2 03:08:43.916 [2972] dbg: message: ---- MIME PARSER END ----
malformed debug message: Nov  2 03:08:43.916 [2972] dbg: message: no encoding detected
malformed debug message: Nov  2 03:08:43.916 [2972] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x8e9a82c) implements 'parsed_metadata', priority 0
malformed debug message: Nov  2 03:08:43.918 [2972] 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: Nov  2 03:08:43.918 [2972] 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: Nov  2 03:08:43.918 [2972] dbg: uridnsbl: considering host=uribl-example-b.com, domain=uribl-example-b.com
malformed debug message: Nov  2 03:08:43.918 [2972] dbg: dns: servers obtained from Net::DNS : [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.918 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.918 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.918 [2972] dbg: dns: connect_sock, resolver: yes
malformed debug message: Nov  2 03:08:43.918 [2972] dbg: dns: LocalAddr: 0.0.0.0, name server(s): [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.924 [2972] dbg: dns: 53959 configured local ports for DNS queries
malformed debug message: Nov  2 03:08:43.925 [2972] dbg: dns: resolver socket rx buffer size is 57344 bytes, local port 31738
malformed debug message: Nov  2 03:08:43.926 [2972] dbg: dns: providing a callback for id: 16550/uribl-example-b.com.multi.surbl.org/A/IN
malformed debug message: Nov  2 03:08:43.926 [2972] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:uribl-example-b.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.926 [2972] dbg: dns: URIBL_PH_SURBL lookup start
malformed debug message: Nov  2 03:08:43.926 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.926 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.926 [2972] dbg: dns: providing a callback for id: 16551/uribl-example-b.com.multi.uribl.com/A/IN
malformed debug message: Nov  2 03:08:43.926 [2972] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:uribl-example-b.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.926 [2972] dbg: dns: URIBL_BLACK lookup start
malformed debug message: Nov  2 03:08:43.926 [2972] dbg: dns: URIBL_RED lookup start
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: URIBL_GREY lookup start
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: providing a callback for id: 16552/uribl-example-b.com.dob.sibl.support-intelligence.net/A/IN
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:uribl-example-b.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: URIBL_RHS_DOB lookup start
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: URIBL_SC_SURBL lookup start
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: URIBL_JP_SURBL lookup start
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: URIBL_AB_SURBL lookup start
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: URIBL_WS_SURBL lookup start
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: URIBL_BLOCKED lookup start
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.927 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: dns: providing a callback for id: 16553/uribl-example-b.com.dbl.spamhaus.org/A/IN
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: async: starting: URI-DNSBL, DNSBL:dbl.spamhaus.org.:uribl-example-b.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: dns: URIBL_DBL_ERROR lookup start
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: dns: URIBL_DBL_SPAM lookup start
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: dns: URIBL_DBL_REDIR lookup start
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: dns: providing a callback for id: 16554/uribl-example-b.com/NS/IN
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: async: starting: URI-NS, NS:uribl-example-b.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.928 [2972] dbg: dns: providing a callback for id: 16555/uribl-example-b.com/A/IN
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: async: starting: URI-A, A:uribl-example-b.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: dns: URIBL_SBL_A lookup start
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: uridnsbl: considering host=foo.bar.baz.uribl-example-a.com, domain=uribl-example-a.com
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: dns: providing a callback for id: 16556/uribl-example-a.com.multi.surbl.org/A/IN
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:uribl-example-a.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: dns: URIBL_PH_SURBL lookup start
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.929 [2972] dbg: dns: providing a callback for id: 16557/uribl-example-a.com.multi.uribl.com/A/IN
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:uribl-example-a.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: URIBL_BLACK lookup start
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: URIBL_RED lookup start
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: URIBL_GREY lookup start
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: providing a callback for id: 16558/uribl-example-a.com.dob.sibl.support-intelligence.net/A/IN
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:uribl-example-a.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: URIBL_RHS_DOB lookup start
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: URIBL_SC_SURBL lookup start
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: URIBL_JP_SURBL lookup start
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: URIBL_AB_SURBL lookup start
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: URIBL_WS_SURBL lookup start
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: URIBL_BLOCKED lookup start
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.930 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: dns: providing a callback for id: 16559/uribl-example-a.com.dbl.spamhaus.org/A/IN
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: async: starting: URI-DNSBL, DNSBL:dbl.spamhaus.org.:uribl-example-a.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: dns: URIBL_DBL_ERROR lookup start
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: dns: URIBL_DBL_SPAM lookup start
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: dns: URIBL_DBL_REDIR lookup start
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: dns: providing a callback for id: 16560/uribl-example-a.com/NS/IN
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: async: starting: URI-NS, NS:uribl-example-a.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.931 [2972] dbg: dns: providing a callback for id: 16561/foo.bar.baz.uribl-example-a.com/A/IN
malformed debug message: Nov  2 03:08:43.932 [2972] dbg: async: starting: URI-A, A:foo.bar.baz.uribl-example-a.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.932 [2972] dbg: dns: URIBL_SBL_A lookup start
malformed debug message: Nov  2 03:08:43.932 [2972] dbg: uridnsbl: considering host=144.137.3.98, domain=144.137.3.98
malformed debug message: Nov  2 03:08:43.932 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.932 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.932 [2972] dbg: dns: providing a callback for id: 16562/98.3.137.144.sbl.spamhaus.org/A/IN
malformed debug message: Nov  2 03:08:43.932 [2972] dbg: async: starting: URI-DNSBL, DNSBL:sbl.spamhaus.org.:98.3.137.144 (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.932 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.933 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.933 [2972] dbg: dns: providing a callback for id: 16563/98.3.137.144.zen.spamhaus.org/A/IN
malformed debug message: Nov  2 03:08:43.933 [2972] dbg: async: starting: URI-DNSBL, DNSBL:zen.spamhaus.org.:98.3.137.144 (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.933 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.933 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.933 [2972] dbg: dns: providing a callback for id: 16564/98.3.137.144.multi.surbl.org/A/IN
malformed debug message: Nov  2 03:08:43.933 [2972] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:98.3.137.144 (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.933 [2972] dbg: dns: URIBL_PH_SURBL lookup start
malformed debug message: Nov  2 03:08:43.933 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.933 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: providing a callback for id: 16565/98.3.137.144.multi.uribl.com/A/IN
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:98.3.137.144 (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: URIBL_BLACK lookup start
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: URIBL_RED lookup start
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: URIBL_GREY lookup start
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: providing a callback for id: 16566/98.3.137.144.dob.sibl.support-intelligence.net/A/IN
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:98.3.137.144 (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: URIBL_RHS_DOB lookup start
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: URIBL_SC_SURBL lookup start
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: URIBL_JP_SURBL lookup start
malformed debug message: Nov  2 03:08:43.934 [2972] dbg: dns: URIBL_AB_SURBL lookup start
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: dns: URIBL_WS_SURBL lookup start
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: dns: URIBL_BLOCKED lookup start
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: uridnsbl: considering host=uribl-example-c.com, domain=uribl-example-c.com
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: dns: providing a callback for id: 16567/uribl-example-c.com.multi.surbl.org/A/IN
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:uribl-example-c.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: dns: URIBL_PH_SURBL lookup start
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.935 [2972] dbg: dns: providing a callback for id: 16568/uribl-example-c.com.multi.uribl.com/A/IN
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:uribl-example-c.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: URIBL_BLACK lookup start
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: URIBL_RED lookup start
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: URIBL_GREY lookup start
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: providing a callback for id: 16569/uribl-example-c.com.dob.sibl.support-intelligence.net/A/IN
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:uribl-example-c.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: URIBL_RHS_DOB lookup start
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: URIBL_SC_SURBL lookup start
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: URIBL_JP_SURBL lookup start
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: URIBL_AB_SURBL lookup start
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: URIBL_WS_SURBL lookup start
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: URIBL_BLOCKED lookup start
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.936 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: dns: providing a callback for id: 16570/uribl-example-c.com.dbl.spamhaus.org/A/IN
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: async: starting: URI-DNSBL, DNSBL:dbl.spamhaus.org.:uribl-example-c.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: dns: URIBL_DBL_ERROR lookup start
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: dns: URIBL_DBL_SPAM lookup start
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: dns: URIBL_DBL_REDIR lookup start
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: dns: providing a callback for id: 16571/uribl-example-c.com/NS/IN
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: async: starting: URI-NS, NS:uribl-example-c.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.937 [2972] dbg: dns: providing a callback for id: 16572/uribl-example-c.com/A/IN
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: async: starting: URI-A, A:uribl-example-c.com (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: dns: URIBL_SBL_A lookup start
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: uridnsbl: considering host=spamassassin.org, domain=spamassassin.org
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: dns: providing a callback for id: 16573/spamassassin.org.multi.surbl.org/A/IN
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: async: starting: URI-DNSBL, DNSBL:multi.surbl.org.:spamassassin.org (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: dns: URIBL_PH_SURBL lookup start
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.938 [2972] dbg: dns: providing a callback for id: 16574/spamassassin.org.multi.uribl.com/A/IN
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: async: starting: URI-DNSBL, DNSBL:multi.uribl.com.:spamassassin.org (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: URIBL_BLACK lookup start
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: URIBL_RED lookup start
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: URIBL_GREY lookup start
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: providing a callback for id: 16575/spamassassin.org.dob.sibl.support-intelligence.net/A/IN
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: async: starting: URI-DNSBL, DNSBL:dob.sibl.support-intelligence.net:spamassassin.org (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: URIBL_RHS_DOB lookup start
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: URIBL_SC_SURBL lookup start
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: URIBL_JP_SURBL lookup start
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: URIBL_AB_SURBL lookup start
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: URIBL_WS_SURBL lookup start
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: URIBL_BLOCKED lookup start
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.939 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.940 [2972] dbg: dns: providing a callback for id: 16576/spamassassin.org.dbl.spamhaus.org/A/IN
malformed debug message: Nov  2 03:08:43.940 [2972] dbg: async: starting: URI-DNSBL, DNSBL:dbl.spamhaus.org.:spamassassin.org (timeout 15.0s, min 3.0s)
malformed debug message: Nov  2 03:08:43.940 [2972] dbg: dns: URIBL_DBL_ERROR lookup start
malformed debug message: Nov  2 03:08:43.940 [2972] dbg: dns: URIBL_DBL_SPAM lookup start
malformed debug message: Nov  2 03:08:43.940 [2972] dbg: dns: URIBL_DBL_REDIR lookup start
malformed debug message: Nov  2 03:08:43.940 [2972] dbg: dns: bgsend, DNS servers: [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.940 [2972] dbg: dns: attempt 1/1, trying connect/sendto to [192.87.36.36]:53
malformed debug message: Nov  2 03:08:43.940 [2972] dbg: dns: providing a callback for id: 16577/spamassassin.org/NS/IN
malformed deRecording test results

Jenkins build is back to normal : SpamAssassin-trunk #8140

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/SpamAssassin-trunk/8140/changes>