Postfix start errors

I tried to carefully follow the tutorial for intstalling ispconfig 3 on Centos 5.4/ When I was finished everything worked great, except email. I configured an email domain and an address using ISPConfig, but no luck sending or receiving

I notice that when I start or restart postfix I am first getting this error:
May 4 18:45:55 webserver2 postfix/master[12788]: daemon started -- version 2.3.3, configuration /etc/postfix
May 4 18:46:09 webserver2 imapd: Failed to create cache file: maildirwatch (steve@somedomain.com)
May 4 18:46:09 webserver2 imapd: Error: No such file or directory


Following this, the email system simply doesnt work:

Then while doing 'tail -f /var/log/maillog' I tried to check for mail using a remote imap mail client (thunderbird):
May 4 18:20:16 webserver2 postfix/smtpd[6941]: connect from unknown[xxx.xxx.xxx.xxx]
May 4 18:20:16 webserver2 postfix/smtpd[6941]: disconnect from unknown[xxx.xxx.xxx.xxx]

Then sent a mail to my address on the new server:
May 4 18:22:59 webserver2 postfix/smtpd[11277]: connect from av.dblue.ie[yyy.yyy.yyy.yyy]
May 4 18:22:59 webserver2 postfix/smtpd[11277]: NOQUEUE: reject: RCPT from av.dblue.ie[yyy.yyy.yyy.yyy]: 554 5.7.1 <steve@somedomain.com>: Relay access denied; from=<stevej@dblue.ie> to=<steve@somedomain.com> proto=ESMTP helo=<av-scan.dblue.ie>
May 4 18:22:59 webserver2 postfix/smtpd[11277]: disconnect from av.dblue.ie[yyy.yyy.yyy.yyy]

Here is the current output of postconf -n
[root@webserver2]# postconf -n
alias_database = hash:/etc/aliases
alias_maps = hash:/etc/aliases
command_directory = /usr/sbin
config_directory = /etc/postfix
daemon_directory = /usr/libexec/postfix
debug_peer_level = 2
html_directory = no
inet_interfaces = all
mail_owner = postfix
mailq_path = /usr/bin/mailq.postfix
manpage_directory = /usr/share/man
mydestination = mail.somedomain.com,localhost,webserver.somedomain.com mail.$mydomain, www.$mydomain, ftp.$mydomain
mydomain = somedomain.com
myhostname = webserver.somedomain.com
newaliases_path = /usr/bin/newaliases.postfix
queue_directory = /var/spool/postfix
readme_directory = /usr/share/doc/postfix-2.3.3/README_FILES
sample_directory = /usr/share/doc/postfix-2.3.3/samples
sendmail_path = /usr/sbin/sendmail.postfix
setgid_group = postdrop
unknown_local_recipient_reject_code = 550

Any thoughts on what I've done wrong
thanks
Steve

 

Did you install courier or dovecot as pop3 / imap server?

 

I've installed courier - followed the 'Perfect Server Centos 5.4 tutorial

 

Please post the output of:

ls -la /var/vmail/

 

The output you asked for is below, but in the meantime I think I've messed up everything. I was running ispconfig 3.0.16 and decided to update to 3.0.21. Now the ispconfig web interface address (mydomain.com:8080) results in a blank page.

Here is the output:
# ls -la /var/vmail/
total 40
drwx------ 5 vmail vmail 4096 May 5 16:28 .
drwxr-xr-x 10 root root 4096 May 5 10:08 ..
-rw-r--r-- 1 vmail vmail 33 Apr 28 19:50 .bash_logout
-rw-r--r-- 1 vmail vmail 176 Apr 28 19:50 .bash_profile
-rw-r--r-- 1 vmail vmail 124 Apr 28 19:50 .bashrc
drwxr-xr-x 4 vmail vmail 4096 May 5 10:35 mydomain.com
-rw------- 1 vmail vmail 1382 May 5 16:28 .mailfilter
-rw-r--r-- 1 root root 1382 May 5 16:28 .mailfilter~
drwxr-xr-x 3 root root 4096 Apr 30 12:46 mailfilters
drwxr-xr-x 4 vmail vmail 4096 Apr 28 19:50 .mozilla

 

