[Bug 1549388] Re: In some rare cases Postfix connect to a wrong MX server

[Msd]

Hi,

Thank you for your help.

I have some news about this bug.

I changed the Postfix config to activate debug log.

And I have caught some errors "wrong MX error". Complete debug logs are
attached.

Here an example with all debug logs.

We can see just after a Gmail transaction, the Postfix process tries to
deliver a mail for YYYACRENNESUSERYYY at ac-rennes.fr.

And the DNS reply give the good MX answer :

Mar  1 09:35:50 ns302430 postfix/smtp[1581]: begin ac-rennes.fr address list
Mar  1 09:35:50 ns302430 postfix/smtp[1581]: pref   20 host mxsrv2.ac-rennes.fr/195.221.67.38
Mar  1 09:35:50 ns302430 postfix/smtp[1581]: pref   20 host mxsrv1.ac-rennes.fr/195.221.67.29
Mar  1 09:35:50 ns302430 postfix/smtp[1581]: end ac-rennes.fr address list

But, we can see just after that scache give a Gmail connexion !!!

Mar  1 09:35:50 ns302430 postfix/smtp[1581]: private/scache: wanted attribute: property
Mar  1 09:35:50 ns302430 postfix/smtp[1581]: input attribute name: property
Mar  1 09:35:50 ns302430 postfix/smtp[1581]: input attribute value: gmail.com?gmail-smtp-in.l.google.com?64.233.167.27?4096

Mar  1 09:35:50 ns302430 postfix/smtp[1581]: scache_clnt_find_dest: dest=smtp?*? dest_prop=gmail.com?gmail-smtp-in.l.google.com?64.233.167.27?4096 endp_prop=0?31?1456821449 fd=16
Mar  1 09:35:50 ns302430 postfix/smtp[1581]: smtp_session_activate: dest=gmail.com host=gmail-smtp-in.l.google.com addr=64.233.167.27 port=25 features=0x101f, ttl=99, reuse=1

And the process try to deliver the mail to a wrong MX server...

Mar  1 09:35:50 ns302430 postfix/smtp[1581]: > gmail-smtp-in.l.google.com[64.233.167.27]:25: RSET
Mar  1 09:35:50 ns302430 postfix/smtp[1581]: > gmail-smtp-in.l.google.com[64.233.167.27]:25: MAIL FROM:<SIGMLRA47_npai+YYYACRENNESUSERYYY=ac-rennes.fr.100827236 at mlg-events.net> SIZE=27032

Mar  1 09:35:50 ns302430 postfix/smtp[1581]: 0D5F218551A:
to=<YYYACRENNESUSERYYY at ac-rennes.fr>, relay=gmail-smtp-
in.l.google.com[64.233.167.27]:25, conn_use=2, delay=582,
delays=0.11/581/0.01/0.09, dsn=5.1.1, status=bounced (host gmail-smtp-
in.l.google.com[64.233.167.27] said: 550-5.1.1 The email account that
you tried to reach does not exist. Please try 550-5.1.1 double-checking
the recipient's email address for typos or 550-5.1.1 unnecessary spaces.
Learn more at 550 5.1.1  https://support.google.com/mail/answer/6596
x64si24468451wmx.5 - gsmtp (in reply to RCPT TO command))

Note :
1. This bug is rare : most of the time Postfix deliver emails correctly
2. Each time the bug occurs, it's when before there were a TLS error with Gmail and the connection is "scached" (private/scache) / reuse=1 / conn_use=2
3. I have all the debug logs you want and other examples of this bug for yesterday

Can this help ?

** Attachment added: "debug_log.txt"
   https://bugs.launchpad.net/ubuntu/+source/postfix/+bug/1549388/+attachment/4585498/+files/debug_log.txt

-- 
You received this bug notification because you are a member of Ubuntu
Server Team, which is subscribed to postfix in Ubuntu.
https://bugs.launchpad.net/bugs/1549388

Title:
  In some rare cases Postfix connect to a wrong MX server

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/postfix/+bug/1549388/+subscriptions