postfix cannot send mail

[Randy]

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

[Randy]

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:<receiver@hotmail.com>
Nov 26 14:47:49 loader1 postfix/smtpd[14079]: extract_addr: input: <receiver@hotmail.com>
Nov 26 14:47:49 loader1 postfix/smtpd[14079]: smtpd_check_addr: addr=receiver@hotmail.com
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 = receiver@hotmail.com
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: receiver@hotmail.com
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: receiver@hotmail.com -> receiver@hotmail.com
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 = receiver@hotmail.com
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: receiver@hotmail.com
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: `receiver@hotmail.com' -> transp=`smtp' host=`hotmail.com' rcpt=`receiver@hotmail.com' flags= class=default
Nov 26 14:47:49 loader1 postfix/smtpd[14079]: ctable_locate: install entry key receiver@hotmail.com
Nov 26 14:47:49 loader1 postfix/smtpd[14079]: extract_addr: result: receiver@hotmail.com
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: postmaster@loader2.domain.nl
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 -> postmaster@loader2.domain.nl
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 receiver@hotmail.com
Nov 26 14:47:49 loader1 postfix/smtpd[14079]: maps_find: recipient_canonical_maps: receiver@hotmail.com: 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

[Randy]

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: receiver@hotmail.com -> (not found)
Nov 26 14:47:49 loader1 postfix/smtpd[14079]: maps_find: canonical_maps: receiver@hotmail.com: 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: receiver@hotmail.com -> (not found)
Nov 26 14:47:49 loader1 postfix/smtpd[14079]: maps_find: virtual_alias_maps: receiver@hotmail.com: 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: receiver@hotmail.com -> (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=<receiver@hotmail.com>, 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

[Randy]

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

[Randy]

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

[falko]

Originally Posted by Randy

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

What's the output of

ls -la /root/ispconfig

? Do you see your new users in /etc/passwd?
The ISPConfig log file is /home/admispconfig/ispconfig/ispconfig.log.

[Randy]

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!

[Randy]

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

[Randy]

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

[falko]

Originally Posted by Randy

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

The next time you have problems please check if /root/ispconfig/.ispconfig.lock exists. If it does, delete it, then run

/root/ispconfig/php/php /root/ispconfig/scripts/writeconf.php

and post the output here.