postfix cannot send mail

Discussion in 'Installation/Configuration' started by Randy, Nov 26, 2006.

  1. Randy

    Randy New Member

    Hi ,
    I've gotten so far as that I can now receive mail from the internet to a local domain. I'm using ispconfig. The thing is that If I send a mail to myself, I don't receive it (but that is a problem for later). But also I cannot send a mail to another domain.I did some verbose logging of the smtp and this is the outcome. I will also place my main.cf inhere. I hope that someone can tell me what I'm missing. E.g I noticed that I don't have a " relay_domains = $mydestination", can this be it (because I saw it in other configs in this forum).

    main.cf:
    smtpd_banner = $myhostname ESMTP $mail_name (Debian/GNU)
    biff = no
    append_dot_mydomain = no
    delay_warning_time = 4h
    myhostname = loader1.veltronix.nl
    alias_maps = hash:/etc/aliases
    alias_database = hash:/etc/aliases
    myorigin = /etc/mailname
    mydestination = /etc/postfix/local-host-names
    virtual_maps = hash:/etc/postfix/virtusertable
    relayhost =
    mynetworks = 127.0.0.0/8
    mailbox_command =
    mailbox_size_limit = 0
    recipient_delimiter = +
    inet_interfaces = all
    smtpd_sasl_local_domain =
    smtpd_sasl_auth_enable = yes
    smtpd_sasl_security_options = noanonymous
    broken_sasl_auth_clients = yes
    smtpd_recipient_restrictions = permit_sasl_authenticated,permit_mynetworks,reject_unauth_destination
    smtpd_tls_auth_only = no
    smtp_use_tls = yes
    smtpd_use_tls = yes
    smtp_tls_note_starttls_offer = yes
    smtpd_tls_key_file = /etc/postfix/ssl/smtpd.key
    smtpd_tls_cert_file = /etc/postfix/ssl/smtpd.crt
    smtpd_tls_CAfile = /etc/postfix/ssl/cacert.pem
    smtpd_tls_loglevel = 1
    smtpd_tls_received_header = yes
    smtpd_tls_session_cache_timeout = 3600s
    tls_random_source = dev:/dev/urandom
    home_mailbox = Maildir/

    mail.log = continuing in next thread
     
  2. Randy

    Randy New Member

    continued

    mail.log
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: > www.domain.nl[192.168.125.246]: 250 Ok
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: watchdog_pat: 0x80889e8
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: < www.domain.nl[192.168.125.246]: RCPT TO:<[email protected]>
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: extract_addr: input: <[email protected]>
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: smtpd_check_addr: [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: send attr request = rewrite
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: send attr rule = canonicalize
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: send attr address = [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: private/rewrite socket: wanted attribute: address
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: address
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute value: [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: private/rewrite socket: wanted attribute: (list terminator)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: (end)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: rewrite_clnt: canonicalize: [email protected] -> [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: send attr request = resolve
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: send attr address = [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: private/rewrite socket: wanted attribute: transport
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: transport
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute value: smtp
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: private/rewrite socket: wanted attribute: nexthop
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: nexthop
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute value: hotmail.com
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: private/rewrite socket: wanted attribute: recipient
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: recipient
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute value: [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: private/rewrite socket: wanted attribute: flags
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: flags
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute value: 4096
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: private/rewrite socket: wanted attribute: (list terminator)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: (end)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: resolve_clnt: `[email protected]' -> transp=`smtp' host=`hotmail.com' rcpt=`[email protected]' flags= class=default
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: ctable_locate: install entry key [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: extract_addr: result: [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: send attr request = rewrite
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: send attr rule = canonicalize
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: send attr address = postmaster
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: private/rewrite socket: wanted attribute: address
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: address
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute value: [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: private/rewrite socket: wanted attribute: (list terminator)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: (end)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: rewrite_clnt: canonicalize: postmaster -> [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: >>> START Recipient address RESTRICTIONS <<<
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: generic_checks: name=permit_sasl_authenticated
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: generic_checks: name=permit_sasl_authenticated status=1
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: >>> CHECKING RECIPIENT MAPS <<<
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: ctable_locate: leave existing entry key [email protected]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: maps_find: recipient_canonical_maps: [email protected]: not found
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? loader1.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost.loader1.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost.localdomain
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? www.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? www.domain2.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_list_match: hotmail.com: no match


    continued
     
  3. Randy

    Randy New Member

    continued

    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: maps_find: recipient_canonical_maps: @hotmail.com: not found
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: mail_addr_find: [email protected] -> (not found)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: maps_find: canonical_maps: [email protected]: not found
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? loader1.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost.loader1.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost.localdomain
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? www.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? www.domain2.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_list_match: hotmail.com: no match
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: maps_find: canonical_maps: @hotmail.com: not found
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: mail_addr_find: [email protected] -> (not found)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: maps_find: virtual_alias_maps: [email protected]: not found
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? loader1.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost.loader1.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? localhost.localdomain
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? www.domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? www.domain2.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_string: hotmail.com ~? domain.nl
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: match_list_match: hotmail.com: no match
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: maps_find: virtual_alias_maps: @hotmail.com: not found
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: mail_addr_find: [email protected] -> (not found)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: connect to subsystem public/cleanup
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: public/cleanup socket: wanted attribute: queue_id
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: queue_id
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute value: 79832AE84E0
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: public/cleanup socket: wanted attribute: (list terminator)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: (end)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: send attr flags = 50
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: 79832AE84E0: client=www.domain.nl[192.168.125.246], sasl_method=LOGIN, sasl_username=web22_domain
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: > www.domain.nl[192.168.125.246]: 250 Ok
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: watchdog_pat: 0x80889e8
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: < www.domain.nl[192.168.125.246]: DATA
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: > www.domain.nl[192.168.125.246]: 354 End data with <CR><LF>.<CR><LF>
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: public/cleanup socket: wanted attribute: status
    Nov 26 14:47:49 loader1 postfix/cleanup[14081]: 79832AE84E0: message-id=<200611261447.49284.root@localhost>
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: status
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute value: 0
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: public/cleanup socket: wanted attribute: reason
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: reason
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute value: (end)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: public/cleanup socket: wanted attribute: (list terminator)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: input attribute name: (end)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: > www.domain.nl[192.168.125.246]: 250 Ok: queued as 79832AE84E0
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: watchdog_pat: 0x80889e8
    Nov 26 14:47:49 loader1 postfix/qmgr[12652]: 79832AE84E0: from=<root@localhost>, size=530, nrcpt=1 (queue active)
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: < www.domain.nl[192.168.125.246]: QUIT
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: > www.domain.nl[192.168.125.246]: 221 Bye
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: disconnect from www.domain.nl[192.168.125.246]
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: master_notify: status 1
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: connection closed
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: watchdog_stop: 0x80889e8
    Nov 26 14:47:49 loader1 postfix/smtpd[14079]: watchdog_start: 0x80889e8
    Nov 26 14:47:50 loader1 postfix/smtp[14083]: 79832AE84E0: to=<[email protected]>, relay=mx3.hotmail.com[65.54.245.72], delay=1, status=sent (250 <200611261447.49284.root@localhost> Queued mail for delivery)
    Nov 26 14:47:50 loader1 postfix/qmgr[12652]: 79832AE84E0: removed

    The mails allways get queued somewhere. Please help because the servers have to go into production very soon and mail is the only problem I have left.
    Thanks
     
  4. Randy

    Randy New Member

    the question

    It seems that postfix is looking up hotmail somewhere (local ?) but it cannot find it as a vallid destination. If so why is it doing that and where should I ammend the main.cf so that I can send to anywhere on the internet ?

    Thanx

    Randy
     
  5. Randy

    Randy New Member

    solved... but..

    I have reinstalled postfix . I also added my isp as a mail relay because I thought that, looking at the above logs, maybe things were being blocked by the isp. (probably could have done this without the reinstallation...)
    Anyway, now the mails are being send okay, thankfully...

    But , now I noticed that when I create a new testsite, that the system-users of that site are not being created anymore. The website dirs are created in apache but no users are created that can login their webmail. Is there a way to trick ispconfig, so that items are being recreated for existing sites and users? Are there any specific logfiles from ispconfig which I could check for error messages?

    Randy
     
  6. falko

    falko Super Moderator Howtoforge Staff

    What's the output of
    Code:
    ls -la /root/ispconfig
    ? Do you see your new users in /etc/passwd?
    The ISPConfig log file is /home/admispconfig/ispconfig/ispconfig.log.
     
  7. Randy

    Randy New Member

    ls -la /root/ispconfig yields:
    total 124
    drwxr-xr-x 9 root root 4096 2006-11-26 21:35 .
    drwxr-xr-x 28 root root 4096 2006-11-27 21:38 ..
    -rwxr-xr-x 1 root root 54020 2006-11-07 15:30 cronolog
    -rwxr-xr-x 1 root root 9673 2006-11-07 15:30 cronosplit
    drwxr-xr-x 12 root root 4096 2006-11-25 12:45 httpd
    drwxr-xr-x 12 root root 4096 2006-11-07 15:30 isp
    -rw-r--r-- 1 root root 0 2006-11-27 21:50 .ispconfig_lock
    -rw-r--r-- 1 root root 16 2006-11-26 01:50 .old_path_httpd_root
    drwxr-xr-x 6 root root 4096 2006-11-25 12:45 openssl
    drwxr-xr-x 6 root root 4096 2006-11-25 12:45 php
    drwxr-xr-x 4 root root 4096 2006-11-25 17:07 scripts
    drwxr-xr-x 4 root root 4096 2006-11-07 15:30 standard_cgis
    drwxr-xr-x 2 root root 4096 2006-11-07 15:29 sv
    -rwx------ 1 root root 9389 2006-11-07 15:29 uninstall

    There are no error messages in the ispconfig.log. Beneath is part of the log file starting from the time that I started up the system and created yet another test site :

    27.11.2006 - 21:33:58 => INFO - /root/ispconfig/scripts/lib/classes/ispconfig_system.lib.php, Line 696: /etc/init.d/apache2 restart &> /dev/null
    27.11.2006 - 21:33:58 => INFO - /root/ispconfig/scripts/lib/classes/ispconfig_system.lib.php, Line 696: /etc/init.d/postfix stop &> /dev/null
    27.11.2006 - 21:33:59 => INFO - /root/ispconfig/scripts/lib/classes/ispconfig_system.lib.php, Line 696: /etc/init.d/postfix start &> /dev/null
    27.11.2006 - 21:34:01 => INFO - /root/ispconfig/scripts/lib/classes/ispconfig_system.lib.php, Line 696: /etc/init.d/bind9 restart &> /dev/null
    27.11.2006 - 21:34:03 => INFO - /root/ispconfig/scripts/lib/classes/ispconfig_system.lib.php, Line 696: /etc/init.d/proftpd restart &> /dev/null
    27.11.2006 - 21:48:01 => INFO - Signalfile Set: insert
    27.11.2006 - 21:49:20 => INFO - Signalfile Set: insert
    27.11.2006 - 21:49:56 => INFO - Signalfile Set: insert
    27.11.2006 - 21:50:07 => INFO - Signalfile Set: update

    I haven't the fogiest about this one. In the worst case senario, is it possible to reinstall ispconfig, without breaking stuff thats already working, like the dreaded mail...
    Thanks in advance for your help!
     
  8. Randy

    Randy New Member

    It seems that the only thing that gets an update is mysql. I can find the new sites in there. But bind als doesn't get an update (no zone files added). It seems like the devil is playing around, because I was about to take the servers into production,
    Please advise...
    By the way Falko, I found a very nice backup program called Mondo. Maybe you can remember that some time ago I had problems with systemimager concerning my kernel and sata drives. Mondo doesn't have that problem (always bakes images with current kernel), and can do almost everything that systemimager can. Maybe something nice for u all (if you didn't know it already).

    Greetings,

    Randy
     
  9. Randy

    Randy New Member

    Hi Falko,
    I just did an upgrade to your new version 2.2.8 . All the test sites were created instantly. Sorry that I don't have the time for in depth troubleshooting (which is ofcourse the most interesting thing to do) but I'm very much pressed for time to finish these servers. My sites have to start next month.
    Thanx
    Randy
     
  10. falko

    falko Super Moderator Howtoforge Staff

    The next time you have problems please check if /root/ispconfig/.ispconfig.lock exists. If it does, delete it, then run
    Code:
    /root/ispconfig/php/php /root/ispconfig/scripts/writeconf.php
    and post the output here.
     
  11. Randy

    Randy New Member

    Thanx Falko
     

Share This Page