On Thu, Jul 12, 2018 at 08:08:17PM +0530, Ram wrote:
> On 07/12/2018 07:36 PM, Viktor Dukhovni wrote:
> > On Thu, Jul 12, 2018 at 07:20:26PM +0530, Ram wrote:
> >
> >> My postfix servers remain pretty busy throughout the day getting around
> >> 100 - 200 mails / second
> >>
> >> I have seen that for every 100 k mails around 20 mails disappear from
> >> the queue.
> >> From maillogs , I can see smtpd accepting the connection , creating a
> >> queue-id and then cleanup picking it up.
> > If you look closely, you'll see smtpd reporting early connection
> > termination after the queue file was created, and message-id written
> > but before the message is fully received (".").
> >
> >> Jul 12 18:20:35 smtpbp1 smtpbp1/cleanup[9121]: open incoming/6262B115F
> >> Jul 12 18:20:35 smtpbp1 smtpbp1/cleanup[9121]: 6262B115F:
> >> message-id=<XXXXXXXXXXx
> >> Jul 12 18:20:35 smtpbp1 smtpbp1/cleanup[9121]: cleanup_flush: status 1
> >> Jul 12 18:20:35 smtpbp1 smtpbp1/cleanup[9121]: send attr status = 1
> >> Jul 12 18:20:35 smtpbp1 smtpbp1/cleanup[9121]: send attr reason =
> >> Jul 12 18:20:35 smtpbp1 smtpbp1/cleanup[9121]: master_notify: status 1
> >> Jul 12 18:20:35 smtpbp1 smtpbp1/cleanup[9121]: connection closed
> > What is the corresponding logging on the smtpd(8) side?
>
> I will look at smtp logs too. The logs are a bit verbose for me to make
> sense , but that should be done
> But the smtp-client closing connection before "." is unlikely , because
> the sender got a full DSN with an OK queued as ...
The sender may have retried at a different MX or a later time.
Postfix does not return "250 OK" to the sender after "." until
cleanup commits (fsync) the complete queue file to stable storage.
There's no need for the verbose logging you're generating, it just
makes it harder to see the logs that matter.
--
Viktor.