Ok. Then we should check this first. Please take a look into the apache error log file.

The output for the /var/vmail directory is ok.

By the way, why did you use centos for the install? Debian, which is the recommended linux distribution fo ispconfig, is much easier to setup. Centos is more for linux cracks which dont care if the have to compile everything manually as centos lacks a lot of software.

 

Here are a few lines from apache error log immediatly following a visit to http://mydomain.com:8080:

Code:

# tail -f error_log
[Thu May 06 10:19:49 2010] [error] [client 86.47.41.3] PHP Warning:  app::include_once() [<a href='function.include'>function.include</a>]: Failed opening '/mnt/drbd/ispconfig/interface/lib/classes/plugin.inc.php' for inclusion (include_path='.:/usr/share/pear:/usr/share/php') in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 79
[Thu May 06 10:19:49 2010] [error] [client 86.47.41.3] PHP Fatal error:  Class 'plugin' not found in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 80
[Thu May 06 10:19:49 2010] [error] [client 86.47.41.3] File does not exist: /usr/local/ispconfig/interface/web/favicon.ico
[Thu May 06 10:19:52 2010] [error] [client 86.47.41.3] File does not exist: /usr/local/ispconfig/interface/web/favicon.ico
[Thu May 06 10:19:56 2010] [error] [client 86.47.41.3] PHP Warning:  app::include_once(/mnt/drbd/ispconfig/interface/lib/classes/plugin.inc.php) [<a href='function.app-include-once'>function.app-include-once</a>]: failed to open stream: Permission denied in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 79
[Thu May 06 10:19:56 2010] [error] [client 86.47.41.3] PHP Warning:  app::include_once() [<a href='function.include'>function.include</a>]: Failed opening '/mnt/drbd/ispconfig/interface/lib/classes/plugin.inc.php' for inclusion (include_path='.:/usr/share/pear:/usr/share/php') in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 79
[Thu May 06 10:19:56 2010] [error] [client 86.47.41.3] PHP Fatal error:  Class 'plugin' not found in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 80
[Thu May 06 10:20:28 2010] [error] [client 86.47.41.3] PHP Warning:  app::include_once(/mnt/drbd/ispconfig/interface/lib/classes/plugin.inc.php) [<a href='function.app-include-once'>function.app-include-once</a>]: failed to open stream: Permission denied in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 79
[Thu May 06 10:20:28 2010] [error] [client 86.47.41.3] PHP Warning:  app::include_once() [<a href='function.include'>function.include</a>]: Failed opening '/mnt/drbd/ispconfig/interface/lib/classes/plugin.inc.php' for inclusion (include_path='.:/usr/share/pear:/usr/share/php') in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 79
[Thu May 06 10:20:28 2010] [error] [client 86.47.41.3] PHP Fatal error:  Class 'plugin' not found in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 80

 

At this point I think I probably should have started with debian, but this project didn't start out with ispconfig in mind.

Here are the apache error logs following an attempted login at http://mydomain.com:8080:

Code:

