2500 timeouts a day!

Wed May 26 13:30:45 IST 2004

Hi,

I'm being plagued by about 2500 spamassassin timeouts per day that is
obviously allowing spam to slip through. I read on a previous post to
kill all MS processes and set MS and SA to debug, then run
check_MailScanner and look for pauses? I've done this several times and
don't really see anything. Is there any other way that I can figure out
what my problem is?

I run several SA rules and run a huge blacklist. I thought I was running
DCC ok, but the debug shows that it's not working now.

Here is a the output of the debug. If anybody could help me with this, I
would sure appreciate it.

Starting MailScanner...
In Debugging mode, not forking...
debug: Score set 0 chosen.
debug: running in taint mode? no
debug: ignore: test message to precompile patterns and load modules
debug: using "/usr/share/spamassassin" for default rules dir
debug: using "/etc/mail/spamassassin" for site rules dir
debug: using "/etc/MailScanner/spam.assassin.prefs.conf" for user prefs
file
debug: bayes: 21126 tie-ing to DB file R/O
/root/.spamassassin/bayes_toks
debug: bayes: 21126 tie-ing to DB file R/O
/root/.spamassassin/bayes_seen
debug: bayes: found bayes db version 2
debug: Score set 3 chosen.
debug: Initialising learner
debug: is Net::DNS::Resolver available? yes
debug: trying (3) yahoo.de...
debug: looking up MX for 'yahoo.de'
debug: MX for 'yahoo.de' exists? 1
debug: MX lookup of yahoo.de succeeded => Dns available (set
dns_available to hardcode)
debug: is DNS available? 1
debug: all '*From' addrs: ignore at compiling.spamassassin.taint.org
debug: running header regexp tests; score so far=0
debug: running body-text per-line regexp tests; score so far=2.077
debug: bayes corpus size: nspam = 8496, nham = 5760
debug: uri tests: Done uriRE
debug: tokenize: header tokens for *F = "U*ignore
D*compiling.spamassassin.taint.org D*spamassassin.taint.org D*taint.org
D*org"
debug: tokenize: header tokens for *m = " 1085574063 12887
spamassassin_spamd_init "
debug: bayes token 'H*F:D*org' => 0.0460172181871846
debug: bayes token 'N:H*m:NNNNN' => 0.875176741681377
debug: bayes: score = 0.39857883440039
debug: bayes: 21126 untie-ing
debug: bayes: 21126 untie-ing db_toks
debug: bayes: 21126 untie-ing db_seen
debug: Razor2 is available
debug: entering helper-app run mode
 Razor-Log: Computed razorhome from env: /root/.razor
 Razor-Log: Found razorhome: /root/.razor
 Razor-Log: read_file: 16 items read from /root/.razor/razor-agent.conf
