Hi, Thank you for the bread crumbs. After looking at my logs I do see differences in the "delay" from 0.72 to 2.2 seconds to send an email. Then I googled this little tid-bit: Postfix logs additional delay information as "delays=a/b/c/d" where a=time before queue manager, including message transmission; b=time in queue manager; c=connection setup time including DNS, HELO and TLS; d=message transmission time.
If most of the time is in "d", what can I do to speed that up ? I also went here http://www.postfix.org/TUNING_README.html and the only offense I seem to be making is using the header_checks variable so I can get the email subjects in my logs. Should my first test be to comment out "header_checks = regexp:/etc/postfix/header_checks" from my main.cf ? Cheers. Below is a sample of 4 emails: 9:22:43 mail4 postfix/smtpd[16278]: connect from unknown[137.99.149.148] Aug 28 09:22:43 mail4 postfix/smtpd[16278]: 42829300: client=unknown[137.99.149.148], sasl_method=LOGIN, sasl_username=wellness Aug 28 09:22:43 mail4 postfix/cleanup[15320]: 42829300: warning: header Subject: ALCOHOLEDU INCOMPLETE from unknown[137.99.149.148]; from=<welln...@uconn.edu> to=<payton.mos...@uconn.edu> proto=ESMTP helo=<SAIT0007739> Aug 28 09:22:43 mail4 postfix/cleanup[15320]: 42829300: message-id=<00c201d43ed2$33b792f0$9b26b8d0$@uconn.edu> Aug 28 09:22:43 mail4 postfix/qmgr[3142]: 42829300: from=<welln...@uconn.edu>, size=3714, nrcpt=1 (queue active) Aug 28 09:22:44 mail4 postfix/smtp[15268]: 42829300: to=<payton.mos...@uconn.edu>, relay=uconn-edu.mail.protection.outlook.com[216.32.181.170]:25, delay=1.2, delays=0.02/0/0.3/0.88, dsn=2.6.0, status=sent (250 2.6.0 <00c201d43ed2$33b792f0$9b26b8d0$@uconn.edu> [InternalId=4677219391064, Hostname=SN6PR05MB4558.namprd05.prod.outlook.com] 11262 bytes in 0.219, 50.159 KB/sec Queued mail for delivery) Aug 28 09:22:44 mail4 postfix/qmgr[3142]: 42829300: removed Aug 28 09:22:45 mail4 postfix/smtpd[16278]: disconnect from unknown[137.99.149.148] Aug 28 09:23:06 mail4 postfix/smtpd[16278]: connect from unknown[137.99.149.148] Aug 28 09:23:06 mail4 postfix/smtpd[16278]: 6A9C5300: client=unknown[137.99.149.148], sasl_method=LOGIN, sasl_username=wellness Aug 28 09:23:06 mail4 postfix/cleanup[15320]: 6A9C5300: warning: header Subject: ALCOHOLEDU INCOMPLETE from unknown[137.99.149.148]; from=<welln...@uconn.edu> to=<thomas.em...@uconn.edu> proto=ESMTP helo=<SAIT0007739> Aug 28 09:23:06 mail4 postfix/cleanup[15320]: 6A9C5300: message-id=<01cc01d43ed2$418645c0$c492d140$@uconn.edu> Aug 28 09:23:06 mail4 postfix/qmgr[3142]: 6A9C5300: from=<welln...@uconn.edu>, size=3712, nrcpt=1 (queue active) Aug 28 09:23:06 mail4 postfix/smtpd[15840]: connect from msb-p-zbxprx.uits.uconn.edu[10.4.18.26] Aug 28 09:23:06 mail4 postfix/smtpd[15840]: disconnect from msb-p-zbxprx.uits.uconn.edu[10.4.18.26] Aug 28 09:23:08 mail4 postfix/smtp[15268]: 6A9C5300: to=<thomas.em...@uconn.edu>, relay=uconn-edu.mail.protection.outlook.com[216.32.181.170]:25, delay=2.2, delays=0.02/0/0.25/1.9, dsn=2.6.0, status=sent (250 2.6.0 <01cc01d43ed2$418645c0$c492d140$@uconn.edu> [InternalId=4728758996924, Hostname=BYAPR05MB4792.namprd05.prod.outlook.com] 11298 bytes in 0.468, 23.558 KB/sec Queued mail for delivery) Aug 28 09:23:08 mail4 postfix/qmgr[3142]: 6A9C5300: removed Aug 28 09:23:08 mail4 postfix/smtpd[16278]: disconnect from unknown[137.99.149.148] Aug 28 09:23:12 mail4 postfix/smtpd[16278]: connect from unknown[137.99.149.148] Aug 28 09:23:12 mail4 postfix/smtpd[16278]: 8F244300: client=unknown[137.99.149.148], sasl_method=LOGIN, sasl_username=wellness Aug 28 09:23:12 mail4 postfix/cleanup[15320]: 8F244300: warning: header Subject: ALCOHOLEDU INCOMPLETE from unknown[137.99.149.148]; from=<welln...@uconn.edu> to=<kalianna.din...@uconn.edu> proto=ESMTP helo=<SAIT0007739> Aug 28 09:23:12 mail4 postfix/cleanup[15320]: 8F244300: message-id=<01e101d43ed2$452f5090$cf8df1b0$@uconn.edu> Aug 28 09:23:12 mail4 postfix/qmgr[3142]: 8F244300: from=<welln...@uconn.edu>, size=3722, nrcpt=1 (queue active) Aug 28 09:23:13 mail4 postfix/smtp[15268]: 8F244300: to=<kalianna.din...@uconn.edu>, relay=uconn-edu.mail.protection.outlook.com[216.32.180.170]:25, delay=0.72, delays=0.02/0/0.06/0.64, dsn=2.6.0, status=sent (250 2.6.0 <01e101d43ed2$452f5090$cf8df1b0$@uconn.edu> [InternalId=4423816319248, Hostname=BN6PR05MB2898.namprd05.prod.outlook.com] 11326 bytes in 0.215, 51.358 KB/sec Queued mail for delivery)36737 Aug 28 09:23:13 mail4 postfix/qmgr[3142]: 8F244300: removed Aug 28 09:23:15 mail4 postfix/smtpd[16278]: disconnect from unknown[137.99.149.148] Aug 28 09:23:17 mail4 postfix/smtpd[16278]: connect from unknown[137.99.149.148] Aug 28 09:23:17 mail4 postfix/smtpd[16278]: 98698300: client=unknown[137.99.149.148], sasl_method=LOGIN, sasl_username=wellness Aug 28 09:23:17 mail4 postfix/cleanup[15320]: 98698300: warning: header Subject: ALCOHOLEDU INCOMPLETE from unknown[137.99.149.148]; from=<welln...@uconn.edu> to=<travis.jo...@uconn.edu> proto=ESMTP helo=<SAIT0007739> Aug 28 09:23:17 mail4 postfix/cleanup[15320]: 98698300: message-id=<01e601d43ed2$482f7130$d88e5390$@uconn.edu> Aug 28 09:23:17 mail4 postfix/qmgr[3142]: 98698300: from=<welln...@uconn.edu>, size=3712, nrcpt=1 (queue active)36744 Aug 28 09:23:18 mail4 postfix/smtp[15268]: 98698300: to=<travis.jo...@uconn.edu>, relay=uconn-edu.mail.protection.outlook.com[216.32.180.170]:25, delay=0.61, delays=0.01/0/0.05/0.54, dsn=2.6.0, status=sent (250 2.6.0 <01e601d43ed2$482f7130$d88e5390$@uconn.edu> [InternalId=1511828492319, Hostname=SN2PR05MB2494.namprd05.prod.outlook .com] 11312 bytes in 0.195, 56.403 KB/sec Queued mail for delivery)36745 Aug 28 09:23:18 mail4 postfix/qmgr[3142]: 98698300: removed36746 Aug 28 09:23:20 mail4 postfix/smtpd[16278]: disconnect from unknown[137.99.149.148]36747 Aug 28 09:23:28 mail4 postfix/smtpd[16278]: connect from unknown[137.99.149.148] Aug 28 09:23:28 mail4 postfix/smtpd[16278]: B9F70300: client=unknown[137.99.149.148], sasl_method=LOGIN, sasl_username=wellness -ANGELO FAZZINA ITS Service Manager: Spam and Virus Prevention Mass Mailing G Suite/Gmail ang...@uconn.edu University of Connecticut, ITS, SSG, Server Systems 860-486-9075 -----Original Message----- From: owner-postfix-us...@postfix.org <owner-postfix-us...@postfix.org> On Behalf Of Viktor Dukhovni Sent: Tuesday, August 28, 2018 2:39 PM To: Postfix users <postfix-users@postfix.org> Subject: Re: Want to be sure i am not throttling user. > On Aug 28, 2018, at 1:47 PM, Fazzina, Angelo <angelo.fazz...@uconn.edu> wrote: > > Hi, i am troubleshooting a client complaint. > This user “wellness” > > Aug 28 10:22:27 mail5 postfix/smtpd[7534]: EE46E2FB: > client=unknown[137.99.149.148], sasl_method=LOGIN, sasl_username=wellness > > Some user feedback : > On Friday I sent a batch of 436 and it took > 11 minutes to send > This morning I sent a batch of 725 and it > took 1 hour and 21 minutes The answer is in your logs. -- Viktor.
