spamassassin not functioning

[Augustin Siaens]

--q4+)3s+U-4,np/9glsU(nZ1tw?mX'HMml+?60Czv+ZDkCHGcBQjm99Eyf1JaGjdpJ7?gH6
Content-Transfer-Encoding: 7bit
Content-Type: text/plain

Hello,

I'm running MailScanner 4.45 on a Fedora 1. I've recently upgraded
MailScanner  from the 4.3xx version, SpamAssassin to version 3.0.1 and
SpamAssassin-tools to 3.0.1 as wel as perl-Mail-SpamAssassin to 3.0.1 too

Now MailScanner is working fine but SpamAssassin doesn't seem to scan
anything. I've sent myself a spam and it went through. I didn't see anything
scanning at all in the logs. 

I've ran in debug mode and got the attached information. Could someone
please check quickly to spot the cause of the problem?

thanks (a lot) in advance

------------------------ MailScanner list ------------------------
To unsubscribe, email jiscmail at jiscmail.ac.uk with the words:
'leave mailscanner' in the body of the email.
Before posting, read the Wiki (http://wiki.mailscanner.info/) and
the archives (http://www.jiscmail.ac.uk/lists/mailscanner.html).

Support MailScanner development - buy the book off the website!

--q4+)3s+U-4,np/9glsU(nZ1tw?mX'HMml+?60Czv+ZDkCHGcBQjm99Eyf1JaGjdpJ7?gH6
Content-Transfer-Encoding: 7bit
Content-Type: TEXT/PLAIN;
 name="mailscan.txt"

debug: SpamAssassin version 3.0.1
debug: Score set 0 chosen.
debug: running in taint mode? no
SA bayes lock is /root/.spamassassin/bayes.lock
Bayes lock is at /root/.spamassassin/bayes.lock
debug: ignore: test message to precompile patterns and load modules
debug: using "/etc/mail/spamassassin/init.pre" for site rules init.pre
debug: config: read file /etc/mail/spamassassin/init.pre
debug: using "/usr/share/spamassassin" for default rules dir
debug: config: read file /usr/share/spamassassin/10_misc.cf
debug: config: read file /usr/share/spamassassin/20_anti_ratware.cf
debug: config: read file /usr/share/spamassassin/20_body_tests.cf
debug: config: read file /usr/share/spamassassin/20_compensate.cf
debug: config: read file /usr/share/spamassassin/20_dnsbl_tests.cf
debug: config: read file /usr/share/spamassassin/20_drugs.cf
debug: config: read file /usr/share/spamassassin/20_fake_helo_tests.cf
debug: config: read file /usr/share/spamassassin/20_head_tests.cf
debug: config: read file /usr/share/spamassassin/20_html_tests.cf
debug: config: read file /usr/share/spamassassin/20_meta_tests.cf
debug: config: read file /usr/share/spamassassin/20_phrases.cf
debug: config: read file /usr/share/spamassassin/20_porn.cf
debug: config: read file /usr/share/spamassassin/20_ratware.cf
debug: config: read file /usr/share/spamassassin/20_uri_tests.cf
debug: config: read file /usr/share/spamassassin/23_bayes.cf
debug: config: read file /usr/share/spamassassin/25_body_tests_es.cf
debug: config: read file /usr/share/spamassassin/25_hashcash.cf
debug: config: read file /usr/share/spamassassin/25_spf.cf
debug: config: read file /usr/share/spamassassin/25_uribl.cf
debug: config: read file /usr/share/spamassassin/30_text_de.cf
debug: config: read file /usr/share/spamassassin/30_text_fr.cf
debug: config: read file /usr/share/spamassassin/30_text_nl.cf
debug: config: read file /usr/share/spamassassin/30_text_pl.cf
debug: config: read file /usr/share/spamassassin/50_scores.cf
debug: config: read file /usr/share/spamassassin/60_whitelist.cf
debug: using "/etc/mail/spamassassin" for site rules dir
debug: config: read file /etc/mail/spamassassin/local.cf
debug: using "/etc/MailScanner/spam.assassin.prefs.conf" for user prefs file
debug: config: read file /etc/MailScanner/spam.assassin.prefs.conf
debug: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
debug: plugin: registered Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034)
debug: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC
debug: plugin: registered Mail::SpamAssassin::Plugin::Hashcash=HASH(0xa74a064)
debug: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
debug: plugin: registered Mail::SpamAssassin::Plugin::SPF=HASH(0xa74a094)
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) implements 'parse_config'
debug: plugin: Mail::SpamAssassin::Plugin::Hashcash=HASH(0xa74a064) implements 'parse_config'
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) inhibited further callbacks
debug: bayes: 31866 tie-ing to DB file R/O /root/.spamassassin/bayes_toks
debug: bayes: 31866 tie-ing to DB file R/O /root/.spamassassin/bayes_seen
debug: bayes: found bayes db version 2
bayes: bayes db version 2 is not able to be used, aborting! at /usr/lib/perl5/site_perl/5.8.3/Mail/SpamAssassin/BayesStore/DBM.pm line 160.
debug: Score set 1 chosen.
debug: ---- MIME PARSER START ----
debug: main message type: text/plain
debug: parsing normal part
debug: added part, type: text/plain
debug: ---- MIME PARSER END ----
debug: bayes: 31866 tie-ing to DB file R/O /root/.spamassassin/bayes_toks
debug: bayes: 31866 tie-ing to DB file R/O /root/.spamassassin/bayes_seen
debug: bayes: found bayes db version 2
bayes: bayes db version 2 is not able to be used, aborting! at /usr/lib/perl5/site_perl/5.8.3/Mail/SpamAssassin/BayesStore/DBM.pm line 160.
debug: metadata: X-Spam-Relays-Trusted:
debug: metadata: X-Spam-Relays-Untrusted:
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) implements 'parsed_metadata'
debug: dns_available set to yes in config file, skipping test
debug: decoding: no encoding detected
debug: URIDNSBL: domains to query:
debug: Running tests for priority: 0
debug: running header regexp tests; score so far=0
debug: registering glue method for check_hashcash_double_spend (Mail::SpamAssassin::Plugin::Hashcash=HASH(0xa74a064))
debug: registering glue method for check_for_spf_helo_pass (Mail::SpamAssassin::Plugin::SPF=HASH(0xa74a094))
debug: SPF: message was delivered entirely via trusted relays, not required
debug: all '*From' addrs: ignore at compiling.spamassassin.taint.org
debug: registering glue method for check_hashcash_value (Mail::SpamAssassin::Plugin::Hashcash=HASH(0xa74a064))
debug: all '*To' addrs:
debug: registering glue method for check_for_spf_softfail (Mail::SpamAssassin::Plugin::SPF=HASH(0xa74a094))
debug: SPF: message was delivered entirely via trusted relays, not required
debug: registering glue method for check_for_spf_pass (Mail::SpamAssassin::Plugin::SPF=HASH(0xa74a094))
debug: registering glue method for check_for_spf_helo_softfail (Mail::SpamAssassin::Plugin::SPF=HASH(0xa74a094))
debug: registering glue method for check_for_spf_helo_fail (Mail::SpamAssassin::Plugin::SPF=HASH(0xa74a094))
debug: running body-text per-line regexp tests; score so far=-2.623
debug: running uri tests; score so far=-2.623
debug: registering glue method for check_uridnsbl (Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034))
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
sep 26 15:16:48.074744 check[31866]: [ 2] [bootup] Logging initiated LogDebugLevel=9 to stdout
sep 26 15:16:48.075199 check[31866]: [ 5] computed razorhome=/root/.razor, conf=/root/.razor/razor-agent.conf, ident=/root/.razor/identity-ru8GtwKyKw
sep 26 15:16:48.075441 check[31866]: [ 8] Client supported_engines: 4
sep 26 15:16:48.075802 check[31866]: [ 8]  prep_mail done: mail 1 headers=102, mime0=1376
sep 26 15:16:48.076153 check[31866]: [ 5] read_file: 1 items read from /root/.razor/servers.discovery.lst
sep 26 15:16:48.076444 check[31866]: [ 5] read_file: 2 items read from /root/.razor/servers.nomination.lst
sep 26 15:16:48.076697 check[31866]: [ 5] read_file: 1 items read from /root/.razor/servers.catalogue.lst
sep 26 15:16:48.077056 check[31866]: [ 9] Assigning defaults to joy.cloudmark.com
sep 26 15:16:48.077256 check[31866]: [ 9] Assigning defaults to folly.cloudmark.com
sep 26 15:16:48.077490 check[31866]: [ 9] Assigning defaults to shock.cloudmark.com
sep 26 15:16:48.078206 check[31866]: [ 5] read_file: 17 items read from /root/.razor/server.wonder.cloudmark.com.conf
sep 26 15:16:48.078696 check[31866]: [ 5] read_file: 17 items read from /root/.razor/server.wonder.cloudmark.com.conf
sep 26 15:16:48.079183 check[31866]: [ 5] read_file: 17 items read from /root/.razor/server.pride.cloudmark.com.conf
sep 26 15:16:48.079642 check[31866]: [ 5] read_file: 17 items read from /root/.razor/server.pride.cloudmark.com.conf
sep 26 15:16:48.080110 check[31866]: [ 5] read_file: 17 items read from /root/.razor/server.thrill.cloudmark.com.conf
sep 26 15:16:48.080558 check[31866]: [ 5] read_file: 17 items read from /root/.razor/server.thrill.cloudmark.com.conf
sep 26 15:16:48.080990 check[31866]: [ 5] read_file: 14 items read from /root/.razor/server.folly.cloudmark.com.conf
sep 26 15:16:48.081404 check[31866]: [ 5] read_file: 14 items read from /root/.razor/server.folly.cloudmark.com.conf
sep 26 15:16:48.081957 check[31866]: [ 5] read_file: 16 items read from /root/.razor/server.stress.cloudmark.com.conf
sep 26 15:16:48.082445 check[31866]: [ 5] read_file: 16 items read from /root/.razor/server.stress.cloudmark.com.conf
sep 26 15:16:48.082909 check[31866]: [ 5] read_file: 16 items read from /root/.razor/server.robust.cloudmark.com.conf
sep 26 15:16:48.083348 check[31866]: [ 5] read_file: 16 items read from /root/.razor/server.robust.cloudmark.com.conf
sep 26 15:16:48.083817 check[31866]: [ 5] read_file: 16 items read from /root/.razor/server.tension.cloudmark.com.conf
sep 26 15:16:48.084258 check[31866]: [ 5] read_file: 16 items read from /root/.razor/server.tension.cloudmark.com.conf
sep 26 15:16:48.084711 check[31866]: [ 5] read_file: 16 items read from /root/.razor/server.shock.cloudmark.com.conf
sep 26 15:16:48.085143 check[31866]: [ 5] read_file: 16 items read from /root/.razor/server.shock.cloudmark.com.conf
sep 26 15:16:48.085370 check[31866]: [ 5] 150093 seconds before closest server discovery
sep 26 15:16:48.085564 check[31866]: [ 6] shock.cloudmark.com is a Catalogue Server srl 5084; computed min_cf=6, Server se: C8
sep 26 15:16:48.085808 check[31866]: [ 8] Computed supported_engines: 4
sep 26 15:16:48.085971 check[31866]: [ 8] Using next closest server shock.cloudmark.com:2703, cached info srl 5084
sep 26 15:16:48.086151 check[31866]: [ 8] mail 1 has no subject
sep 26 15:16:48.087148 check[31866]: [ 6] preproc: mail 1.0 went from 1376 bytes to 1339
sep 26 15:16:48.087349 check[31866]: [ 6] computing sigs for mail 1.0, len 1339
sep 26 15:16:48.088325 check[31866]: [ 6] skipping whitelist file (empty?): /root/.razor/razor-whitelist
sep 26 15:16:48.088541 check[31866]: [ 5] Connecting to shock.cloudmark.com ...
sep 26 15:16:51.464388 check[31866]: [ 8] Connection established
sep 26 15:16:51.464530 check[31866]: [ 4] shock.cloudmark.com >> 36 server greeting: sn=C&srl=5084&a=l&a=cg&ep4=7542-10
sep 26 15:16:51.464781 check[31866]: [ 4] shock.cloudmark.com << 25
sep 26 15:16:51.464847 check[31866]: [ 6] cn=razor-agents&cv=2.40
sep 26 15:16:51.465006 check[31866]: [ 6] shock.cloudmark.com is a Catalogue Server srl 5084; computed min_cf=6, Server se: C8
sep 26 15:16:51.465167 check[31866]: [ 8] Computed supported_engines: 4
sep 26 15:16:51.465276 check[31866]: [ 8] mail 1.0 e4 sig: xFaZIZUVHk90OQfARnenjx5BZTMA
sep 26 15:16:51.465401 check[31866]: [ 8] preparing 1 queries
sep 26 15:16:51.465545 check[31866]: [ 8] sending 1 batches
sep 26 15:16:51.465658 check[31866]: [ 4] shock.cloudmark.com << 52
sep 26 15:16:51.465706 check[31866]: [ 6] a=c&e=4&ep4=7542-10&s=xFaZIZUVHk90OQfARnenjx5BZTMA
sep 26 15:16:56.231841 check[31866]: [ 4] shock.cloudmark.com >> 5
sep 26 15:16:56.231931 check[31866]: [ 6] response to sent.2
p=0
sep 26 15:16:56.232214 check[31866]: [ 6] mail 1.0 e=4 sig=xFaZIZUVHk90OQfARnenjx5BZTMA: sig not found.
sep 26 15:16:56.232300 check[31866]: [ 7] method 4: mail 1.0: no-contention part, spam=0
sep 26 15:16:56.232359 check[31866]: [ 7] method 4: mail 1: all non-contention parts not spam, mail not spam
sep 26 15:16:56.232408 check[31866]: [ 3] mail 1 is not known spam.
sep 26 15:16:56.232468 check[31866]: [ 5] disconnecting from server shock.cloudmark.com
sep 26 15:16:56.232586 check[31866]: [ 4] shock.cloudmark.com << 5
sep 26 15:16:56.232633 check[31866]: [ 6] a=q
debug: Using results from Razor v2.40
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: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) implements 'check_tick'
debug: running raw-body-text per-line regexp tests; score so far=-2.623
debug: running full-text regexp tests; score so far=-2.623
debug: Razor2 is available
debug: Current PATH is: /sbin:/bin:/usr/sbin:/usr/bin
debug: executable for pyzor was found at /usr/bin/pyzor
debug: Pyzor is available: /usr/bin/pyzor
debug: entering helper-app run mode
debug: setuid: helper proc 31869: ruid=0 euid=0
debug: Pyzor: got response: 66.250.40.33:24441  TimeoutError:
debug: leaving helper-app run mode
debug: Pyzor: couldn't grok response "66.250.40.33:24441        TimeoutError: "
debug: DCCifd is not available: no r/w dccifd socket found.
debug: DCC is available: /usr/local/bin/dccproc
debug: entering helper-app run mode
debug: setuid: helper proc 31870: ruid=0 euid=0
debug: DCC: got response: X-DCC-NIET-Metrics: server1 1080; Body=18879 Fuz1=98941 Fuz2=98941
debug: leaving helper-app run mode
debug: Running tests for priority: 500
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0xa74a034) implements 'check_post_dnsbl'
debug: running meta tests; score so far=-2.623
debug: running header regexp tests; score so far=-1.053
debug: running body-text per-line regexp tests; score so far=-1.053
debug: running uri tests; score so far=-1.053
debug: running raw-body-text per-line regexp tests; score so far=-1.053
debug: running full-text regexp tests; score so far=-1.053
debug: Running tests for priority: 1000
debug: running meta tests; score so far=-1.053
debug: running header regexp tests; score so far=-1.053
debug: lock: 31866 created /root/.spamassassin/auto-whitelist.lock.server1.31866
debug: lock: 31866 trying to get lock on /root/.spamassassin/auto-whitelist with 0 retries
debug: lock: 31866 link to /root/.spamassassin/auto-whitelist.lock: link ok
debug: Tie-ing to DB file R/W in /root/.spamassassin/auto-whitelist
debug: auto-whitelist (db-based): ignore at compiling.spamassassin.taint.org|ip=none scores 0/0
debug: AWL active, pre-score: -1.053, autolearn score: -1.053, mean: undef, IP: undef
debug: DB addr list: untie-ing and unlocking.
debug: DB addr list: file locked, breaking lock.
debug: unlock: 31866 unlink /root/.spamassassin/auto-whitelist.lock
debug: Post AWL score: -1.053
debug: running body-text per-line regexp tests; score so far=-1.053
debug: running uri tests; score so far=-1.053
debug: running raw-body-text per-line regexp tests; score so far=-1.053
debug: running full-text regexp tests; score so far=-1.053
debug: is spam? score=-1.053 required=5
debug: tests=ALL_TRUSTED,MISSING_DATE,MISSING_SUBJECT,NO_REAL_NAME
debug: subtests=__HAS_MSGID,__MSGID_OK_DIGITS,__MSGID_OK_HOST,__SANE_MSGID,__UNUSABLE_MSGID

------------------------ MailScanner list ------------------------
To unsubscribe, email jiscmail at jiscmail.ac.uk with the words:
'leave mailscanner' in the body of the email.
Before posting, read the Wiki (http://wiki.mailscanner.info/) and
the archives (http://www.jiscmail.ac.uk/lists/mailscanner.html).

Support MailScanner development - buy the book off the website!