Hi,
I’ve done some testing with swaks trying to track my performance issue. I
don’t think this is a postfix issue. It is just the most apparent symptom.
I’ve also noticed SSH to my server is quite laggy but if I use the command line
with Screen Sharing it is responsive. Same with SFTP, sometime fast and
sometime really laggy.
I’ve turned on verbose logging for smtpd and tis but this hasn’t revealed
anything yet.
I’ve been hitting the mail server using swaks remotely vs. directly on the
server, I’ve attached some logs if you are interested. Initially the remote
attempts would take 5-30 seconds from start to finish. Directly on the server
would take tenths of a second. I’ve noticed that the longest waits appear to
be at "220 2.0.0 Ready to start TLS” and "EHLO [10.0.1.7]” but I couldn’t find
any red flags in my logs. Then I found this archived posting
http://www.irbs.net/internet/postfix/0501/1437.html, noticed that I was
blocking ICMP traffic, and enabled it. After enabling ICMP traffic I was
getting external response of 1-3 seconds for a short while but then they shot
back up to 20+ seconds again.
Thanks,
Chris McKinnon
{\rtf1\ansi\ansicpg1252\cocoartf1265\cocoasubrtf210
{\fonttbl\f0\fnil\fcharset0 Monaco;}
{\colortbl;\red255\green255\blue255;}
\margl1440\margr1440\vieww10800\viewh8400\viewkind0
\pard\tx560\tx1120\tx1680\tx2240\tx2800\tx3360\tx3920\tx4480\tx5040\tx5600\tx6160\tx6720\pardirnatural
\f0\fs20 \cf0 \CocoaLigature0 /usr/bin/time ./swaks -f ad...@ravenviewhomes.com -t ad...@ravenviewhomes.com -tls --ehlo [10.0.1.7] --server mail.ravenviewhomes.com -a PLAIN -au ad...@ravenviewhomes.com -ap 'Rav3nV!ew' -stl\
=== Trying mail.ravenviewhomes.com:25...\
=== Connected to mail.ravenviewhomes.com.\
=== response in 1.132s\
<- 220 mail.ravenviewhomes.com ESMTP\
-> EHLO [10.0.1.7]\
=== response in 0.043s\
<- 250-mail.ravenviewhomes.com\
<- 250-PIPELINING\
<- 250-SIZE 10240000\
<- 250-ETRN\
<- 250-STARTTLS\
<- 250-ENHANCEDSTATUSCODES\
<- 250-8BITMIME\
<- 250 DSN\
-> STARTTLS\
=== response in 0.182s\
<- 220 2.0.0 Ready to start TLS\
=== TLS started with cipher TLSv1:DHE-RSA-AES256-SHA:256\
=== TLS no local certificate set\
=== TLS peer DN="/C=CA/ST=Alberta/L=Calgary/O=Raven View Homes/CN=mail.ravenviewhomes.com/emailAddress=ad...@ravenviewhomes.com"\
~> EHLO [10.0.1.7]\
=== response in 0.034s\
<~ 250-mail.ravenviewhomes.com\
<~ 250-PIPELINING\
<~ 250-SIZE 10240000\
<~ 250-ETRN\
<~ 250-AUTH PLAIN LOGIN\
<~ 250-AUTH=PLAIN LOGIN\
<~ 250-ENHANCEDSTATUSCODES\
<~ 250-8BITMIME\
<~ 250 DSN\
~> AUTH PLAIN AGFkbWluQHJhdmVudmlld2hvbWVzLmNvbQBSYXYzblYhZXc=\
=== response in 0.052s\
<~ 235 2.7.0 Authentication successful\
~> MAIL FROM:<ad...@ravenviewhomes.com>\
=== response in 0.056s\
<~ 250 2.1.0 Ok\
~> RCPT TO:<ad...@ravenviewhomes.com>\
=== response in 0.387s\
<~ 250 2.1.5 Ok\
~> DATA\
=== response in 0.334s\
<~ 354 End data with <CR><LF>.<CR><LF>\
~> Date: Fri, 25 Jul 2014 17:20:13 -0600\
~> To: ad...@ravenviewhomes.com\
~> From: ad...@ravenviewhomes.com\
~> Subject: test Fri, 25 Jul 2014 17:20:13 -0600\
~> X-Mailer: swaks v20130209.0 jetmore.org/john/code/swaks/\
~> \
~> This is a test mailing\
~> \
~> .\
=== response in 0.033s\
<~ 250 2.0.0 Ok: queued as D03F25C5773\
~> QUIT\
=== response in 0.107s\
<~ 221 2.0.0 Bye\
=== Connection closed with remote host.\
3.14 real 0.07 user 0.00 sys}{\rtf1\ansi\ansicpg1252\cocoartf1265\cocoasubrtf210
{\fonttbl\f0\fnil\fcharset0 Monaco;}
{\colortbl;\red255\green255\blue255;}
\margl1440\margr1440\vieww10800\viewh8400\viewkind0
\pard\tx560\tx1120\tx1680\tx2240\tx2800\tx3360\tx3920\tx4480\tx5040\tx5600\tx6160\tx6720\pardirnatural
\f0\fs20 \cf0 \CocoaLigature0 /usr/bin/time ./swaks -f ad...@ravenviewhomes.com -t ad...@ravenviewhomes.com --server 127.0.0.1 -tls -stl\
=== Trying 127.0.0.1:25...\
=== Connected to 127.0.0.1.\
=== response in 0.000s\
<- 220 mail.ravenviewhomes.com ESMTP\
-> EHLO ravenviewhomes.com\
=== response in 0.002s\
<- 250-mail.ravenviewhomes.com\
<- 250-PIPELINING\
<- 250-SIZE 10240000\
<- 250-ETRN\
<- 250-STARTTLS\
<- 250-ENHANCEDSTATUSCODES\
<- 250-8BITMIME\
<- 250 DSN\
-> STARTTLS\
=== response in 0.000s\
<- 220 2.0.0 Ready to start TLS\
=== TLS started with cipher TLSv1:DHE-RSA-AES256-SHA:256\
=== TLS no local certificate set\
=== TLS peer DN="/C=CA/ST=Alberta/L=Calgary/O=Raven View Homes/CN=mail.ravenviewhomes.com/emailAddress=ad...@ravenviewhomes.com"\
~> EHLO ravenviewhomes.com\
=== response in 0.003s\
<~ 250-mail.ravenviewhomes.com\
<~ 250-PIPELINING\
<~ 250-SIZE 10240000\
<~ 250-ETRN\
<~ 250-AUTH PLAIN LOGIN\
<~ 250-AUTH=PLAIN LOGIN\
<~ 250-ENHANCEDSTATUSCODES\
<~ 250-8BITMIME\
<~ 250 DSN\
~> MAIL FROM:<ad...@ravenviewhomes.com>\
=== response in 0.001s\
<~ 250 2.1.0 Ok\
~> RCPT TO:<ad...@ravenviewhomes.com>\
=== response in 0.007s\
<~ 250 2.1.5 Ok\
~> DATA\
=== response in 0.001s\
<~ 354 End data with <CR><LF>.<CR><LF>\
~> Date: Fri, 25 Jul 2014 17:18:07 -0600\
~> To: ad...@ravenviewhomes.com\
~> From: ad...@ravenviewhomes.com\
~> Subject: test Fri, 25 Jul 2014 17:18:07 -0600\
~> X-Mailer: swaks v20130209.0 jetmore.org/john/code/swaks/\
~> \
~> This is a test mailing\
~> \
~> .\
=== response in 0.003s\
<~ 250 2.0.0 Ok: queued as B20B85C5738\
~> QUIT\
=== response in 0.001s\
<~ 221 2.0.0 Bye\
=== Connection closed with remote host.\
0.14 real 0.09 user 0.01 sys}{\rtf1\ansi\ansicpg1252\cocoartf1265\cocoasubrtf210
{\fonttbl\f0\fnil\fcharset0 Monaco;}
{\colortbl;\red255\green255\blue255;}
\margl1440\margr1440\vieww13120\viewh19280\viewkind0
\pard\tx560\tx1120\tx1680\tx2240\tx2800\tx3360\tx3920\tx4480\tx5040\tx5600\tx6160\tx6720\pardirnatural
\f0\fs20 \cf0 \CocoaLigature0 /usr/bin/time ./swaks -f ad...@ravenviewhomes.com -t ad...@ravenviewhomes.com -tls --ehlo [10.0.1.7] --server mail.ravenviewhomes.com -a PLAIN -au ad...@ravenviewhomes.com -ap 'Rav3nV!ew' -stl\
=== Trying mail.ravenviewhomes.com:25...\
=== Connected to mail.ravenviewhomes.com.\
=== response in 0.076s\
<- 220 mail.ravenviewhomes.com ESMTP\
-> EHLO [10.0.1.7]\
=== response in 0.030s\
<- 250-mail.ravenviewhomes.com\
<- 250-PIPELINING\
<- 250-SIZE 10240000\
<- 250-ETRN\
<- 250-STARTTLS\
<- 250-ENHANCEDSTATUSCODES\
<- 250-8BITMIME\
<- 250 DSN\
-> STARTTLS\
=== response in 1.430s\
<- 220 2.0.0 Ready to start TLS\
=== TLS started with cipher TLSv1:DHE-RSA-AES256-SHA:256\
=== TLS no local certificate set\
=== TLS peer DN="/C=CA/ST=Alberta/L=Calgary/O=Raven View Homes/CN=mail.ravenviewhomes.com/emailAddress=ad...@ravenviewhomes.com"\
~> EHLO [10.0.1.7]\
=== response in 0.028s\
<~ 250-mail.ravenviewhomes.com\
<~ 250-PIPELINING\
<~ 250-SIZE 10240000\
<~ 250-ETRN\
<~ 250-AUTH PLAIN LOGIN\
<~ 250-AUTH=PLAIN LOGIN\
<~ 250-ENHANCEDSTATUSCODES\
<~ 250-8BITMIME\
<~ 250 DSN\
~> AUTH PLAIN AGFkbWluQHJhdmVudmlld2hvbWVzLmNvbQBSYXYzblYhZXc=\
=== response in 0.051s\
<~ 235 2.7.0 Authentication successful\
~> MAIL FROM:<ad...@ravenviewhomes.com>\
=== response in 0.325s\
<~ 250 2.1.0 Ok\
~> RCPT TO:<ad...@ravenviewhomes.com>\
=== response in 1.899s\
<~ 250 2.1.5 Ok\
~> DATA\
=== response in 0.028s\
<~ 354 End data with <CR><LF>.<CR><LF>\
~> Date: Fri, 25 Jul 2014 17:27:15 -0600\
~> To: ad...@ravenviewhomes.com\
~> From: ad...@ravenviewhomes.com\
~> Subject: test Fri, 25 Jul 2014 17:27:15 -0600\
~> X-Mailer: swaks v20130209.0 jetmore.org/john/code/swaks/\
~> \
~> This is a test mailing\
~> \
~> .\
=== response in 0.059s\
<~ 250 2.0.0 Ok: queued as 6808F5C5838\
~> QUIT\
=== response in 0.025s\
<~ 221 2.0.0 Bye\
=== Connection closed with remote host.\
25.23 real 0.07 user 0.00 sys\
\
\
/usr/bin/time ./swaks -f ad...@ravenviewhomes.com -t ad...@ravenviewhomes.com -tls --ehlo [10.0.1.7] --server mail.ravenviewhomes.com -a PLAIN -au ad...@ravenviewhomes.com -ap 'Rav3nV!ew' -stl\
=== Trying mail.ravenviewhomes.com:25...\
=== Connected to mail.ravenviewhomes.com.\
=== response in 0.047s\
<- 220 mail.ravenviewhomes.com ESMTP\
-> EHLO [10.0.1.7]\
=== response in 0.022s\
<- 250-mail.ravenviewhomes.com\
<- 250-PIPELINING\
<- 250-SIZE 10240000\
<- 250-ETRN\
<- 250-STARTTLS\
<- 250-ENHANCEDSTATUSCODES\
<- 250-8BITMIME\
<- 250 DSN\
-> STARTTLS\
=== response in 0.055s\
<- 220 2.0.0 Ready to start TLS\
=== TLS started with cipher TLSv1:DHE-RSA-AES256-SHA:256\
=== TLS no local certificate set\
=== TLS peer DN="/C=CA/ST=Alberta/L=Calgary/O=Raven View Homes/CN=mail.ravenviewhomes.com/emailAddress=ad...@ravenviewhomes.com"\
~> EHLO [10.0.1.7]\
=== response in 0.920s\
<~ 250-mail.ravenviewhomes.com\
<~ 250-PIPELINING\
<~ 250-SIZE 10240000\
<~ 250-ETRN\
<~ 250-AUTH PLAIN LOGIN\
<~ 250-AUTH=PLAIN LOGIN\
<~ 250-ENHANCEDSTATUSCODES\
<~ 250-8BITMIME\
<~ 250 DSN\
~> AUTH PLAIN AGFkbWluQHJhdmVudmlld2hvbWVzLmNvbQBSYXYzblYhZXc=\
=== response in 1.428s\
<~ 235 2.7.0 Authentication successful\
~> MAIL FROM:<ad...@ravenviewhomes.com>\
=== response in 0.026s\
<~ 250 2.1.0 Ok\
~> RCPT TO:<ad...@ravenviewhomes.com>\
=== response in 1.502s\
<~ 250 2.1.5 Ok\
~> DATA\
=== response in 1.755s\
<~ 354 End data with <CR><LF>.<CR><LF>\
~> Date: Fri, 25 Jul 2014 17:27:43 -0600\
~> To: ad...@ravenviewhomes.com\
~> From: ad...@ravenviewhomes.com\
~> Subject: test Fri, 25 Jul 2014 17:27:43 -0600\
~> X-Mailer: swaks v20130209.0 jetmore.org/john/code/swaks/\
~> \
~> This is a test mailing\
~> \
~> .\
=== response in 16.566s\
<~ 250 2.0.0 Ok: queued as 10DFC5C5846\
~> QUIT\
=== response in 0.060s\
<~ 221 2.0.0 Bye\
=== Connection closed with remote host.\
23.46 real 0.07 user 0.00 sys\
\
\
/usr/bin/time ./swaks -f ad...@ravenviewhomes.com -t ad...@ravenviewhomes.com -tls --ehlo [10.0.1.7] --server mail.ravenviewhomes.com -a PLAIN -au ad...@ravenviewhomes.com -ap 'Rav3nV!ew' -stl\
=== Trying mail.ravenviewhomes.com:25...\
=== Connected to mail.ravenviewhomes.com.\
=== response in 1.048s\
<- 220 mail.ravenviewhomes.com ESMTP\
-> EHLO [10.0.1.7]\
=== response in 7.809s\
<- 250-mail.ravenviewhomes.com\
<- 250-PIPELINING\
<- 250-SIZE 10240000\
<- 250-ETRN\
<- 250-STARTTLS\
<- 250-ENHANCEDSTATUSCODES\
<- 250-8BITMIME\
<- 250 DSN\
-> STARTTLS\
=== response in 0.031s\
<- 220 2.0.0 Ready to start TLS\
=== TLS started with cipher TLSv1:DHE-RSA-AES256-SHA:256\
=== TLS no local certificate set\
=== TLS peer DN="/C=CA/ST=Alberta/L=Calgary/O=Raven View Homes/CN=mail.ravenviewhomes.com/emailAddress=ad...@ravenviewhomes.com"\
~> EHLO [10.0.1.7]\
=== response in 0.035s\
<~ 250-mail.ravenviewhomes.com\
<~ 250-PIPELINING\
<~ 250-SIZE 10240000\
<~ 250-ETRN\
<~ 250-AUTH PLAIN LOGIN\
<~ 250-AUTH=PLAIN LOGIN\
<~ 250-ENHANCEDSTATUSCODES\
<~ 250-8BITMIME\
<~ 250 DSN\
~> AUTH PLAIN AGFkbWluQHJhdmVudmlld2hvbWVzLmNvbQBSYXYzblYhZXc=\
=== response in 0.043s\
<~ 235 2.7.0 Authentication successful\
~> MAIL FROM:<ad...@ravenviewhomes.com>\
=== response in 0.037s\
<~ 250 2.1.0 Ok\
~> RCPT TO:<ad...@ravenviewhomes.com>\
=== response in 0.052s\
<~ 250 2.1.5 Ok\
~> DATA\
=== response in 0.031s\
<~ 354 End data with <CR><LF>.<CR><LF>\
~> Date: Fri, 25 Jul 2014 17:28:17 -0600\
~> To: ad...@ravenviewhomes.com\
~> From: ad...@ravenviewhomes.com\
~> Subject: test Fri, 25 Jul 2014 17:28:17 -0600\
~> X-Mailer: swaks v20130209.0 jetmore.org/john/code/swaks/\
~> \
~> This is a test mailing\
~> \
~> .\
=== response in 0.033s\
<~ 250 2.0.0 Ok: queued as 2626F5C586C\
~> QUIT\
=== response in 0.033s\
<~ 221 2.0.0 Bye\
=== Connection closed with remote host.\
33.56 real 0.07 user 0.00 sys}