# tail -f error_log
[Thu May 06 10:19:49 2010] [error] [client 86.47.41.3] PHP Warning:  app::include_once() [<a href='function.include'>function.include</a>]: Failed opening '/mnt/drbd/ispconfig/interface/lib/classes/plugin.inc.php' for inclusion (include_path='.:/usr/share/pear:/usr/share/php') in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 79
[Thu May 06 10:19:49 2010] [error] [client 86.47.41.3] PHP Fatal error:  Class 'plugin' not found in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 80
[Thu May 06 10:19:49 2010] [error] [client 86.47.41.3] File does not exist: /usr/local/ispconfig/interface/web/favicon.ico
[Thu May 06 10:19:52 2010] [error] [client 86.47.41.3] File does not exist: /usr/local/ispconfig/interface/web/favicon.ico
[Thu May 06 10:19:56 2010] [error] [client 86.47.41.3] PHP Warning:  app::include_once(/mnt/drbd/ispconfig/interface/lib/classes/plugin.inc.php) [<a href='function.app-include-once'>function.app-include-once</a>]: failed to open stream: Permission denied in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 79
[Thu May 06 10:19:56 2010] [error] [client 86.47.41.3] PHP Warning:  app::include_once() [<a href='function.include'>function.include</a>]: Failed opening '/mnt/drbd/ispconfig/interface/lib/classes/plugin.inc.php' for inclusion (include_path='.:/usr/share/pear:/usr/share/php') in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 79
[Thu May 06 10:19:56 2010] [error] [client 86.47.41.3] PHP Fatal error:  Class 'plugin' not found in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 80
[Thu May 06 10:20:28 2010] [error] [client 86.47.41.3] PHP Warning:  app::include_once(/mnt/drbd/ispconfig/interface/lib/classes/plugin.inc.php) [<a href='function.app-include-once'>function.app-include-once</a>]: failed to open stream: Permission denied in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 79
[Thu May 06 10:20:28 2010] [error] [client 86.47.41.3] PHP Warning:  app::include_once() [<a href='function.include'>function.include</a>]: Failed opening '/mnt/drbd/ispconfig/interface/lib/classes/plugin.inc.php' for inclusion (include_path='.:/usr/share/pear:/usr/share/php') in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 79
[Thu May 06 10:20:28 2010] [error] [client 86.47.41.3] PHP Fatal error:  Class 'plugin' not found in /mnt/drbd/ispconfig/interface/lib/app.inc.php on line 80

 

On the upgrade of ispconfig, it seemed to leave some files with incorrect ownership. I changed /interface/lib/classes/plugin.inc.php from being owned by root to ispconfig:ispconfig and the site seems to be working again. Could all files in the ispconfig folder safely be chowned to ispconfig:ispconfig?

 

Still cannot send or receive email. Some troubleshooting output - mail log after trying to send myself an email from squirrelmail:
May 6 12:36:41 webserver2 imapd: Connection, ip=[::ffff:127.0.0.1]
May 6 12:36:41 webserver2 imapd: LOGIN, user=steve@bordnamona.ie, ip=[::ffff:127.0.0.1], port=[41053], protocol=IMAP
May 6 12:36:41 webserver2 postfix/pickup[706]: E20A2211002C: uid=48 from=<steve@bordnamona.ie>
May 6 12:36:41 webserver2 postfix/cleanup[19299]: E20A2211002C: message-id=<52286.86.47.41.3.1273145801.squirrel@78.153.221.52>
May 6 12:36:41 webserver2 postfix/qmgr[707]: E20A2211002C: from=<steve@bordnamona.ie>, size=796, nrcpt=1 (queue active)
May 6 12:36:41 webserver2 postfix/smtp[19304]: connect to 127.0.0.1[127.0.0.1]: Connection refused (port 10024)
May 6 11:36:41 webserver2 postfix/smtp[19304]: E20A2211002C: to=<steve@bordnamona.ie>, relay=none, delay=0.03, delays=0.02/0.01/0/0, dsn=4.4.1, status=deferred (connect to 127.0.0.1[127.0.0.1]: Connection refused)
May 6 12:36:41 webserver2 imapd: LOGOUT, user=steve@bordnamona.ie, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=756, sent=203, time=0
May 6 12:36:42 webserver2 imapd: Connection, ip=[::ffff:127.0.0.1]
May 6 12:36:42 webserver2 imapd: LOGIN, user=steve@bordnamona.ie, ip=[::ffff:127.0.0.1], port=[41057], protocol=IMAP
May 6 12:36:42 webserver2 imapd: LOGOUT, user=steve@bordnamona.ie, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=119, sent=900, time=0
May 6 12:37:02 webserver2 imapd: Connection, ip=[::ffff:127.0.0.1]
May 6 12:37:02 webserver2 imapd: LOGIN, user=steve@bordnamona.ie, ip=[::ffff:127.0.0.1], port=[41058], protocol=IMAP
May 6 12:37:02 webserver2 imapd: LOGOUT, user=steve@bordnamona.ie, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=87, sent=391, time=0

