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
  • Ver 6.304 is Version 6
    Ver 7.003 is Version 7
    What hardware are you running ?
    We are running 
    ASG220 - V6
    ASG220 - V7
    ASG320 - V7

    Mail Server Novell Groupwise 7 - We have had NO issues with SMTP delivery here, it is very important to decide if you are "Self Hosting" or using a SMART Host, when sending ..post here we will try to help
  • Hi
    I use ASG 425 with 6.304 Release

    This is my SMTP proxy log (i clean external account email and public ip for privacy reason)

    Log has many of raws like these

    2007:05:08-00:12:57 (none) exim[6201]: 2007-05-08 00:12:57 1Hjzxb-0001LH-7M == *** R=dnslookup T=remote_smtp defer (-53): retry time not reached for any host
    2007:05:08-00:12:58 (none) exim[6204]: 2007-05-08 00:12:58 1Hjzxb-0001LH-7M imail.in-network.com [YYY]: Connection reset by peer

    As the message had a lot of recepients i decided to send a single message to one of these recepient.
    But i had same result.

    At the end i have:

    2007:05:08-18:55:55 (none) exim[24186]: 2007-05-08 18:55:55 1Hjzxb-0001LH-7M ** ***: retry timeout exceeded
    2007:05:08-18:55:55 (none) exim[24189]: 2007-05-08 18:55:55 1HlSyh-0006I9-9i <> R=1Hjzxb-0001LH-7M U=exim P=local S=108654
    2007:05:08-18:55:55 (none) exim[24186]: 2007-05-08 18:55:55 1Hjzxb-0001LH-7M Completed

    What does it mean?

    This morning i looked my proxy queue and the most part of message was away.
    I found only four message and two with Force Delivery went out proxy queue.
    I wander if they are send or not because in my log i am not able to find its raws.


    ps I'm Self Hosting
  • 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.
Reply Children
No Data