Of course today I figure out that it only works properly if I change the alpine local config smtp-server to "localhost/...." and send from the localhost (cielo). If I change the local config's smtp-server to " cielo.unm.edu/...." and send from the localhost or from some other server, it performs the same behavior as before... Argh.
I am giving up. My best guess is that there are at least two different alpine bugs at work here. I'm more convinced than ever to move my pine users to mutt or something more 21st century... Renee On Tue, May 5, 2009 at 5:37 AM, Ian Eiloart <[email protected]> wrote: > > > --On 1 May 2009 11:10:24 -0600 Renee <[email protected]> wrote: > > Thanks Ian & Bill, for your advice. >> >> Bill- I think I'm okay under the scenarios you listed in "A". "B" could >> be a different story...? My un-obfuscated logs, below, with "log_selector >> +all" should be able to shed more light on this subject. >> > > Interesting. Alpine is connecting on port 587, and getting a "relay not > permitted" error. Then it does an RSET, and somehow manages to send the > message, to the same Exim process "[4062]". > > Does your backend authenticator log authentication attempts? My guess is > that alpine is maybe trying to send email without authentication first, then > with authentication. The first attempt really ought to fail, whoever the > recipient is. > > If you send to a local recipient, then this may succeed first time. It > shouldn't really. You really do want your MSA connections authenticated. > Exceptions may be permitted if you have a reason to trust the machine > sending the email. > > > I did a few different tests this morning. Two from alpine on both the >> localhost (cielo) and another host within my control that has alpine >> configured to look to cielo. I won't post the log from the latter, as it >> is essentially the same, just a different sending host. I also did some >> tests from webmail (squirrelmail) and thunderbird to show the difference. >> I'll just post the log from thunderbird. >> >> It appears that there are two extra lines in the alpine output logging >> that aren't displayed when using thunderbird, etc.. First the "relay not >> permitted" line, then an "incomplete transaction" statement. >> >> Also, since I first posted, I've added mailscanner back into the loop, but >> I've already confirmed that the same problem occurs with and without >> mailscanner's involvement. >> >> alpine: >> 2009-05-01 10:49:47 [3958] SMTP connection from [129.24.125.136]:33508 >> I=[129.24.125.136]:587 (TCP/IP connection count = 1) >> 2009-05-01 10:49:47 [4062] H=cielo.unm.edu [129.24.125.136]:33508 >> I=[129.24.125.136]:587 F=<[email protected]> rejected RCPT < >> [email protected]>: relay not permitted >> 2009-05-01 10:49:47 [4062] H=cielo.unm.edu [129.24.125.136]:33508 >> I=[129.24.125.136]:587 incomplete transaction (RSET) from < >> [email protected]> >> 2009-05-01 10:49:47 [4062] 1Lzvvn-00013W-Of "[email protected]" from >> env-from rewritten as "[email protected]" by submission mode >> 2009-05-01 10:49:47 [4062] 1Lzvvn-00013W-Of <= [email protected] H= >> cielo.unm.edu [129.24.125.136]:33508 I=[129.24.125.136]:587 P=esmtpsa >> X=TLSv1:AES256-SHA:256 CV=no A=dovecot_plain:obscure S=571 id= >> [email protected] T="test from alpine" >> from < [email protected]> for [email protected] >> 2009-05-01 10:49:47 [4062] SMTP connection from >> cielo.unm.edu[129.24.125.136]:33508 I=[129.24.125.136]:587 closed by >> QUIT >> 2009-05-01 10:49:50 [4069] cwd=/var/spool/MailScanner/incoming/3969 5 >> args: /usr/local/exim/bin/exim -C /usr/local/exim/etc/configure.out -Mc >> 1Lzvvn-00013W-Of >> 2009-05-01 10:49:51 [4069] 1Lzvvn-00013W-Of => [email protected] F=< >> [email protected]> P=<[email protected]> >> R=dnslookup_batv T=external_smtp_batv S=857 >> H=gmail-smtp-in.l.google.com[209.85.147.27]:25 C="250 2.0.0 OK >> 1241196591 v9si4857027wah.1" QT=4s DT=1s >> 2009-05-01 10:49:51 [4069] 1Lzvvn-00013W-Of Completed QT=4s >> >> thunderbird: >> 2009-05-01 10:51:03 [3958] SMTP connection from [129.24.124.254]:50149 >> I=[129.24.125.136]:587 (TCP/IP connection count = 1) >> 2009-05-01 10:51:12 [4073] 1LzvxA-00013h-2e "[email protected]" from >> env-from rewritten as "[email protected]" by submission mode >> 2009-05-01 10:51:12 [4073] 1LzvxA-00013h-2e <= [email protected] H= >> d00-129-24-124-254.dhcp.unm.edu [129.24.124.254]:50149 >> I=[129.24.125.136]:587 P=esmtpsa X=TLSv1:AES256-SHA:256 CV=no >> A=dovecot_plain:obscure S=603 [email protected] T="test >> from thunderbird" from <[email protected]> for [email protected] >> 2009-05-01 10:51:12 [4073] SMTP connection from >> d00-129-24-124-254.dhcp.unm.edu [129.24.124.254]:50149 >> I=[129.24.125.136]:587 closed by QUIT >> 2009-05-01 10:51:15 [4079] cwd=/var/spool/MailScanner/incoming/4013 5 >> args: /usr/local/exim/bin/exim -C /usr/local/exim/etc/configure.out -Mc >> 1LzvxA-00013h-2e >> 2009-05-01 10:51:16 [4079] 1LzvxA-00013h-2e => [email protected] F=< >> [email protected]> P=<[email protected]> >> R=dnslookup_batv T=external_smtp_batv S=891 >> H=gmail-smtp-in.l.google.com[209.85.147.27]:25 C="250 2.0.0 OK >> 1241196676 j15si4817136waf.64" QT=4s >> DT=1s >> 2009-05-01 10:51:16 [4079] 1LzvxA-00013h-2e Completed QT=4s >> > > > > -- > Ian Eiloart > IT Services, University of Sussex > 01273-873148 x3148 > For new support requests, see http://www.sussex.ac.uk/its/help/ > -- ## List details at http://lists.exim.org/mailman/listinfo/exim-users ## Exim details at http://www.exim.org/ ## Please use the Wiki with this list - http://wiki.exim.org/
