http://bugzilla.spamassassin.org/show_bug.cgi?id=3649





------- Additional Comments From [EMAIL PROTECTED]  2004-09-28 12:26 -------
I patch comment #34 against SA 3.0 and got the following results from a single 
message:

Sep 28 12:15:59 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7289]: (07289-
01) LMTP::10025 /var/amavisd/tmp/amavis-20040928T121559-07289: 
<[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]> Received: SIZE=1050 from 
mgw1.pointshare.com ([127.0.0.1]) by localhost (mgw1.healthknowledge.com 
[127.0.0.1]) (amavisd-new, port 10025) with LMTP id 07289-01 for 
<[EMAIL PROTECTED]>; Tue, 28 Sep 2004 12:15:59 -0700 (PDT)
Sep 28 12:15:59 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7289]: (07289-
01) Checking: <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>
debug: received-header: parsed as [ ip=204.189.38.4 rdns=gw1.pointshare.com 
helo=gw1.pointshare.com by=mgw1.pointshare.com ident= envfrom= intl=0 
id=D97BE99B17 ]
debug: received-header: parsed as [ ip=216.136.174.138 
rdns=smtp100.mail.sc5.yahoo.com helo=smtp100.mail.sc5.yahoo.com 
by=gw3.pointshare.com ident= envfrom= intl=0 id=2A736AE0AC ]
debug: is Net::DNS::Resolver available? yes
debug: Net::DNS version: 0.48
debug: looking up PTR record for '206.96.149.251'
debug: PTR for '206.96.149.251': ''
debug: received-header: parsed as [ ip=206.96.149.251 rdns= helo=blhome 
by=smtp100.mail.sc5.yahoo.com ident=bill?landry envfrom= intl=0 id= ]
debug: received-header: relay 204.189.38.4 trusted? yes internal? yes
debug: received-header: relay 216.136.174.138 trusted? no internal? no
debug: received-header: relay 206.96.149.251 trusted? no internal? no
debug: metadata: X-Spam-Relays-Trusted: [ ip=204.189.38.4 
rdns=gw1.pointshare.com helo=gw1.pointshare.com by=mgw1.pointshare.com ident= 
envfrom= intl=1 id=D97BE99B17 ]
debug: metadata: X-Spam-Relays-Untrusted: [ ip=216.136.174.138 
rdns=smtp100.mail.sc5.yahoo.com helo=smtp100.mail.sc5.yahoo.com 
by=gw3.pointshare.com ident= envfrom= intl=0 id=2A736AE0AC ] [ 
ip=206.96.149.251 rdns= helo=blhome by=smtp100.mail.sc5.yahoo.com ident=bill?
landry envfrom= intl=0 id= ]
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: decoding: other encoding type (7bit), ignoring
debug: Message too short for language analysis
debug: URIDNSBL: domains to query: 
debug: is Net::DNS::Resolver available? yes
debug: Net::DNS version: 0.48
debug: all '*From' addrs: [EMAIL PROTECTED]
debug: Running tests for priority: 0
debug: running header regexp tests; score so far=0
debug: SPF: checking HELO (helo=smtp100.mail.sc5.yahoo.com, ip=216.136.174.138)
debug: SPF: trimmed HELO down to 'yahoo.com'
debug: SPF: query for /216.136.174.138/yahoo.com: result: none, comment: SPF: 
domain of sender yahoo.com does not designate mailers
debug: forged-HELO: from=yahoo.com helo=yahoo.com by=pointshare.com
debug: forged-HELO: from= helo=blhome by=yahoo.com
debug: all '*To' addrs: [EMAIL PROTECTED]
debug: SPF: relayed through one or more trusted relays, cannot use header-based 
Envelope-From, skipping
debug: running body-text per-line regexp tests; score so far=0
debug: running uri tests; score so far=0
debug: Razor2 is available
debug: entering helper-app run mode
 Razor-Log: read_file: 18 items read from /var/amavisd/.razor/razor-agent.conf
 Razor-Log: Found razorhome: /var/amavisd/.razor
