This discussion has been locked.
You can no longer post new replies to this discussion. If you have a question you can start a new discussion

SMTP recent failure

Hi,

The SMTP proxy has been working fine for weeks now, but this morning I was worried to find that I had no new external email waiting for me as usual.

A quick look into the log files showed entries similar to this:

2007:03:15-20:21:29 (none) exim[17091]: 2007-03-15 20:21:29 End queue run: pid=17091 
2007:03:15-20:21:53 (none) exim[5430]: 2007-03-15 20:21:53 SMTP connection from [146.82.220.36]:41231 (TCP/IP connection count = 1) 
2007:03:15-20:22:04 (none) exim[18642]: 2007-03-15 20:22:04 [pid 18642] [146.82.220.36] F= Untrusted message 
2007:03:15-20:22:04 (none) exim[18642]: 2007-03-15 20:22:04 [pid 18642] [146.82.220.36] F= R= Verifying recipient address 
2007:03:15-20:22:04 (none) exim[18642]: 2007-03-15 20:22:04 [pid 18642] [146.82.220.36] F= R= Performing default RBL checks 
2007:03:15-20:22:04 (none) exim[18642]: 2007-03-15 20:22:04 [pid 18642] [146.82.220.36] F= R= Performing dialup RBL checks 
2007:03:15-20:22:04 (none) exim[18642]: 2007-03-15 20:22:04 [pid 18642] [146.82.220.36] F= R= Performing 'fail' SPF check 
2007:03:15-20:22:05 (none) exim[18642]: 2007-03-15 20:22:05 [pid 18642] [146.82.220.36] F= R= Greylisting: (still) deferring this host 
2007:03:15-20:22:05 (none) exim[18642]: 2007-03-15 20:22:05 H=(mta1.primary.ddc.dartmail.net) [146.82.220.36]:41231 F= temporarily rejected RCPT : Temporary failure 
2007:03:15-20:22:07 (none) exim[18322]: 2007-03-15 20:22:07 1HRvI2-00044T-J9 == astaro@secret.co.uk R=cff_route T=cff_smtp defer (-46): SMTP error from remote mail server after end of data: host 127.0.0.1 [127.0.0.1]: 451 basic_ios::clear 
2007:03:15-20:22:07 (none) exim[18646]: 2007-03-15 20:22:07 1HReYZ-0003cN-Ia == johndoe@secret.co.uk R=cff_route T=cff_smtp defer (-53): retry time not reached for any host 
2007:03:15-20:22:09 (none) exim[18655]: 2007-03-15 20:22:09 Start queue run: pid=18655 
2007:03:15-20:22:09 (none) exim[18659]: 2007-03-15 20:22:09 1HRZ46-0001di-5f == johndoe@secret.co.uk R=cff_route T=cff_smtp defer (-53): retry time not reached for any host 
2007:03:15-20:22:10 (none) exim[18642]: 2007-03-15 20:22:10 SMTP connection from (mta1.primary.ddc.dartmail.net) [146.82.220.36]:41231 closed by QUIT 
2007:03:15-20:22:20 (none) exim[18334]: 2007-03-15 20:22:20 1HRh4m-0004jP-Ll == johndoe@secret.co.uk R=cff_route T=cff_smtp defer (-46): SMTP error from remote mail server after end of data: host 127.0.0.1 [127.0.0.1]: 451 basic_ios::clear 
2007:03:15-20:22:20 (none) exim[18672]: 2007-03-15 20:22:20 1HReYZ-0003cN-Ia == johndoe@secret.co.uk R=cff_route T=cff_smtp defer (-53): retry time not reached for any host 
2007:03:15-20:23:09 (none) exim[18692]: 2007-03-15 20:23:09 Start queue run: pid=18692 
2007:03:15-20:23:11 (none) exim[18376]: 2007-03-15 20:23:11 1HRoPJ-0000Cr-C5 == johndoe@secret.co.uk R=cff_route T=cff_smtp defer (-46): SMTP error from remote mail server after end of data: host 127.0.0.1 [127.0.0.1]: 451 basic_ios::clear 
2007:03:15-20:23:11 (none) exim[18362]: 2007-03-15 20:23:11 End queue run: pid=18362 
2007:03:15-20:23:16 (none) exim[18384]: 2007-03-15 20:23:16 1HRhSg-0004uS-3t == johndoe@secret.co.uk R=cff_route T=cff_smtp defer (-46): SMTP error from remote mail server after end of data: host 127.0.0.1 [127.0.0.1]: 451 basic_ios::clear 
2007:03:15-20:23:16 (none) exim[18709]: 2007-03-15 20:23:16 1HRoPJ-0000Cr-C5 == johndoe@secret.co.uk R=cff_route T=cff_smtp defer (-53): retry time not reached for any host 
2007:03:15-20:23:16 (none) exim[18712]: 2007-03-15 20:23:16 1HRh4m-0004jP-Ll == johndoe@secret.co.uk R=cff_route T=cff_smtp defer (-53): retry time not reached for any host 
2007:03:15-20:23:22 (none) exim[18392]: 2007-03-15 20:23:22 1HRfqz-0004BH-PX == johndoe@secret.co.uk R=cff_route T=cff_smtp defer (-46): SMTP error from remote mail server after end of data: host 127.0.0.1 [127.0.0.1]: 451 basic_ios::clear 
2007:03:15-20:23:22 (none) exim[17678]: 2007-03-15 20:23:22 End queue run: pid=17678 
2007:03:15-20:23:27 (none) exim[18396]: 2007-03-15 20:23:27 1HRuxo-0003tp-R9 == johndoe@secret.co.uk R=cff_route T=cff_smtp defer (-46): SMTP error from remote mail server after end of data: host 127.0.0.1 [127.0.0.1]: 451 basic_ios::clear 

