You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@spamassassin.apache.org by Rejaine Monteiro <re...@bhz.jamef.com.br> on 2006/11/30 15:37:54 UTC
long: spamc returns 0/0 (spamd is crashing)
My spamassassin works fine, but sometimes is crashing . I need some
help to figure out the cause and fix...
Above, many details for this problem:
I'm using Suse 10.0
Spamassassin 3.1.6
perl-5.8.7
As you can see, all my spamd process is running
# netstat -an | grep 783
tcp 0 0 127.0.0.1:783 0.0.0.0:* LISTEN
# ps -ef | grep spam
root 29440 1 0 09:58 ? 00:00:29 /usr/bin/perl -T -w
/usr/sbin/spamd -x -u spamd -d -m 15 -D -r
/home/spamd/.spamassassin/var/run/spamd.pid
spamd 15777 29440 0 11:36 ? 00:00:00 spamd child
spamd 15783 29440 0 11:37 ? 00:00:00 spamd child
But, spamc don't work (returns 0/0 results with a spam message)
# spamc -c -R -l < /tmp/spam1.eml
0/0
The cpu load is fine:
top - 11:40:48 up 5 days, 21:47, 3 users, load average: 0.12, 0.18, 1.63
And no timout are given:
# time spamc -c -R -l < /tmp/spam1.eml
0/0
real 0m1.762s
user 0m0.004s
sys 0m0.000s
as all can see, no erros are report (a long debug, be patient....):
----------------------------------------------------------------------
Nov 30 12:07:25 server spamd[29440]: prefork: ordered 24417 to accept
Nov 30 12:07:25 server spamd[29440]: prefork: sysread(7) not ready, wait
max 300 secs
Nov 30 12:07:25 server spamd[29440]: prefork: child 24417: entering state 2
Nov 30 12:07:25 server spamd[24417]: spamd: connection from localhost
[127.0.0.1] at port 48289
Nov 30 12:07:25 server spamd[29440]: prefork: new lowest idle kid: 24490
Nov 30 12:07:25 server spamd[24417]: spamd: checking message
<00...@worot55a9fbdd9> for root:1001
Nov 30 12:07:25 server spamd[24417]: dns: name server: 200.XXX.XXX.XXX,
family: 2, ipv6: 0
Nov 30 12:07:25 server spamd[24417]: received-header: parsed as [
ip=200.XXX.XXX.XXX rdns=server.jamef.com.br helo=mail.jamef.com.br
by=mail.bhz.jamef ident= envfrom=Needless@otonom.com intl=0 id= auth= ]
Nov 30 12:07:25 server spamd[24417]: received-header: parsed as [
ip=200.XXX.XXX.XXX rdns=server.jamef.com.br helo=mail.jamef.com.br
by=mail.bhz.jamef ident= envfrom=needless@otonom.com intl=0 id= auth= ]
Nov 30 12:07:25 server spamd[24417]: dns: looking up A records for
'mail.bhz.jamef'
Nov 30 12:07:25 server spamd[24417]: dns: A records for
'mail.bhz.jamef': 172.22.10.1
Nov 30 12:07:25 server spamd[24417]: dns: looking up A records for
'mail.bhz.jamef'
Nov 30 12:07:25 server spamd[24417]: dns: A records for
'mail.bhz.jamef': 172.22.10.1
Nov 30 12:07:25 server spamd[24417]: received-header: 'by'
mail.bhz.jamef has private IP 172.22.10.1
Nov 30 12:07:25 server spamd[24417]: received-header: 'by'
mail.bhz.jamef has no public IPs
Nov 30 12:07:25 server spamd[24417]: received-header: relay
200.XXX.XXX.XXX trusted? yes internal? no
Nov 30 12:07:25 server spamd[24417]: received-header: parsed as [
ip=195.13.38.136 rdns=terminal-1-136.retsat1.com.pl
helo=terminal-1-136.retsat1.com.pl by=mail.jamef.com.br ident= envfrom=
intl=0 id= auth= ]
Nov 30 12:07:25 server spamd[24417]: received-header: parsed as [
ip=195.13.38.136 rdns=terminal-1-136.retsat1.com.pl
helo=terminal-1-136.retsat1.com.pl by=mail.jamef.com.br ident=
envfrom=Needless@otonom.com intl=0 id= auth= ]
Nov 30 12:07:25 server spamd[24417]: dns: looking up A records for
'mail.jamef.com.br'
Nov 30 12:07:25 server spamd[24417]: dns: A records for
'mail.jamef.com.br': 200.XXX.XXX.XXX
Nov 30 12:07:25 server spamd[24417]: received-header: 'by'
mail.jamef.com.br has public IP 200.XXX.XXX.XXX
Nov 30 12:07:25 server spamd[24417]: received-header: relay
195.13.38.136 trusted? no internal? no
Nov 30 12:07:25 server spamd[24417]: metadata: X-Spam-Relays-Trusted: [
ip=200.XXX.XXX.XXX rdns=server.jamef.com.br helo=mail.jamef.com.br
by=mail.bhz.jamef ident= envfrom=needless@otonom.com intl=0 id= auth= ]
Nov 30 12:07:25 server spamd[24417]: metadata: X-Spam-Relays-Untrusted:
[ ip=195.13.38.136 rdns=terminal-1-136.retsat1.com.pl
helo=terminal-1-136.retsat1.com.pl by=mail.jamef.com.br ident=
envfrom=Needless@otonom.com intl=0 id= auth= ]
Nov 30 12:07:25 server spamd[24417]: metadata: X-Spam-Relays-Internal:
Nov 30 12:07:25 server spamd[24417]: metadata: X-Spam-Relays-External: [
ip=200.XXX.XXX.XXX rdns=server.jamef.com.br helo=mail.jamef.com.br
by=mail.bhz.jamef ident= envfrom=needless@otonom.com intl=0 id= auth= ]
[ ip=195.13.38.136 rdns=terminal-1-136.retsat1.com.pl
helo=terminal-1-136.retsat1.com.pl by=mail.jamef.com.br ident=
envfrom=Needless@otonom.com intl=0 id= auth= ]
Nov 30 12:07:25 server spamd[24417]: message: ---- MIME PARSER START ----
Nov 30 12:07:25 server spamd[24417]: message: main message type:
multipart/mixed
Nov 30 12:07:25 server spamd[24417]: message: parsing multipart, got
boundary: ----=_NextPart_000_0007_01C70B4D.87788B80
Nov 30 12:07:25 server spamd[24417]: message: found part of type
multipart/alternative, boundary: ----=_NextPart_001_0008_01C70B4D.87788B80
Nov 30 12:07:25 server spamd[24417]: message: parsing multipart, got
boundary: ----=_NextPart_001_0008_01C70B4D.87788B80
Nov 30 12:07:25 server spamd[24417]: message: found part of type
text/plain, boundary: ----=_NextPart_001_0008_01C70B4D.87788B80
Nov 30 12:07:25 server spamd[24417]: message: parsing normal part
Nov 30 12:07:25 server spamd[24417]: message: added part, type: text/plain
Nov 30 12:07:25 server spamd[24417]: message: found part of type
text/html, boundary: ----=_NextPart_001_0008_01C70B4D.87788B80
Nov 30 12:07:25 server spamd[24417]: message: parsing normal part
Nov 30 12:07:25 server spamd[24417]: message: added part, type: text/html
Nov 30 12:07:25 server spamd[24417]: message: added part, type:
multipart/alternative
Nov 30 12:07:25 server spamd[24417]: message: found part of type
image/gif, boundary: ----=_NextPart_000_0007_01C70B4D.87788B80
Nov 30 12:07:25 server spamd[24417]: message: parsing normal part
Nov 30 12:07:25 server spamd[24417]: message: added part, type: image/gif
Nov 30 12:07:25 server spamd[24417]: message: ---- MIME PARSER END ----
Nov 30 12:07:25 server spamd[24417]: message: decoding quoted-printable
Nov 30 12:07:25 server spamd[24417]: message: decoding quoted-printable
Nov 30 12:07:25 server spamd[24417]: textcat: classifying, skipping: yi
sco lv is bs sl la ga sa eu et rm cy fy eo lt gd
Nov 30 12:07:25 server spamd[24417]: textcat: language possibly: en
Nov 30 12:07:25 server spamd[24417]: textcat: X-Languages: "en",
X-Languages-Length: 652
Nov 30 12:07:25 server spamd[24417]: uridnsbl: domains to query:
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL
sbl-xbl.spamhaus.org., set sblxbl
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 195.13.38.136
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
136.38.13.195.sbl-xbl.spamhaus.org. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL
sa-other.bondedsender.org., set bsp-untrusted
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following IPs:
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL
combined.njabl.org., set njabl-lastexternal
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 200.XXX.XXX.XXX
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
100.222.243.200.combined.njabl.org. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL
combined.njabl.org., set njabl
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 195.13.38.136
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
136.38.13.195.combined.njabl.org. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL bl.spamcop.net.,
set spamcop
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 195.13.38.136
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS TXT query for
136.38.13.195.bl.spamcop.net. in background
Nov 30 12:07:25 server spamd[24417]: dns: _check_rbl_addresses RBL
blackhole.securitysage.com., set securitysage
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
otonom.com.blackhole.securitysage.com. in background
Nov 30 12:07:25 server spamd[24417]: dns: _check_rbl_addresses RBL
rhsbl.ahbl.org., set ahbl
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
otonom.com.rhsbl.ahbl.org. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking A and MX for host
otonom.com
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
otonom.com in background
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS MX query for
otonom.com in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL dnsbl.sorbs.net.,
set sorbs-lastexternal
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 200.XXX.XXX.XXX
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
100.222.243.200.dnsbl.sorbs.net. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL dnsbl.sorbs.net.,
set sorbs
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 195.13.38.136
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
136.38.13.195.dnsbl.sorbs.net. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL
sbl-xbl.spamhaus.org., set sblxbl-lastexternal
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 200.XXX.XXX.XXX
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
100.222.243.200.sbl-xbl.spamhaus.org. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL
sa-accredit.habeas.com., set habeas-firsttrusted
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 195.13.38.136
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
136.38.13.195.sa-accredit.habeas.com. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL
combined-HIB.dnsiplists.completewhois.com., set whois
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 195.13.38.136
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
136.38.13.195.combined-HIB.dnsiplists.completewhois.com. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL list.dsbl.org.,
set dsbl-lastexternal
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 200.XXX.XXX.XXX
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS TXT query for
100.222.243.200.list.dsbl.org. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL
sa-trusted.bondedsender.org., set bsp-firsttrusted
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 195.13.38.136
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS TXT query for
136.38.13.195.sa-trusted.bondedsender.org. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL
combined-HIB.dnsiplists.completewhois.com., set whois-lastexternal
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 200.XXX.XXX.XXX
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
100.222.243.200.combined-HIB.dnsiplists.completewhois.com. in background
Nov 30 12:07:25 server spamd[24417]: dns: _check_rbl_addresses RBL
fulldom.rfc-ignorant.org., set rfci_envfrom
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
otonom.com.fulldom.rfc-ignorant.org. in background
Nov 30 12:07:25 server spamd[24417]: dns: checking RBL iadb.isipp.com.,
set iadb-firsttrusted
Nov 30 12:07:25 server spamd[24417]: dns: IPs found: full-external:
200.XXX.XXX.XXX, 195.13.38.136 untrusted: 195.13.38.136 originating:
Nov 30 12:07:25 server spamd[24417]: dns: only inspecting the following
IPs: 195.13.38.136
Nov 30 12:07:25 server spamd[24417]: dns: launching DNS A query for
136.38.13.195.iadb.isipp.com. in background
Nov 30 12:07:25 server spamd[24417]: check: running tests for priority: 0
Nov 30 12:07:25 server spamd[24417]: rules: running header regexp tests;
score so far=0
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule __HAS_MSGID
======> got hit: "<"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule __SANE_MSGID
======> got hit: "<00...@worot55a9fbdd9>
Nov 30 12:07:25 server spamd[24417]: rules: "
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__ANY_OUTLOOK_MUA ======> got hit: "Microsoft Outlook"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__HAS_OUTLOOK_IN_MAILER_NEW ======> got hit: "Microsoft Outlook"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__MSGID_DOLLARS_MAYBE ======> got hit:
"<00...@worot55a9fbdd9>"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule __OE_MUA
======> got hit: "Outlook Express 6."
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__MSGID_DOLLARS_OK ======> got hit:
"<00...@worot55a9fbdd9>"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule __CT ======>
got hit: "m"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__NEXTPART_NORMAL ======> got hit:
"="----=_NextPart_000_0007_01C70B4D.87788B80""
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__HAS_MSMAIL_PRI ======> got hit: "N"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__NEXTPART_ALL ======> got hit: "NextPart"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__TOCC_EXISTS ======> got hit: "a"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__HAS_MIMEOLE ======> got hit: "P"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule __OE_MSGID_2
======> got hit: "<00...@worot55a9fbdd9>"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__HAS_SUBJECT ======> got hit: "g"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__SARE_HEAD_SUBJ_RAND ======> got hit: "garden hose
Nov 30 12:07:25 server spamd[24417]: rules: "
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__MSGID_OK_HEX ======> got hit: "2727fed0"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule __HAS_RCVD
======> got hit: "("
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule __MIMEOLE_MS
======> got hit: "Produced By Microsoft MimeOLE"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__SARE_SPEC_SUBJ_LEO ======> got hit: "garden hose
Nov 30 12:07:25 server spamd[24417]: rules: "
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule __MOLE_2962
======> got hit: "Produced By Microsoft MimeOLE V6.00.2900.2962"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule __NAKED_TO
======> got hit: "admrede@jamef.com.br"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__CTYPE_HAS_BOUNDARY ======> got hit: "boundary"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__HAS_X_MAILER ======> got hit: "M"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__MSGID_RANDY ======> got hit:
"<00...@worot55a9fbdd9>"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__MIME_VERSION ======> got hit: "1"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__HAS_X_PRIORITY ======> got hit: "3"
Nov 30 12:07:25 server spamd[24417]: rules: ran header rule
__HAS_OUTLOOK_IN_MAILER ======> got hit: "Microsoft Outlook"
Nov 30 12:07:25 server spamd[24417]: spf: checking HELO
(helo=mail.jamef.com.br, ip=200.XXX.XXX.XXX)
Nov 30 12:07:25 server spamd[24417]: spf: query for
/200.XXX.XXX.XXX/mail.jamef.com.br: result: none, comment: SPF: domain
of sender mail.jamef.com.br does not designate mailers
Nov 30 12:07:25 server spamd[24417]: eval: all '*From' addrs:
Needless@otonom.com
Nov 30 12:07:25 server spamd[24417]: eval: forged-HELO:
from=retsat1.com.pl helo=retsat1.com.pl by=jamef.com.br
Nov 30 12:07:25 server spamd[24417]: eval: trying Received header date
for real time: 18 Nov 2006 19:14:41 -0000
Nov 30 12:07:25 server spamd[24417]: eval: time_t from date=1163877281,
rcvd= 18 Nov 2006 19:14:41 -0000
Nov 30 12:07:25 server spamd[24417]: eval: trying Received header date
for real time: 18 Nov 2006 19:14:41 -0000
Nov 30 12:07:25 server spamd[24417]: eval: time_t from date=1163877281,
rcvd= 18 Nov 2006 19:14:41 -0000
Nov 30 12:07:25 server spamd[24417]: eval: trying Received header date
for real time: 18 Nov 2006 19:14:41 -0000
Nov 30 12:07:25 server spamd[24417]: eval: time_t from date=1163877281,
rcvd= 18 Nov 2006 19:14:41 -0000
Nov 30 12:07:25 server spamd[24417]: eval: trying Received header date
for real time: 18 Nov 2006 19:14:41 -0000
Nov 30 12:07:25 server spamd[24417]: eval: time_t from date=1163877281,
rcvd= 18 Nov 2006 19:14:41 -0000
Nov 30 12:07:25 server spamd[24417]: eval: trying Received header date
for real time: 18 Nov 2006 17:14:41 -0200
Nov 30 12:07:25 server spamd[24417]: eval: time_t from date=1163877281,
rcvd= 18 Nov 2006 17:14:41 -0200
Nov 30 12:07:25 server spamd[24417]: eval: trying Received header date
for real time: 18 Nov 2006 17:14:40 -0200
Nov 30 12:07:25 server spamd[24417]: eval: time_t from date=1163877280,
rcvd= 18 Nov 2006 17:14:40 -0200
Nov 30 12:07:25 server spamd[24417]: eval: trying Received header date
for real time: 18 Nov 2006 19:14:40 -0000
Nov 30 12:07:25 server spamd[24417]: eval: time_t from date=1163877280,
rcvd= 18 Nov 2006 19:14:40 -0000
Nov 30 12:07:25 server spamd[24417]: eval: trying Received header date
for real time: 18 Nov 2006 17:14:32 -0200
Nov 30 12:07:25 server spamd[24417]: eval: time_t from date=1163877272,
rcvd= 18 Nov 2006 17:14:32 -0200
Nov 30 12:07:25 server spamd[24417]: eval: all '*To' addrs:
admrede@jamef.com.br rejaine@bhz.jamef.com.br admrede@bhz.jamef
bhz-admrede@jamef.com.br
Nov 30 12:07:25 server spamd[24417]: spf: found Envelope-From in first
external Received header
Nov 30 12:07:25 server spamd[24417]: spf: checking EnvelopeFrom
(helo=mail.jamef.com.br, ip=200.XXX.XXX.XXX, envfrom=needless@otonom.com)
Nov 30 12:07:25 server spamd[24417]: spf: query for
needless@otonom.com/200.XXX.XXX.XXX/mail.jamef.com.br: result: none,
comment: SPF: domain of sender needless@otonom.com does not designate
mailers
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__ENV_AND_HDR_FROM_MATCH ======> got hit
Nov 30 12:07:25 server spamd[24417]: spf: def_whitelist_from_spf:
needless@otonom.com is not in DEF_WHITELIST_FROM_SPF
Nov 30 12:07:25 server spamd[24417]: eval: date chosen from message: Sat
Nov 18 17:14:32 2006
Nov 30 12:07:25 server spamd[24417]: spf: whitelist_from_spf:
needless@otonom.com is not in user's WHITELIST_FROM_SPF
Nov 30 12:07:25 server spamd[24417]: rules: running body-text per-line
regexp tests; score so far=0
Nov 30 12:07:25 server spamd[24417]: rules: ran body rule __KAM_TIME4
======> got hit: "time"
Nov 30 12:07:25 server spamd[24417]: rules: ran body rule __WORD_STOCK_F
======> got hit: "Stocks"
Nov 30 12:07:25 server spamd[24417]: rules: ran body rule
__NONEMPTY_BODY ======> got hit: "g"
Nov 30 12:07:25 server spamd[24417]: uri: running uri tests; score so far=0
Nov 30 12:07:25 server spamd[24417]: bayes: tie-ing to DB file R/O
/var/qmail/.spamassassin/bayes_toks
Nov 30 12:07:25 server spamd[24417]: bayes: tie-ing to DB file R/O
/var/qmail/.spamassassin/bayes_seen
Nov 30 12:07:25 server spamd[24417]: bayes: found bayes db version 3
Nov 30 12:07:25 server spamd[24417]: bayes: DB journal sync: last sync:
1164892830
Nov 30 12:07:25 server spamd[24417]: bayes: corpus size: nspam = 66422,
nham = 7617
Nov 30 12:07:25 server spamd[24417]: bayes: score = 0.499864293043668
Nov 30 12:07:25 server spamd[24417]: bayes: DB journal sync: last sync:
1164892830
Nov 30 12:07:25 server spamd[24417]: bayes: untie-ing
Nov 30 12:07:25 server spamd[24417]: bayes: untie-ing db_toks
Nov 30 12:07:25 server spamd[24417]: bayes: untie-ing db_seen
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__TAG_EXISTS_BODY ======> got hit
Nov 30 12:07:25 server spamd[24417]: eval: text words: 46, html words: 46
Nov 30 12:07:25 server spamd[24417]: eval: madiff: left: 0, orig: 46,
max-difference: 0.00%
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
UNWANTED_LANGUAGE_BODY ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__HTML_LENGTH_0000_1024 ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__PART_STOCK_CD_F ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule HTML_40_50
======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule __MIME_HTML
======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
TVD_FW_GRAPHIC_NAME_MID ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__MIME_ATTACHMENT ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule __GIF_ATTACH
======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule HTML_MESSAGE
======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__TAG_EXISTS_HTML ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule BAYES_50
======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__ANY_IMAGE_ATTACH ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__SARE_BODY_BLNK_5_100 ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__TAG_EXISTS_HEAD ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__TAG_EXISTS_META ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: running raw-body-text
per-line regexp tests; score so far=4.498
Nov 30 12:07:25 server spamd[24417]: rules: ran rawbody rule
__MY_CLOSING ======> got hit: "</FONT></DIV></BODY></HTML>"
Nov 30 12:07:25 server spamd[24417]: rules: ran rawbody rule
__SARE_HAS_BG_COLOR ======> got hit: "bgColor="
Nov 30 12:07:25 server spamd[24417]: rules: ran rawbody rule
__SARE_WHITE_BG_COLOR ======> got hit: "bgColor=#ffffff"
Nov 30 12:07:25 server spamd[24417]: rules: ran rawbody rule __MY_STYLE
======> got hit: "<STYLE></STYLE>"
Nov 30 12:07:25 server spamd[24417]: rules: ran rawbody rule __MY_ARIAL2
======> got hit: "face=Arial size=2>"
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__SARE_HTML_HAS_BR ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule __MIME_BASE64
======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__SARE_HTML_HAS_DIV ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule __MIME_QP
======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: ran eval rule
__SARE_HTML_HAS_FONT ======> got hit
Nov 30 12:07:25 server spamd[24417]: rules: running full-text regexp
tests; score so far=4.498
Nov 30 12:07:25 server spamd[24417]: rules: ran full rule
SARE_GIF_ATTACH ======> got hit: "name="sister.gif""
Nov 30 12:07:25 server spamd[24417]: info: entering helper-app run mode
Nov 30 12:07:26 server spamd[24417]: info: leaving helper-app run mode
Nov 30 12:07:26 server spamd[24417]: razor2: part=0 engine=4 contested=0
confidence=0
Nov 30 12:07:26 server spamd[24417]: razor2: part=1 engine=4 contested=0
confidence=0
Nov 30 12:07:26 server spamd[24417]: razor2: part=2 engine=4 contested=0
confidence=0
Nov 30 12:07:26 server spamd[24417]: razor2: results: spam? 0
Nov 30 12:07:26 server spamd[24417]: razor2: results: engine 8, highest
cf score: 0
Nov 30 12:07:26 server spamd[24417]: razor2: results: engine 4, highest
cf score: 0
Nov 30 12:07:26 server spamd[24417]: dcc: dccifd is not available: no
r/w dccifd socket found
Nov 30 12:07:26 server spamd[24417]: dcc: dccproc is available:
/usr/local/bin/dccproc
Nov 30 12:07:26 server spamd[24417]: info: entering helper-app run mode
Nov 30 12:07:26 server spamd[24417]: dcc: opening pipe:
/usr/local/bin/dccproc -H -x 0 -a 200.XXX.XXX.XXX <
/tmp/.spamassassin244178OUjPBtmp
Nov 30 12:07:26 server spamd[25830]: util: setuid: ruid=1001 euid=1001
Nov 30 12:07:27 server spamd[24417]: dcc: got response:
X-DCC-sonic.net-Metrics: server 1156; Body=22 Fuz1=22 Fuz2=22
Nov 30 12:07:27 server spamd[24417]: info: leaving helper-app run mode
Nov 30 12:07:27 server spamd[29440]: prefork: new lowest idle kid: 24490
Nov 30 12:07:27 server spamd[29440]: spamd: handled cleanup of child pid
24417 due to SIGCHLD
Nov 30 12:07:27 server spamd[29440]: prefork: child closed connection
Nov 30 12:07:27 server spamd[29440]: prefork: child states: I
Nov 30 12:07:27 server spamd[29440]: spamd: server successfully spawned
child process, pid 25831
Nov 30 12:07:27 server spamd[29440]: prefork: child 25831: entering state 0
Nov 30 12:07:27 server spamd[29440]: prefork: new lowest idle kid: 24490
Nov 30 12:07:27 server spamd[25831]: prefork: sysread(8) not ready, wait
max 300 secs
Nov 30 12:07:27 server spamd[29440]: prefork: child 25831: entering state 1
Nov 30 12:07:27 server spamd[29440]: prefork: new lowest idle kid: 24490
Nov 30 12:07:27 server spamd[29440]: prefork: child reports idle
Nov 30 12:07:27 server spamd[29440]: prefork: child states: II
-------------------------------------------------------------------
After, just restart spamd, all works fine again...
#rcspamd restart
# spamc -c -l -R < /tmp/spam1.eml
6.8/5.0
What you can see wrong on debug ?? I can see errors or problems..
What's happen??
Re: long: spamc returns 0/0 (spamd is crashing)
Posted by Rejaine Monteiro <re...@bhz.jamef.com.br>.
The *difference between* log before and after spamd restart (maybe) is:
the spamd do a prefork child and closed connection, before finish the
rest of tests (*terminated* prematurely !?!)
Look:
- log when spamd crashed
+ log when spamd is work fine....
- dcc: got response: X-DCC-sonic.net-Metrics: server 1156; Body=22
Fuz1=22 Fuz2=22
+ dcc: got response: X-DCC-sonic.net-Metrics: server 1156; Body=28
Fuz1=28 Fuz2=28
info: leaving helper-app run mode
- prefork: new lowest idle kid: 24490 <<< -----------------------
prefork child
- spamd: handled cleanup of child pid 24417 due to SIGCHLD <<<
----------------------- clenup here
- prefork: child closed connection <<<< -------------- close
connection....
- prefork: child states: I
- spamd: server successfully spawned child process, pid 25831
- prefork: child 25831: entering state 0
- prefork: new lowest idle kid: 24490
- prefork: sysread(8) not ready, wait max 300 secs
- prefork: child 25831: entering state 1
- prefork: new lowest idle kid: 24490
+ check: running tests for priority: 500 <------------ after spamd
restarted, prefork is doing, but the rest of tests continues.....
+ dns: success for 15 of 18 queries <---------------------- see???
+ dns: timeout for whois-lastexternal after 5 seconds
<---------------------- see???
+ dns: timeout for whois after 5 seconds <---------------------- see???
+ dns: timeout for dsbl-lastexternal after 5 seconds
<---------------------- see???
+ rules: running meta tests; score so far=6.806 <----------------------
see???
+ rules: running header regexp tests; score so far=6.806
+ rules: running body-text per-line regexp tests; score so far=6.806
+ uri: running uri tests; score so far=6.806
+ rules: running raw-body-text per-line regexp tests; score so far=6.806
+ rules: running full-text regexp tests; score so far=6.806
+ check: running tests for priority: 900
+ rules: running meta tests; score so far=6.806