Mac Mail can’t send email from our mail server, but thunderbird/pine/mutt can!

[cyapple]

Hi, strange thing.

I have set up a postfix server on our Redhat Linux system. Everything seems fine.
Now, when I connect using Mac Mail, I can’t send, but I can receive. There is nothing in the console.log on the Mac. But on the same Mac, thunderbird works just fine with both receiving and sending. I am wondering if anybody can give some hints as to where should I start looking.

Thanks a lot.

CY

[cyapple]

here is the log: thanks a lot, I enabled -v -v for smtpd in master.conf, so a lot info, but I think the only relevent ones are ” lost connection after EHLO from ….”, but I have no idea why this is happening.

….

Aug 13 16:52:04 mailserver postfix/smtpd[10747]: connect from xxx.xxx.xxx.edu[xxx.xxx.xxx.13]
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_list_match: xxx.xxx.xxx.edu: no match
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_list_match: xxx.xxx.xxx.13: no match
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_list_match: xxx.xxx.xxx.edu: no match
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_list_match: xxx.xxx.xxx.13: no match
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_hostname: xxx.xxx.xxx.edu ~? xxx.xxx.xxx.0/24
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_hostaddr: xxx.xxx.xxx.13 ~? xxx.xxx.xxx.0/24
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 220 xxx.xxx.xxx.edu ESMTP Postfix
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: watchdog_pat: 0x6e9270
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: < xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: EHLO [xxx.xxx.xxx.13] Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-xxx.xxx.xxx.edu
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-PIPELINING
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-SIZE 102400000
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-VRFY
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-ETRN
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_hostname: xxx.xxx.xxx.edu ~? xxx.xxx.xxx.0/24
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_hostaddr: xxx.xxx.xxx.13 ~? xxx.xxx.xxx.0/24
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: sasl_exceptions: xxx.xxx.xxx.edu[xxx.xxx.xxx.13], match=1
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_list_match: xxx.xxx.xxx.edu: no match
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_list_match: xxx.xxx.xxx.13: no match
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-STARTTLS
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-ENHANCEDSTATUSCODES
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-8BITMIME
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250 DSN
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: watchdog_pat: 0x6e9270
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: < xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: STARTTLS Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 220 2.0.0 Ready to start TLS
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: setting up TLS connection from xxx.xxx.xxx.edu[xxx.xxx.xxx.13]
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: auto_clnt_open: connected to private/tlsmgr
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: send attr request = seed
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: send attr size = 32
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: private/tlsmgr: wanted attribute: status
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: input attribute name: status
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: input attribute value: 0
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: private/tlsmgr: wanted attribute: seed
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: input attribute name: seed
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: input attribute value: awIbsZpCNUuy25vgNp/TqK1tzLpdQZSkU=
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: private/tlsmgr: wanted attribute: (list terminator)
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: input attribute name: (end)
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:before/accept initialization
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:error in SSLv2/v3 read client hello A
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:error in SSLv3 read client hello B
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:error in SSLv3 read client hello B
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: looking up session 608B2533BC300290206DE9D59A36D237EC8B00CEA63B8EB5AF6649F
322B43FCE&s=smtp in smtpd cache
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: send attr request = lookup
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: send attr cache_type = smtpd
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: send attr cache_id = 608B2533BC300290206DE9D59A36D237EC8B00CEA63B8EB5AF664
9F322B43FCE&s=smtp
Aug 13 16:52:04 mailserver postfix/tlsmgr[10749]: lookup smtpd session id=608B2533BC300290206DE9D59A36D237EC8B00CEA63B8EB5A
F6649F322B43FCE&s=smtp
Aug 13 16:52:04 mailserver postfix/tlsmgr[10749]: read smtpd TLS cache entry 608B2533BC300290206DE9D59A36D237EC8B00CEA63B8E
B5AF6649F322B43FCE&s=smtp: time=1187038230 [data 127 bytes]
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: private/tlsmgr: wanted attribute: status
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: input attribute name: status
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: input attribute value: 0
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: private/tlsmgr: wanted attribute: session
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: input attribute name: session
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: input attribute value: MH0CAQECAgMBBAIALwQgYIslM7wwApAgbenVmjbSN+yLAM6mO46
1r2ZJ8yK0P84EMBcSKskgrLYkv4fuqXt+eSeEUcaQXcdcahwLK+V51Cq/kuQcRVVBVor+xjERTkdn46EGAgRGwMQWogQCAg4QpA4EDFBvc3RmaXgvVExTAA==
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: private/tlsmgr: wanted attribute: (list terminator)
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: input attribute name: (end)
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: reloaded session 608B2533BC300290206DE9D59A36D237EC8B00CEA63B8EB5AF6649F32
2B43FCE&s=smtp from smtpd cache
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:SSLv3 read client hello B
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:SSLv3 write server hello A
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:SSLv3 write change cipher spec A
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:SSLv3 write finished A
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:SSLv3 flush data
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:error in SSLv3 read finished A
Aug 13 16:52:04 mailserver last message repeated 3 times
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: SSL_accept:SSLv3 read finished A
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: Reusing old session
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: TLS connection established from xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: TLSv1 wit
h cipher AES128-SHA (128/128 bits)
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: watchdog_pat: 0x6e9270
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: < xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: EHLO [xxx.xxx.xxx.13] Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-xxx.xxx.xxx.edu
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-PIPELINING
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-SIZE 102400000
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-VRFY
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_hostname: xxx.xxx.xxx.edu ~? xxx.xxx.xxx.0/24
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_hostaddr: xxx.xxx.xxx.13 ~? xxx.xxx.xxx.0/24
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: sasl_exceptions: xxx.xxx.xxx.edu[xxx.xxx.xxx.13], match=1
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_list_match: xxx.xxx.xxx.edu: no match
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_list_match: xxx.xxx.xxx.13: no match
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-ETRN
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-ENHANCEDSTATUSCODES
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250-8BITMIME
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: > xxx.xxx.xxx.edu[xxx.xxx.xxx.13]: 250 DSN
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: watchdog_pat: 0x6e9270
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: smtp_get: EOF
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_hostname: xxx.xxx.xxx.edu ~? xxx.xxx.xxx.0/24
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: match_hostaddr: xxx.xxx.xxx.13 ~? xxx.xxx.xxx.0/24
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: lost connection after EHLO from xxx.xxx.xxx.edu[xxx.xxx.xxx.13]
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: disconnect from xxx.xxx.xxx.edu[xxx.xxx.xxx.13]
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: master_notify: status 1
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: connection closed
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: watchdog_stop: 0x6e9270
Aug 13 16:52:04 mailserver postfix/smtpd[10747]: watchdog_start: 0x6e9270
Aug 13 16:52:09 mailserver postfix/smtpd[10747]: auto_clnt_close: disconnect private/tlsmgr stream
Aug 13 16:52:09 mailserver postfix/smtpd[10747]: watchdog_stop: 0x6e9270
Aug 13 16:52:09 mailserver postfix/smtpd[10747]: watchdog_start: 0x6e9270
——————
…….

Thanks in advance!

CY

[cyapple]

Some more weird informaiton

If I use Mac Mail from a different network than the mail server, all is fine, can send and receive. But if they are on the same network, Mac Mail can’t send.

Any idea?

Thx
CY

[Author]

Posts