[Mailman-Users] Mailman needs 20 seconds to complete per email

[Mark Sapiro]

Karsten Becker wrote:
>
>> Dec 04 09:25:15 2012 qrunner(8680): connect:   ('localhost', 10030)


smtplib begins the connect process at 09:25:15


>> Dec 04 09:25:15 2012 qrunner(8680): connect:   (10030, 'localhost')


at 09:25:15 smtplib has established a socket (connection) to port 10030
on localhost. This message indicates the connect was successful and
the socket exists.


>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '220 mail01.foodmz.local ESMTP Ecologic Institute ready\r\n'


Postfix answers the connect with the above 220 status message at
09:25:36 (21 seconds later). Mailman has been doing nothing but
waiting for this reply from Postfix.


>> Dec 04 09:25:36 2012 qrunner(8680): reply: retcode (220); Msg: mail01.foodmz.local ESMTP Foo ready


smtplib.getreply() returns the retcode and message to smtplib.connect().


>> Dec 04 09:25:36 2012 qrunner(8680): connect:   mail01.foodmz.local ESMTP Foo ready


smtplib.connect() logs its return to the caller (in this case,
smtplib.sendmail()). The connection is established.


>> Dec 04 09:25:36 2012 qrunner(8680): send:   'ehlo mail01.foodmz.local\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250-mail01.foodmz.local\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250-PIPELINING\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250-SIZE 20971520\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250-ETRN\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250-STARTTLS\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250-AUTH PLAIN LOGIN\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250-AUTH=PLAIN LOGIN\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250-XFORWARD NAME ADDR PROTO HELO SOURCE PORT\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250-ENHANCEDSTATUSCODES\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250-8BITMIME\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply:   '250 DSN\r\n'
>> Dec 04 09:25:36 2012 qrunner(8680): reply: retcode (250); Msg: mail01.foodmz.local


smtplib.sendmail() continues with EHLO and gets a reply.


>What is this f***ing Postfix doing...????


It is waiting 21 seconds to answer the connect. What it's doing and why
during that time is a Postfix question, not a Mailman question.


>Or is it the mailman searching for localhost all the time? But
>"localhost" has an entry in /etc/hosts and /etc/nsswitch.conf looks okay.


It is not Mailman. Mailman has established the socket and is waiting
for Postfix to speak. It's Postfix's turn.

What happens if you

telnet localhost 10030

Presumably, it takes 21 seconds before you get a response from Postfix.
That's the delay.

I wrote the above before realizing that others had replied and the
issue was resolved. I'm posting it anyway just for the detailed
interpretation of the smtplib debug messages.

-- 
Mark Sapiro <mark at msapiro.net>        The highway is for gamblers,
San Francisco Bay Area, California    better use your sense - B. Dylan