Sep 28 12:16:09.996143 check[7289]: [ 2] [bootup] Logging initiated 
LogDebugLevel=9 to stdout
Sep 28 12:16:09.998257 check[7289]: [ 5] computed 
razorhome=/var/amavisd/.razor, conf=/var/amavisd/.razor/razor-agent.conf, 
ident=/var/amavisd/.razor/[EMAIL PROTECTED]
Sep 28 12:16:09.999841 check[7289]: [ 8] Client supported_engines: 4 8
Sep 28 12:16:10.003827 check[7289]: [ 8]  prep_mail done: mail 1 headers=1101, 
mime0=119
Sep 28 12:16:10.006684 check[7289]: [ 5] read_file: 8 items read 
from /var/amavisd/.razor/servers.discovery.lst
Sep 28 12:16:10.009880 check[7289]: [ 5] read_file: 2 items read 
from /var/amavisd/.razor/servers.nomination.lst
Sep 28 12:16:10.011543 check[7289]: [ 5] read_file: 3 items read 
from /var/amavisd/.razor/servers.catalogue.lst
Sep 28 12:16:10.013550 check[7289]: [ 9] Assigning defaults to 
folly.cloudmark.com
Sep 28 12:16:10.014830 check[7289]: [ 9] Assigning defaults to joy.cloudmark.com
Sep 28 12:16:10.016103 check[7289]: [ 9] Assigning defaults to 
thrill.cloudmark.com
Sep 28 12:16:10.017271 check[7289]: [ 9] Assigning defaults to 
wonder.cloudmark.com
Sep 28 12:16:10.018501 check[7289]: [ 9] Assigning defaults to 
pride.cloudmark.com
Sep 28 12:16:10.022570 check[7289]: [ 5] read_file: 14 items read 
from /var/amavisd/.razor/server.joy.cloudmark.com.conf
Sep 28 12:16:10.027146 check[7289]: [ 5] read_file: 14 items read 
from /var/amavisd/.razor/server.joy.cloudmark.com.conf
Sep 28 12:16:10.030089 check[7289]: [ 5] read_file: 14 items read 
from /var/amavisd/.razor/server.folly.cloudmark.com.conf
Sep 28 12:16:10.034171 check[7289]: [ 5] read_file: 14 items read 
from /var/amavisd/.razor/server.folly.cloudmark.com.conf
Sep 28 12:16:10.038497 check[7289]: [ 5] read_file: 16 items read 
from /var/amavisd/.razor/server.pride.cloudmark.com.conf
Sep 28 12:16:10.041673 check[7289]: [ 5] read_file: 16 items read 
from /var/amavisd/.razor/server.pride.cloudmark.com.conf
Sep 28 12:16:10.044732 check[7289]: [ 5] read_file: 16 items read 
from /var/amavisd/.razor/server.thrill.cloudmark.com.conf
Sep 28 12:16:10.049937 check[7289]: [ 5] read_file: 16 items read 
from /var/amavisd/.razor/server.thrill.cloudmark.com.conf
Sep 28 12:16:10.053019 check[7289]: [ 5] read_file: 16 items read 
from /var/amavisd/.razor/server.wonder.cloudmark.com.conf
Sep 28 12:16:10.056039 check[7289]: [ 5] read_file: 16 items read 
from /var/amavisd/.razor/server.wonder.cloudmark.com.conf
Sep 28 12:16:10.059127 check[7289]: [ 5] read_file: 16 items read 
from /var/amavisd/.razor/server.solace.cloudmark.com.conf
Sep 28 12:16:10.062176 check[7289]: [ 5] read_file: 16 items read 
from /var/amavisd/.razor/server.solace.cloudmark.com.conf
Sep 28 12:16:10.063547 check[7289]: [ 5] 162353 seconds before closest server 
discovery
Sep 28 12:16:10.065147 check[7289]: [ 6] thrill.cloudmark.com is a Catalogue 
Server srl 5047; computed min_cf=6, Server se: C8
Sep 28 12:16:10.066335 check[7289]: [ 8] Computed supported_engines: 4 8
Sep 28 12:16:10.068428 check[7289]: [ 8] Using next closest server 
thrill.cloudmark.com:2703, cached info srl 5047
Sep 28 12:16:10.069518 check[7289]: [ 8] mail 1 Subject: test
Sep 28 12:16:10.071446 check[7289]: [ 6] preproc: mail 1.0 went from 119 bytes 
to 4 
Sep 28 12:16:10.072464 check[7289]: [ 6] computing sigs for mail 1.0, len 4
Sep 28 12:16:10.079353 check[7289]: [ 6] Engine (8) didn't produce a signature 
for mail 1.0
Sep 28 12:16:10.080982 check[7289]: [ 6] skipping whitelist file 
(empty?): /var/amavisd/.razor/razor-whitelist
Sep 28 12:16:10.082217 check[7289]: [ 5] Connecting to thrill.cloudmark.com ...
Sep 28 12:16:10.202651 check[7289]: [ 8] Connection established
Sep 28 12:16:10.203807 check[7289]: [ 4] thrill.cloudmark.com >> 36 server 
greeting: sn=C&srl=5047&a=l&a=cg&ep4=7542-10
Sep 28 12:16:10.206651 check[7289]: [ 4] thrill.cloudmark.com << 25
Sep 28 12:16:10.207446 check[7289]: [ 6] cn=razor-agents&cv=2.61
Sep 28 12:16:10.208811 check[7289]: [ 6] thrill.cloudmark.com is a Catalogue 
Server srl 5047; computed min_cf=6, Server se: C8
Sep 28 12:16:10.209963 check[7289]: [ 8] Computed supported_engines: 4 8
Sep 28 12:16:10.211085 check[7289]: [ 8] mail 1.0 e4 sig: 
WSUfejPYnVaDIwHsvJh5HpFP3bwA
Sep 28 12:16:10.212315 check[7289]: [ 5] mail 1.0 e8 got no sig
Sep 28 12:16:10.213244 check[7289]: [ 8] preparing 1 queries
Sep 28 12:16:10.215053 check[7289]: [ 8] sending 1 batches
Sep 28 12:16:10.216695 check[7289]: [ 4] thrill.cloudmark.com << 52
Sep 28 12:16:10.217385 check[7289]: [ 6] a=c&e=4&ep4=7542-
10&s=WSUfejPYnVaDIwHsvJh5HpFP3bwA
Sep 28 12:16:10.456659 check[7289]: [ 4] thrill.cloudmark.com >> 10
Sep 28 12:16:10.457404 check[7289]: [ 6] response to sent.2
p=0&ct=1
Sep 28 12:16:10.460116 check[7289]: [ 6] mail 1.0 e=4 
sig=WSUfejPYnVaDIwHsvJh5HpFP3bwA: sig not found.
Sep 28 12:16:10.461245 check[7289]: [ 7] method 4: mail 1.0: contention part, 
skipping
Sep 28 12:16:10.461967 check[7289]: [ 7] method 4: mail 1: all non-contention 
parts not spam, mail not spam
Sep 28 12:16:10.462657 check[7289]: [ 3] mail 1 is not known spam.
Sep 28 12:16:10.463529 check[7289]: [ 5] disconnecting from server 
thrill.cloudmark.com
Sep 28 12:16:10.465150 check[7289]: [ 4] thrill.cloudmark.com << 5
Sep 28 12:16:10.465828 check[7289]: [ 6] a=q
debug: Using results from Razor v2.61
debug: Found Razor2 part: part=0 engine=4 ct=1 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=0
debug: running full-text regexp tests; score so far=0
debug: Razor2 is available
debug: Pyzor is available: /usr/local/bin/pyzor
debug: entering helper-app run mode
debug: setuid: helper proc 7309: ruid=3000 euid=3000
debug: leaving helper-app run mode
Pyzor -> check failed: setuid: oops: fileno(STDOUT) [0] != 1 
at /usr/lib/perl5/site_perl/5.8.3/Mail/SpamAssassin/Util.pm line 1060.