May 26 07:21:05.335440 check[21126]: [ 1] [bootup] Logging initiated
LogDebugLevel=9 to stdout
May 26 07:21:05.336365 check[21126]: [ 5] computed
razorhome=/root/.razor, conf=/root/.razor/razor-agent.conf,
ident=/root/.razor/identity-mkipness at geniant.com
May 26 07:21:05.336836 check[21126]: [ 8] Client supported_engines: 1 2
3 4
May 26 07:21:05.337615 check[21126]: [ 8]  prep_mail done: mail 1
headers=102, mime0=1376
May 26 07:21:05.338299 check[21126]: [ 5] read_file: 1 items read from
/root/.razor/servers.discovery.lst
May 26 07:21:05.338889 check[21126]: [ 5] read_file: 2 items read from
/root/.razor/servers.nomination.lst
May 26 07:21:05.339462 check[21126]: [ 5] read_file: 2 items read from
/root/.razor/servers.catalogue.lst
May 26 07:21:05.340180 check[21126]: [ 9] Assigning defaults to
folly.cloudmark.com
May 26 07:21:05.340602 check[21126]: [ 9] Assigning defaults to
joy.cloudmark.com
May 26 07:21:05.341013 check[21126]: [ 9] Assigning defaults to
pride.cloudmark.com
May 26 07:21:05.341411 check[21126]: [ 9] Assigning defaults to
thrill.cloudmark.com
May 26 07:21:05.342848 check[21126]: [ 5] read_file: 16 items read from
/root/.razor/server.pride.cloudmark.com.conf
May 26 07:21:05.343966 check[21126]: [ 5] read_file: 14 items read from
/root/.razor/server.prejudice.cloudmark.com.conf
May 26 07:21:05.345185 check[21126]: [ 5] read_file: 16 items read from
/root/.razor/server.wonder.cloudmark.com.conf
May 26 07:21:05.346277 check[21126]: [ 5] read_file: 13 items read from
/root/.razor/server.joy.cloudmark.com.conf
May 26 07:21:05.347425 check[21126]: [ 5] read_file: 16 items read from
/root/.razor/server.thrill.cloudmark.com.conf
May 26 07:21:05.348497 check[21126]: [ 5] read_file: 14 items read from
/root/.razor/server.stress.cloudmark.com.conf
May 26 07:21:05.349595 check[21126]: [ 5] read_file: 14 items read from
/root/.razor/server.tension.cloudmark.com.conf
May 26 07:21:05.350686 check[21126]: [ 5] read_file: 14 items read from
/root/.razor/server.malice.cloudmark.com.conf
May 26 07:21:05.351735 check[21126]: [ 5] read_file: 13 items read from
/root/.razor/server.solace.cloudmark.com.conf
May 26 07:21:05.352834 check[21126]: [ 5] read_file: 14 items read from
/root/.razor/server.shock.cloudmark.com.conf
May 26 07:21:05.353939 check[21126]: [ 5] read_file: 14 items read from
/root/.razor/server.truth.cloudmark.com.conf
May 26 07:21:05.354370 check[21126]: [ 5] 113683 seconds before closest
server discovery
May 26 07:21:05.354913 check[21126]: [ 6] pride.cloudmark.com is a
Catalogue Server srl 5003; computed min_cf=6, Server se: D8
May 26 07:21:05.355310 check[21126]: [ 8] Computed supported_engines: 4
May 26 07:21:05.355655 check[21126]: [ 8] Using next closest server
pride.cloudmark.com:2703, cached info srl 5003
May 26 07:21:05.356021 check[21126]: [ 8] mail 1 has no subject
May 26 07:21:05.357757 check[21126]: [ 6] preproc: mail 1.0 went from
1376 bytes to 1339 
May 26 07:21:05.358076 check[21126]: [ 6] computing sigs for mail 1.0,
len 1339
May 26 07:21:05.360018 check[21126]: [ 6] skipping whitelist file
(empty?): /root/.razor/razor-whitelist
May 26 07:21:05.360375 check[21126]: [ 5] Connecting to
pride.cloudmark.com ...
May 26 07:21:05.536166 check[21126]: [ 8] Connection established
May 26 07:21:05.536483 check[21126]: [ 4] pride.cloudmark.com >> 36
server greeting: sn=C&srl=5003&a=l&a=cg&ep4=7542-10
May 26 07:21:05.537106 check[21126]: [ 4] pride.cloudmark.com << 25
May 26 07:21:05.537437 check[21126]: [ 6] cn=razor-agents&cv=2.36
May 26 07:21:05.537866 check[21126]: [ 6] pride.cloudmark.com is a
Catalogue Server srl 5003; computed min_cf=6, Server se: D8
May 26 07:21:05.538264 check[21126]: [ 8] Computed supported_engines: 4
May 26 07:21:05.538731 check[21126]: [ 8] mail 1.0 e4 sig:
xFaZIZUVHk90OQfARnenjx5BZTMA
May 26 07:21:05.539142 check[21126]: [ 8] preparing 1 queries
May 26 07:21:05.539547 check[21126]: [ 8] sending 1 batches
May 26 07:21:05.539886 check[21126]: [ 4] pride.cloudmark.com << 52
May 26 07:21:05.540184 check[21126]: [ 6]
a=c&e=4&ep4=7542-10&s=xFaZIZUVHk90OQfARnenjx5BZTMA
May 26 07:21:05.782230 check[21126]: [ 4] pride.cloudmark.com >> 5
May 26 07:21:05.782451 check[21126]: [ 6] response to sent.2
p=0
May 26 07:21:05.783125 check[21126]: [ 6] mail 1.0 e=4
sig=xFaZIZUVHk90OQfARnenjx5BZTMA: sig not found.
May 26 07:21:05.783416 check[21126]: [ 7] method 4: mail 1.0:
no-contention part, spam=0
May 26 07:21:05.783729 check[21126]: [ 7] method 4: mail 1: all
non-contention parts not spam, mail not spam
May 26 07:21:05.783947 check[21126]: [ 3] mail 1 is not known spam.
May 26 07:21:05.784198 check[21126]: [ 5] disconnecting from server
pride.cloudmark.com
May 26 07:21:05.784626 check[21126]: [ 4] pride.cloudmark.com << 5
May 26 07:21:05.784842 check[21126]: [ 6] a=q
debug: Using results from Razor v2.36
debug: Found Razor2 part: part=0 engine=4 ct=0 cf=0
debug: leaving helper-app run mode
debug: Razor2 results: spam? 0  highest cf score: 0
debug: running raw-body-text per-line regexp tests; score so far=2.077
debug: running uri tests; score so far=2.077
debug: uri tests: Done uriRE
debug: running full-text regexp tests; score so far=2.077
debug: Razor2 is available
debug: Current PATH is: /sbin:/bin:/usr/sbin:/usr/bin
debug: Pyzor is not available: pyzor not found
debug: DCCifd is not available: no r/w dccifd socket found.
debug: DCC is not available: no executable dccproc found.
debug: all '*To' addrs: 
debug: RBL: success for 1 of 1 queries
debug: running meta tests; score so far=2.077
debug: is spam? score=1.173 required=5
tests=BAYES_30,DATE_MISSING,NO_REAL_NAME
debug: received-header: parsed as [ ip=66.63.176.180
rdns=m3-8.dtmiles.com helo=M3-8.dtmiles.com
by=manhattan.aztechsolutions.com ident= ]
debug: received-header: 'by' manhattan.aztechsolutions.com has reserved
IP 10.11.20.41
debug: received-header: 'by' manhattan.aztechsolutions.com has no public
IPs
debug: received-header: relay 66.63.176.180 trusted? yes
debug: is Net::DNS::Resolver available? yes
debug: all '*From' addrs: 14199.20709397 at dtmiles.com
debug: running header regexp tests; score so far=0
debug: running body-text per-line regexp tests; score so far=0.994
debug: bayes: 21128 tie-ing to DB file R/O
/root/.spamassassin/bayes_toks
debug: bayes: 21128 tie-ing to DB file R/O
/root/.spamassassin/bayes_seen
debug: bayes: found bayes db version 2
debug: bayes corpus size: nspam = 8496, nham = 5760
debug: uri tests: Done uriRE
debug: tokenize: header tokens for *p = ""
debug: tokenize: header tokens for *m = " 14199 20709397 dtmiles com "
debug: tokenize: header tokens for *F = "U*14199.20709397 D*dtmiles.com
D*com"
debug: tokenize: header tokens for To = "U*jcenter D*groteconsulting.com
D*com"
debug: tokenize: header tokens for X-E = "kdfoufsAhspufdpotvmujoh/dpn"
debug: tokenize: header tokens for X-I = "14199.20709397"
debug: tokenize: header tokens for X-Rec = "groteconsulting.com!jcenter"
debug: tokenize: header tokens for Mime-Version = "1.0"
debug: tokenize: header tokens for *c = "multipart/alternative;
boundary=-14199.20709397"
debug: tokenize: header tokens for *r = "by M3-8.dtmiles.com
(PowerMTA(TM) v1.6a30); envelope-  <OnlineSurvey at dtmiles.com>)"
debug: tokenize: header tokens for *r = "by M3-8.dtmiles.com
(PowerMTA(TM) v1.6a30); envelope-  <OnlineSurvey at dtmiles.com>)
M3-8.dtmiles.com (m3-8.dtmiles.com [66.63.176]) by
manhattan.aztechsolutions.com      idi4QCHjXw020760
<jcenter at groteconsulting.com>; "
debug: bayes token 'H*r:PowerMTA' => 0.999806775407779
debug: bayes token 'Removal' => 0.999615320566195
debug: bayes token 'Lauderdale' => 0.999514195583596
debug: bayes token 'removals' => 0.999503759398496
debug: bayes token 'Opt-out' => 0.999498371335505
debug: bayes token '187' => 0.999498371335505
debug: bayes token '6278' => 0.999492864983535
debug: bayes token 'advertiser' => 0.999487236403996
debug: bayes token '33308' => 0.999481481481482
debug: bayes token 'UD:GIF' => 0.999290322580645
debug: bayes token '317' => 0.998295202952029
debug: bayes token 'HX-E:dpn' => 0.998229885057471
debug: bayes token 'N:H*r:vN.NaNN' => 0.998229885057471
debug: bayes token 'N:sk:NNNNNIN' => 0.998229885057471
debug: bayes token 'H*r:v1.6a30' => 0.998229885057471
debug: bayes token 'N:sk:NNNNNON' => 0.998229885057471
debug: bayes token 'H*r:66.63.176' => 0.998159362549801
debug: bayes token 'bucks' => 0.997581151832461
debug: bayes token 'UD:HTM' => 0.997581151832461
debug: bayes token 'N:H*F:U*NNNNN.NNNNNNNN' => 0.997447513812155
debug: bayes token 'UD:dtmiles.com' => 0.997130434782609
debug: bayes token 'N:H*c:NNNNN.NNNNNNNN' => 0.997130434782609
debug: bayes token 'N:HX-I:NNNNN.NNNNNNNN' => 0.997130434782609
debug: bayes token 'N:NNN.gif' => 0.997130434782609
debug: bayes token 'H*m:dtmiles' => 0.997130434782609
debug: bayes token 'www.dtmiles.com' => 0.997130434782609
debug: bayes token 'H*F:D*dtmiles.com' => 0.997130434782609
debug: bayes token 'N:H*r:sk:MN-N.dt' => 0.996940397350993
debug: bayes token 'N:H*r:sk:mN-N.dt' => 0.996940397350993
debug: bayes token 'HX-Rec:sk:groteco' => 0.996723404255319
debug: bayes token 'HX-E:sk:kdfoufs' => 0.996723404255319
debug: bayes token 'H*m:20709397' => 0.994296296296296
debug: bayes token 'us!' => 0.988731707317073
debug: bayes token 'UD:gif' => 0.985932549192803
debug: bayes token 'UD:jpg' => 0.985101086602717
debug: bayes token '456' => 0.985096774193548
debug: bayes token 'Scout' => 0.0173548387096774
debug: bayes token 'easiest' => 0.978
debug: bayes token 'telling' => 0.978
debug: bayes token 'H*r:sk:m3-8.dt' => 0.978
debug: bayes token 'H*r:sk:M3-8.dt' => 0.978
debug: bayes token 'seller' => 0.965575274881969
debug: bayes token 'URI' => 0.965120411392134
debug: bayes token 'receipt' => 0.962453188595835
debug: bayes token 'Works' => 0.958976653589229
debug: bayes token 'sk:www.qua' => 0.958
debug: bayes token 'H*r:sk:jcenter' => 0.952729334291984
debug: bayes token 'Write' => 0.950442799143232
debug: bayes token 'promise' => 0.948374092044443
debug: bayes token 'HTo:D*groteconsulting.com' => 0.946086523810392
debug: bayes token 'Companies' => 0.940120287293403
debug: bayes token 'career' => 0.939399922944703
debug: bayes token 'East' => 0.935623061011694
debug: bayes token 'money' => 0.932293225247586
debug: bayes token 'HTo:U*jcenter' => 0.930169305025821
debug: bayes token 'Hwy' => 0.927571356604324
debug: bayes token 'yourself' => 0.919276923392342
debug: bayes token 'paid' => 0.911736797195204
debug: bayes token 'emails' => 0.906566137767734
debug: bayes token 'H*c:alternative' => 0.90410141423876
debug: bayes token 'Get' => 0.900108510765839
debug: bayes token 'marketing' => 0.890854856218948
debug: bayes token 'federal' => 0.888822282043085
debug: bayes token 'Marketing' => 0.8876301327067
debug: bayes token 'End' => 0.880325646789985
debug: bayes token 'Privacy' => 0.880001927251804
debug: bayes token 'N:H*m:NNNNN' => 0.875176741681377
debug: bayes token 'learn' => 0.870189405730155
debug: bayes token 'H*r:envelope' => 0.868361700043087
debug: bayes token 'month' => 0.862704213458833
debug: bayes token 'terms' => 0.862535712992171
debug: bayes token 'longer' => 0.861026210251235
debug: bayes token 'H*c:multipart' => 0.859834684510929
debug: bayes token 'provider' => 0.852946621801994
debug: bayes token 'prefer' => 0.851302795491674
debug: bayes token 'Policy' => 0.85090579655665
debug: bayes token 'product' => 0.847237427739184
debug: bayes: score = 1
debug: bayes: 21128 untie-ing
debug: bayes: 21128 untie-ing db_toks
debug: bayes: 21128 untie-ing db_seen
debug: Razor2 is available
debug: entering helper-app run mode
 Razor-Log: Computed razorhome from env: /root/.razor
 Razor-Log: Found razorhome: /root/.razor
 Razor-Log: read_file: 16 items read from /root/.razor/razor-agent.conf
