Thank you Viktor, The only logs I can access is the receipt-
Mar 19 19:00:13 mail-d1f9ab60 postfix/smtp[30442]: 3qQpd33djJzDywj: to=<REDACTED>, relay=cluster1.us.[REDACTED].com[XXX.XX.XX.XXX]:25, conn_use=66, delay=193766, delays=193728/38/0/0.1, dsn=2.0.0, status=sent (250 ok 1458414013 qp 36488 server-16.tower-192.REDACTED.com !1458414005!48124713!66) Funny thing is the delays started at ~2000+, then after a handful went up to 36000+, then after the first 300 deliveries the delay jumps up to 180000+, which looks like basic throttling to me. I have a hard time believing it is my mail sever not sending the mail (though entirely possible by the ISP but I've ruled that out), versus their mailserver putting it in delayed (you can probably guess the service redacted above). Many thanks for your assistance On Thu, Mar 24, 2016 at 8:14 PM, Viktor Dukhovni <postfix-us...@dukhovni.org > wrote: > > > On Mar 24, 2016, at 10:40 PM, Aaron Routt <aaronro...@gmail.com> wrote: > > > > delays=193728/38/0/0.1 > > Insufficient context. Post all the log entries for the queue-id > of the message that logged this combination of delays. > > > http://www.postfix.org/postconf.5.html > > The format of the "delays=a/b/c/d" logging is as follows: > > > > • a = time from message arrival to last active queue entry > > • b = time from last active queue entry to connection setup > > • c = time in connection setup, including DNS, EHLO and STARTTLS > > • d = time in message transmission > > > Does "message arrival" also mean "pickup" in the flowchart? where it > enters Postfix? My thinking is spam filters are the delay cause here, in > the "cleanup" process? > > Yes, time spent in the "maildrop" directory is included: > > On my laptop I used sendmail(1), while Postfix was stopped, and > then started Postfix: > > Mar 24 22:55:08 vpro postfix/postfix-script[88679]: > starting the Postfix mail system > Mar 24 22:55:08 vpro postfix/pickup[88682]: 06A07FDE712: > uid=0 from=<root> > Mar 24 22:55:08 vpro postfix/cleanup[88684]: 06A07FDE712: > message-id=<20160325025508.06a07fde...@vpro.lan> > Mar 24 22:55:08 vpro postfix/qmgr[88683]: 06A07FDE712: > from=<r...@vpro.lan>, size=251, nrcpt=1 (queue active) > Mar 24 22:55:08 vpro postfix/discard[88686]: 06A07FDE712: > to=<vik...@example.org>, relay=none, delay=12, > delays=12/0.01/0/0, dsn=2.0.0, status=sent (silently) > Mar 24 22:55:08 vpro postfix/qmgr[88683]: 06A07FDE712: removed > > The 12s delay was due the time it took to type "postfix start" after > sending the message. > > > But a colleague says it is our server sending slowly...but that > > doesn't make sense..., how is the receiving server going to know > > of delays by the sending server, if not logged in c/d? > > Hard to know without the logs. Did I mention the logs? They'll > show any previous times spent in the active queue, reasons deferred, > ... Also find any other queue-ids for the message id and logs for > those, if any. > > -- > Viktor.
