I recently upgraded from using ASSP1.6 to ASSP1.9. Since that upgrade, a lot of message deliveries are not successful. After some investigation, is turns out that the SMTP connections are timing out.
The debug log of ASSP (ConTimeOutDebug) tells the following on the SMTP session: Sep-09-11 21:27:59 client Timeout after 600 secs Sep-09-11 21:27:59 client was readable Sep-09-11 21:27:59 client was not writable The MTA behind ASSP is hMailServer. That server was not changed. A correct log of hMail for a correctly delivered message looks like this: "SMTPD" 2724 21701 "2011-09-09 12:26:08.296" "127.0.0.1" "SENT: 220 mail.domain.tld ESMTP" "SMTPD" 2724 21701 "2011-09-09 12:26:08.343" "127.0.0.1" "RECEIVED: EHLO mail.domain.tld" "SMTPD" 2724 21701 "2011-09-09 12:26:08.359" "127.0.0.1" "SENT: 250-mail.domain.tld[nl]250-SIZE 50000000[nl]250 AUTH LOGIN" "SMTPD" 2724 21701 "2011-09-09 12:26:08.375" "127.0.0.1" "RECEIVED: MAIL FROM:<[email protected]> SIZE=7756" "SMTPD" 2724 21701 "2011-09-09 12:26:08.375" "127.0.0.1" "SENT: 250 OK" "SMTPD" 2724 21701 "2011-09-09 12:26:08.390" "127.0.0.1" "RECEIVED: RCPT TO:<[email protected]>" "SMTPD" 2724 21701 "2011-09-09 12:26:08.406" "127.0.0.1" "SENT: 250 OK" "SMTPD" 2724 21701 "2011-09-09 12:26:08.421" "127.0.0.1" "RECEIVED: DATA" "SMTPD" 2724 21701 "2011-09-09 12:26:08.421" "127.0.0.1" "SENT: 354 OK, send." "DEBUG" 2540 "2011-09-09 12:26:08.475" "Saving message: M:\hMailServer\Data\{60DE2F15-B4G8-4JAE-84QB-73DAC0C1CM0A}.eml" "DEBUG" 2540 "2011-09-09 12:26:08.545" "Requesting SMTPDeliveryManager to start message delivery" "SMTPD" 2540 21701 "2011-09-09 12:26:08.687" "127.0.0.1" "SENT: 250 Queued (0.109 seconds)" "SMTPD" 2740 21701 "2011-09-09 12:26:08.703" "127.0.0.1" "RECEIVED: QUIT" "SMTPD" 2740 21701 "2011-09-09 12:26:08.703" "127.0.0.1" "SENT: 221 goodbye" Whereas a message that is not delivered looks like this, from hMail log perspective: "DEBUG" 2724 "2011-09-09 21:17:56.796" "Creating session 21979" "SMTPD" 2724 21979 "2011-09-09 21:17:56.796" "127.0.0.1" "SENT: 220 mail.domain.tld ESMTP" "SMTPD" 2724 21979 "2011-09-09 21:17:56.796" "127.0.0.1" "SENT: 250 OK" "SMTPD" 2724 21979 "2011-09-09 21:17:56.843" "127.0.0.1" "RECEIVED: EHLO mail.domain.tld" "SMTPD" 2724 21979 "2011-09-09 21:17:56.843" "127.0.0.1" "SENT: 250-mail.domain.tld[nl]250-SIZE 50000000[nl]250 AUTH LOGIN" "SMTPD" 2724 21979 "2011-09-09 21:17:56.859" "127.0.0.1" "RECEIVED: MAIL FROM:<[email protected]> SIZE=10466" "SMTPD" 2724 21979 "2011-09-09 21:17:56.859" "127.0.0.1" "SENT: 250 OK" "SMTPD" 2724 21979 "2011-09-09 21:17:56.890" "127.0.0.1" "RECEIVED: RCPT TO:<[email protected]>" "SMTPD" 2724 21979 "2011-09-09 21:17:56.890" "127.0.0.1" "SENT: 250 OK" "SMTPD" 2724 21979 "2011-09-09 21:17:56.906" "127.0.0.1" "RECEIVED: DATA" "SMTPD" 2724 21979 "2011-09-09 21:17:56.906" "127.0.0.1" "SENT: 354 OK, send." "DEBUG" 2724 "2011-09-09 21:27:56.937" "Deleting message file" "DEBUG" 2724 "2011-09-09 21:27:56.937" "Ending session 21979" "DEBUG" 2724 "2011-09-09 21:37:59.062" "The client has timed out. Session: 21479" As also the ASSP SMTP debug log is showing, the connection is terminated by hMail after 10 minutes of inactivity (initiated by ASSP). Strange is that some message are delivered, but most are not. Only workarround at present is to add sending MTA's to the NoProcessing list. This issue was addressed earlier in the ASSP forum '[1.7.5.6] timeout problem' (http://sourceforge.net/apps/phpbb/assp/viewtopic.php?f=9&t=794), on which I already posted a reply. Main question here is what is going on .. why is the connection timing out. Is ASSP waiting for some kind of reply from the MTA (hMailServer)? Adding servers to NoProcessing solves the issue (please note that the messages would not be blocked due to other filtering) ... which comes weird to me as the SMTP delivery to hMail is not affected by this ... or is it? Many thanks, Vwout ------------------------------------------------------------------------------ All of the data generated in your IT infrastructure is seriously valuable. Why? It contains a definitive record of application performance, security threats, fraudulent activity, and more. Splunk takes this data and makes sense of it. IT sense. And common sense. http://p.sf.net/sfu/splunk-d2dcopy2 _______________________________________________ Assp-user mailing list [email protected] https://lists.sourceforge.net/lists/listinfo/assp-user
