Package: spamassassin Version: 3.2.5-6I have found that in some rare cases spamd does not send the checked mail back through sendmail. If is removed from postfix queue and never sent.
The example of failed delivery:Dec 16 18:27:48 centurion postfix/smtpd[9706]: connect from localhost[127.0.0.1] Dec 16 18:27:50 centurion postfix/smtpd[9706]: NOQUEUE: filter: RCPT from localhost[127.0.0.1]: <localhost[127.0.0.1]>: Client host triggers FILTER spamassassin:dummy; from=<[email protected]> to=<[email protected]> proto=ESMTP helo=<[127.0.0.1]> Dec 16 18:27:50 centurion postfix/smtpd[9706]: E3FDA600B8FA: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=dmi...@centurion Dec 16 18:27:51 centurion postfix/smtpd[9706]: E3FDA600B8FA: filter: RCPT from localhost[127.0.0.1]: <localhost[127.0.0.1]>: Client host triggers FILTER spamassassin:dummy; from=<[email protected]> to=<[email protected]> proto=ESMTP helo=<[127.0.0.1]> Dec 16 18:27:51 centurion postfix/cleanup[9709]: E3FDA600B8FA: message-id=<[email protected]> Dec 16 18:27:51 centurion postfix/qmgr[4731]: E3FDA600B8FA: from=<[email protected]>, size=1969, nrcpt=2 (queue active) Dec 16 18:27:51 centurion spamd[2012]: spamd: connection from localhost [127.0.0.1] at port 38955 Dec 16 18:27:51 centurion spamd[2012]: spamd: handle_user unable to find user: 'H.W.P.vandenToorn' Dec 16 18:27:51 centurion spamd[2012]: spamd: still running as root: user not specified with -u, not found, or set to root, falling back to nobody Dec 16 18:27:51 centurion spamd[2012]: spamd: processing message <[email protected]> for H.W.P.vandenToorn:65534 Dec 16 18:27:52 centurion postfix/smtpd[9706]: disconnect from localhost[127.0.0.1] Dec 16 18:28:10 centurion spamd[2012]: auto-whitelist: open of auto-whitelist file failed: locker: safe_lock: cannot create tmp lockfile /nonexistent/.spamassassin/auto-whitelist.lock.centurion.dynalias.com.2012 for /nonexistent/.spamassassin/auto-whitelist.lock: No such file or directory Dec 16 18:28:10 centurion spamd[2012]: spamd: clean message (-101.4/5.0) for H.W.P.vandenToorn:65534 in 18.2 seconds, 1961 bytes. Dec 16 18:28:10 centurion spamd[2012]: spamd: result: . -101 - ALL_TRUSTED,USER_IN_WHITELIST scantime=18.2,size=1961,user=H.W.P.vandenToorn,uid=65534,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=38955,mid=<[email protected]>,autolearn=failed Dec 16 18:28:10 centurion postfix/pipe[9710]: E3FDA600B8FA: to=<[email protected]>, relay=spamassassin, delay=20, delays=1.3/0.02/0/18, dsn=2.0.0, status=sent (delivered via spamassassin service) Dec 16 18:28:10 centurion postfix/pipe[9710]: E3FDA600B8FA: to=<[email protected]>, relay=spamassassin, delay=20, delays=1.3/0.02/0/18, dsn=2.0.0, status=sent (delivered via spamassassin service)
Dec 16 18:28:10 centurion postfix/qmgr[4731]: E3FDA600B8FA: removed Dec 16 18:28:10 centurion spamd[1597]: prefork: child states: IIpostfix/pipe reports that the message was delivered to spamassassin, but it is never being piped to sendmail. strace of postfix is attached.
The example of successful delivery via spamassassin filter (with save configuration settings):
Dec 8 13:15:24 centurion postfix/smtpd[12361]: connect from localhost[127.0.0.1] Dec 8 13:15:27 centurion postfix/smtpd[12361]: NOQUEUE: filter: RCPT from localhost[127.0.0.1]: <localhost[127.0.0.1]>: Client host triggers FILTER spamassassin:dummy; from=<[email protected]> to=<[email protected]> proto=ESMTP helo=<[127.0.0.1]> Dec 8 13:15:27 centurion postfix/smtpd[12361]: AE891600CF01: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=dmi...@centurion Dec 8 13:15:29 centurion postfix/cleanup[12365]: AE891600CF01: message-id=<[email protected]> Dec 8 13:15:29 centurion postfix/qmgr[25264]: AE891600CF01: from=<[email protected]>, size=1750, nrcpt=1 (queue active) Dec 8 13:15:29 centurion spamd[321]: spamd: connection from localhost [127.0.0.1] at port 52403 Dec 8 13:15:29 centurion spamd[321]: spamd: handle_user unable to find user: 'pamitu' Dec 8 13:15:29 centurion spamd[321]: spamd: still running as root: user not specified with -u, not found, or set to root, falling back to nobody Dec 8 13:15:29 centurion spamd[321]: spamd: processing message <[email protected]> for pamitu:65534 Dec 8 13:15:29 centurion postfix/smtpd[12361]: disconnect from localhost[127.0.0.1] Dec 8 13:15:53 centurion spamd[321]: auto-whitelist: open of auto-whitelist file failed: locker: safe_lock: cannot create tmp lockfile /nonexistent/.spamassassin/auto-whitelist.lock.centurion.dynalias.com.321 for /nonexistent/.spamassassin/auto-whitelist.lock: No such file or directory Dec 8 13:15:53 centurion spamd[321]: spamd: clean message (-1.4/5.0) for pamitu:65534 in 24.1 seconds, 1742 bytes. Dec 8 13:15:53 centurion spamd[321]: spamd: result: . -1 - ALL_TRUSTED scantime=24.1,size=1742,user=pamitu,uid=65534,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=52403,mid=<[email protected]>,autolearn=failed
Dec 8 13:15:53 centurion spamd[2684]: prefork: child states: IIDec 8 13:15:53 centurion postfix/pickup[11237]: 946D6600EFFD: uid=65534 from=<[email protected]> Dec 8 13:15:53 centurion postfix/cleanup[12365]: 946D6600EFFD: message-id=<[email protected]> Dec 8 13:15:53 centurion postfix/pipe[12366]: AE891600CF01: to=<[email protected]>, relay=spamassassin, delay=26, delays=2.1/0.06/0/24,dsn=2.0.0, status=sent (delivered via spamassassin service)
Dec 8 13:15:53 centurion postfix/qmgr[25264]: AE891600CF01: removedDec 8 13:15:53 centurion postfix/qmgr[25264]: 946D6600EFFD: from=<[email protected]>, size=2056, nrcpt=1 (queue active) Dec 8 13:15:55 centurion postfix/smtp[12380]: 946D6600EFFD: to=<[email protected]>, relay=194.134.41.21[194.134.41.21]:25, delay=2.1, delays=0.1/0.02/1.1/0.89, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as AD1B66618F)
Dec 8 13:15:55 centurion postfix/qmgr[25264]: 946D6600EFFD: removed Additional information: # dpkg -l | egrep '(spam|postfix)' ii postfix 2.6.5-3 ii postfix-pcre 2.6.5-3 ii spamassassin 3.2.5-6 ii spamc 3.2.5-6 -- With best regards, Dmitry
postfix_config.tar.bz2
Description: Binary data
postfix.strace.gz
Description: application/gzip
