Итак, добавил в /etc/default/spamassassin в OPTIONS -s /var/log/spamd.log -D
Перезапустил SA:
Код: Выделить всё
root@smicro:/etc/spamassassin# sudo /etc/init.d/spamassassin restart
Restarting SpamAssassin Mail Filter Daemon: июля 18 18:48:59.357 [3271] dbg: logger: adding facilities: all
июля 18 18:48:59.357 [3271] dbg: logger: logging level is DBG
июля 18 18:48:59.359 [3271] dbg: logger: successfully opened file /var/log/spamd.log
июля 18 18:48:59.359 [3271] dbg: logger: successfully added file method
июля 18 18:48:59.359 [3271] dbg: spamd: will perform setuids? 1
июля 18 18:48:59.359 [3271] dbg: spamd: creating INET socket:
июля 18 18:48:59.359 [3271] dbg: spamd: [...] Listen: 128
июля 18 18:48:59.359 [3271] dbg: spamd: [...] LocalAddr: 127.0.0.1
июля 18 18:48:59.359 [3271] dbg: spamd: [...] LocalPort: 783
июля 18 18:48:59.359 [3271] dbg: spamd: [...] Proto: 6
июля 18 18:48:59.359 [3271] dbg: spamd: [...] ReuseAddr: 1
июля 18 18:48:59.359 [3271] dbg: spamd: [...] Type: 1
июля 18 18:48:59.360 [3271] dbg: logger: adding facilities: all
июля 18 18:48:59.360 [3271] dbg: logger: logging level is DBG
июля 18 18:48:59.360 [3271] dbg: generic: SpamAssassin version 3.3.1
июля 18 18:48:59.360 [3271] dbg: generic: Perl 5.010001, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin
июля 18 18:48:59.360 [3271] dbg: config: timing enabled
июля 18 18:48:59.361 [3271] dbg: config: score set 0 chosen.
июля 18 18:48:59.485 [3271] dbg: dns: is Net::DNS::Resolver available? yes
июля 18 18:48:59.485 [3271] dbg: dns: Net::DNS version: 0.66
Перепроверил правило /etc/spamassassin/90_wwtmasterhost.cf:
header WWT_TEST01 Subject =~ /qweeertSS/
score WWT_TEST01 5.9
Посмотрел в spamd.log, увидел что:
Wed Jul 18 18:48:59 2012 [3274] dbg: config: read file /etc/spamassassin/90_wwtmasterhost.cf
Ругани про этот файл нет, сделал spamassassin --lint, всё ок
Послал письмо с темой "qweeertSS"
В mail.log:
Код: Выделить всё
Jul 18 19:00:56 smicro postfix/smtpd[2470]: connect from smicro[127.0.0.1]
Jul 18 19:00:56 smicro postfix/smtpd[2470]: 27E1DF41E23: client=smicro[127.0.0.1]
Jul 18 19:00:56 smicro postfix/cleanup[3432]: 27E1DF41E23: message-id=<5006CF8C.1080408@aaa.ru>
Jul 18 19:00:56 smicro postfix/qmgr[14374]: 27E1DF41E23: from=<wwt@aaa.ru>, size=1699, nrcpt=2 (queue active)
Jul 18 19:00:56 smicro postfix/smtpd[2470]: disconnect from smicro[127.0.0.1]
Jul 18 19:00:56 smicro amavis[2825]: (02825-08) FWD via SMTP: <wwt@aaa.ru> -> <wwt@xxx.ru>,BODY=8BITMIME 250 2.0.0 Ok, id=02825-08, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 27E1DF41E23
Jul 18 19:00:56 smicro amavis[2825]: (02825-08) Passed CLEAN, [195.90.163.198] [109.252.202.142] <wwt@aaa.ru> -> <wwt@xxx.ru>, Message-ID: <5006CF8C.1080408@aaa.ru>, mail_id: HRKPW+bRswoS, Hits: 1.58, size: 1278, queued_as: 27E1DF41E23, 15556 ms
Jul 18 19:00:56 smicro amavis[2825]: (02825-08) TIMING-SA total 15293 ms - parse: 1.17 (0.0%), extract_message_metadata: 2 (0.0%), get_uri_detail_list: 0.34 (0.0%), tests_pri_-1000: 173 (1.1%), tests_pri_-950: 0.87 (0.0%), tests_pri_-900: 0.92 (0.0%), tests_pri_-400: 152 (1.0%), check_bayes: 151 (1.0%), tests_pri_0: 11087 (72.5%), check_dkim_adsp: 438 (2.9%), check_spf: 5332 (34.9%), poll_dns_idle: 8880 (58.1%),check_dcc: 111 (0.7%), check_razor2: 5001 (32.7%), check_pyzor: 0.13 (0.0%), tests_pri_500: 3555 (23.2%), tests_pri_1000: 26 (0.2%), total_awl: 25 (0.2%), check_awl: 0.12 (0.0%), update_awl: 0.06 (0.0%), learn: 279 (1.8%), get_report: 0.87 (0.0%)
Jul 18 19:00:56 smicro postfix/smtp[3618]: 984DCF41E43: to=<wwt@xxx.ru>, relay=127.0.0.1[127.0.0.1]:10024, delay=16, delays=0.12/0/0/16, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=02825-08, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 27E1DF41E23)
Jul 18 19:00:56 smicro postfix/qmgr[14374]: 984DCF41E43: removed
Jul 18 19:00:56 smicro amavis[2825]: (02825-08) TIMING [total 15559 ms] - SMTP greeting: 1 (0%)0, SMTP EHLO: 0 (0%)0, SMTP pre-MAIL: 0 (0%)0, SMTP pre-DATA-flush: 1 (0%)0, SMTP DATA: 35 (0%)0, check_init: 0 (0%)0, digest_hdr: 1 (0%)0, digest_body_dkim: 0 (0%)0, gen_mail_id: 1 (0%)0, mime_decode: 4 (0%)0, get-file-type1: 142 (1%)1, parts_decode: 0 (0%)1, check_header: 1 (0%)1, AV-scan-1: 1 (0%)1, spam-wb-list: 1 (0%)1, SA parse: 2 (0%)1, SA check: 15289 (98%)99, update_cache: 4 (0%)100, decide_mail_destiny: 0 (0%)100, fwd-connect: 2 (0%)100, fwd-mail-pip: 1 (0%)100, fwd-rcpt-pip: 0 (0%)100, fwd-data-chkpnt: 0 (0%)100, write-header: 1 (0%)100, fwd-data-contents: 0 (0%)100, fwd-end-chkpnt: 64 (0%)100, prepare-dsn: 0 (0%)100, main_log_entry: 4 (0%)100, update_snmp: 1 (0%)100, SMTP pre-response: 0 (0%)100, SMTP response: 0 (0%)100, unlink-1-files: 0 (0%)100, rundown: 0 (0%)100
Jul 18 19:00:56 smicro postfix/pipe[3776]: 27E1DF41E23: to=<kr@xxx.ru>, orig_to=<wwt@xxx.ru>, relay=dovecot, delay=0.36, delays=0.06/0.1/0/0.2, dsn=2.0.0, status=sent (delivered via dovecot service)
Jul 18 19:00:56 smicro postfix/pipe[3777]: 27E1DF41E23: to=<ms@xxx.ru>, orig_to=<wwt@xxx.ru>, relay=dovecot, delay=0.45, delays=0.06/0.2/0/0.18, dsn=2.0.0, status=sent (delivered via dovecot service)
Jul 18 19:00:56 smicro postfix/qmgr[14374]: 27E1DF41E23: removed
В spamd.log нет ничего в это время:
Код: Выделить всё
Wed Jul 18 18:59:07 2012 [3296] dbg: prefork: periodic ping from spamd parent
Wed Jul 18 18:59:07 2012 [3295] dbg: prefork: periodic ping from spamd parent
Wed Jul 18 18:59:07 2012 [3296] dbg: prefork: sysread(9) not ready, wait max 300 secs
Wed Jul 18 18:59:07 2012 [3295] dbg: prefork: sysread(7) not ready, wait max 300 secs
Wed Jul 18 19:01:39 2012 [3295] dbg: prefork: periodic ping from spamd parent
Wed Jul 18 19:01:39 2012 [3295] dbg: prefork: sysread(7) not ready, wait max 300 secs
Wed Jul 18 19:01:39 2012 [3296] dbg: prefork: periodic ping from spamd parent
Wed Jul 18 19:01:39 2012 [3296] dbg: prefork: sysread(9) not ready, wait max 300 secs
Wed Jul 18 19:04:10 2012 [3295] dbg: prefork: periodic ping from spamd parent
Wed Jul 18 19:04:10 2012 [3295] dbg: prefork: sysread(7) not ready, wait max 300 secs
Wed Jul 18 19:04:10 2012 [3296] dbg: prefork: periodic ping from spamd parent
Wed Jul 18 19:04:10 2012 [3296] dbg: prefork: sysread(9) not ready, wait max 300 secs
Чтож ему надо то?