This is a log file from this evening with the names changed to protect the innocent.

Can anyone help me with this?

Thanks


This thread was automatically locked due to age.
Parents
  • Hi,

    It looks like this is the start of the problem: 

    2007:03:17-07:49:57 (none) exim[25188]: 2007-03-17 07:49:57 1HSTel-0006Vm-TS 127.0.0.1 [127.0.0.1]:1234 Connection refused

    I've looked through the exim FAQ's and although there are some suggestions as to a resolution to this, it means editing configuration files, something that should not be needed.

    Is there anyway to "default" the exim installation without 1) losing my emails currently stuck in there, and 2) having to re-build the box?

    Thanks
  • Hi,
    Has anyone got an update on this one?

    I'm still suffering with the same problems :E

    Many thanks
  • Hi All,

    Slightly disappointed to have started to get these messages now - and no mail deliveries.. 

    Any ideas?  7.003.

    Thanks

    Freudy


    2007:05:13-17:21:22 (none) exim[15112]: 2007-05-13 17:21:22 Start queue run: pid=15112
    2007:05:13-17:21:22 (none) exim[15114]: 2007-05-13 17:21:22 1HnFaZ-0001sB-L8 127.0.0.1 [127.0.0.1]:9925 Connection refused
    2007:05:13-17:21:22 (none) exim[15113]: 2007-05-13 17:21:22 1HnFaZ-0001sB-L8 == ***xx@***x.co.uk R=crypto_route T=crypto_smtp defer (111): Connection refused
    2007:05:13-17:21:22 (none) exim[15115]: 2007-05-13 17:21:22 1HnBVA-0004su-B2 == ***xx@***x.co.uk R=crypto_route T=crypto_smtp defer (-53): retry time not reached for any host
    2007:05:13-17:21:22 (none) exim[15117]: 2007-05-13 17:21:22 1HnEvt-0000xt-SW == ***xx@***x.co.uk R=crypto_route T=crypto_smtp defer (-53): retry time not reached for any host
    2007:05:13-17:21:22 (none) exim[15112]: 2007-05-13 17:21:22 End queue run: pid=15112
  • So.. 

    I applied 7.004 and these emails came flooding through.

    Is there something that happens as part of a firmware upgrade that somehow clears the log jam - as this is twice the emails have been released after a firmeware upgrade.

    ??
  • I believe there was a fix in v7.004 for the exim issue.

    Ian M
  • Hmmm - not here. I rebuilt my Astaro box and moved it to elsewhere on my network at home (licensing issue) but still use it as an SMTP relay. After a week I have 10 unaffected (the catagory) emails stuck in the Astaro FW with no way of getting at them. They do not appear in Quarantine Manager.

    I am on V 7.004
    Running Astaro's Virtual Appliance.

    However I should add that I do NOT appear to be getting the retry limit error that was occurring previously...

    Sean
  • Hi,

    I've the same issue with 7.004, sometimes mail gets delivered immediately, then it getting stuck for 1 hour or more and suddenly they are being delivered. Guess it's back to 6.x

    Franc.
  • Got the same issue, lodged a support request with Astaro but progress is painfully slow.

    Emails coming in seem to get accepted, then dissapear for up to around an hour before ending up in Quarantine with "error" tag.

    Email is a rather critical issue, until this is sorted out, theres no way we can move from v6 to v7.  Our maintenance is due for renewal in under two weeks now.  Whether we pay for v6 or v7 is up to Astaro.  Not very happy at all.

    Part of my support email:
    OK, set up a mail client on my laptop, set the SMTP server to the problem ASL. (192.168.3.7)

    Sent a mail, to simon@micromine.ru which should get routed by ASL to mail.micromine.ru

    The log below, mail was not delivered.

     

    2007:05:31-13:33:39 (none) exim[5358]: 2007-05-31 13:33:39 SMTP connection from [192.168.3.30]:63721 (TCP/IP connection count = 1) 

    2007:05:31-13:33:39 (none) exim[7334]: 2007-05-31 13:33:39 [pid 7334] [192.168.3.30] F= Trusted (sent from relay or localhost) 

    2007:05:31-13:33:39 (none) exim[7334]: 2007-05-31 13:33:39 [pid 7334] [192.168.3.30] F= R= Accepted: from relay 

    2007:05:31-13:33:42 (none) exim[7334]: 2007-05-31 13:33:42 [pid 7334] [192.168.3.30] F= Trusted (sent from relay or localhost) 

    2007:05:31-13:33:42 (none) exim[7334]: 2007-05-31 13:33:42 [pid 7334] [192.168.3.30] F= R= Accepted: from relay 

    2007:05:31-13:33:42 (none) exim[7334]: 2007-05-31 13:33:42 1HtdI6-0001uI-Ff simon@micromine.ru H=(MM00500) [192.168.3.30]:63721 P=esmtp S=2795 id=80DB32973A034A4C98088342E32C8391@micromine.com.au 

    2007:05:31-13:33:42 (none) exim[7335]: 2007-05-31 13:33:42 1HtdI6-0001uI-Ff => simon@micromine.ru F= P= R=cff_route T=cff_smtp H=127.0.0.1 [127.0.0.1]:1234 

    2007:05:31-13:33:42 (none) exim[7335]: 2007-05-31 13:33:42 1HtdI6-0001uI-Ff Completed 

    2007:05:31-13:33:42 (none) exim[7334]: 2007-05-31 13:33:42 SMTP connection from (MM00500) [192.168.3.30]:63721 closed by QUIT 

     

    The H=127.0.0.1  Should that not be the mail host IP for mail.micromine.ru ???

     

    That log is from the SMTP engine.  If I go to the SMTP proxy log, nothing shows! (Related to this message)

     

    Mails EVENTUALLY appear in Quarantine Manager.  There is one or more there for you to look at now under SMTP – ERROR area.

     

    2007:05:31-14:10:15 (none) cffd[5262]: id="101P" severity="info" sys="SecureMail" sub="smtp" name="email blocked, error" srcip="192.168.3.30" from="test@micromine.ru" to="simon@micromine.ru" subject="1" messageid="3737" size="3329" action="freeze" 

    2007:05:31-14:11:59 (none) cffd[5262]: id="101P" severity="info" sys="SecureMail" sub="smtp" name="email blocked, error" srcip="192.168.3.30" from="test@micromine.ru" to="simon@micromine.ru" subject="2" messageid="3797" size="3324" action="freeze" 

     

    These appeared in the SMTP Proxy log around 30 minutes AFTER being accepted by SMTP engine.

     

    Switched to static hosts and rebooted.  No good either.
  • Almost every hour I've the following entry in my SMTP engine log:

    2007:06:07-11:58:28 (none) exim[3565]: 2007-06-07 11:58:28 1HwEje-0000qu-Ta 127.0.0.1 [127.0.0.1]:1234 Connection refused

    and then many 'T=cff_smtp defer (-53): retry time not reached for any host'.

    When I do a 

    /var/mdw/scripts/smtp restart 

    the deferred mail is being delivered until next occurrence of the issue.

    Franc.
  • Installing beta up2date 7.005 and see if that fixes it.
  • Well, fixed my issue with 7.004

    I updated to beta 7.005  Still broken emails.

    Added exception in SMTP proxy for ALL networks.. With AntiVirus turned off.  Mail now flows OK.

    May or may not work for the other issues reported here.

    Did find a bug though,  I have AV turned on in the AntiVirus area of the SMTP proxy, but turned it OFF in the exceptions area.

    Despite that, emails still come through with the "This email has been scanned for viruses" tag!
  • Well, I've decided to go back to V6. V7 has way too many issues for us.

    Franc.
