Страница 1 из 1

SpamAssassin + Exim не работает после обновления

Добавлено: 2010-08-23 23:02:34
zibadi
Debian lenny, SpamAssassin v3.3.1, Perl v5.10.1, Exim v4.72
Проблема в следующем: после обновления перестал работать спам фильтр, включил в spamassassin -D, вот лог:

Код: Выделить всё

Mon Aug 23 17:54:46 2010 [10236] dbg: logger: successfully added file method
Mon Aug 23 17:54:46 2010 [10236] dbg: spamd: will perform setuids? 0
Mon Aug 23 17:54:46 2010 [10236] dbg: spamd: creating INET socket:
Mon Aug 23 17:54:46 2010 [10236] dbg: spamd: Listen: 128
Mon Aug 23 17:54:46 2010 [10236] dbg: spamd: LocalAddr: 127.0.0.1
Mon Aug 23 17:54:46 2010 [10236] dbg: spamd: LocalPort: 783
Mon Aug 23 17:54:46 2010 [10236] dbg: spamd: Proto: 6
Mon Aug 23 17:54:46 2010 [10236] dbg: spamd: ReuseAddr: 1
Mon Aug 23 17:54:46 2010 [10236] dbg: spamd: Type: 1
Mon Aug 23 17:54:46 2010 [10236] dbg: logger: adding facilities: all
Mon Aug 23 17:54:46 2010 [10236] dbg: logger: logging level is DBG
Mon Aug 23 17:54:46 2010 [10236] dbg: generic: SpamAssassin version 3.2.5
Mon Aug 23 17:54:46 2010 [10236] dbg: config: score set 0 chosen.
Mon Aug 23 17:54:46 2010 [10236] dbg: dns: is Net::DNS::Resolver available? yes
Mon Aug 23 17:54:46 2010 [10236] dbg: dns: Net::DNS version: 0.66
Mon Aug 23 17:54:46 2010 [10236] info: logger: removing stderr method
Mon Aug 23 17:54:46 2010 [10238] dbg: spamd: successfully daemonized
Mon Aug 23 17:54:46 2010 [10238] dbg: spamd: Preloading modules with HOME=/tmp/spamd-10238-init
Mon Aug 23 17:54:46 2010 [10238] dbg: ignore: test message to precompile patterns and load modules
Mon Aug 23 17:54:46 2010 [10238] dbg: config: using "/etc/spamassassin" for site rules pre files
Mon Aug 23 17:54:46 2010 [10238] dbg: config: read file /etc/spamassassin/init.pre
Mon Aug 23 17:54:46 2010 [10238] dbg: config: read file /etc/spamassassin/v310.pre
Mon Aug 23 17:54:46 2010 [10238] dbg: config: read file /etc/spamassassin/v312.pre
Mon Aug 23 17:54:46 2010 [10238] dbg: config: read file /etc/spamassassin/v320.pre
Mon Aug 23 17:54:46 2010 [10238] dbg: config: read file /etc/spamassassin/v330.pre
Mon Aug 23 17:54:46 2010 [10238] dbg: config: using "/etc/spamassassin/local.cf" for sys rules pre files
Mon Aug 23 17:54:46 2010 [10238] dbg: config: read file /etc/spamassassin/local.cf
Mon Aug 23 17:54:46 2010 [10238] dbg: config: using "/etc/spamassassin/local.cf" for default rules dir
Mon Aug 23 17:54:46 2010 [10238] dbg: config: read file /etc/spamassassin/local.cf
Mon Aug 23 17:54:46 2010 [10238] dbg: config: using "/etc/spamassassin" for site rules dir
Mon Aug 23 17:54:46 2010 [10238] dbg: config: read file /etc/spamassassin/65_debian.cf
Mon Aug 23 17:54:46 2010 [10238] dbg: config: read file /etc/spamassassin/local.cf
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: pyzor: network tests on, attempting Pyzor
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: razor2: razor2 is available, version 2.84
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: reporter: network tests on, attempting SpamCop
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::AWL from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: loading Mail::SpamAssassin::Plugin::FreeMail from @INC
Mon Aug 23 17:54:46 2010 [10238] dbg: conf: finish parsing
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x8fe6688) implements 'finish_parsing_end', priority 0
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x9199080) implements 'finish_parsing_end', priority 0
Mon Aug 23 17:54:46 2010 [10238] dbg: replacetags: replacing tags
Mon Aug 23 17:54:46 2010 [10238] dbg: replacetags: done replacing tags
Mon Aug 23 17:54:46 2010 [10238] dbg: FreeMail: no freemail_domains entries defined, disabling plugin
Mon Aug 23 17:54:46 2010 [10238] dbg: bayes: tie-ing to DB file R/O /var/spool/exim4/.spamassassin/bayes_toks
Mon Aug 23 17:54:46 2010 [10238] dbg: bayes: tie-ing to DB file R/O /var/spool/exim4/.spamassassin/bayes_seen
Mon Aug 23 17:54:46 2010 [10238] dbg: bayes: found bayes db version 3
Mon Aug 23 17:54:46 2010 [10238] dbg: bayes: DB journal sync: last sync: 1282567606
Mon Aug 23 17:54:46 2010 [10238] dbg: config: score set 3 chosen.
Mon Aug 23 17:54:46 2010 [10238] dbg: message: main message type: text/plain
Mon Aug 23 17:54:46 2010 [10238] dbg: message: ---- MIME PARSER START ----
Mon Aug 23 17:54:46 2010 [10238] dbg: message: parsing normal part
Mon Aug 23 17:54:46 2010 [10238] dbg: message: ---- MIME PARSER END ----
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x906d6e0) implements 'check_start', priority 0
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x9026140) implements 'check_main', priority 0
Mon Aug 23 17:54:46 2010 [10238] dbg: conf: trusted_networks are not configured; it is recommended that you configure trusted_networks manually
Mon Aug 23 17:54:46 2010 [10238] dbg: metadata: X-Spam-Relays-Trusted: 
Mon Aug 23 17:54:46 2010 [10238] dbg: metadata: X-Spam-Relays-Untrusted: 
Mon Aug 23 17:54:46 2010 [10238] dbg: metadata: X-Spam-Relays-Internal: 
Mon Aug 23 17:54:46 2010 [10238] dbg: metadata: X-Spam-Relays-External: 
Mon Aug 23 17:54:46 2010 [10238] dbg: message: no encoding detected
Mon Aug 23 17:54:46 2010 [10238] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x8c9c6d0) implements 'parsed_metadata', priority 0
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: is_dns_available() last checked 1282571686.0 seconds ago; re-checking
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: is Net::DNS::Resolver available? yes
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: Net::DNS version: 0.66
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: name server: 213.133.98.98, LocalAddr: 0.0.0.0
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: resolver socket rx buffer size is 110592 bytes
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: testing resolver nameservers: 213.133.98.98, 213.133.99.99, 213.133.100.100
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: trying (3) ebay.com...
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: looking up NS for 'ebay.com'
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: NS lookup of ebay.com using 213.133.98.98 succeeded => DNS available (set dns_available to override)
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: name server: 213.133.99.99, LocalAddr: 0.0.0.0
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: resolver socket rx buffer size is 110592 bytes
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: trying (3) sourceforge.net...
Mon Aug 23 17:54:46 2010 [10238] dbg: dns: looking up NS for 'sourceforge.net'
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: NS lookup of sourceforge.net using 213.133.99.99 succeeded => DNS available (set dns_available to override)
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: name server: 213.133.100.100, LocalAddr: 0.0.0.0
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: resolver socket rx buffer size is 110592 bytes
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: trying (3) akamai.com...
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: looking up NS for 'akamai.com'
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: NS lookup of akamai.com using 213.133.100.100 succeeded => DNS available (set dns_available to override)
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: name server: 213.133.100.100, LocalAddr: 0.0.0.0
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: resolver socket rx buffer size is 110592 bytes
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: NS list: 213.133.98.98, 213.133.99.99, 213.133.100.100
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: name server: 213.133.98.98, LocalAddr: 0.0.0.0
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: resolver socket rx buffer size is 110592 bytes
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: is DNS available? 1
Mon Aug 23 17:54:47 2010 [10238] dbg: uridnsbl: domains to query: 
Mon Aug 23 17:54:47 2010 [10238] dbg: check: running tests for priority: 0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running head tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled head tests
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running body tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled body tests
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running uri tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled uri tests
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running rawbody tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled rawbody tests
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running full tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled full tests
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running meta tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled meta tests
Mon Aug 23 17:54:47 2010 [10238] dbg: check: running tests for priority: 500
Mon Aug 23 17:54:47 2010 [10238] dbg: dns: harvest_dnsbl_queries
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running head tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled head tests
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running body tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled body tests
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running uri tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled uri tests
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running rawbody tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled rawbody tests
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running full tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled full tests
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: running meta tests; score so far=0
Mon Aug 23 17:54:47 2010 [10238] dbg: rules: compiled meta tests
Mon Aug 23 17:54:47 2010 [10238] dbg: check: is spam? score=0 required=5
Mon Aug 23 17:54:47 2010 [10238] dbg: check: tests=
Mon Aug 23 17:54:47 2010 [10238] dbg: check: subtests=
Mon Aug 23 17:54:47 2010 [10238] dbg: bayes: untie-ing
Mon Aug 23 17:54:47 2010 [10238] dbg: learn: initializing learner
Mon Aug 23 17:54:47 2010 [10238] dbg: config: copying current conf to backup
Mon Aug 23 17:54:47 2010 [10238] info: spamd: server started on port 783/tcp (running version 3.2.5)
Mon Aug 23 17:54:47 2010 [10238] info: spamd: server pid: 10238
Mon Aug 23 17:54:47 2010 [10238] info: spamd: server successfully spawned child process, pid 10239
Mon Aug 23 17:54:47 2010 [10238] dbg: prefork: child 10239: entering state 0
Mon Aug 23 17:54:47 2010 [10238] dbg: prefork: new lowest idle kid: none
Mon Aug 23 17:54:47 2010 [10239] dbg: prefork: sysread(9) not ready, wait max 300 secs
Mon Aug 23 17:54:47 2010 [10240] dbg: prefork: sysread(10) not ready, wait max 300 secs
Mon Aug 23 17:54:47 2010 [10238] info: spamd: server successfully spawned child process, pid 10240
Mon Aug 23 17:54:47 2010 [10238] dbg: prefork: child 10240: entering state 0
Mon Aug 23 17:54:47 2010 [10238] dbg: prefork: new lowest idle kid: none
Mon Aug 23 17:54:47 2010 [10238] dbg: prefork: child 10239: entering state 1
Mon Aug 23 17:54:47 2010 [10238] dbg: prefork: new lowest idle kid: 10239
Mon Aug 23 17:54:47 2010 [10238] dbg: prefork: child reports idle
Mon Aug 23 17:54:47 2010 [10238] dbg: prefork: child 10240: entering state 1
Mon Aug 23 17:54:47 2010 [10238] dbg: prefork: new lowest idle kid: 10239
Mon Aug 23 17:54:47 2010 [10238] dbg: prefork: child reports idle
Mon Aug 23 17:54:47 2010 [10238] info: prefork: child states: II
Mon Aug 23 17:56:01 2010 [10238] dbg: prefork: ordered 10239 to accept
Mon Aug 23 17:56:01 2010 [10238] dbg: prefork: child 10239: entering state 2
Mon Aug 23 17:56:01 2010 [10238] dbg: prefork: new lowest idle kid: 10240
Mon Aug 23 17:56:01 2010 [10239] info: spamd: connection from localhost [127.0.0.1] at port 41106
Mon Aug 23 17:56:01 2010 [10239] dbg: conf: finish parsing
Mon Aug 23 17:56:01 2010 [10239] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x8fe6688) implements 'user_conf_parsing_end', priority 0
Mon Aug 23 17:56:01 2010 [10239] dbg: replacetags: replacing tags
Mon Aug 23 17:56:01 2010 [10239] dbg: replacetags: done replacing tags
Mon Aug 23 17:56:01 2010 [10239] dbg: info: user has changed
Mon Aug 23 17:56:01 2010 [10239] dbg: bayes: tie-ing to DB file R/O /var/spool/exim4/.spamassassin/bayes_toks
Mon Aug 23 17:56:01 2010 [10239] dbg: bayes: tie-ing to DB file R/O /var/spool/exim4/.spamassassin/bayes_seen
Mon Aug 23 17:56:01 2010 [10239] dbg: bayes: found bayes db version 3
Mon Aug 23 17:56:01 2010 [10239] dbg: bayes: DB journal sync: last sync: 1282567606
Mon Aug 23 17:56:01 2010 [10239] dbg: config: score set 3 chosen.
Mon Aug 23 17:56:01 2010 [10239] dbg: spamd: running as uid 103
Mon Aug 23 17:56:01 2010 [10239] dbg: dns: name server: 213.133.98.98, LocalAddr: 0.0.0.0
Mon Aug 23 17:56:01 2010 [10239] dbg: dns: resolver socket rx buffer size is 110592 bytes
Mon Aug 23 17:56:01 2010 [10239] dbg: message: main message type: text/html
Mon Aug 23 17:56:01 2010 [10239] info: spamd: checking message <92611282571606@web87.othedomain.ru> for Debian-exim:103
Mon Aug 23 17:56:01 2010 [10239] dbg: conf: trusted_networks are not configured; it is recommended that you configure trusted_networks manually
Mon Aug 23 17:56:01 2010 [10239] dbg: received-header: parsed as [ ip=77.88.61.48 rdns=forward9.mail.othedomain.net helo=forward9.mail.othedomain.net by=mydomain ident= envfrom=user@othedomain.ru intl=0 id=1OnXVI-0002fl-RR auth= msa=0 ]
Mon Aug 23 17:56:01 2010 [10239] dbg: received-header: do not trust any hosts from here on
Mon Aug 23 17:56:01 2010 [10239] dbg: received-header: relay 77.88.61.48 trusted? no internal? no msa? no
Mon Aug 23 17:56:01 2010 [10239] dbg: received-header: parsed as [ ip=77.88.60.150 rdns=web87.othedomain.ru helo=web87.othedomain.ru by=forward9.mail.othedomain.net ident= envfrom= intl=0 id=0A6291CC8002 auth= msa=0 ]
Mon Aug 23 17:56:01 2010 [10239] dbg: received-header: relay 77.88.60.150 trusted? no internal? no msa? no
Mon Aug 23 17:56:01 2010 [10239] dbg: received-header: parsed as [ ip=127.0.0.1 rdns=localhost.localdomain helo=localhost by=web87.othedomain.ru ident= envfrom= intl=0 id=0797728805F auth= msa=0 ]
Mon Aug 23 17:56:01 2010 [10239] dbg: received-header: relay 127.0.0.1 trusted? no internal? no msa? no
Mon Aug 23 17:56:01 2010 [10239] dbg: received-header: parsed as [ ip=109.111.5.17 rdns= helo=!109.111.5.17! by=mail.othedomain.ru ident= envfrom= intl=0 id= auth=HTTP msa=0 ]
Mon Aug 23 17:56:01 2010 [10239] dbg: received-header: relay 109.111.5.17 trusted? no internal? no msa? no
Mon Aug 23 17:56:01 2010 [10239] dbg: metadata: X-Spam-Relays-Trusted: 
Mon Aug 23 17:56:01 2010 [10239] dbg: metadata: X-Spam-Relays-Untrusted: [ ip=77.88.61.48 rdns=forward9.mail.othedomain.net helo=forward9.mail.othedomain.net by=mydomain ident= envfrom=user@othedomain.ru intl=0 id=1OnXVI-0002fl-RR auth= msa=0 ] [ ip=77.88.60.150 rdns=web87.othedomain.ru helo=web87.othedomain.ru by=forward9.mail.othedomain.net ident= envfrom= intl=0 id=0A6291CC8002 auth= msa=0 ] [ ip=127.0.0.1 rdns=localhost.localdomain helo=localhost by=web87.othedomain.ru ident= envfrom= intl=0 id=0797728805F auth= msa=0 ] [ ip=109.111.5.17 rdns= helo=!109.111.5.17! by=mail.othedomain.ru ident= envfrom= intl=0 id= auth=HTTP msa=0 ]
Mon Aug 23 17:56:01 2010 [10239] dbg: metadata: X-Spam-Relays-Internal: 
Mon Aug 23 17:56:01 2010 [10239] dbg: metadata: X-Spam-Relays-External: [ ip=77.88.61.48 rdns=forward9.mail.othedomain.net helo=forward9.mail.othedomain.net by=mydomain ident= envfrom=user@othedomain.ru intl=0 id=1OnXVI-0002fl-RR auth= msa=0 ] [ ip=77.88.60.150 rdns=web87.othedomain.ru helo=web87.othedomain.ru by=forward9.mail.othedomain.net ident= envfrom= intl=0 id=0A6291CC8002 auth= msa=0 ] [ ip=127.0.0.1 rdns=localhost.localdomain helo=localhost by=web87.othedomain.ru ident= envfrom= intl=0 id=0797728805F auth= msa=0 ] [ ip=109.111.5.17 rdns= helo=!109.111.5.17! by=mail.othedomain.ru ident= envfrom= intl=0 id= auth=HTTP msa=0 ]
Mon Aug 23 17:56:01 2010 [10239] dbg: message: ---- MIME PARSER START ----
Mon Aug 23 17:56:01 2010 [10239] dbg: message: parsing normal part
Mon Aug 23 17:56:01 2010 [10239] dbg: message: ---- MIME PARSER END ----
Mon Aug 23 17:56:01 2010 [10239] dbg: message: decoding base64
Mon Aug 23 17:56:01 2010 [10239] dbg: uridnsbl: domains to query: othedomain.ru caused.com giaodichbatdongsan.com groups.by
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-NS, NS:othedomain.ru (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-NS, NS:caused.com (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-NS, NS:giaodichbatdongsan.com (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-NS, NS:groups.by (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: check: running tests for priority: 0
Mon Aug 23 17:56:01 2010 [10239] dbg: async: select found 2 responses ready (t.o.=0.0)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.002 s: URI-NS, NS:othedomain.ru
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:ns1.othedomain.ru. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:ns2.othedomain.ru. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:ns4.othedomain.ru. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:ns5.othedomain.ru. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.002 s: URI-NS, NS:caused.com
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:dns1.name-services.com. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:dns2.name-services.com. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:dns3.name-services.com. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:dns4.name-services.com. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:dns5.name-services.com. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: queries completed: 2, started: 9
Mon Aug 23 17:56:01 2010 [10239] dbg: async: queries active: URI-A=9 URI-NS=2 at Mon Aug 23 17:56:01 2010
Mon Aug 23 17:56:01 2010 [10239] dbg: dns: harvested completed queries
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running head tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running body tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running uri tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running rawbody tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running full tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running meta tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: check: running tests for priority: 500
Mon Aug 23 17:56:01 2010 [10239] dbg: dns: harvest_dnsbl_queries
Mon Aug 23 17:56:01 2010 [10239] dbg: async: select found 9 responses ready (t.o.=0.0)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.005 s: URI-A, A:dns5.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.005 s: URI-A, A:ns1.othedomain.ru.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.006 s: URI-A, A:dns1.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.006 s: URI-A, A:ns5.othedomain.ru.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.006 s: URI-A, A:dns2.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.006 s: URI-A, A:ns2.othedomain.ru.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.005 s: URI-A, A:dns4.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.006 s: URI-A, A:ns4.othedomain.ru.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.005 s: URI-A, A:dns3.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: queries completed: 9, started: 0
Mon Aug 23 17:56:01 2010 [10239] dbg: async: queries active: URI-NS=2 at Mon Aug 23 17:56:01 2010
Mon Aug 23 17:56:01 2010 [10239] dbg: dns: harvest_dnsbl_queries - check_tick
Mon Aug 23 17:56:01 2010 [10239] dbg: async: select found 1 responses ready (t.o.=1.0)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.036 s: URI-NS, NS:groups.by
Mon Aug 23 17:56:01 2010 [10239] dbg: async: queries completed: 1, started: 0
Mon Aug 23 17:56:01 2010 [10239] dbg: async: queries active: URI-NS=1 at Mon Aug 23 17:56:01 2010
Mon Aug 23 17:56:01 2010 [10239] dbg: dns: harvest_dnsbl_queries - check_tick
Mon Aug 23 17:56:01 2010 [10239] dbg: async: select found 1 responses ready (t.o.=1.0)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.223 s: URI-NS, NS:giaodichbatdongsan.com
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:dns1.name-services.com. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:dns2.name-services.com. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:dns3.name-services.com. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:dns4.name-services.com. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: starting: URI-A, A:dns5.name-services.com. (timeout 15.0s, min 3.0s)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: queries completed: 1, started: 5
Mon Aug 23 17:56:01 2010 [10239] dbg: async: queries active: URI-A=5 at Mon Aug 23 17:56:01 2010
Mon Aug 23 17:56:01 2010 [10239] dbg: dns: harvest_dnsbl_queries - check_tick
Mon Aug 23 17:56:01 2010 [10239] dbg: async: select found 5 responses ready (t.o.=1.0)
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.001 s: URI-A, A:dns5.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.002 s: URI-A, A:dns1.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.001 s: URI-A, A:dns2.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.001 s: URI-A, A:dns4.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: completed in 0.001 s: URI-A, A:dns3.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.002 . NS:othedomain.ru
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.002 . NS:caused.com
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.005 . A:ns1.othedomain.ru.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.006 . A:ns5.othedomain.ru.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.006 . A:dns5.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.006 . A:ns4.othedomain.ru.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.006 . A:ns2.othedomain.ru.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.006 . A:dns4.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.007 . A:dns3.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.007 . A:dns2.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.007 . A:dns1.name-services.com.
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.036 . NS:groups.by
Mon Aug 23 17:56:01 2010 [10239] dbg: async: timing: 0.223 . NS:giaodichbatdongsan.com
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running head tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running body tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running uri tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running rawbody tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running full tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: rules: running meta tests; score so far=0
Mon Aug 23 17:56:01 2010 [10239] dbg: plugin: Mail::SpamAssassin::Plugin::AutoLearnThreshold=HASH(0x8fc6850) implements 'autolearn_discriminator', priority 0
Mon Aug 23 17:56:01 2010 [10239] dbg: learn: auto-learn: currently using scoreset 3, recomputing score based on scoreset 1
Mon Aug 23 17:56:01 2010 [10239] dbg: learn: auto-learn: message score: 0, computed score for autolearn: 0
Mon Aug 23 17:56:01 2010 [10239] dbg: learn: auto-learn? ham=0.1, spam=12, body-points=0, head-points=0, learned-points=0
Mon Aug 23 17:56:01 2010 [10239] dbg: learn: auto-learn? yes, ham (0 < 0.1)
Mon Aug 23 17:56:01 2010 [10239] dbg: learn: initializing learner
Mon Aug 23 17:56:01 2010 [10239] dbg: learn: learning ham
Mon Aug 23 17:56:01 2010 [10239] dbg: plugin: Mail::SpamAssassin::Plugin::WLBLEval=HASH(0x9147e08) implements 'check_wb_list', priority 0
Mon Aug 23 17:56:01 2010 [10239] dbg: eval: all '*From' addrs: user@othedomain.ru
Mon Aug 23 17:56:01 2010 [10239] dbg: eval: all '*To' addrs: user@mydomain
Mon Aug 23 17:56:01 2010 [10239] dbg: bayes: learned 'ae3f7dbadce9bbc7b4c9ffaa96ffa724cfe0bc9f@sa_generated', atime: 1282571760
Mon Aug 23 17:56:01 2010 [10239] dbg: bayes: untie-ing
Mon Aug 23 17:56:01 2010 [10239] dbg: learn: initializing learner
Mon Aug 23 17:56:01 2010 [10239] dbg: check: is spam? score=0 required=5
Mon Aug 23 17:56:01 2010 [10239] dbg: check: tests=
Mon Aug 23 17:56:01 2010 [10239] dbg: check: subtests=
Mon Aug 23 17:56:01 2010 [10239] info: spamd: clean message (0.0/5.0) for Debian-exim:103 in 0.3 seconds, 8643 bytes.
Mon Aug 23 17:56:01 2010 [10239] info: spamd: result: . 0 - scantime=0.3,size=8643,user=Debian-exim,uid=103,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=41106,mid=<92611282571606@web87.othedomain.ru>,autolearn=ham
Mon Aug 23 17:56:01 2010 [10239] dbg: config: copying current conf from backup
Mon Aug 23 17:56:01 2010 [10238] dbg: prefork: child 10239: entering state 1
Mon Aug 23 17:56:01 2010 [10238] dbg: prefork: new lowest idle kid: 10239
Mon Aug 23 17:56:01 2010 [10238] dbg: prefork: child reports idle
Mon Aug 23 17:56:01 2010 [10238] info: prefork: child states: II
Mon Aug 23 17:56:01 2010 [10239] dbg: prefork: sysread(9) not ready, wait max 300 secs
Mon Aug 23 17:57:17 2010 [10239] dbg: prefork: periodic ping from spamd parent
Mon Aug 23 17:57:17 2010 [10239] dbg: prefork: sysread(9) not ready, wait max 300 secs
Mon Aug 23 17:57:17 2010 [10240] dbg: prefork: periodic ping from spamd parent
Mon Aug 23 17:57:17 2010 [10240] dbg: prefork: sysread(10) not ready, wait max 300 secs
Mon Aug 23 17:57:34 2010 [10238] dbg: prefork: ordered 10239 to accept
Mon Aug 23 17:57:34 2010 [10238] dbg: prefork: sysread(8) not ready, wait max 300 secs
раньше было в логе вот что:

Код: Выделить всё

fixed relative path: /var/lib/spamassassin/3.002005/updates_spamassassin_org/10_default_prefs.cf
Mon Aug 23 18:56:53 2010 [3890] dbg: config: using "/var/lib/spamassassin/3.002005/updates_spamassassin_org/10_default_prefs.cf" for included file
Mon Aug 23 18:56:53 2010 [3890] dbg: config: read file /var/lib/spamassassin/3.002005/updates_spamassassin_org/10_default_prefs.cf
Mon Aug 23 18:56:53 2010 [3890] dbg: config: fixed relative path: /var/lib/spamassassin/3.002005/updates_spamassassin_org/20_advance_fee.cf
Mon Aug 23 18:56:53 2010 [3890] dbg: config: using "/var/lib/spamassassin/3.002005/updates_spamassassin_org/20_advance_fee.cf" for included file
Mon Aug 23 18:56:53 2010 [3890] dbg: config: read file /var/lib/spamassassin/3.002005/updates_spamassassin_org/20_advance_fee.cf
Mon Aug 23 18:56:53 2010 [3890] dbg: config: fixed relative path: /var/lib/spamassassin/3.002005/updates_spamassassin_org/20_body_tests.cf
Mon Aug 23 18:56:53 2010 [3890] dbg: config: using "/var/lib/spamassassin/3.002005/updates_spamassassin_org/20_body_tests.cf" for included file
Mon Aug 23 18:56:53 2010 [3890] dbg: config: read file /var/lib/spamassassin/3.002005/updates_spamassassin_org/20_body_tests.cf
Mon Aug 23 18:56:53 2010 [3890] dbg: config: fixed relative path: /var/lib/spamassassin/3.002005/updates_spamassassin_org/20_compensate.cf
Mon Aug 23 18:56:53 2010 [3890] dbg: config: using "/var/lib/spamassassin/3.002005/updates_spamassassin_org/20_compensate.cf" for included file
и так далее
сейчас как видно нет и ошибок тоже не вижу
в письмах вот что:

Код: Выделить всё

X-Spam-Report: (no report template found)
X-Spam-score: 0
при проверке письма командой: spamassassin -t < mail.test

Код: Выделить всё

X-Spam-Checker-Version: SpamAssassin 3.3.1 (2010-03-16) on debian
X-Spam-Flag: YES
X-Spam-Level: **************************************************
X-Spam-Status: Yes, score=1002.1 required=5.0 tests=BAYES_40,GTUBE,NO_RECEIVED,
	NO_RELAYS,RAZOR2_CF_RANGE_51_100,RAZOR2_CF_RANGE_E4_51_100,RAZOR2_CHECK
	autolearn=no version=3.3.1
X-Spam-Report: 
	* -0.0 NO_RELAYS Informational: message was not relayed via SMTP
	* 1000 GTUBE BODY: Generic Test for Unsolicited Bulk Email
	* -0.0 BAYES_40 BODY: Bayes spam probability is 20 to 40%
	*      [score: 0.3266]
	*  0.5 RAZOR2_CF_RANGE_51_100 Razor2 gives confidence level above 50%
	*      [cf: 100]
	*  0.6 RAZOR2_CF_RANGE_E4_51_100 Razor2 gives engine 4 confidence level
	*      above 50%
	*      [cf: 100]
	*  0.9 RAZOR2_CHECK Listed in Razor2 (http://razor.sf.net/)
	* -0.0 NO_RECEIVED Informational: message has no Received headers
Subject: *****SPAM***** Test spam mail (GTUBE)
SpamAssassin отработал как положено.
У меня есть ещё сервак там работает SpamAssassin, подключил Exim к нему, всё работает и фильтрует спам.
Что могло сломаться не пойму, может нужны ещё данные для анализа?

Re: SpamAssassin + Exim не работает после обновления

Добавлено: 2010-09-13 19:19:14
zibadi
в общем методом исключений понял, что проблема в обновлённом perl 5.10.1, но ни как не пойму как заставить с ним работать spamassassin, в логах вроде нет ошибок, может кто откликнется для решения вопроса