It didn't come through to the squirrelmail inbox.

 

What's the output of

Code:

netstat -tap

? Make sure that amavisd is started.

 

Hmm... amavisd won't start. Not sure why - here is what happens
[root@webserver2 ~]# service amavisd start
Starting Mail Virus Scanner (amavisd): [ OK ]
[root@webserver2 ~]# service amavisd status
amavisd dead but subsys locked
amavis-milter is stopped

Here is the output of netstat -tap
[root@webserver2 ~]# netstat -tap
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name
tcp 0 0 localhost.localdomain:2208 *:* LISTEN 4156/hpiod
tcp 0 0 *:esro-emsdp *:* LISTEN 3852/rpc.statd
tcp 0 0 localhost.localdomain:10025 *:* LISTEN 704/master
tcp 0 0 *:mysql *:* LISTEN 20738/mysqld
tcp 0 0 localhost.local:dyna-access *:* LISTEN 4494/clamd
tcp 0 0 *:sunrpc *:* LISTEN 3775/portmap
tcp 0 0 *:smtps *:* LISTEN 704/master
tcp 0 0 192.168.1.115:domain *:* LISTEN 21196/mydns
tcp 0 0 221-50.colo.sta.blac:domain *:* LISTEN 21196/mydns
tcp 0 0 221-53.colo.sta.blac:domain *:* LISTEN 21196/mydns
tcp 0 0 webserver2.bordnamon:domain *:* LISTEN 21196/mydns
tcp 0 0 localhost.localdomai:domain *:* LISTEN 21196/mydns
tcp 0 0 *:ftp *:* LISTEN 21181/pure-ftpd (SE
tcp 0 0 localhost.localdomain:ipp *:* LISTEN 4183/cupsd
tcp 0 0 *:smtp *:* LISTEN 704/master
tcp 0 0 localhost.localdomain:2207 *:* LISTEN 4161/python
tcp 0 0 192.168.1.115:35860 192.168.1.125:7788 ESTABLISHED -
tcp 0 0 192.168.1.115:7788 192.168.1.125:51681 ESTABLISHED -
tcp 0 0 *:imaps *:* LISTEN 21136/couriertcpd
tcp 0 0 *op3s *:* LISTEN 21148/couriertcpd
tcp 0 0 *op3 *:* LISTEN 21142/couriertcpd
tcp 0 0 *:imap *:* LISTEN 21129/couriertcpd
tcp 0 0 *:webcache *:* LISTEN 16580/httpd
tcp 0 0 *:http *:* LISTEN 16580/httpd
tcp 0 0 *:tproxy *:* LISTEN 16580/httpd
tcp 0 0 localhost6.localdoma:domain *:* LISTEN 21196/mydns
tcp 0 0 *:ftp *:* LISTEN 21181/pure-ftpd (SE
tcp 0 0 *:ssh *:* LISTEN 4174/sshd
tcp 0 2848 221-50.colo.sta.blackni:ssh ::ffff:86.47.41.3:58482 ESTABLISHED 11110/4
tcp 0 0 221-50.colo.sta.blackni:ssh ::ffff:86.47.41.3:65507 ESTABLISHED 6572/3
tcp 0 0 221-50.colo.sta.blackn:imap ::ffff:86.47.41.3:49894 ESTABLISHED 11314/couriertls
tcp 0 0 webserver2.bordnamona.i:ssh ::ffff:86.47.41.3:52136 ESTABLISHED 12561/5

 

In the interest of putting together a stable system, we are just about ready to give up on getting Centos to work and starting over with Debian, or perhaps the latest Ubuntu server, based on the 'How-to' that Falko published yesterday. Any thoughts, opinions or suggestions on this?

Thanks
Steve

 

Have you tried to restart the system?

As we test on Debian most of the time, Debian would be the best choice in my opinion. Ubuntu is good as well, as it is based on Debian, but from my experience Debian seems a little more stable...