On Fri, Jul 22, 2011 at 09:32:29AM -0400, Wietse Venema wrote:
> > So what are those?
>
> Postfix prints all information that is available on the OpenSSL
> error stack. The absence of such logging suggests that the error
> stack is empty (perhaps the client hung up), or that your grep(1)
> command eliminated them.
These are typically just lost connections. A problem client in my
logs shows:
8 plaintext deliveries
6 plaintext DATA timeouts
24 TLS deliveries
109 TLS DATA timeouts
7 TLS SSL accept error: 0
There is nothing on the TLS error stack. Anonymised log samples:
TLS delivery:
2011-07-22T05:51:11-04:00 amnesiac postfix/smtpd[9446]: connect from
unknown[192.0.2.1]
2011-07-22T05:51:12-04:00 amnesiac postfix/smtpd[9446]: Anonymous TLS
connection established from unknown[192.0.2.1]: TLSv1 with cipher
DHE-RSA-AES256-SHA (256/256 bits)
2011-07-22T05:51:12-04:00 amnesiac postfix/smtpd[9446]: 755A11AC8003:
client=unknown[192.0.2.1]
2011-07-22T05:51:12-04:00 amnesiac postfix/cleanup[9603]: 755A11AC8003:
message-id=<id1>
2011-07-22T05:51:12-04:00 amnesiac postfix/qmgr[11097]: 755A11AC8003:
from=<sender1>, size=19041, nrcpt=1 (queue active)
2011-07-22T05:51:12-04:00 amnesiac postfix/smtp[9512]: 755A11AC8003:
to=<rcpt1>, relay=127.0.0.1[127.0.0.1]:27, delay=0.2, delays=0.16/0/0/0.03,
dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 9C384EF8003)
2011-07-22T05:51:12-04:00 amnesiac postfix/qmgr[11097]: 755A11AC8003: removed
2011-07-22T05:51:12-04:00 amnesiac postfix/smtpd[9446]: disconnect from
unknown[192.0.2.1]
TLS DATA timeout:
2011-07-22T05:51:30-04:00 amnesiac postfix/smtpd[9390]: connect from
unknown[192.0.2.1]
2011-07-22T05:51:30-04:00 amnesiac postfix/smtpd[9390]: Anonymous TLS
connection established from unknown[192.0.2.1]: TLSv1 with cipher
DHE-RSA-AES256-SHA (256/256 bits)
2011-07-22T05:51:30-04:00 amnesiac postfix/smtpd[9390]: 921D21AC8009:
client=unknown[192.0.2.1]
2011-07-22T05:52:16-04:00 amnesiac postfix/smtpd[9390]: timeout after DATA
(57269 bytes) from unknown[192.0.2.1]
2011-07-22T05:52:26-04:00 amnesiac postfix/smtpd[9390]: disconnect from
unknown[192.0.2.1]
plaintext delivery
2011-07-22T05:53:22-04:00 amnesiac postfix/smtpd[9443]: C62C71748001:
client=unknown[192.0.2.1]
2011-07-22T05:53:22-04:00 amnesiac postfix/cleanup[9278]: C62C71748001:
message-id=<id2>
2011-07-22T05:53:57-04:00 amnesiac postfix/qmgr[11097]: C62C71748001:
from=<sender1>, size=161047, nrcpt=1 (queue active)
2011-07-22T05:53:57-04:00 amnesiac postfix/smtp[9509]: C62C71748001:
to=<rcpt1>, relay=127.0.0.1[127.0.0.1]:27, delay=35, delays=35/0/0/0.12,
dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 717B5EF8001)
2011-07-22T05:53:57-04:00 amnesiac postfix/qmgr[11097]: C62C71748001: removed
TLS SSL_accept error 0
2011-07-22T05:53:33-04:00 amnesiac postfix/smtpd[9446]: connect from
unknown[192.0.2.1]
2011-07-22T05:53:33-04:00 amnesiac postfix/smtpd[9446]: SSL_accept error from
unknown[192.0.2.1]: 0
2011-07-22T05:53:33-04:00 amnesiac postfix/smtpd[9446]: lost connection after
STARTTLS from unknown[192.0.2.1]
2011-07-22T05:53:33-04:00 amnesiac postfix/smtpd[9446]: disconnect from
unknown[192.0.2.1]
plaintext DATA timeout
2011-07-22T05:54:22-04:00 amnesiac postfix/smtpd[9389]: connect from
unknown[192.0.2.1]
2011-07-22T05:54:22-04:00 amnesiac postfix/smtpd[9389]: D4AF21748001:
client=unknown[192.0.2.1]
2011-07-22T05:55:08-04:00 amnesiac postfix/smtpd[9389]: timeout after DATA
(65624 bytes) from unknown[192.0.2.1]
2011-07-22T05:55:08-04:00 amnesiac postfix/smtpd[9389]: disconnect from
unknown[192.0.2.1]
--
Viktor.
