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 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:<[email protected]> ORCPT=rfc822;[email protected]
    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 [email protected] 
    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=<[email protected]>, 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 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