conversation with /var/lib/imap/socket/lmtp timed out while sending end of data

[Leon Kolchinsky]

Hello all,

Once in a while one of my users (and this is always the same one) stops
getting e-mails, and this is what I see in the logs:

# grep 3ED8314F024 /var/log/mail
Sep 20 05:58:25 mail postfix/smtpd[31183]: 3ED8314F024:
client=localhost[127.0.0.1]
Sep 20 05:58:25 mail postfix/cleanup[31179]: 3ED8314F024:
message-id=<073C25444897A747B1596B30172CE4A6AAA725 at SUEXCL-02.ad.syr.edu>
Sep 20 05:58:25 mail postfix/qmgr[4632]: 3ED8314F024:
from=<hperetz at syr.edu>, size=59674, nrcpt=1 (queue active)
Sep 20 05:58:25 mail amavis[31099]: (31099-04) FWD via SMTP:
<hperetz at syr.edu> -> <username at myserver.mydomain.com>,BODY=7BIT 250 2.6.0
Ok, id=31099-04, from MTA([127.0.0.1]:10025): 250 Ok: queued as 3ED8314F024
Sep 20 05:58:25 mail amavis[31099]: (31099-04) Passed CLEAN,
[128.230.108.46] <hperetz at syr.edu> -> <username at myserver.mydomain.com>,
Message-ID: <073C25444897A747B1596B30172CE4A6AAA725 at SUEXCL-02.ad.syr.edu>,
mail_id: 9ObScyAvZeGF, Hits: -5.021, size: 58979, queued_as: 3ED8314F024,
7040 ms
Sep 20 05:58:25 mail postfix/smtp[31180]: 3BF9A190FB:
to=<username at myserver.mydomain.com>, relay=127.0.0.1[127.0.0.1], delay=8,
status=sent (250 Ok: queued as 3ED8314F024)
Sep 20 06:46:10 mail postfix/lmtp[31653]: 3ED8314F024:
to=<username at mail.mydomain.com>, orig_to=<username at myserver.mydomain.com>,
relay=/var/lib/imap/socket/lmtp[/var/lib/imap/socket/lmtp], delay=2865,
status=deferred (conversation with
/var/lib/imap/socket/lmtp[/var/lib/imap/socket/lmtp] timed out while sending
end of data -- message may be sent more than once)
Sep 20 06:50:27 mail postfix/qmgr[4632]: 3ED8314F024:
from=<hperetz at syr.edu>, size=59674, nrcpt=1 (queue active)
Sep 20 07:50:32 mail postfix/lmtp[32493]: 3ED8314F024:
to=<username at mail.mydomain.com>, orig_to=<username at myserver.mydomain.com>,
relay=/var/lib/imap/socket/lmtp[/var/lib/imap/socket/lmtp], delay=6727,
status=deferred (conversation with
/var/lib/imap/socket/lmtp[/var/lib/imap/socket/lmtp] timed out while sending
end of data -- message may be sent more than once)
Sep 20 07:57:07 mail postfix/qmgr[4632]: 3ED8314F024:
from=<hperetz at syr.edu>, size=59674, nrcpt=1 (queue active)
Sep 20 08:36:38 mail postfix/lmtp[800]: 3ED8314F024:
to=<username at mail.mydomain.com>, orig_to=<username at myserver.mydomain.com>,
relay=none, delay=9493, status=deferred (connect to
/var/lib/imap/socket/lmtp[/var/lib/imap/socket/lmtp]: Connection refused)

My system is SLES10:
postfix-2.1.1-2
cyrus-imapd-devel-2.2.3-83.5
cyrus-sasl-2.1.18-33.8
cyrus-sasl-gssapi-2.1.18-33.1
cyrus-sasl-plain-2.1.18-33.1
cyrus-imapd-2.2.3-83.35

The only thing that helps is to restart postfix. Then the queue is empties
and all got back to normal.

Anyone have any idea how to avoid this situation in the future and what
could cause that?


Best Regards,
Leon Kolchinsky