[milters] Archive

Lists Index Date Thread Search

Article: 1757
From: Matt Richter
Date: 2007-10-04 10:16:04 -0400
Subject: Re: Milter-Link problem - Timeout Error

Removal...........: milters-request@milter.info?subject=remove
More information..: http://www.milter.info/#Support
--------------------------------------------------------

The problem continues after increasing timeout values. The current
parameters are:
INPUT_MAIL_FILTER(
        `milter-link',
        `S=unix:/var/run/milter/milter-link.socket, T=C:20s;R:4m;E:5m'
)dnl

Here is a log snip:
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: Milter
(milter-link): init success to negotiate
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: Milter:
connect to filters
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217:
milter=milter-link, action=connect, continue
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: --- 220
scanner.domain.net ESMTP Sendmail 8.13.8/8.13.8; Tue, 2 Oct 2007
10:50:28 -0500
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: <-- HELO
web88212.mail.re2.yahoo.com
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217:
milter=milter-link, action=helo, continue
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: --- 250
scanner.domain.net Hello web88212.mail.re2.yahoo.com [206.190.37.235],
pleased to meet you
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: <-- MAIL
FROM:< user@rogers.com>
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: Milter:
senders: <user@rogers.com>
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217:
milter=milter-link, action=mail, continue
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: --- 250 2.1.0
<user@rogers.com>... Sender ok
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: <-- RCPT TO:<
user@domain.net>
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: Milter: rcpts:
<user@domain.net>
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217:
milter=milter-link, action=rcpt, continue
Oct 4 10:50:28 scanner sendmail[21217]: l92FoSVL021217: --- 250 2.1.5
<user@domain.net>... Recipient ok
Oct 4 10:50:29 scanner sendmail[21217]: l92FoSVL021217: <-- DATA
Oct 4 10:50:29 scanner sendmail[21217]: l92FoSVL021217: --- 354 Enter
mail, end with "." on a line by itself
Oct 4 10:50:29 scanner sendmail[21217]: l92FoSVL021217: from=<
user@rogers.com>, size=19759, class=0, nrcpts=1,
msgid=<891564.28188.qm@web88212.mail.re2.yahoo.com>, proto=SMTP,
daemon=MTA, relay= web88212.mail.re2.yahoo.com [206.190.37.235]
Oct 4 10:50:29 scanner sendmail[21217]: l92FoSVL021217: Milter
(milter-link): headers, send
Oct 4 10:50:29 scanner sendmail[21217]: l92FoSVL021217:
milter=milter-link, action=header, continue
Oct 4 10:50:29 scanner sendmail[21217]: l92FoSVL021217: Milter
(milter-link): headers, sent
Oct 4 10:50:29 scanner sendmail[21217]: l92FoSVL021217:
milter=milter-link, action=eoh, continue
Oct 4 10:50:29 scanner sendmail[21217]: l92FoSVL021217: Milter
(milter-link): body, send
Oct 4 10:54:29 scanner sendmail[21217]: l92FoSVL021217: Milter
(milter-link): timeout before data read
Oct 4 10:54:29 scanner sendmail[21217]: l92FoSVL021217: Milter
(milter-link): to error state
Oct 4 10:54:29 scanner sendmail[21217]: l92FoSVL021217: Milter
(milter-link): body, sent
Oct 4 10:54:29 scanner sendmail[21217]: l92FoSVL021217: Milter accept: message
Oct 4 10:54:29 scanner sendmail[21217]: l92FoSVL021217: --- 050
<user@domain.net>... queued
Oct 4 10:54:29 scanner sendmail[21217]: l92FoSVL021217:
to=<user@domain.net>, delay=00:04:01, mailer=smtp, pri=49759,
stat=queued
Oct 4 10:54:29 scanner sendmail[21217]: l92FoSVL021217: --- 250 2.0.0
l92FoSVL021217 Message accepted for delivery
Oct 4 10:55:22 scanner sendmail[25404]: l92FoSVL021217:
to=<user@domain.net>, delay=00:04:54, xdelay=00:00:00, mailer=smtp,
pri=139759, relay=[ x.x.x.x] [x.x.x.x], dsn=2.0.0, stat=Sent
(l92G0a77018431 Message accepted for delivery)
Oct 4 10:55:23 scanner sendmail[25404]: l92FoSVL021217: done;
delay=00:04:55, ntries=1

Thank you.
Matt Richter

On 9/30/07, Anthony Howe <achowe@snert.com> wrote:
> Removal...........: milters-request@milter.info?subject=remove
> More information..: http://www.milter.info/#Support
> --------------------------------------------------------
>
> Matt Richter wrote:
> > Milter-Link is running and functioning correctly for the most part, however,
> > the maillog keeps reporting the following:
> > Sep 28 13:31:55 scanner sendmail[29016]: l8SIUtHQ029016: Milter
> > (milter-link): timeout before data read
>
> This sendmail timing out before the milter reports an answer. The log
> line prior to this one in the session would allow me to see the time
> difference.  However, check you INPUT_MAIL_FILTER T= values. Make sure
> the E: is 5m or so.
>
> > Sep 28 13:31:55 scanner sendmail[29016]: l8SIUtHQ029016: Milter
> > (milter-link): to error state
> >
> > End-users are complaining that they are receiving multiple copies of the
> > same emails. I checked with some admins
> > of sending servers and they report that my receiving server is breaking the
> > connection prior to completion.
> > Are the milter-link error the problem, or should I be looking elsewhere.
>
> Don't see why. If the sendmail were breaking the connection, because of
> setting F=T or something else, I would expect that they would get an
> actual reply code and message. Turn up you sendmail LogLevel to show the
> SMTP dialog and watch for a while.
>
> --
> Anthony C Howe          Skype: SirWumpus                    SnertSoft
> +33 6 11 89 73 78         ICQ: 7116561          BarricadeMX & Milters
> http://www.snert.com/            
          http://www.snertsoft.com/
>
>

Lists Index Date Thread Search