May 26 07:21:07.175062 check[21128]: [ 1] [bootup] Logging initiated
LogDebugLevel=9 to stdout
May 26 07:21:07.176138 check[21128]: [ 5] computed
razorhome=/root/.razor, conf=/root/.razor/razor-agent.conf,
ident=/root/.razor/identity-mkipness at geniant.com
May 26 07:21:07.176701 check[21128]: [ 8] Client supported_engines: 1 2
3 4
May 26 07:21:07.178772 check[21128]: [ 8]  prep_mail done: mail 1
headers=722, mime0=1163, mime1=3389
May 26 07:21:07.179494 check[21128]: [ 5] read_file: 1 items read from
/root/.razor/servers.discovery.lst
May 26 07:21:07.180118 check[21128]: [ 5] read_file: 2 items read from
/root/.razor/servers.nomination.lst
May 26 07:21:07.180697 check[21128]: [ 5] read_file: 2 items read from
/root/.razor/servers.catalogue.lst
May 26 07:21:07.181509 check[21128]: [ 9] Assigning defaults to
folly.cloudmark.com
May 26 07:21:07.181957 check[21128]: [ 9] Assigning defaults to
joy.cloudmark.com
May 26 07:21:07.182392 check[21128]: [ 9] Assigning defaults to
pride.cloudmark.com
May 26 07:21:07.182833 check[21128]: [ 9] Assigning defaults to
thrill.cloudmark.com
May 26 07:21:07.184244 check[21128]: [ 5] read_file: 16 items read from
/root/.razor/server.pride.cloudmark.com.conf
May 26 07:21:07.185351 check[21128]: [ 5] read_file: 14 items read from
/root/.razor/server.prejudice.cloudmark.com.conf
May 26 07:21:07.186704 check[21128]: [ 5] read_file: 16 items read from
/root/.razor/server.wonder.cloudmark.com.conf
May 26 07:21:07.187796 check[21128]: [ 5] read_file: 13 items read from
/root/.razor/server.joy.cloudmark.com.conf
May 26 07:21:07.188887 check[21128]: [ 5] read_file: 16 items read from
/root/.razor/server.thrill.cloudmark.com.conf
May 26 07:21:07.189996 check[21128]: [ 5] read_file: 14 items read from
/root/.razor/server.stress.cloudmark.com.conf
May 26 07:21:07.191125 check[21128]: [ 5] read_file: 14 items read from
/root/.razor/server.tension.cloudmark.com.conf
May 26 07:21:07.192217 check[21128]: [ 5] read_file: 14 items read from
/root/.razor/server.malice.cloudmark.com.conf
May 26 07:21:07.193283 check[21128]: [ 5] read_file: 13 items read from
/root/.razor/server.solace.cloudmark.com.conf
May 26 07:21:07.194476 check[21128]: [ 5] read_file: 14 items read from
/root/.razor/server.shock.cloudmark.com.conf
May 26 07:21:07.195628 check[21128]: [ 5] read_file: 14 items read from
/root/.razor/server.truth.cloudmark.com.conf
May 26 07:21:07.196183 check[21128]: [ 5] 98883 seconds before closest
server discovery
May 26 07:21:07.196682 check[21128]: [ 6] pride.cloudmark.com is a
Catalogue Server srl 5003; computed min_cf=6, Server se: D8
May 26 07:21:07.197163 check[21128]: [ 8] Computed supported_engines: 4
May 26 07:21:07.197536 check[21128]: [ 8] Using next closest server
pride.cloudmark.com:2703, cached info srl 5003
May 26 07:21:07.197981 check[21128]: [ 8] mail 1 Subject: Make money for
your opinions!
May 26 07:21:07.200218 check[21128]: [ 6] preproc: mail 1.0 went from
1163 bytes to 1047 
May 26 07:21:07.200948 check[21128]: [ 6] preproc: mail 1.1 went from
3389 bytes to 993 
May 26 07:21:07.201346 check[21128]: [ 6] computing sigs for mail 1.0,
len 1047
May 26 07:21:07.203291 check[21128]: [ 6] computing sigs for mail 1.1,
len 993
May 26 07:21:07.205235 check[21128]: [ 6] skipping whitelist file
(empty?): /root/.razor/razor-whitelist
May 26 07:21:07.205638 check[21128]: [ 5] Connecting to
pride.cloudmark.com ...
May 26 07:21:07.368999 check[21128]: [ 8] Connection established
May 26 07:21:07.369357 check[21128]: [ 4] pride.cloudmark.com >> 36
server greeting: sn=C&srl=5003&a=l&a=cg&ep4=7542-10
May 26 07:21:07.370171 check[21128]: [ 4] pride.cloudmark.com << 25
May 26 07:21:07.370490 check[21128]: [ 6] cn=razor-agents&cv=2.36
May 26 07:21:07.370949 check[21128]: [ 6] pride.cloudmark.com is a
Catalogue Server srl 5003; computed min_cf=6, Server se: D8
May 26 07:21:07.371379 check[21128]: [ 8] Computed supported_engines: 4
May 26 07:21:07.371883 check[21128]: [ 8] mail 1.0 e4 sig:
BCzNZYLJ5tfOnmABFYshPcr9YA8A
May 26 07:21:07.372310 check[21128]: [ 8] mail 1.1 e4 sig:
PvAgJ8J7-7SGzgRHIb_8ZI8K6QkA
May 26 07:21:07.372730 check[21128]: [ 8] preparing 2 queries
May 26 07:21:07.373490 check[21128]: [ 8] sending 1 batches
May 26 07:21:07.373963 check[21128]: [ 4] pride.cloudmark.com << 108
May 26 07:21:07.374193 check[21128]: [ 6]
-a=c&e=4&ep4=7542-10&s=BCzNZYLJ5tfOnmABFYshPcr9YA8A
a=c&e=4&ep4=7542-10&s=PvAgJ8J7-7SGzgRHIb_8ZI8K6QkA
.
May 26 07:21:07.579867 check[21128]: [ 4] pride.cloudmark.com >> 21
May 26 07:21:07.580142 check[21128]: [ 6] response to sent.2
-p=0
p=1&cf=100
.
May 26 07:21:07.581133 check[21128]: [ 6] mail 1.0 e=4
sig=BCzNZYLJ5tfOnmABFYshPcr9YA8A: sig not found.
May 26 07:21:07.581554 check[21128]: [ 6] mail 1.1 e=4
sig=PvAgJ8J7-7SGzgRHIb_8ZI8K6QkA: Is spam: cf 100 >= min_cf 6
May 26 07:21:07.581865 check[21128]: [ 7] method 4: mail 1.0:
no-contention part, spam=0
May 26 07:21:07.582116 check[21128]: [ 7] method 4: mail 1.1:
no-contention part, spam=1
May 26 07:21:07.582427 check[21128]: [ 7] method 4: mail 1: a
non-contention part was spam, mail spam
May 26 07:21:07.582647 check[21128]: [ 3] mail 1 is known spam.
May 26 07:21:07.582913 check[21128]: [ 5] disconnecting from server
pride.cloudmark.com
May 26 07:21:07.583373 check[21128]: [ 4] pride.cloudmark.com << 5
May 26 07:21:07.583592 check[21128]: [ 6] a=q
debug: Using results from Razor v2.36
debug: Found Razor2 part: part=0 engine=4 ct=0 cf=0
debug: Found Razor2 part: part=1 engine=4 ct=0 cf=100
debug: leaving helper-app run mode
debug: Razor2 results: spam? 1  highest cf score: 100
debug: running raw-body-text per-line regexp tests; score so far=4.74
debug: running uri tests; score so far=4.74
debug: uri tests: Done uriRE
debug: running full-text regexp tests; score so far=4.74
debug: Razor2 is available
debug: Pyzor is not available: pyzor not found
debug: DCCifd is not available: no r/w dccifd socket found.
debug: DCC is not available: no executable dccproc found.
debug: all '*To' addrs: jcenter at groteconsulting.com
debug: DNS MX records found: 2
debug: RBL: success for 1 of 1 queries
debug: running meta tests; score so far=5.787
debug: auto-learn? ham=0.1, spam=7, body-hits=4.793, head-hits=2.041
debug: auto-learn: currently using scoreset 3.  recomputing score based
on scoreset 1.
debug: Score set 1 chosen.
debug: auto-learn: original score: 5.787, recomputed score: 5.604
debug: Score set 3 chosen.
debug: auto-learn? no: inside auto-learn thresholds
debug: is spam? score=11.187 required=5
tests=BAYES_99,EVILNUMBER_A_6XX_1,FROM_ENDS_IN_NUMS,GET_PAID,HTML_50_60,
HTML_FONT_BIG,HTML_MESSAGE,RAZOR2_CF_RANGE_51_100,RAZOR2_CHECK
Stopping now as you are debugging me.

-------------------------- MailScanner list ----------------------
To leave, send    leave mailscanner    to jiscmail at jiscmail.ac.uk
Before posting, please see the Most Asked Questions at
http://www.mailscanner.biz/maq/     and the archives at
http://www.jiscmail.ac.uk/lists/mailscanner.html