Project

General

Profile

Bug #634

Problem With Postfix Receiving eMail From External Mail Servers

Added by Daniel Curtis about 5 years ago. Updated about 5 years ago.

Status:
Closed
Priority:
Urgent
Assignee:
Category:
Mail Server
Target version:
Start date:
07/17/2015
Due date:
% Done:

100%

Estimated time:
0.10 h
Spent time:

Description

I encountered a problem with iRedMail running postfix and it not being able to receive email from certain external mail servers.

The error I received from my email client (Thunderbird) said the following:

----- Transcript of session follows -----
... while talking to mx1.example.com.:
>>> DATA
<<< 553 5.7.1 <user1@example.com>: Sender address rejected: not logged in
550 5.1.1 <user2@example.net>... User unknown
<<< 554 5.5.1 Error: no valid recipients

And the log file on the mail server stated:

Jul 17 16:18:02 mx1 postfix/qmgr[60164]: F3E7A123A3A: from=<>, size=2865, nrcpt=1 (queue active)
Jul 17 16:18:02 mx1 postfix/cleanup[71309]: 06D04123A3B: message-id=<dovecot-1437175080-971579-0@mx1.example.com>
Jul 17 16:18:02 mx1 postfix/local[67463]: F3E7A123A3A: to=<root@mx1.example.com>, relay=local, delay=0.17, delays=0.15/0/0/0.02, dsn=2.0.0, status=sent (forwarded as 06D04123A3B)
Jul 17 16:18:02 mx1 postfix/qmgr[60164]: 06D04123A3B: from=<>, size=3003, nrcpt=1 (queue active)
Jul 17 16:18:02 mx1 amavis[68254]: (68254-13) Passed CLEAN {RelayedInternal}, LOCAL [127.0.0.1] <> -> <root@mx1.example.com>, Message-ID: <dovecot-1437175080-971579-0@mx1.example.com>, mail_id: vDpv6L-DvP3w, Hits: -0.001, size: 2382, queued_as: F3E7A123A3A, 836 ms
Jul 17 16:18:02 mx1 postfix/qmgr[60164]: F3E7A123A3A: removed
Jul 17 16:18:02 mx1 postfix/smtp[70981]: 1273F123A2C: to=<root@mx1.example.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=1.1, delays=0.19/0/0.01/0.86, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as F3E7A123A3A)
Jul 17 16:18:02 mx1 postfix/qmgr[60164]: 1273F123A2C: removed
Jul 17 16:18:02 mx1 postfix/pipe[64826]: 06D04123A3B: to=<postmaster@example.com>, relay=dovecot, delay=0.12, delays=0.02/0/0/0.1, dsn=2.0.0, status=sent (delivered via dovecot service)
Jul 17 16:18:02 mx1 postfix/qmgr[60164]: 06D04123A3B: removed
Jul 17 16:19:00 mx1 postfix/pickup[70686]: 6AC3A123A3E: uid=0 from=<root>
Jul 17 16:19:00 mx1 postfix/cleanup[71309]: 6AC3A123A3E: message-id=<20150717231900.6AC3A123A3E@mx1.example.com>
Jul 17 16:19:00 mx1 postfix/qmgr[60164]: 6AC3A123A3E: from=<root@mx1.example.com>, size=540, nrcpt=1 (queue active)
Jul 17 16:19:00 mx1 postfix/smtpd[68755]: connect from mx1.example.com[127.0.0.1]
Jul 17 16:19:00 mx1 postfix/smtpd[68755]: E67F1123A44: client=mx1.example.com[127.0.0.1]
Jul 17 16:19:00 mx1 postfix/cleanup[71309]: E67F1123A44: message-id=<20150717231900.6AC3A123A3E@mx1.example.com>
Jul 17 16:19:00 mx1 postfix/smtpd[68755]: disconnect from mx1.example.com[127.0.0.1]
Jul 17 16:19:00 mx1 postfix/qmgr[60164]: E67F1123A44: from=<root@mx1.example.com>, size=1023, nrcpt=1 (queue active)
Jul 17 16:19:00 mx1 postfix/cleanup[71309]: EA8FE123A45: message-id=<20150717231900.6AC3A123A3E@mx1.example.com>
Jul 17 16:19:00 mx1 postfix/local[67463]: E67F1123A44: to=<root@mx1.example.com>, relay=local, delay=0.08, delays=0.06/0/0/0.02, dsn=2.0.0, status=sent (forwarded as EA8FE123A45)
Jul 17 16:19:00 mx1 postfix/qmgr[60164]: EA8FE123A45: from=<root@mx1.example.com>, size=1161, nrcpt=1 (queue active)
Jul 17 16:19:00 mx1 postfix/qmgr[60164]: E67F1123A44: removed
Jul 17 16:19:00 mx1 amavis[66590]: (66590-19) Passed CLEAN {RelayedInternal}, MYUSERS LOCAL [127.0.0.1] <root@mx1.example.com> -> <root@mx1.example.com>, Message-ID: <20150717231900.6AC3A123A3E@mx1.example.com>, mail_id: wRldy-mpdjOD, Hits: -0.001, size: 540, queued_as: E67F1123A44, 512 ms
Jul 17 16:19:00 mx1 postfix/smtp[70677]: 6AC3A123A3E: to=<root@mx1.example.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.58, delays=0.04/0/0.01/0.53, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as E67F1123A44)
Jul 17 16:19:00 mx1 postfix/qmgr[60164]: 6AC3A123A3E: removed
Jul 17 16:19:01 mx1 postfix/pickup[70686]: 3F350123A49: uid=2000 from=<>
Jul 17 16:19:01 mx1 postfix/pipe[68637]: EA8FE123A45: to=<postmaster@example.com>, relay=dovecot, delay=0.3, delays=0.01/0/0/0.29, dsn=2.0.0, status=sent (delivered via dovecot service)
Jul 17 16:19:01 mx1 postfix/qmgr[60164]: EA8FE123A45: removed
Jul 17 16:19:01 mx1 postfix/cleanup[71309]: 3F350123A49: message-id=<dovecot-1437175141-40596-0@mx1.example.com>
Jul 17 16:19:01 mx1 postfix/qmgr[60164]: 3F350123A49: from=<>, size=2381, nrcpt=1 (queue active)
Jul 17 16:19:01 mx1 postfix/smtpd[68652]: connect from mx1.example.com[127.0.0.1]
Jul 17 16:19:02 mx1 postfix/smtpd[68652]: 00472123A5A: client=mx1.example.com[127.0.0.1]
Jul 17 16:19:02 mx1 postfix/cleanup[71309]: 00472123A5A: message-id=<dovecot-1437175141-40596-0@mx1.example.com>
Jul 17 16:19:02 mx1 postfix/smtpd[68652]: disconnect from mx1.example.com[127.0.0.1]
Jul 17 16:19:02 mx1 postfix/qmgr[60164]: 00472123A5A: from=<>, size=2864, nrcpt=1 (queue active)
Jul 17 16:19:02 mx1 postfix/cleanup[71309]: 0655B123A5B: message-id=<dovecot-1437175141-40596-0@mx1.example.com>
Jul 17 16:19:02 mx1 postfix/local[67463]: 00472123A5A: to=<root@mx1.example.com>, relay=local, delay=0.1, delays=0.08/0/0/0.02, dsn=2.0.0, status=sent (forwarded as 0655B123A5B)
Jul 17 16:19:02 mx1 postfix/qmgr[60164]: 0655B123A5B: from=<>, size=3002, nrcpt=1 (queue active)
Jul 17 16:19:02 mx1 postfix/qmgr[60164]: 00472123A5A: removed
Jul 17 16:19:02 mx1 amavis[70493]: (70493-04) Passed CLEAN {RelayedInternal}, LOCAL [127.0.0.1] <> -> <root@mx1.example.com>, Message-ID: <dovecot-1437175141-40596-0@mx1.example.com>, mail_id: vVqpsCZDVVTd, Hits: -0.001, size: 2381, queued_as: 00472123A5A, 682 ms
Jul 17 16:19:02 mx1 postfix/smtp[70981]: 3F350123A49: to=<root@mx1.example.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.98, delays=0.27/0/0.01/0.7, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 00472123A5A)
Jul 17 16:19:02 mx1 postfix/qmgr[60164]: 3F350123A49: removed
Jul 17 16:19:02 mx1 postfix/pipe[64826]: 0655B123A5B: to=<postmaster@example.com>, relay=dovecot, delay=0.12, delays=0.01/0/0/0.1, dsn=2.0.0, status=sent (delivered via dovecot service)
Jul 17 16:19:02 mx1 postfix/qmgr[60164]: 0655B123A5B: removed
Jul 17 16:19:02 mx1 postfix/smtpd[71508]: connect from atl4mhob04.myregisteredsite.com[209.17.115.42]
Jul 17 16:19:03 mx1 postfix/smtpd[71508]: NOQUEUE: reject: RCPT from atl4mhob04.myregisteredsite.com[209.17.115.42]: 553 5.7.1 <user1@example.com>: Sender address rejected: not logged in; from=<user1@example.com> to=<user2@example.net> proto=ESMTP helo=<atl4mhob04.myregisteredsite.com>
Jul 17 16:19:04 mx1 postfix/smtpd[71508]: disconnect from atl4mhob04.myregisteredsite.com[209.17.115.42]

  • The problem turned out that I needed to edit the postfix main.cf file:
    vi /usr/local/etc/postfix/main.cf
    
    • And remove remove the reject_sender_login_mismatch
      # Removed reject_sender_login_mismatch option
      smtpd_sender_restrictions = reject_unknown_sender_domain, reject_non_fqdn_sender
      , reject_unlisted_sender, permit_mynetworks, permit_sasl_authenticated
      

Resources

History

#1 Updated by Daniel Curtis about 5 years ago

  • Status changed from New to Resolved
  • % Done changed from 0 to 100

#2 Updated by Daniel Curtis about 5 years ago

  • Status changed from Resolved to Closed

Also available in: Atom PDF