I have assp connected to exim4, and it's been working fine for months and months. Now, from one site, assp is disconnecting. I turned on debug in everything, and it looks like exim4 and the remote correspondent are getting unexpected disconnects, which assp is, for some reason, initiating.
Here is the assp main log: Jun-14-11 21:31:56 Connected: 72.165.61.135:41187 -> 10.1.1.23:25 -> 10.1.1.23:125; Jun-14-11 21:31:56 72.165.61.135 <> MTA offered STARTTLS - converting to SSL; Jun-14-11 21:31:57 Disconnected: 72.165.61.135; The exim4 log just says this (after hundreds of lines of debug): 21:31:57 16576 accept: condition test succeeded 21:31:57 16576 SMTP>> 250 Accepted 21:31:57 16576 tls_do_write(811c998, 14) 21:31:57 16576 gnutls_record_send(SSL, 811c998, 14) 21:31:57 16576 outbytes=14 21:31:57 16576 Calling gnutls_record_recv(814ebd8, 8151250, 4096) 21:31:57 16576 Got TLS_EOF 21:31:57 16576 SMTP>> 421 ops.infoisland.net lost input connection 21:31:57 16576 LOG: lost_incoming_connection MAIN 21:31:57 16576 unexpected disconnection while reading SMTP command from mail.infoisland.net [10.1.1.23] 21:31:57 16576 search_tidyup called 21:31:57 16364 child 16576 ended: status=0x100 After this paragraph, the germane part of the debug log (the one second connection to the offending site) is pasted (all 300 lines of it). It is at the bottom where the error 451 occurs. The only thing else to say is that this is a site for downloading games and appears to be the only site where this is happening. TIA! Jun-14-11 21-31-52 <mainloop before servicecheck> Jun-14-11 21-31-52 <mainloop before restart check> Jun-14-11 21-31-56 <NewSMTPConnection - no relay> Jun-14-11 21-31-56 <ipNetwork: ip=72.165.61.135 netblock=1> Jun-14-11 21-31-56 <ipNetwork: ip=72.165.61.135 netblock=1> Jun-14-11 21-31-56 <addfh> Jun-14-11 21-31-56 <addfh> Jun-14-11 21-31-56 <Connected: IO::Socket::INET=GLOB(0x99febb8) -- IO::Socket::INET=GLOB(0x9da42f0)> Jun-14-11 21-31-56 <* connect ip=72.165.61.135 relay=<0> *> Jun-14-11 21:31:56 Connected: 72.165.61.135:41187 -> 10.1.1.23:25 -> 10.1.1.23:125; Jun-14-11 21-31-56 <mainloop before servicecheck> Jun-14-11 21-31-56 <mainloop before restart check> Jun-14-11 21-31-56 <SMTPTraffic - read OK> Jun-14-11 21-31-56 <SMTPTraffic - process read> Jun-14-11 21-31-56 <doing line <220 ops.infoisland.net ESMTP Exim 4.69 Tue, 14 Jun 2011 21:31:56 -0400>> Jun-14-11 21-31-56 <reply> Jun-14-11 21-31-56 <sq: IO::Socket::INET=GLOB(0x99febb8) l=72> Jun-14-11 21-31-56 <SMTPTraffic - process read end> Jun-14-11 21-31-56 <mainloop before servicecheck> Jun-14-11 21-31-56 <mainloop before restart check> Jun-14-11 21-31-56 <IO::Socket::INET=GLOB(0x99febb8) HASH(0x9da3980) l=72> Jun-14-11 21-31-56 <wrote: (72)<220 ops.infoisland.net ESMTP Exim 4.69 Tue, 14 Jun 2011 21:31:56 -0400>> Jun-14-11 21-31-56 <mainloop before servicecheck> Jun-14-11 21-31-56 <mainloop before restart check> Jun-14-11 21-31-56 <SMTPTraffic - read OK> Jun-14-11 21-31-56 <SMTPTraffic - process read> Jun-14-11 21-31-56 <doing line <EHLO wcmx2.valvesoftware.com>> Jun-14-11 21-31-56 <getline> Jun-14-11 21-31-56 <gl: <EHLO wcmx2.valvesoftware.com>> Jun-14-11 21-31-56 <headerWrap> Jun-14-11 21-31-56 <sq: IO::Socket::INET=GLOB(0x9da42f0) l=26> Jun-14-11 21-31-56 <SMTPTraffic - process read end> Jun-14-11 21-31-56 <mainloop before servicecheck> Jun-14-11 21-31-56 <mainloop before restart check> Jun-14-11 21-31-56 <IO::Socket::INET=GLOB(0x9da42f0) HASH(0x9a02628) l=26> Jun-14-11 21-31-56 <wrote: (26)<EHLO mail.infoisland.net>> Jun-14-11 21-31-56 <mainloop before servicecheck> Jun-14-11 21-31-56 <mainloop before restart check> Jun-14-11 21-31-56 <SMTPTraffic - read OK> Jun-14-11 21-31-56 <SMTPTraffic - process read> Jun-14-11 21-31-56 <doing line <250-ops.infoisland.net Hello mail.infoisland.net [10.1.1.23]>> Jun-14-11 21-31-56 <reply> Jun-14-11 21-31-56 <sq: IO::Socket::INET=GLOB(0x99febb8) l=62> Jun-14-11 21-31-56 <doing line <250-SIZE 52428800>> Jun-14-11 21-31-56 <reply> Jun-14-11 21-31-56 <sq: IO::Socket::INET=GLOB(0x99febb8) l=19> Jun-14-11 21-31-56 <doing line <250-PIPELINING>> Jun-14-11 21-31-56 <reply> Jun-14-11 21-31-56 <doing line <250-STARTTLS>> Jun-14-11 21-31-56 <reply> Jun-14-11 21-31-56 <doing line <250 HELP>> Jun-14-11 21-31-56 <reply> Jun-14-11 21-31-56 <enabling SSL to MTA> Jun-14-11 21-31-56 <injecting STARTTLS into client response> Jun-14-11 21-31-56 <sq: IO::Socket::INET=GLOB(0x99febb8) l=14> Jun-14-11 21-31-56 <sq: IO::Socket::INET=GLOB(0x99febb8) l=10> Jun-14-11 21-31-56 <SMTPTraffic - process read end> Jun-14-11 21-31-56 <mainloop before servicecheck> Jun-14-11 21-31-56 <mainloop before restart check> Jun-14-11 21-31-56 <IO::Socket::INET=GLOB(0x99febb8) HASH(0x9da3980) l=105> Jun-14-11 21-31-56 <wrote: (105)<250-ops.infoisland.net Hello mail.infoisland.net [10.1.1.23]250-SIZE 52428800 250-STARTTLS 250 HELP >> Jun-14-11 21-31-56 <mainloop before servicecheck> Jun-14-11 21-31-56 <mainloop before restart check> Jun-14-11 21-31-56 <SMTPTraffic - read OK> Jun-14-11 21-31-56 <SMTPTraffic - process read> Jun-14-11 21-31-56 <doing line <220 TLS go ahead>> Jun-14-11 21-31-56 <reply> Jun-14-11 21-31-56 <MTA SSL start> Jun-14-11 21:31:56 72.165.61.135 <> MTA offered STARTTLS - converting to SSL; Jun-14-11 21-31-57 <MTA SSL ok> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x9da42f0) l=26> Jun-14-11 21-31-57 <SMTPTraffic - process read end> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <IO::Socket::SSL=GLOB(0x9da42f0) HASH(0x9a02628) l=26> Jun-14-11 21-31-57 <wrote: (26)<EHLO mail.infoisland.net>> Jun-14-11 21-31-57 <SMTPTraffic - read OK> Jun-14-11 21-31-57 <SMTPTraffic - process read> Jun-14-11 21-31-57 <doing line <STARTTLS>> Jun-14-11 21-31-57 <getline> Jun-14-11 21-31-57 <gl: <STARTTLS>> Jun-14-11 21-31-57 <SSL: IO::Socket::SSL=GLOB(0x99febb8) HASH(0x9da3980)> Jun-14-11 21-31-57 <SMTPTraffic - process read end> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <SMTPTraffic - read OK> Jun-14-11 21-31-57 <SMTPTraffic - process read> Jun-14-11 21-31-57 <doing line <250-ops.infoisland.net Hello mail.infoisland.net [10.1.1.23]>> Jun-14-11 21-31-57 <dropreply: 250-ops.infoisland.net Hello mail.infoisland.net [10.1.1.23]> Jun-14-11 21-31-57 <doing line <250-SIZE 52428800>> Jun-14-11 21-31-57 <dropreply: 250-SIZE 52428800> Jun-14-11 21-31-57 <doing line <250-PIPELINING>> Jun-14-11 21-31-57 <dropreply: 250-PIPELINING> Jun-14-11 21-31-57 <doing line <250-AUTH LOGIN>> Jun-14-11 21-31-57 <dropreply: 250-AUTH LOGIN> Jun-14-11 21-31-57 <doing line <250 HELP>> Jun-14-11 21-31-57 <dropreply: 250 HELP> Jun-14-11 21-31-57 <SMTPTraffic - process read end> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <SMTPTraffic - read OK> Jun-14-11 21-31-57 <SMTPTraffic - process read> Jun-14-11 21-31-57 <doing line <EHLO wcmx2.valvesoftware.com>> Jun-14-11 21-31-57 <getline> Jun-14-11 21-31-57 <gl: <EHLO wcmx2.valvesoftware.com>> Jun-14-11 21-31-57 <headerWrap> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x9da42f0) l=26> Jun-14-11 21-31-57 <SMTPTraffic - process read end> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <IO::Socket::SSL=GLOB(0x9da42f0) HASH(0x9a02628) l=26> Jun-14-11 21-31-57 <wrote: (26)<EHLO mail.infoisland.net>> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <SMTPTraffic - read OK> Jun-14-11 21-31-57 <SMTPTraffic - process read> Jun-14-11 21-31-57 <doing line <250-ops.infoisland.net Hello mail.infoisland.net [10.1.1.23]>> Jun-14-11 21-31-57 <reply> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x99febb8) l=62> Jun-14-11 21-31-57 <doing line <250-SIZE 52428800>> Jun-14-11 21-31-57 <reply> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x99febb8) l=19> Jun-14-11 21-31-57 <doing line <250-PIPELINING>> Jun-14-11 21-31-57 <reply> Jun-14-11 21-31-57 <doing line <250-AUTH LOGIN>> Jun-14-11 21-31-57 <reply> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x99febb8) l=16> Jun-14-11 21-31-57 <doing line <250 HELP>> Jun-14-11 21-31-57 <reply> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x99febb8) l=10> Jun-14-11 21-31-57 <SMTPTraffic - process read end> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <IO::Socket::SSL=GLOB(0x99febb8) HASH(0x9da3980) l=107> Jun-14-11 21-31-57 <wrote: (107)<250-ops.infoisland.net Hello mail.infoisland.net [10.1.1.23]250-SIZE 52428800 250-AUTH LOGIN 250 HELP >> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <SMTPTraffic - read OK> Jun-14-11 21-31-57 <SMTPTraffic - process read> Jun-14-11 21-31-57 <doing line <MAIL FROM:<[email protected]> SIZE=6926>> Jun-14-11 21-31-57 <getline> Jun-14-11 21-31-57 <gl: <MAIL FROM:<[email protected]> SIZE=6926>> Jun-14-11 21-31-57 <stateReset> Jun-14-11 21-31-57 <localmail - [email protected]> Jun-14-11 21-31-57 <localdomains - [email protected]> Jun-14-11 21-31-57 <WhiteOk> Jun-14-11 21-31-57 <BombWeight - whiteRe> Jun-14-11 21-31-57 <localmail - [email protected]> Jun-14-11 21-31-57 <localdomains - [email protected]> Jun-14-11 21-31-57 <ipNetwork: ip=72.165.61.135 netblock=1> Jun-14-11 21-31-57 <ipNetwork: ip=72.165.61.135 netblock=1> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x9da42f0) l=51> Jun-14-11 21-31-57 <SMTPTraffic - process read end> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <IO::Socket::SSL=GLOB(0x9da42f0) HASH(0x9a02628) l=51> Jun-14-11 21-31-57 <wrote: (51)<MAIL FROM:<[email protected]> SIZE=6926>> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <SMTPTraffic - read OK> Jun-14-11 21-31-57 <SMTPTraffic - process read> Jun-14-11 21-31-57 <doing line <250 OK>> Jun-14-11 21-31-57 <reply> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x99febb8) l=8> Jun-14-11 21-31-57 <SMTPTraffic - process read end> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <IO::Socket::SSL=GLOB(0x99febb8) HASH(0x9da3980) l=8> Jun-14-11 21-31-57 <wrote: (8)<250 OK>> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <SMTPTraffic - read OK> Jun-14-11 21-31-57 <SMTPTraffic - process read> Jun-14-11 21-31-57 <doing line <RCPT TO:<[email protected]>>> Jun-14-11 21-31-57 <getline> Jun-14-11 21-31-57 <gl: <RCPT TO:<[email protected]>>> Jun-14-11 21-31-57 <localmail - infoisland.net> Jun-14-11 21-31-57 <localdomains - infoisland.net> Jun-14-11 21-31-57 <localmail - infoisland.net> Jun-14-11 21-31-57 <localdomains - infoisland.net> Jun-14-11 21-31-57 <localmail - infoisland.net> Jun-14-11 21-31-57 <localdomains - infoisland.net> Jun-14-11 21-31-57 <Delayok> Jun-14-11 21-31-57 <ipNetwork: ip=IO::Socket::SSL=GLOB(0x99febb8) netblock=1> Jun-14-11 21-31-57 <ipNetwork: ip=72.165.61.135 netblock=1> Jun-14-11 21-31-57 <ipNetwork: ip=72.165.61.135 netblock=1> Jun-14-11 21-31-57 <ipNetwork: ip=72.165.61.135 netblock=1> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x9da42f0) l=30> Jun-14-11 21-31-57 <SMTPTraffic - process read end> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <IO::Socket::SSL=GLOB(0x9da42f0) HASH(0x9a02628) l=30> Jun-14-11 21-31-57 <wrote: (30)<RCPT TO:<[email protected]>>> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <SMTPTraffic - read OK> Jun-14-11 21-31-57 <SMTPTraffic - process read> Jun-14-11 21-31-57 <doing line <250 Accepted>> Jun-14-11 21-31-57 <reply> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x99febb8) l=14> Jun-14-11 21-31-57 <SMTPTraffic - process read end> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <IO::Socket::SSL=GLOB(0x99febb8) HASH(0x9da3980) l=14> Jun-14-11 21-31-57 <wrote: (14)<250 Accepted>> Jun-14-11 21-31-57 <mainloop before servicecheck> Jun-14-11 21-31-57 <mainloop before restart check> Jun-14-11 21-31-57 <SMTPTraffic - read OK> Jun-14-11 21-31-57 <SMTPTraffic - process read> Jun-14-11 21-31-57 <doing line <DATA>> Jun-14-11 21-31-57 <getline> Jun-14-11 21-31-57 <gl: <DATA>> Jun-14-11 21-31-57 <NumRcptOK> Jun-14-11 21-31-57 <seterror> Jun-14-11 21-31-57 <error> Jun-14-11 21-31-57 <replylog - [SMTP Status] 451 4.7.1 Please try again later> Jun-14-11 21-31-57 <sq: IO::Socket::SSL=GLOB(0x99febb8) l=34> Jun-14-11 21-31-57 <done2> Jun-14-11 21:31:57 Disconnected: 72.165.61.135; Jun-14-11 21-31-57 <closing IO::Socket::SSL=GLOB(0x99febb8)> Jun-14-11 21-31-57 <finished closing connection> Jun-14-11 21-31-57 <done2> Jun-14-11 21-31-57 <closing IO::Socket::SSL=GLOB(0x9da42f0)> Jun-14-11 21-31-57 <finished closing connection> Jun-14-11 21-31-57 <SMTPTraffic - process read end> Jun-14-11 21-31-57 <mainloop before servicecheck> ------------------------------------------------------------------------------ EditLive Enterprise is the world's most technically advanced content authoring tool. Experience the power of Track Changes, Inline Image Editing and ensure content is compliant with Accessibility Checking. http://p.sf.net/sfu/ephox-dev2dev _______________________________________________ Assp-test mailing list [email protected] https://lists.sourceforge.net/lists/listinfo/assp-test
