Hello, I've implemented a milter (SNFMilter), and when I use it with postfix, and send postfix multiple messages over one SMTP connection, I get the following warning in the log file:
Jun 18 10:32:58 skidmark postfix/smtpd[11622]: warning: milter unix:/snf- milter/socket: unexpected filter response SMFIR_ADDHEADER after event SMFIC_MAIL I've attached the log messages before and after the above message. This warning does not occur when I send postfix one message per SMTP connection. The warning also does not occur when I use sendmail, with either one message per SMTP connection, or multiple messages per SMTP connection. I ran postfix with '-v', but the additional messages didn't help me understand what's going on. I think this indicates a problem, and would appreciate any help on what's going on, and how to fix it. I'm using Ubuntu 9.04, and my postfix version is 2.5.5. Thanks, Alban
Jun 18 10:32:57 skidmark postfix/smtpd[11622]: C880D451FB: client=neo.localdomain[192.168.1.4] Jun 18 10:32:57 skidmark postfix/cleanup[11629]: C880D451FB: message-id=<[email protected]> Jun 18 10:32:57 skidmark postfix/local[11630]: B56B64517F: to=<ade...@skidmark>, relay=local, delay=0.13, delays=0.07/0/0/0.06, dsn=2.0.0, status=sent (delivered to mailbox) Jun 18 10:32:57 skidmark postfix/qmgr[11610]: B56B64517F: removed Jun 18 10:32:57 skidmark postfix/qmgr[11610]: C880D451FB: from=<ade...@neo>, size=641, nrcpt=1 (queue active) Jun 18 10:32:57 skidmark postfix/smtpd[11622]: DB8E84517F: client=neo.localdomain[192.168.1.4] Jun 18 10:32:57 skidmark postfix/cleanup[11629]: DB8E84517F: message-id=<[email protected]> Jun 18 10:32:57 skidmark postfix/cleanup[11629]: DB8E84517F: milter-hold: END-OF-MESSAGE from neo.localdomain[192.168.1.4]: milter triggers HOLD action; from=<ade...@neo> to=<ade...@skidmark> proto=ESMTP helo=<neo.localdomain> Jun 18 10:32:57 skidmark postfix/local[11630]: C880D451FB: to=<ade...@skidmark>, relay=local, delay=0.13, delays=0.07/0/0/0.06, dsn=2.0.0, status=sent (delivered to mailbox) Jun 18 10:32:57 skidmark postfix/qmgr[11610]: C880D451FB: removed Jun 18 10:32:57 skidmark postfix/smtpd[11622]: EE0F9136E8: client=neo.localdomain[192.168.1.4] Jun 18 10:32:57 skidmark postfix/cleanup[11629]: EE0F9136E8: message-id=<000d01c9dd8d$f7a56a30$6400a...@itchedl> Jun 18 10:32:57 skidmark postfix/cleanup[11629]: EE0F9136E8: milter-hold: END-OF-MESSAGE from neo.localdomain[192.168.1.4]: milter triggers HOLD action; from=<ade...@neo> to=<ade...@skidmark> proto=ESMTP helo=<neo.localdomain> Jun 18 10:32:58 skidmark postfix/smtpd[11622]: 28B45451FB: client=neo.localdomain[192.168.1.4] Jun 18 10:32:58 skidmark postfix/cleanup[11629]: 28B45451FB: message-id=<[email protected]> Jun 18 10:32:58 skidmark postfix/qmgr[11610]: 28B45451FB: from=<ade...@neo>, size=607, nrcpt=1 (queue active) Jun 18 10:32:58 skidmark postfix/smtpd[11622]: warning: milter unix:/snf-milter/socket: unexpected filter response SMFIR_ADDHEADER after event SMFIC_MAIL Jun 18 10:32:58 skidmark postfix/smtpd[11622]: NOQUEUE: milter-reject: MAIL from neo.localdomain[192.168.1.4]: 451 4.7.1 Service unavailable - try again later; from=<ade...@neo> proto=ESMTP helo=<neo.localdomain> Jun 18 10:32:58 skidmark postfix/local[11630]: 28B45451FB: to=<ade...@skidmark>, relay=local, delay=0.09, delays=0.04/0/0/0.04, dsn=2.0.0, status=sent (delivered to mailbox) Jun 18 10:32:58 skidmark postfix/qmgr[11610]: 28B45451FB: removed
