Postfix + postfixadmin = SMTP errors...

Discussion in 'Server Operation' started by Rashef, Jun 23, 2009.

  1. Rashef

    Rashef New Member

    I installed Postfix with Postfixadmin some weeks ago. I've been able to solve most of issues I met (several errors from receiving servers) but there is something I cannot understand yet.

    This is what I get from a receiving SMTP server:

    Code:
    Jun 23 09:57:43 node1 postfix/smtp[3950]: send attr reason = host 
    mail.domain.it[abc.abc.abc.abc] refused to talk to me: 550 
    Jun 23 09:57:43 node1 postfix/smtp[3950]: 418E098004C2: 
    to=<xxxxx...@mediofactoring.it>, relay=mail.domain.it[abc.abc.abc.abc]: 
    25, delay=80211, delays=80211/0.03/0.45/0, dsn=4.0.0, status=deferred 
    (host mail.domain.it[abc.abc.abc.abc] refused to talk to me: 550 )
    I also tried:

    Code:
    # telnet mail.domain.it 25 
    Trying abc.abc.abc.abc... 
    Connected to mail.domain.it (abc.abc.abc.abc). 
    Escape character is '^]'. 
    550 
    Connection closed by foreign host. 
    What's that? A sort of blacklist? :(

    UPDATE
    I checked my IP at http://mxtoolbox.com/blacklists.aspx but it seems it's not blacklisted at all... :-(
     
    Last edited: Jun 23, 2009
  2. falko

    falko Super Moderator Howtoforge Staff Moderator HowtoForge Supporter ISPConfig Developer

    There might be some other blacklist where your server is on.
    Is your server hosted on a dynamic IP address?
     
  3. Rashef

    Rashef New Member

    No, the server is connected to a public IP address, it's not open relay, the SPF check is passed, the DNS reverse lookup is ok.

    I have another case when sending mail to one of the biggest italian provider. The log is long but it's the only way to understand:

    Code:
    Jun 24 17:15:30 mail postfix/smtp[12774]: smtp_parse_destination: telecomitalia.it smtp
    Jun 24 17:15:30 mail postfix/smtp[12774]: connecting to telecomitalia.it port 25
    Jun 24 17:15:30 mail postfix/smtp[12774]: dns_query: telecomitalia.it (MX): OK
    Jun 24 17:15:30 mail postfix/smtp[12774]: dns_get_answer: type MX for telecomitalia.it
    Jun 24 17:15:30 mail postfix/smtp[12774]: smtp_addr_one: host mx4.telecomitalia.it
    Jun 24 17:15:30 mail postfix/smtp[12774]: lookup mx4.telecomitalia.it type A flags 128
    Jun 24 17:15:30 mail postfix/smtp[12774]: dns_query: mx4.telecomitalia.it (A): OK
    Jun 24 17:15:30 mail postfix/smtp[12774]: dns_get_answer: type A for mx4.telecomitalia.it
    Jun 24 17:15:30 mail postfix/smtp[12774]: lookup mx4.telecomitalia.it type AAAA flags 128
    Jun 24 17:15:55 mail postfix/smtp[12774]: dns_query: mx4.telecomitalia.it (AAAA): Host found but no data record of requested type
    Jun 24 17:15:55 mail postfix/smtp[12774]: smtp_addr_one: host mx5.telecomitalia.it
    Jun 24 17:15:55 mail postfix/smtp[12774]: lookup mx5.telecomitalia.it type A flags 128
    Jun 24 17:15:55 mail postfix/smtp[12774]: dns_query: mx5.telecomitalia.it (A): OK
    Jun 24 17:15:55 mail postfix/smtp[12774]: dns_get_answer: type A for mx5.telecomitalia.it
    Jun 24 17:15:55 mail postfix/smtp[12774]: lookup mx5.telecomitalia.it type AAAA flags 128
    Jun 24 17:16:06 mail postfix/smtp[12774]: dns_query: mx5.telecomitalia.it (AAAA): Host found but no data record of requested type
    Jun 24 17:16:06 mail postfix/smtp[12774]: smtp_addr_one: host mx6.telecomitalia.it
    Jun 24 17:16:06 mail postfix/smtp[12774]: lookup mx6.telecomitalia.it type A flags 128
    Jun 24 17:16:06 mail postfix/smtp[12774]: dns_query: mx6.telecomitalia.it (A): OK
    Jun 24 17:16:06 mail postfix/smtp[12774]: dns_get_answer: type A for mx6.telecomitalia.it
    Jun 24 17:16:06 mail postfix/smtp[12774]: lookup mx6.telecomitalia.it type AAAA flags 128
    Jun 24 17:16:16 mail postfix/smtp[12774]: dns_query: mx6.telecomitalia.it (AAAA): Host found but no data record of requested type
    Jun 24 17:16:16 mail postfix/smtp[12774]: smtp_addr_one: host mx2.telecomitalia.it
    Jun 24 17:16:16 mail postfix/smtp[12774]: lookup mx2.telecomitalia.it type A flags 128
    Jun 24 17:16:16 mail postfix/smtp[12774]: dns_query: mx2.telecomitalia.it (A): OK
    Jun 24 17:16:16 mail postfix/smtp[12774]: dns_get_answer: type A for mx2.telecomitalia.it
    Jun 24 17:16:16 mail postfix/smtp[12774]: lookup mx2.telecomitalia.it type AAAA flags 128
    Jun 24 17:16:16 mail postfix/smtp[12774]: dns_query: mx2.telecomitalia.it (AAAA): Host found but no data record of requested type
    Jun 24 17:16:16 mail postfix/smtp[12774]: smtp_addr_one: host mx3.telecomitalia.it
    Jun 24 17:16:16 mail postfix/smtp[12774]: lookup mx3.telecomitalia.it type A flags 128
    Jun 24 17:16:16 mail postfix/smtp[12774]: dns_query: mx3.telecomitalia.it (A): OK
    Jun 24 17:16:16 mail postfix/smtp[12774]: dns_get_answer: type A for mx3.telecomitalia.it
    Jun 24 17:16:16 mail postfix/smtp[12774]: lookup mx3.telecomitalia.it type AAAA flags 128
    Jun 24 17:16:16 mail postfix/smtp[12774]: dns_query: mx3.telecomitalia.it (AAAA): Host found but no data record of requested type
    Jun 24 17:16:16 mail postfix/smtp[12774]: smtp_addr_one: host mx1.telecomitalia.it
    Jun 24 17:16:16 mail postfix/smtp[12774]: lookup mx1.telecomitalia.it type A flags 128
    Jun 24 17:16:16 mail postfix/smtp[12774]: dns_query: mx1.telecomitalia.it (A): OK
    Jun 24 17:16:16 mail postfix/smtp[12774]: dns_get_answer: type A for mx1.telecomitalia.it
    Jun 24 17:16:16 mail postfix/smtp[12774]: lookup mx1.telecomitalia.it type AAAA flags 128
    Jun 24 17:16:17 mail postfix/smtp[12774]: dns_query: mx1.telecomitalia.it (AAAA): Host found but no data record of requested type
    Jun 24 17:16:17 mail postfix/smtp[12774]: begin telecomitalia.it address list
    Jun 24 17:16:17 mail postfix/smtp[12774]: pref   10 host mx4.telecomitalia.it/217.169.121.15
    Jun 24 17:16:17 mail postfix/smtp[12774]: pref   10 host mx5.telecomitalia.it/217.169.121.66
    Jun 24 17:16:17 mail postfix/smtp[12774]: pref   10 host mx6.telecomitalia.it/217.169.121.66
    Jun 24 17:16:17 mail postfix/smtp[12774]: pref   20 host mx2.telecomitalia.it/156.54.233.161
    Jun 24 17:16:17 mail postfix/smtp[12774]: pref   20 host mx3.telecomitalia.it/156.54.233.162
    Jun 24 17:16:17 mail postfix/smtp[12774]: pref   20 host mx1.telecomitalia.it/156.54.233.160
    Jun 24 17:16:17 mail postfix/smtp[12774]: end telecomitalia.it address list
    Jun 24 17:16:17 mail postfix/smtp[12774]: smtp_connect_addr: trying: mx6.telecomitalia.it[217.169.121.66] port 25...
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 220 mx6.telecomitalia.it   Wed, 24 Jun 2009 17:16:17 +0200 
    Jun 24 17:16:17 mail postfix/smtp[12774]: > mx6.telecomitalia.it[217.169.121.66]:25: EHLO mail.mydomain.it
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-mx6.telecomitalia.it Hello [my.ip.addr]
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-TURN
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-SIZE 20480000
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-ETRN
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-PIPELINING
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-DSN
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-ENHANCEDSTATUSCODES
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-8bitmime
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-BINARYMIME
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-CHUNKING
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-VRFY
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-X-LINK2STATE
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250-XEXCH50
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250 OK
    Jun 24 17:16:17 mail postfix/smtp[12774]: > mx6.telecomitalia.it[217.169.121.66]:25: MAIL FROM:<john.doe@first_virtual_domain.it> SIZE=5826 BODY=7BIT
    Jun 24 17:16:17 mail postfix/smtp[12774]: > mx6.telecomitalia.it[217.169.121.66]:25: RCPT TO:<false.user@telecomitalia.it> ORCPT=rfc822;false.user@telecomitalia.it
    Jun 24 17:16:17 mail postfix/smtp[12774]: > mx6.telecomitalia.it[217.169.121.66]:25: DATA
    Jun 24 17:16:17 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250 2.1.0 john.doe@first_virtual_domain.it....Sender OK
    Jun 24 17:16:18 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250 2.1.5 false.user@telecomitalia.it 
    Jun 24 17:16:18 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 354 Start mail input; end with <CRLF>.<CRLF>
    Jun 24 17:16:18 mail postfix/smtp[12774]: > mx6.telecomitalia.it[217.169.121.66]:25: .
    Jun 24 17:16:18 mail postfix/smtp[12774]: > mx6.telecomitalia.it[217.169.121.66]:25: QUIT
    Jun 24 17:16:19 mail postfix/smtp[12774]: < mx6.telecomitalia.it[217.169.121.66]:25: 250 2.6.0  <1F71AE495A4C45979BC8B510A2EC7ECA@MYPC> Queued mail for delivery
    Jun 24 17:16:19 mail postfix/smtp[12774]: F37FB98004FC: to=<false.user@telecomitalia.it>, relay=mx6.telecomitalia.it[217.169.121.66]:25, delay=50, delays=0.01/0.02/48/1.8, dsn=2.6.0, status=sent (250 2.6.0  <1F71AE495A4C45979BC8B510A2EC7ECA@MYPC> Queued mail for delivery)
    
    It seems that after parsing all smtp servers from MX records Postfix is able to send the email. I get no errors. But the mail never arrives!!!
     
    Last edited: Jun 24, 2009
  4. falko

    falko Super Moderator Howtoforge Staff Moderator HowtoForge Supporter ISPConfig Developer

    Maybe it ended up in a spam folder?
     
  5. Rashef

    Rashef New Member

    Mails are not in their junk folder...
    Did you notice something strange in the log?
     

Share This Page