Issues with Mail Setup after "successful" Centos 6_64 Perfect Server (Apache 2) setup

Discussion in 'ISPConfig 3 Priority Support' started by elijahb., Aug 27, 2013.

  1. elijahb.

    elijahb. New Member

    After a successful setup, adding domains, users, email to ISPCONFIG3 on a Centos 6_64 Perfect Server (Apache 2)

    I try to log in with a webmail I have created.
    https://example.com/webmail

    I enter username & password (I have tried with and without the domain name) only to receive the following error after a minute or two:
    ERROR: Connection dropped by IMAP server.


    The log file viewed from ISPConfig Panel reveals many errors: :eek::eek:
    Mail-Error - Log
    Code:
    Aug 27 11:27:04 s1 dovecot: log: Error: service(auth): child 8524 returned error 89 (Fatal failure)
    Aug 27 11:27:39 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=8474, input bytes=0
    Aug 27 11:27:58 s1 postfix/qmgr[1352]: 492FB12363A: from=<[email protected]>, size=506, nrcpt=1 (queue active)
    Aug 27 11:27:58 s1 postfix/qmgr[1352]: BC3D012369C: from=<>, size=2516, nrcpt=1 (queue active)
    Aug 27 11:27:58 s1 postfix/qmgr[1352]: 3D47B1236A1: from=<>, size=2529, nrcpt=1 (queue active)
    Aug 27 11:27:58 s1 postfix/smtp[8564]: connect to example.com[2606:2800:220:6d:26bf:1447:1097:aa7]:25: Network is unreachable
    Aug 27 11:27:58 s1 postfix/smtp[8565]: connect to example.com[2606:2800:220:6d:26bf:1447:1097:aa7]:25: Network is unreachable
    Aug 27 11:27:58 s1 amavis[8200]: (08200-03) (!)connect to /var/spool/amavisd/clamd.sock failed, attempt #1: Can't connect to UNIX socket /var/spool/amavisd/clamd.sock: 2
    Aug 27 11:27:59 s1 amavis[8200]: (08200-03) (!)connect to /var/spool/amavisd/clamd.sock failed, attempt #1: Can't connect to UNIX socket /var/spool/amavisd/clamd.sock: No such file or directory
    Aug 27 11:27:59 s1 amavis[8200]: (08200-03) (!)ClamAV-clamd: All attempts (1) failed connecting to /var/spool/amavisd/clamd.sock, retrying (2)
    Aug 27 11:28:01 s1 dovecot: pop3-login: Disconnected: Inactivity (no auth attempts): rip=::1, lip=::1, secured
    Aug 27 11:28:04 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:28:04 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:28:04 s1 dovecot: log: Error: service(auth): child 8584 returned error 89 (Fatal failure)
    Aug 27 11:28:05 s1 amavis[8200]: (08200-03) (!)connect to /var/spool/amavisd/clamd.sock failed, attempt #1: Can't connect to UNIX socket /var/spool/amavisd/clamd.sock: No such file or directory
    Aug 27 11:28:05 s1 amavis[8200]: (08200-03) (!)ClamAV-clamd av-scanner FAILED: run_av error: Too many retries to talk to /var/spool/amavisd/clamd.sock (All attempts (1) failed connecting to /var/spool/amavisd/clamd.sock) at (eval 103) line 600.\n
    Aug 27 11:28:05 s1 amavis[8200]: (08200-03) (!)WARN: all primary virus scanners failed, considering backups
    Aug 27 11:28:13 s1 amavis[8200]: (08200-03) (!)ClamAV-clamscan av-scanner FAILED: /usr/bin/clamscan KILLED, signal 9 (0009) at (eval 103) line 897.
    Aug 27 11:28:13 s1 amavis[8200]: (08200-03) (!!)AV: ALL VIRUS SCANNERS FAILED
    Aug 27 11:28:14 s1 postfix/smtpd[8588]: connect from unknown[127.0.0.1]
    Aug 27 11:28:24 s1 postfix/smtpd[8588]: fatal: no SASL authentication mechanisms
    Aug 27 11:28:25 s1 amavis[8200]: (08200-03) (!)FWD from <[email protected]> -> <[email protected]>, 451 4.5.0 From MTA() during fwd-connect (No greeting, dt: 11.084 s): id=08200-03
    Aug 27 11:28:25 s1 postfix/master[1343]: warning: process /usr/libexec/postfix/smtpd pid 8588 exit status 1
    Aug 27 11:28:25 s1 postfix/master[1343]: warning: /usr/libexec/postfix/smtpd: bad command startup -- throttling
    Aug 27 11:28:25 s1 amavis[8200]: (08200-03) Blocked MTA-BLOCKED {TempFailedInbound}, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: p7FeFd0c7NP4, Hits: -0.001, size: 504, 27077 ms
    Aug 27 11:28:25 s1 postfix/smtp[8558]: 492FB12363A: to=<[email protected]>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, delay=19538, delays=19511/0.04/0.01/27, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=08200-03 - Temporary MTA failure on relaying, From MTA() during fwd-connect (No greeting, dt: 11.084 s): id=08200-03 (in reply to end of DATA command))
    Aug 27 11:28:28 s1 postfix/smtp[8564]: connect to example.com[93.184.216.119]:25: Connection timed out
    Aug 27 11:28:28 s1 postfix/smtp[8565]: connect to example.com[93.184.216.119]:25: Connection timed out
    Aug 27 11:28:28 s1 postfix/smtp[8564]: BC3D012369C: to=<[email protected]>, relay=none, delay=1504, delays=1474/0.06/30/0, dsn=4.4.1, status=deferred (connect to example.com[93.184.216.119]:25: Connection timed out)
    Aug 27 11:28:28 s1 postfix/smtp[8565]: 3D47B1236A1: to=<[email protected]>, relay=none, delay=1498, delays=1468/0.03/30/0, dsn=4.4.1, status=deferred (connect to example.com[93.184.216.119]:25: Connection timed out)
    Aug 27 11:29:04 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:29:04 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:29:04 s1 dovecot: log: Error: service(auth): child 8638 returned error 89 (Fatal failure)
    Aug 27 11:30:02 s1 dovecot: imap-login: Disconnected (no auth attempts): rip=::1, lip=::1, secured
    Aug 27 11:30:02 s1 postfix/smtpd[8680]: connect from localhost.localdomain[::1]
    Aug 27 11:30:04 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:30:04 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:30:04 s1 dovecot: log: Error: service(auth): child 8704 returned error 89 (Fatal failure)
    Aug 27 11:30:04 s1 postfix/smtpd[8680]: fatal: no SASL authentication mechanisms
    Aug 27 11:30:05 s1 postfix/master[1343]: warning: process /usr/libexec/postfix/smtpd pid 8680 exit status 1
    Aug 27 11:30:05 s1 postfix/master[1343]: warning: /usr/libexec/postfix/smtpd: bad command startup -- throttling
    Aug 27 11:30:37 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=8681, input bytes=0
    Aug 27 11:31:04 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:31:04 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:31:04 s1 dovecot: log: Error: service(auth): child 8720 returned error 89 (Fatal failure)
    Aug 27 11:31:34 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=8681, input bytes=0
    Aug 27 11:31:52 s1 dovecot: imap-login: Error: Timeout waiting for handshake from auth server. my pid=8725, input bytes=0
    Aug 27 11:32:04 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=8681, input bytes=0
    Aug 27 11:32:04 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:32:04 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:32:04 s1 dovecot: log: Error: service(auth): child 8735 returned error 89 (Fatal failure)
    Aug 27 11:32:34 s1 dovecot: imap-login: Error: Timeout waiting for handshake from auth server. my pid=8725, input bytes=0
    Aug 27 11:32:39 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=8681, input bytes=0
    Aug 27 11:32:52 s1 dovecot: imap-login: Disconnected (no auth attempts): rip=::1, lip=::1, secured
    Aug 27 11:33:02 s1 dovecot: pop3-login: Disconnected: Inactivity (no auth attempts): rip=::1, lip=::1, secured
    Aug 27 11:33:04 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:33:04 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:33:04 s1 dovecot: log: Error: service(auth): child 8745 returned error 89 (Fatal failure)
    Aug 27 11:35:01 s1 dovecot: imap-login: Disconnected (no auth attempts): rip=::1, lip=::1, secured
    Aug 27 11:35:01 s1 postfix/smtpd[8789]: connect from localhost.localdomain[::1]
    Aug 27 11:35:01 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:35:01 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:35:01 s1 dovecot: log: Error: service(auth): child 8797 returned error 89 (Fatal failure)
    Aug 27 11:35:01 s1 postfix/smtpd[8789]: fatal: no SASL authentication mechanisms
    Aug 27 11:35:02 s1 postfix/master[1343]: warning: process /usr/libexec/postfix/smtpd pid 8789 exit status 1
    Aug 27 11:35:02 s1 postfix/master[1343]: warning: /usr/libexec/postfix/smtpd: bad command startup -- throttling
    Aug 27 11:35:36 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=8790, input bytes=0
    Aug 27 11:36:01 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:36:01 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:36:01 s1 dovecot: log: Error: service(auth): child 8815 returned error 89 (Fatal failure)
    Aug 27 11:36:31 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=8790, input bytes=0
    Aug 27 11:37:01 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=8790, input bytes=0
    Aug 27 11:37:01 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:37:01 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:37:01 s1 dovecot: log: Error: service(auth): child 8834 returned error 89 (Fatal failure)
    Aug 27 11:37:36 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=8790, input bytes=0
    Aug 27 11:37:58 s1 postfix/qmgr[1352]: A647C123B5B: from=<[email protected]>, size=509, nrcpt=1 (queue active)
    Aug 27 11:37:58 s1 amavis[8436]: (08436-01) (!)connect to /var/spool/amavisd/clamd.sock failed, attempt #1: Can't connect to UNIX socket /var/spool/amavisd/clamd.sock: 2
    Aug 27 11:37:59 s1 amavis[8436]: (08436-01) (!)connect to /var/spool/amavisd/clamd.sock failed, attempt #1: Can't connect to UNIX socket /var/spool/amavisd/clamd.sock: No such file or directory
    Aug 27 11:37:59 s1 amavis[8436]: (08436-01) (!)ClamAV-clamd: All attempts (1) failed connecting to /var/spool/amavisd/clamd.sock, retrying (2)
    Aug 27 11:38:01 s1 dovecot: pop3-login: Disconnected: Inactivity (no auth attempts): rip=::1, lip=::1, secured
    Aug 27 11:38:01 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:38:01 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:38:01 s1 dovecot: log: Error: service(auth): child 8847 returned error 89 (Fatal failure)
    Aug 27 11:38:05 s1 amavis[8436]: (08436-01) (!)connect to /var/spool/amavisd/clamd.sock failed, attempt #1: Can't connect to UNIX socket /var/spool/amavisd/clamd.sock: No such file or directory
    Aug 27 11:38:05 s1 amavis[8436]: (08436-01) (!)ClamAV-clamd av-scanner FAILED: run_av error: Too many retries to talk to /var/spool/amavisd/clamd.sock (All attempts (1) failed connecting to /var/spool/amavisd/clamd.sock) at (eval 103) line 600.\n
    Aug 27 11:38:05 s1 amavis[8436]: (08436-01) (!)WARN: all primary virus scanners failed, considering backups
    Aug 27 11:38:13 s1 amavis[8436]: (08436-01) (!)ClamAV-clamscan av-scanner FAILED: /usr/bin/clamscan KILLED, signal 9 (0009) at (eval 103) line 897.
    Aug 27 11:38:13 s1 amavis[8436]: (08436-01) (!!)AV: ALL VIRUS SCANNERS FAILED
    Aug 27 11:38:14 s1 postfix/smtpd[8849]: connect from unknown[127.0.0.1]
    Aug 27 11:38:24 s1 postfix/smtpd[8849]: fatal: no SASL authentication mechanisms
    Aug 27 11:38:25 s1 amavis[8436]: (08436-01) (!)FWD from <[email protected]> -> <[email protected]>, 451 4.5.0 From MTA() during fwd-connect (No greeting, dt: 11.085 s): id=08436-01
    Aug 27 11:38:25 s1 postfix/master[1343]: warning: process /usr/libexec/postfix/smtpd pid 8849 exit status 1
    Aug 27 11:38:25 s1 postfix/master[1343]: warning: /usr/libexec/postfix/smtpd: bad command startup -- throttling
    Aug 27 11:38:25 s1 amavis[8436]: (08436-01) Blocked MTA-BLOCKED {TempFailedInbound}, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: eAtYrsWoPLTI, Hits: -0.001, size: 507, 26715 ms
    Aug 27 11:38:25 s1 postfix/smtp[8835]: A647C123B5B: to=<[email protected]>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, delay=244014, delays=243987/0.01/0.01/27, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=08436-01 - Temporary MTA failure on relaying, From MTA() during fwd-connect (No greeting, dt: 11.085 s): id=08436-01 (in reply to end of DATA command))
    Aug 27 11:39:02 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 27 11:39:02 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 27 11:39:02 s1 dovecot: log: Error: service(auth): child 8862 returned error 89 (Fatal failure)
    Aug 27 11:40:02 s1 dovecot: imap-login: Disconnected (no auth attempts): rip=::1, lip=::1, secured
    
    can anyone help?!
     
  2. till

    till Super Moderator Staff Member ISPConfig Developer

    Please post your postfix main.cf and master.cf file. And clamav does not seem to be started, how much ram does the server has?
     
  3. elijahb.

    elijahb. New Member

    Files extracted from: /etc/postfix/
    Attached

    My VPS has 512MB

    Thank you kindly for your reply
     

    Attached Files:

  4. till

    till Super Moderator Staff Member ISPConfig Developer

    512 MB is not enough for a full setup with web and mail. You need at least 1GB RAM or you will have to disable the mail scanning (antivirus and spam) as described here:

    http://www.faqforge.com/linux/contr...ilter-and-antivirus-functions-in-ispconfig-3/

    If the server as not enough ram, then services like clamav / sasl and others will not start or the processes will die unexectedly and this results in mail delivery failures or websites are inaccessible.
     
  5. till

    till Super Moderator Staff Member ISPConfig Developer

    In the master.cf file, change the lines:
    Code:
    #submission inet n       -       n       -       -       smtpd
    #  -o smtpd_tls_security_level=encrypt
    #  -o smtpd_sasl_auth_enable=yes
    #  -o smtpd_client_restrictions=permit_sasl_authenticated,reject
    #  -o milter_macro_daemon_name=ORIGINATING
    #smtps     inet  n       -       n       -       -       smtpd
    #  -o smtpd_tls_wrappermode=yes
    #  -o smtpd_sasl_auth_enable=yes
    #  -o smtpd_client_restrictions=permit_sasl_authenticated,reject
    #  -o milter_macro_daemon_name=ORIGINATING
    
    to:

    Code:
    submission inet n       -       n       -       -       smtpd
      -o smtpd_tls_security_level=encrypt
      -o smtpd_sasl_auth_enable=yes
      -o smtpd_client_restrictions=permit_sasl_authenticated,reject
    #  -o milter_macro_daemon_name=ORIGINATING
    smtps     inet  n       -       n       -       -       smtpd
      -o smtpd_tls_wrappermode=yes
      -o smtpd_sasl_auth_enable=yes
      -o smtpd_client_restrictions=permit_sasl_authenticated,reject
    #  -o milter_macro_daemon_name=ORIGINATING 
    to enable smtps if you want to use it. But thats not related to your mail issue.
     
  6. elijahb.

    elijahb. New Member

    First of all thank you!

    Second - I have re-sized my VPS to have 1 GB RAM

    Third, while I am having a better looking error log after the suggested changes, I am still having troubles logging into my email (although I see now it has 50kb worth emails from the ISPConfig control panel)

    Clam I suspect is still not working!

    Browser Error: ERROR: Connection dropped by IMAP server.

    Mail-Error - Log (Server : s1.MY-DOMAIN.co)
    Code:
     19:20:49 s1 amavis[1230]: Found secondary av scanner ClamAV-clamscan at /usr/bin/clamscan
     19:20:49 s1 amavis[1230]: Deleting db files __db.001,__db.003,nanny.db,__db.002,snmp.db,__db.004 in /var/spool/amavisd/db
     19:20:49 s1 amavis[1230]: Creating db in /var/spool/amavisd/db/; BerkeleyDB 0.43, libdb 4.7
     19:20:50 s1 postfix/postfix-script[1305]: starting the Postfix mail system
     19:20:50 s1 postfix/master[1306]: daemon started -- version 2.6.6, configuration /etc/postfix
     19:20:50 s1 postfix/qmgr[1316]: CAB7C123B55: from=<[email protected]>, size=509, nrcpt=1 (queue active)
     19:20:50 s1 postfix/qmgr[1316]: CAFE6123B50: from=<[email protected]>, size=506, nrcpt=1 (queue active)
     19:20:53 s1 postfix/pickup[1315]: 3B4A3123B5E: uid=0 from=<[email protected]>
     19:20:53 s1 postfix/cleanup[1392]: 3B4A3123B5E: message-id=<[email protected]>
     19:20:53 s1 postfix/qmgr[1316]: 3B4A3123B5E: from=<[email protected]>, size=393, nrcpt=1 (queue active)
     19:20:53 s1 postfix/local[1412]: 3B4A3123B5E: to=<[email protected]>, orig_to=<root>, relay=local, delay=0.18, delays=0.14/0.04/0/0.01, dsn=2.0.0, status=sent (delivered to mailbox)
     19:20:53 s1 postfix/qmgr[1316]: 3B4A3123B5E: removed
     19:20:55 s1 amavis[1230]: DKIM signature verification disabled, corresponding features not available. If not intentional, consider enabling it by setting: $enable_dkim_verification to 1, or explicitly disable it by setting it to 0 to mute this warning.
     19:20:56 s1 postfix/smtpd[1421]: connect from unknown[127.0.0.1]
     19:20:56 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
     19:20:56 s1 dovecot: master: Error: service(auth): command startup failed, throttling
     19:20:56 s1 dovecot: log: Error: service(auth): child 1423 returned error 89 (Fatal failure)
     19:20:56 s1 postfix/smtpd[1421]: fatal: no SASL authentication mechanisms
     19:20:57 s1 postfix/smtpd[1424]: connect from unknown[127.0.0.1]
     19:20:57 s1 amavis[1414]: (01414-01) (!)FWD from <[email protected]> -> <[email protected]>, 451 4.5.0 From MTA() during fwd-connect (No greeting, dt: 1.065 s): id=01414-01
     19:20:57 s1 postfix/master[1306]: warning: process /usr/libexec/postfix/smtpd pid 1421 exit status 1
     19:20:57 s1 postfix/master[1306]: warning: /usr/libexec/postfix/smtpd: bad command startup -- throttling
     19:20:57 s1 amavis[1414]: (01414-01) Blocked MTA-BLOCKED {TempFailedInbound}, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: KJUE2B9PCRVG, Hits: -0.001, size: 504, 1816 ms
     19:20:57 s1 postfix/smtp[1318]: CAFE6123B50: to=<[email protected]>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, delay=117831, delays=117825/0.07/4.5/1.8, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=01414-01 - Temporary MTA failure on relaying, From MTA() during fwd-connect (No greeting, dt: 1.065 s): id=01414-01 (in reply to end of DATA command))
     19:21:07 s1 postfix/smtpd[1424]: fatal: no SASL authentication mechanisms
     19:21:08 s1 amavis[1413]: (01413-01) (!)FWD from <[email protected]> -> <[email protected]>, 451 4.5.0 From MTA() during fwd-connect (No greeting, dt: 11.026 s): id=01413-01
     19:21:08 s1 postfix/master[1306]: warning: process /usr/libexec/postfix/smtpd pid 1424 exit status 1
     19:21:08 s1 amavis[1413]: (01413-01) Blocked MTA-BLOCKED {TempFailedInbound}, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: N_iqU4It55jZ, Hits: -0.001, size: 507, 12787 ms
     19:21:08 s1 postfix/smtp[1317]: CAB7C123B55: to=<[email protected]>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, delay=273432, delays=273415/0.04/4.6/13, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=01413-01 - Temporary MTA failure on relaying, From MTA() during fwd-connect (No greeting, dt: 11.026 s): id=01413-01 (in reply to end of DATA command))
     19:21:56 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
     19:21:56 s1 dovecot: master: Error: service(auth): command startup failed, throttling
     19:21:56 s1 dovecot: log: Error: service(auth): child 1457 returned error 89 (Fatal failure)
     19:22:39 s1 postfix/postfix-script[1478]: stopping the Postfix mail system
     19:22:39 s1 postfix/master[1306]: terminating on signal 15
     19:22:40 s1 postfix/postfix-script[1550]: starting the Postfix mail system
     19:22:40 s1 postfix/master[1551]: daemon started -- version 2.6.6, configuration /etc/postfix
     19:22:40 s1 postfix/qmgr[1554]: 93342123B3F: from=<[email protected]>, size=624, nrcpt=1 (queue active)
     19:22:40 s1 postfix/smtpd[1558]: connect from unknown[127.0.0.1]
     19:22:50 s1 postfix/smtpd[1558]: fatal: no SASL authentication mechanisms
     19:22:51 s1 amavis[1414]: (01414-02) (!)FWD from <[email protected]> -> <[email protected]>, 451 4.5.0 From MTA() during fwd-connect (No greeting, dt: 11.040 s): id=01414-02
     19:22:51 s1 postfix/master[1551]: warning: process /usr/libexec/postfix/smtpd pid 1558 exit status 1
     19:22:51 s1 postfix/master[1551]: warning: /usr/libexec/postfix/smtpd: bad command startup -- throttling
     19:22:51 s1 amavis[1414]: (01414-02) Blocked MTA-BLOCKED {TempFailedInbound}, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: G8t0thspqMB0, Hits: 0.799, size: 624, 11541 ms
     19:22:51 s1 postfix/smtp[1555]: 93342123B3F: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=307250, delays=307239/0.01/0/12, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=01414-02 - Temporary MTA failure on relaying, From MTA() during fwd-connect (No greeting, dt: 11.040 s): id=01414-02 (in reply to end of DATA command))
     19:22:56 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
     19:22:56 s1 dovecot: master: Error: service(auth): command startup failed, throttling
     19:22:56 s1 dovecot: log: Error: service(auth): child 1563 returned error 89 (Fatal failure)
     19:25:01 s1 dovecot: imap-login: Disconnected (no auth attempts): rip=::1, lip=::1, secured
     19:25:01 s1 postfix/smtpd[1618]: connect from localhost.localdomain[::1]
     19:25:02 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
     19:25:02 s1 dovecot: master: Error: service(auth): command startup failed, throttling
     19:25:02 s1 dovecot: log: Error: service(auth): child 1627 returned error 89 (Fatal failure)
     19:25:02 s1 postfix/smtpd[1618]: fatal: no SASL authentication mechanisms
     19:25:03 s1 postfix/master[1551]: warning: process /usr/libexec/postfix/smtpd pid 1618 exit status 1
     19:25:03 s1 postfix/master[1551]: warning: /usr/libexec/postfix/smtpd: bad command startup -- throttling
     19:25:36 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=1619, input bytes=0
     19:26:02 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
     19:26:02 s1 dovecot: master: Error: service(auth): command startup failed, throttling
     19:26:02 s1 dovecot: log: Error: service(auth): child 1656 returned error 89 (Fatal failure)
     19:26:32 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=1619, input bytes=0
     19:27:02 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=1619, input bytes=0
     19:27:02 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
     19:27:02 s1 dovecot: master: Error: service(auth): command startup failed, throttling
     19:27:02 s1 dovecot: log: Error: service(auth): child 1721 returned error 89 (Fatal failure)
     19:27:37 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=1619, input bytes=0
     19:27:40 s1 postfix/qmgr[1554]: 1ABC5123BED: from=<[email protected]>, size=506, nrcpt=1 (queue active)
     19:27:40 s1 postfix/qmgr[1554]: 67455123B4E: from=<[email protected]>, size=506, nrcpt=1 (queue active)
     19:27:40 s1 postfix/qmgr[1554]: 08800123BEA: from=<[email protected]>, size=500, nrcpt=1 (queue active)
     19:27:40 s1 postfix/smtpd[1738]: connect from unknown[127.0.0.1]
     19:27:40 s1 postfix/smtpd[1740]: connect from unknown[127.0.0.1]
     19:27:43 s1 dovecot: imap-login: Error: Timeout waiting for handshake from auth server. my pid=1722, input bytes=0
     19:27:50 s1 postfix/smtpd[1738]: fatal: no SASL authentication mechanisms
     19:27:50 s1 postfix/smtpd[1740]: fatal: no SASL authentication mechanisms
     19:27:51 s1 amavis[1413]: (01413-02) (!)FWD from <[email protected]> -> <[email protected]>, 451 4.5.0 From MTA() during fwd-connect (No greeting, dt: 11.047 s): id=01413-02
     19:27:51 s1 postfix/master[1551]: warning: process /usr/libexec/postfix/smtpd pid 1738 exit status 1
     19:27:51 s1 postfix/master[1551]: warning: /usr/libexec/postfix/smtpd: bad command startup -- throttling
     19:27:51 s1 amavis[1413]: (01413-02) Blocked MTA-BLOCKED {TempFailedInbound}, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: Y7I37naSHatP, Hits: -0.001, size: 504, 11667 ms
     19:27:51 s1 postfix/master[1551]: warning: process /usr/libexec/postfix/smtpd pid 1740 exit status 1
     19:27:51 s1 amavis[1414]: (01414-03) (!)FWD from <[email protected]> -> <[email protected]>, 451 4.5.0 From MTA() during fwd-connect (No greeting, dt: 11.038 s): id=01414-03
     19:27:51 s1 amavis[1414]: (01414-03) Blocked MTA-BLOCKED {TempFailedInbound}, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: DPgVUaMVhI84, Hits: -0.001, size: 504, 11676 ms
     19:27:51 s1 postfix/smtp[1733]: 1ABC5123BED: to=<[email protected]>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, delay=185225, delays=185213/0.01/0/12, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=01413-02 - Temporary MTA failure on relaying, From MTA() during fwd-connect (No greeting, dt: 11.047 s): id=01413-02 (in reply to end of DATA command))
     19:27:51 s1 postfix/smtp[1734]: 67455123B4E: to=<[email protected]>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, delay=48914, delays=48902/0.02/0/12, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=01414-03 - Temporary MTA failure on relaying, From MTA() during fwd-connect (No greeting, dt: 11.038 s): id=01414-03 (in reply to end of DATA command))
     19:28:01 s1 dovecot: pop3-login: Disconnected: Inactivity (no auth attempts): rip=::1, lip=::1, secured
     19:28:02 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
     19:28:02 s1 dovecot: master: Error: service(auth): command startup failed, throttling
     19:28:02 s1 dovecot: log: Error: service(auth): child 1756 returned error 89 (Fatal failure)
     19:28:27 s1 amavis[1413]: (01413-03) (!)rw_loop: leaving rw loop, no progress, last event (select) 35.035 s ago
     19:28:27 s1 amavis[1413]: (01413-03) (!)FWD from <[email protected]> -> <[email protected]>, 451 4.5.0 From MTA() during fwd-connect (No greeting, dt: 35.036 s): id=01413-03
     19:28:27 s1 amavis[1413]: (01413-03) Blocked MTA-BLOCKED {TempFailedInbound}, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: UB2_sG1tUnCK, Hits: -0.001, size: 498, 35551 ms
     19:28:27 s1 postfix/smtp[1733]: 08800123BEA: to=<[email protected]>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, delay=185293, delays=185246/12/0.01/36, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=01413-03 - Temporary MTA failure on relaying, From MTA() during fwd-connect (No greeting, dt: 35.036 s): id=01413-03 (in reply to end of DATA command))
     19:28:32 s1 dovecot: imap-login: Error: Timeout waiting for handshake from auth server. my pid=1722, input bytes=0
     19:28:43 s1 dovecot: imap-login: Disconnected (no auth attempts): rip=::1, lip=::1, secured
     19:28:51 s1 postfix/smtpd[1757]: connect from unknown[127.0.0.1]
     19:29:01 s1 postfix/smtpd[1757]: fatal: no SASL authentication mechanisms
     19:29:02 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
     19:29:02 s1 dovecot: master: Error: service(auth): command startup failed, throttling
     19:29:02 s1 dovecot: log: Error: service(auth): child 1784 returned error 89 (Fatal failure)
     19:29:02 s1 postfix/master[1551]: warning: process /usr/libexec/postfix/smtpd pid 1757 exit status 1
     19:29:02 s1 postfix/master[1551]: warning: /usr/libexec/postfix/smtpd: bad command startup -- throttling
     19:30:02 s1 dovecot: imap-login: Disconnected (no auth attempts): rip=::1, lip=::1, secured
    
     
  7. till

    till Super Moderator Staff Member ISPConfig Developer

    There seem to be some config files missing that are created by ispconfig, maybe dovecot was not installed at the time ispconfig was installed or dovecot was reinstalled after you installed ispconfig. Download the ispconfig tar.gz again, unpack it and run the update.php script which is in the install folder. Choose to reconfigure services during update.
     
  8. elijahb.

    elijahb. New Member

    I followed the guide literally but did not install mailman as I have no use of it.

    UPDATE Done... and YET!

    Code:
    Aug 28 07:19:48 s1 dovecot: imap-login: Error: Timeout waiting for handshake from auth server. my pid=1588, input bytes=0
    Aug 28 07:19:48 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 28 07:19:48 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 28 07:19:48 s1 dovecot: log: Error: service(auth): child 1609 returned error 89 (Fatal failure)
    Aug 28 07:19:49 s1 dovecot: imap-login: Disconnected (no auth attempts): rip=::1, lip=::1, secured
    Aug 28 07:20:02 s1 dovecot: imap-login: Disconnected (no auth attempts): rip=::1, lip=::1, secured
    Aug 28 07:20:02 s1 postfix/smtpd[1644]: connect from localhost.localdomain[::1]
    Aug 28 07:20:12 s1 postfix/smtpd[1644]: fatal: no SASL authentication mechanisms
    Aug 28 07:20:13 s1 postfix/master[1302]: warning: process /usr/libexec/postfix/smtpd pid 1644 exit status 1
    Aug 28 07:20:13 s1 postfix/master[1302]: warning: /usr/libexec/postfix/smtpd: bad command startup -- throttling
    Aug 28 07:20:32 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=1645, input bytes=0
    Aug 28 07:20:48 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 28 07:20:48 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 28 07:20:48 s1 dovecot: log: Error: service(auth): child 1668 returned error 89 (Fatal failure)
    Aug 28 07:21:18 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=1645, input bytes=0
    Aug 28 07:21:35 s1 dovecot: imap-login: Error: Timeout waiting for handshake from auth server. my pid=1678, input bytes=0
    Aug 28 07:21:49 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=1645, input bytes=0
    Aug 28 07:21:49 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 28 07:21:49 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 28 07:21:49 s1 dovecot: log: Error: service(auth): child 1679 returned error 89 (Fatal failure)
    Aug 28 07:22:19 s1 dovecot: imap-login: Error: Timeout waiting for handshake from auth server. my pid=1678, input bytes=0
    Aug 28 07:22:23 s1 dovecot: pop3-login: Error: Timeout waiting for handshake from auth server. my pid=1645, input bytes=0
    Aug 28 07:22:35 s1 dovecot: imap-login: Disconnected (no auth attempts): rip=::1, lip=::1, secured
    Aug 28 07:22:49 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 28 07:22:49 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 28 07:22:49 s1 dovecot: log: Error: service(auth): child 1711 returned error 89 (Fatal failure)
    Aug 28 07:23:02 s1 dovecot: pop3-login: Disconnected: Inactivity (no auth attempts): rip=::1, lip=::1, secured
    Aug 28 07:23:49 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 28 07:23:49 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 28 07:23:49 s1 dovecot: log: Error: service(auth): child 1734 returned error 89 (Fatal failure)
    Aug 28 07:24:44 s1 postfix/qmgr[1311]: 4797D123678: from=<[email protected]>, size=380, nrcpt=1 (queue active)
    Aug 28 07:24:44 s1 postfix/qmgr[1311]: B0523123B62: from=<[email protected]>, size=393, nrcpt=1 (queue active)
    Aug 28 07:24:44 s1 postfix/qmgr[1311]: 9EEC612363C: from=<[email protected]>, size=380, nrcpt=1 (queue active)
    Aug 28 07:24:44 s1 clamd[1217]: No stats for Database check - forcing reload
    Aug 28 07:24:44 s1 clamd[1217]: Reading databases from /var/lib/clamav
    Aug 28 07:24:52 s1 clamd[1217]: Database correctly reloaded (2697660 signatures)
    Aug 28 07:24:52 s1 postfix/smtpd[1750]: connect from unknown[127.0.0.1]
    Aug 28 07:24:52 s1 dovecot: auth: Error: Can't open configuration file /etc/dovecot-sql.conf: No such file or directory
    Aug 28 07:24:52 s1 dovecot: master: Error: service(auth): command startup failed, throttling
    Aug 28 07:24:52 s1 dovecot: log: Error: service(auth): child 1752 returned error 89 (Fatal failure)
    Aug 28 07:24:52 s1 postfix/smtpd[1750]: fatal: no SASL authentication mechanisms
    Aug 28 07:24:53 s1 postfix/smtpd[1754]: connect from unknown[127.0.0.1]
    


    :(:(:(
    This is getting frustrating !
     
  9. till

    till Super Moderator Staff Member ISPConfig Developer

    Please post the output of:

    ls -la /etc/dovecot/

    and

    ls -la /etc/dovecot*
     
  10. elijahb.

    elijahb. New Member

    ls -la /etc/dovecot/
    Code:
    total 36
    drwxr-xr-x  3 root root 4096 Aug 28 07:10 .
    drwxr-xr-x 93 root root 4096 Aug 28 07:14 ..
    drwxr-xr-x  2 root root 4096 Aug 20 20:47 conf.d
    -rw-------  1 root root 5811 Aug 28 07:10 dovecot-sql.conf
    -r--------  1 root root 5811 Aug 28 07:10 dovecot-sql.conf~
    -rw-r--r--  1 root root  907 Aug 28 07:10 dovecot.conf
    -rw-r--r--  1 root root  907 Aug 28 07:10 dovecot.conf~
    
    and

    ls -la /etc/dovecot*

    Code:
    total 36
    drwxr-xr-x  3 root root 4096 Aug 28 07:10 .
    drwxr-xr-x 93 root root 4096 Aug 28 07:14 ..
    drwxr-xr-x  2 root root 4096 Aug 20 20:47 conf.d
    -rw-------  1 root root 5811 Aug 28 07:10 dovecot-sql.conf
    -r--------  1 root root 5811 Aug 28 07:10 dovecot-sql.conf~
    -rw-r--r--  1 root root  907 Aug 28 07:10 dovecot.conf
    -rw-r--r--  1 root root  907 Aug 28 07:10 dovecot.conf~
    
     
  11. till

    till Super Moderator Staff Member ISPConfig Developer

    Try this:

    ln -s /etc/dovecot/dovecot-sql.conf /etc/dovecot-sql.conf

    and then restart dovecot.
     

Share This Page