Postfix start errors

Discussion in 'Installation/Configuration' started by stevegjacobs, May 5, 2010.

  1. stevegjacobs

    stevegjacobs New Member

    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
     
  2. till

    till Super Moderator Howtoforge Staff HowtoForge Supporter ISPConfig Developer

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

    stevegjacobs New Member

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

    till Super Moderator Howtoforge Staff HowtoForge Supporter ISPConfig Developer

    Please post the output of:

    ls -la /var/vmail/
     
  5. stevegjacobs

    stevegjacobs New Member

    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
     
    Last edited: May 5, 2010
  6. till

    till Super Moderator Howtoforge Staff HowtoForge Supporter ISPConfig Developer

    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.
     
  7. stevegjacobs

    stevegjacobs New Member

    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
    
    
     
  8. stevegjacobs

    stevegjacobs New Member

    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
     
  9. stevegjacobs

    stevegjacobs New Member

    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?
     
  10. stevegjacobs

    stevegjacobs New Member

    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.
     
  11. falko

    falko Super Moderator Howtoforge Staff Moderator HowtoForge Supporter ISPConfig Developer

    What's the output of
    Code:
    netstat -tap
    ? Make sure that amavisd is started.
     
  12. stevegjacobs

    stevegjacobs New Member

    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 *:pop3s *:* LISTEN 21148/couriertcpd
    tcp 0 0 *:pop3 *:* 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
     
  13. stevegjacobs

    stevegjacobs New Member

    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
     
  14. falko

    falko Super Moderator Howtoforge Staff Moderator HowtoForge Supporter ISPConfig Developer

    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...
     

Share This Page