Reply Children
  • Anyone tried the just released 7.005 update to see if it resolved the 'Connection refused' issue ? In the relase notes there is a fix about SMTP proxy and virusscanning, but I'm not sure if that resolves this specific issue.

    Franc.
  • Running 7.005 and the fix does not fix my virus scanning/SMTP issue.

    Unless they have updated 7.005........
  • Hi,

    I just installed 7.005 and I must say, SMTP is much more stable now. Quarantine manager doesn't hang anymore and the stuck mail problem seems also to be almost resolved (but not completely, on average once a day mail get stuck for half an hour).

    Franc.
  • Hi,

    I suffered the same problem again today.  


    2007:09:26-12:39:57 (none) exim[5721]: 2007-09-26 12:39:57 Start queue run: pid=5721
    2007:09:26-12:39:57 (none) exim[5722]: 2007-09-26 12:39:57 1IaKJy-00023h-Bl == chris@testdomain.co.uk routing defer (-51): retry time not reached
    2007:09:26-12:39:57 (none) exim[5724]: 2007-09-26 12:39:57 1IaVAX-0001TT-1F Remote host 127.0.0.1 [127.0.0.1] closed connection in response to initial connection
    2007:09:26-12:39:57 (none) exim[5723]: 2007-09-26 12:39:57 1IaVAX-0001TT-1F == me@gmail.com R=crypto_route T=crypto_smtp defer (-18): Remote host 127.0.0.1 [127.0.0.1] closed connection in response to initial connection
    2007:09:26-12:39:57 (none) exim[5721]: 2007-09-26 12:39:57 End queue run: pid=5721


    Why is it saying the remote host is 127.0.0.1?  Is there a service running on the localhost which should be accepting the connections and processing them?  How do I restart the listener if this is the case?

    The whole thing seems a bit flakey - any chance we can have some tools to properly re-start the email subsystem?

    Thanks

    Freudy