debug: DCCifd is available: /var/dcc/dccifd
debug: entering helper-app run mode
debug: DCCifd: got response: X-DCC-sgs_public_dcc_server-Metrics: 
mgw1.pointshare.com 1199; Body=0
debug: leaving helper-app run mode
debug: Running tests for priority: 500
debug: RBL: success for 88 of 90 queries
debug: DNS: timeout for BLARS after 3 seconds
debug: DNS: timeout for BLARS after 3 seconds
debug: running meta tests; score so far=4.374
debug: running header regexp tests; score so far=4.374
debug: running body-text per-line regexp tests; score so far=4.374
debug: running uri tests; score so far=4.374
debug: running raw-body-text per-line regexp tests; score so far=4.374
debug: running full-text regexp tests; score so far=4.374
debug: Running tests for priority: 1000
debug: running meta tests; score so far=4.374
debug: running header regexp tests; score so far=4.374
debug: lock: 7309 created /var/amavisd/.spamassassin/auto-whitelist.mutex
debug: lock: 7309 trying to get lock on /var/amavisd/.spamassassin/auto-
whitelist with 30 timeout
debug: lock: 7309 link to /var/amavisd/.spamassassin/auto-whitelist.mutex: link 
ok
debug: Tie-ing to DB file R/W in /var/amavisd/.spamassassin/auto-whitelist
debug: auto-whitelist (db-based): [EMAIL PROTECTED]|ip=206.96 scores 
39/321.633
debug: AWL active, pre-score: 4.374, autolearn score: 4.374, mean: 8.247, IP: 
206.96.149.251
debug: add_score: New count: 40, new totscore: 326.007
debug: DB addr list: untie-ing and unlocking.
debug: DB addr list: file locked, breaking lock.
debug: unlock: 7309 unlocked /var/amavisd/.spamassassin/auto-whitelist.mutex
debug: Post AWL score: 6.3105
debug: running body-text per-line regexp tests; score so far=6.3105
debug: running uri tests; score so far=6.3105
debug: running raw-body-text per-line regexp tests; score so far=6.3105
debug: running full-text regexp tests; score so far=6.3105
debug: is spam? score=6.311 required=1
debug: tests=AWL,DNS_FROM_RFC_ABUSE,FROM_RFCI_ABUSE,RCVD_IN_SPAMBAG
debug: 
subtests=__ANY_OUTLOOK_MUA,__CT,__CTE,__CTYPE_CHARSET_QUOTED,__CT_TEXT_PLAIN,__H
AS_MIMEOLE,__HAS_MSGID,__HAS_MSMAIL_PRI,__HAS_OUTLOOK_IN_MAILER,__HAS_SUBJECT,__
HAS_X_MAILER,__HAS_X_PRIORITY,__MIME_VERSION,__MSGID_OK_HEX,__MSGID_OK_HOST,__MS
GID_RANDY,__OE_MSGID_2,__OE_MUA,__OUTLOOK_DOLLARS_MSGID,__RFC_IGNORANT_ENVFROM,_
_SANE_MSGID
Sep 28 12:16:12 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7309]: (07289-
01) SPAM, <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>, Yes, 
hits=6.311 tag=1 tag2=1 kill=1 tests=AWL, DNS_FROM_RFC_ABUSE, FROM_RFCI_ABUSE, 
RCVD_IN_SPAMBAG
Sep 28 12:16:12 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7309]: (07289-
01) SPAM-TAG, <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>, Yes, 
hits=6.311 tagged_above=1 required=1 tests=AWL, DNS_FROM_RFC_ABUSE, 
FROM_RFCI_ABUSE, RCVD_IN_SPAMBAG
Sep 28 12:16:12 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7309]: (07289-
01) FWD via SMTP: [127.0.0.1]:10026 <[EMAIL PROTECTED]> -> 
<[EMAIL PROTECTED]>
Sep 28 12:16:12 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7309]: (07289-
01) Passed SPAM, <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>, 
Message-ID: <[EMAIL PROTECTED]>, Hits: 6.311
Sep 28 12:16:12 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7309]: (07289-
01) TIMING [total 13221 ms] - SMTP LHLO: 36 (0%), SMTP pre-MAIL: 2 (0%), mkdir 
tempdir: 1 (0%), create email.txt: 1 (0%), SMTP pre-DATA-flush: 16 (0%), SMTP 
DATA: 29 (0%), body_hash: 3 (0%), mkdir parts: 5 (0%), mime_decode: 57 (0%), 
get-file-type1: 43 (0%), decompose_part: 3 (0%), parts_decode: 0 (0%), AV-scan-
1: 326 (2%), AV-scan-2: 1705 (13%), AV-scan-3: 5 (0%), AV-scan-4: 6556 (50%), 
wb-list: 10 (0%), SA msg read: 3 (0%), SA parse: 13 (0%), SA check: 4118 (31%), 
update_cache: 19 (0%), post-do_spam: 13 (0%), fwd-connect: 64 (0%), fwd-mail-
from: 5 (0%), fwd-rcpt-to: 35 (0%), write-header: 14 (0%), fwd-data: 1 (0%), 
fwd-data-end: 69 (1%), fwd-rundown: 4 (0%), main_log_entry: 45 (0%), 
update_snmp: 12 (0%), unlink-1-files: 3 (0%), rundown: 3 (0%)
Sep 28 12:16:12 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7309]: (07289-
01) TROUBLE in process_request: Error writing a SMTP response to the socket: 
Bad file descriptor at (eval 39) line 660, <GEN21> line 89.
Suicide (07289-01) TROUBLE in process_request: Error writing a SMTP response to 
the socket: Bad file descriptor at (eval 39) line 660, <GEN21> line 89.
Sep 28 12:16:12 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7309]: (07289-
01) tempdir being removed: /var/amavisd/tmp/amavis-20040928T121559-07289
debug: leaving helper-app run mode
debug: Pyzor -> check failed: no response
debug: DCCifd is available: /var/dcc/dccifd
debug: entering helper-app run mode
debug: DCCifd: got response: X-DCC-sgs_public_dcc_server-Metrics: 
mgw1.pointshare.com 1199; Body=0
debug: leaving helper-app run mode
debug: Running tests for priority: 500
debug: RBL: success for 0 of 90 queries
debug: DNS: timeout for Korea after 5 seconds
debug: DNS: timeout for DNSRBLSPAM after 5 seconds
debug: DNS: timeout for Thailand after 5 seconds
debug: DNS: timeout for securitysage after 5 seconds
debug: DNS: timeout for RFCIPigs after 5 seconds
debug: DNS: timeout for ANTISPAM after 5 seconds
debug: DNS: timeout for MPPorn after 5 seconds
debug: DNS: timeout for Nigeria after 5 seconds
debug: DNS: timeout for MPBulk after 5 seconds
debug: DNS: timeout for Russia after 5 seconds
debug: DNS: timeout for Japan after 5 seconds
debug: DNS: timeout for AHBL after 5 seconds
debug: DNS: timeout for SPAMBAG after 5 seconds
debug: DNS: timeout for BLARS after 5 seconds
debug: DNS: timeout for NOMOREFUNN after 5 seconds
debug: DNS: timeout for FIVETEN after 5 seconds
debug: DNS: timeout for KROPKA after 5 seconds
debug: DNS: timeout for Russia after 5 seconds
debug: DNS: timeout for THEY after 5 seconds
debug: DNS: timeout for NO_DNS_FOR_FROM after 5 seconds
debug: DNS: timeout for Turkey after 5 seconds
debug: DNS: timeout for Nigeria after 5 seconds
debug: DNS: timeout for Korea after 5 seconds
debug: DNS: timeout for njabl after 5 seconds
debug: DNS: timeout for RFCIAbuse after 5 seconds
debug: DNS: timeout for sblxbl,sblxbl-notfirsthop after 5 seconds
debug: DNS: timeout for Taiwan after 5 seconds
debug: DNS: timeout for njabl-notfirsthop,njabl after 5 seconds
debug: DNS: timeout for sorbs-notfirsthop,sorbs after 5 seconds
debug: DNS: timeout for Singapore after 5 seconds
debug: DNS: timeout for ANTISPAM after 5 seconds
debug: DNS: timeout for INTERSIL after 5 seconds
debug: DNS: timeout for China after 5 seconds
debug: DNS: timeout for Thailand after 5 seconds
debug: DNS: timeout for Singapore after 5 seconds
debug: DNS: timeout for SpamSites after 5 seconds
debug: DNS: timeout for Mexico after 5 seconds
debug: DNS: timeout for Hongkong after 5 seconds
debug: DNS: timeout for China after 5 seconds
debug: DNS: timeout for ahbl after 5 seconds
debug: DNS: timeout for Malaysia after 5 seconds
debug: DNS: timeout for Brazil after 5 seconds
debug: DNS: timeout for Turkey after 5 seconds
debug: DNS: timeout for INTERSIL after 5 seconds
debug: DNS: timeout for Argentina after 5 seconds
debug: DNS: timeout for Argentina after 5 seconds
debug: DNS: timeout for FIVETEN after 5 seconds
debug: DNS: timeout for THEY after 5 seconds
debug: DNS: timeout for BLARS after 5 seconds
debug: DNS: timeout for sorbs after 5 seconds
debug: DNS: timeout for sblxbl after 5 seconds
debug: DNS: timeout for NOMOREFUNN after 5 seconds
debug: DNS: timeout for DNSRBLDUN-notfirsthop after 5 seconds
debug: DNS: timeout for Malaysia after 5 seconds
debug: DNS: timeout for RFCIPostmaster after 5 seconds
debug: DNS: timeout for SPAMBAG after 5 seconds
debug: DNS: timeout for KROPKA after 5 seconds
debug: DNS: timeout for Mexico after 5 seconds
debug: DNS: timeout for DNSRBLSPAM after 5 seconds
debug: DNS: timeout for Brazil after 5 seconds
debug: DNS: timeout for Hongkong after 5 seconds
debug: DNS: timeout for Taiwan after 5 seconds
debug: DNS: timeout for AHBL after 5 seconds
debug: DNS: timeout for Japan after 5 seconds
debug: DNS: timeout for rfci_envfrom after 5 seconds
debug: DNS: timeout for NO_DNS_FOR_FROM after 5 seconds
debug: DNS: timeout for rsl after 5 seconds
debug: DNS: timeout for ipwhois-notfirsthop after 5 seconds
debug: DNS: timeout for ORDB after 5 seconds
debug: DNS: timeout for rsl after 5 seconds
debug: DNS: timeout for ORDB after 5 seconds
debug: DNS: timeout for VOX after 5 seconds
debug: DNS: timeout for UCEB after 5 seconds
debug: DNS: timeout for spamcop after 5 seconds
debug: DNS: timeout for dsbl-notfirsthop after 5 seconds
debug: DNS: timeout for DSBLMULTI after 5 seconds
debug: DNS: timeout for UCEB after 5 seconds
debug: DNS: timeout for bsp-firsttrusted after 5 seconds
debug: DNS: timeout for KUNDENSERVER after 5 seconds
debug: DNS: timeout for spamcop after 5 seconds
debug: DNS: timeout for ABUSEAT after 5 seconds
debug: DNS: timeout for FABEL after 5 seconds
debug: DNS: timeout for KUNDENSERVER after 5 seconds
debug: DNS: timeout for ABUSEAT after 5 seconds
debug: DNS: timeout for LEADMON after 5 seconds
debug: DNS: timeout for LEADMON after 5 seconds
debug: DNS: timeout for bsp-untrusted after 5 seconds
debug: DNS: timeout for DSBLMULTI after 5 seconds
debug: DNS: timeout for VOX after 5 seconds
debug: DNS: timeout for FABEL after 5 seconds
debug: running meta tests; score so far=0
debug: running header regexp tests; score so far=0
debug: running body-text per-line regexp tests; score so far=0
debug: running uri tests; score so far=0
debug: running raw-body-text per-line regexp tests; score so far=0
debug: running full-text regexp tests; score so far=0
debug: Running tests for priority: 1000
debug: running meta tests; score so far=0
debug: running header regexp tests; score so far=0
debug: lock: 7289 created /var/amavisd/.spamassassin/auto-whitelist.mutex
debug: lock: 7289 trying to get lock on /var/amavisd/.spamassassin/auto-
whitelist with 30 timeout
debug: lock: 7289 link to /var/amavisd/.spamassassin/auto-whitelist.mutex: link 
ok
debug: Tie-ing to DB file R/W in /var/amavisd/.spamassassin/auto-whitelist
debug: auto-whitelist (db-based): [EMAIL PROTECTED]|ip=206.96 scores 
40/326.007
debug: AWL active, pre-score: 0, autolearn score: 0, mean: 8.150175, IP: 
206.96.149.251
debug: add_score: New count: 41, new totscore: 326.007
debug: DB addr list: untie-ing and unlocking.
debug: DB addr list: file locked, breaking lock.
debug: unlock: 7289 unlocked /var/amavisd/.spamassassin/auto-whitelist.mutex
debug: Post AWL score: 4.0750875
debug: running body-text per-line regexp tests; score so far=4.0750875
debug: running uri tests; score so far=4.0750875
debug: running raw-body-text per-line regexp tests; score so far=4.0750875
debug: running full-text regexp tests; score so far=4.0750875
debug: is spam? score=4.075 required=1
debug: tests=AWL
debug: 
subtests=__ANY_OUTLOOK_MUA,__CT,__CTE,__CTYPE_CHARSET_QUOTED,__CT_TEXT_PLAIN,__H
AS_MIMEOLE,__HAS_MSGID,__HAS_MSMAIL_PRI,__HAS_OUTLOOK_IN_MAILER,__HAS_SUBJECT,__
HAS_X_MAILER,__HAS_X_PRIORITY,__MIME_VERSION,__MSGID_OK_HEX,__MSGID_OK_HOST,__MS
GID_RANDY,__OE_MSGID_2,__OE_MUA,__OUTLOOK_DOLLARS_MSGID,__SANE_MSGID
Sep 28 12:16:14 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7289]: (07289-
01) TROUBLE in check_mail: spam_scan FAILED: BDB db_cursor: Locker does not 
exist, No such file or directory. at /usr/local/sbin/amavisd line 5162.
Sep 28 12:16:14 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7289]: (07289-
01) PRESERVING EVIDENCE in /var/amavisd/tmp/amavis-20040928T121559-07289
Sep 28 12:16:14 mgw1.healthknowledge.com /usr/local/sbin/amavisd[7289]: (07289-
01) TIMING [total 14961 ms] - SMTP LHLO: 36 (0%), SMTP pre-MAIL: 2 (0%), mkdir 
tempdir: 1 (0%), create email.txt: 1 (0%), SMTP pre-DATA-flush: 16 (0%), SMTP 
DATA: 29 (0%), body_hash: 3 (0%), mkdir parts: 5 (0%), mime_decode: 57 (0%), 
get-file-type1: 43 (0%), decompose_part: 3 (0%), parts_decode: 0 (0%), AV-scan-
1: 326 (2%), AV-scan-2: 1705 (11%), AV-scan-3: 5 (0%), AV-scan-4: 6556 (44%), 
wb-list: 10 (0%), SA msg read: 3 (0%), SA parse: 13 (0%), SA check: 6141 (41%), 
rundown: 5 (0%)

Hope this helps.

Bill



------- You are receiving this mail because: -------
You are the assignee for the bug, or are watching the assignee.

Reply via email to