Mail not working anymore after upgrade to Ubuntu 14.04 /ISPConfig 3.0.5.4 Patch 1

Discussion in 'Installation/Configuration' started by nunor, May 14, 2014.

  1. nunor

    nunor New Member

    Hi,

    I was running ISPConfig 3.0.5.3 on Ubuntu server 13.04.
    As it was out of support and because the Openssl bug. I've upgraded to 13.10 then to 14.04 and upgraded Ispconfig to the latest version.

    Since then emails are not working anymore (imap & smtp). I followed the perfect server guide here

    even trying to connect locally fails

    root@braveheart:/home/nunor# telnet localhost 143
    Trying 127.0.0.1...
    telnet: Unable to connect to remote host: Connection refused

    I checked the postfix main.cf / master.cf and everything looks fine

    mail.err output

    May 14 15:57:09 braveheart postfix/smtpd[24701]: fatal: no SASL authentication mechanisms

    mail.log output

    May 14 15:57:10 braveheart postfix/master[22575]: warning: process /usr/lib/postfix/smtpd pid 24701 exit status 1
    May 14 15:57:10 braveheart amavis[23921]: (23921-04-10) (!)FWD from <[email protected]> -> <[email protected]>, 451 4.5.0 From MTA() during fwd-connect (Negative greeting: at (eval 134) line 479.): id=23921-04-10
    May 14 15:57:10 braveheart amavis[23921]: (23921-04-10) Blocked MTA-BLOCKED {TempFailedInternal}, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: OCcR0IzO1upM, Hits: -1.901, size: 733, 16079 ms
    May 14 15:57:10 braveheart postfix/smtp[22580]: C2D14480F7E: to=<[email protected]>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, conn_use=10, delay=343089, delays=342752/320/0/16, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=23920-04-10 - Temporary MTA failure on relaying, From MTA() during fwd-connect (Negative greeting: at (eval 134) line 479.): id=23920-04-10 (in reply to end of DATA command))
    May 14 15:57:10 braveheart postfix/smtp[23532]: C49C348104F: to=<[email protected]>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, conn_use=10, delay=69489, delays=69152/320/0/16, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=23921-04-10 - Temporary MTA failure on relaying, From MTA() during fwd-connect (Negative greeting: at (eval 134) line 479.): id=23921-04-10 (in reply to end of DATA command))
    May 14 15:57:28 braveheart postfix/smtpd[24709]: connect from 61-221-11-210.HINET-IP.hinet.net[61.221.11.210]
    May 14 15:57:28 braveheart postfix/smtpd[24709]: warning: SASL: Connect to private/auth failed: Connection refused
    May 14 15:57:28 braveheart postfix/smtpd[24709]: fatal: no SASL authentication mechanisms
    May 14 15:57:29 braveheart postfix/master[22575]: warning: process /usr/lib/postfix/smtpd pid 24709 exit status 1
    May 14 15:57:29 braveheart postfix/master[22575]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling

    I don't understand why smtpd cannot be started. Does anyone could help?

    Many thanks,
    Nuno
     
  2. Croydon

    Croydon ISPConfig Developer ISPConfig Developer

    Are you using dovecot?
    What about
    Code:
    service dovecot restart
    and logs in syslog when doing this?
     
  3. nunor

    nunor New Member

    Hi,

    I have following when restarting dovecot:
    dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused

    Strange is that while reconfiguring ispconfig the mails works for a few seconds.

    Code:
    May 15 08:57:18 braveheart postfix/qmgr[29794]: 42BC5480860: from=<[email protected]>, size=72830, nrcpt=1 (queue active)
    May 15 08:57:18 braveheart postfix/qmgr[29794]: 95D9048053B: from=<3Pq9zUxQKBQ0t11tyrnyr465-014r2yBt11tyr.p1z07014nxxv.y7@alerts.bounces.google.com>, size=22134, nrcpt=1 (queue active)
    May 15 08:57:18 braveheart postfix/qmgr[29794]: C9D494804BB: from=<[email protected]>, size=27332, nrcpt=1 (queue active)
    May 15 08:57:19 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 15 08:57:19 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 15 08:57:19 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 15 08:57:19 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 15 08:57:19 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 15 08:57:19 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 15 08:57:19 braveheart postfix/pipe[20109]: 95D9048053B: to=<[email protected]>, relay=dovecot, delay=46602, delays=46601/0.02/0/1.1, dsn=4.3.0, status=deferred (temporary failure)
    May 15 08:57:19 braveheart postfix/pipe[20108]: 42BC5480860: to=<[email protected]>, relay=dovecot, delay=2238, delays=2237/0.03/0/1.1, dsn=4.3.0, status=deferred (temporary failure)
    May 15 08:57:19 braveheart postfix/pipe[20111]: C9D494804BB: to=<[email protected]>, relay=dovecot, delay=42424, delays=42423/0.03/0/1.1, dsn=4.3.0, status=deferred (temporary failure)
    May 15 08:58:01 braveheart CRON[20122]: (root) CMD (/usr/local/ispconfig/server/server.sh 2>&1 > /dev/null | while read line; do echo `/bin/date` "$line" >> /var/log/ispconfig/cron.log; done)
    May 15 08:58:01 braveheart CRON[20126]: (root) CMD (/usr/local/ispconfig/server/cron.sh 2>&1 > /dev/null | while read line; do echo `/bin/date` "$line" >> /var/log/ispconfig/cron.log; done)
    May 15 08:58:13 braveheart pure-ftpd: ([email protected]) [INFO] New connection from 185.32.236.149
    May 15 08:58:33 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 08:58:33 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 08:58:33 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 08:58:34 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 08:58:34 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 08:58:34 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 08:58:34 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 08:58:34 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 08:58:34 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 08:58:34 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 08:58:34 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
     
    Last edited by a moderator: Feb 1, 2015
  4. nunor

    nunor New Member

    Hi,

    I have following error in syslog when restarting dovecot.
    It's seems to be some authentication issue that prevents dovecot to run

    service dovecot restart
    stop: Unknown instance:
    dovecot start/running, process 22416

    Code:
    May 15 09:51:31 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:31 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:31 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:31 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:31 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:31 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:32 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:32 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:32 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:32 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:32 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:32 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:51:32 braveheart dovecot: master: Fatal: service(lmtp) access(/usr/lib/dovecot/lmtp) failed: No such file or directory
    May 15 09:52:01 braveheart CRON[22247]: (root) CMD (/usr/local/ispconfig/server/server.sh 2>&1 > /dev/null | while read line; do echo `/bin/date` "$line" >> /var/log/ispconfig/cron.log; done)
    May 15 09:52:01 braveheart CRON[22250]: (root) CMD (/usr/local/ispconfig/server/cron.sh 2>&1 > /dev/null | while read line; do echo `/bin/date` "$line" >> /var/log/ispconfig/cron.log; done)
    May 15 09:52:18 braveheart postfix/qmgr[29794]: 0A58E480084: from=<bounce-108_HTML-326228249-228015-1014838-73293@bounce.email.microsoftonline.com>, size=10639, nrcpt=1 (queue active)
    May 15 09:52:18 braveheart postfix/qmgr[29794]: 53CD24802E2: from=<bounce-108_HTML-326214965-228015-1014838-72910@bounce.email.microsoftonline.com>, size=11119, nrcpt=1 (queue active)        
    May 15 09:52:18 braveheart postfix/qmgr[29794]: 442F248005E: from=<[email protected]>, size=40414, nrcpt=1 (queue active)
    May 15 09:52:19 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 15 09:52:19 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 15 09:52:19 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 15 09:52:19 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 15 09:52:19 braveheart postfix/pipe[22262]: 0A58E480084: to=<[email protected]>, relay=dovecot, delay=60318, delays=60317/0.03/0/1, dsn=4.3.0, status=deferred (temporary failure)
    May 15 09:52:19 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 15 09:52:19 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 15 09:52:20 braveheart postfix/pipe[22263]: 53CD24802E2: to=<[email protected]>, relay=dovecot, delay=60305, delays=60304/0.01/0/1.1, dsn=4.3.0, status=deferred (temporary failure)
    May 15 09:52:20 braveheart postfix/pipe[22265]: 442F248005E: to=<[email protected]>, relay=dovecot, delay=60322, delays=60321/0.02/0/1.1, dsn=4.3.0, status=deferred (temporary failure)
     
    Last edited by a moderator: Feb 1, 2015
  5. nunor

    nunor New Member

    Hi,

    Yes I'm using dovecot. Here the error I receive

    root@braveheart:/home/nunor# service dovecot restart
    stop: Unknown instance:
    dovecot start/running, process 18811

    syslog output:

    May 20 10:03:09 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 20 10:03:09 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 20 10:03:09 braveheart postfix/pipe[15071]: 0AA09480857: to=<[email protected]>, relay=dovecot, delay=399537, delays=399536/0.07/0/1, dsn=4.3.0, status=deferred (temporary failure)
    May 20 10:03:09 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 20 10:03:09 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 20 10:03:09 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 20 10:03:09 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 20 10:03:09 braveheart postfix/pipe[15077]: B8FC1480BDD: to=<[email protected]>, relay=dovecot, delay=281781, delays=281780/0.04/0/1.1, dsn=4.3.0, status=deferred (temporary failure)
    May 20 10:03:09 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 20 10:03:09 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 20 10:03:09 braveheart postfix/pipe[15078]: 3C6F14808C2: to=<[email protected]>, relay=dovecot, delay=353260, delays=353259/0.06/0/1.1, dsn=4.3.0, status=deferred (temporary failure)
    May 20 10:03:09 braveheart postfix/pipe[15074]: 0324248088C: to=<[email protected]>, relay=dovecot, delay=348983, delays=348981/0.03/0/1.1, dsn=4.3.0, status=deferred (temporary failure)
    May 20 10:03:09 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 20 10:03:09 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 20 10:03:09 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 20 10:03:09 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 20 10:03:09 braveheart postfix/pipe[15082]: E8885480BBE: to=<[email protected]>, relay=dovecot, delay=243968, delays=243967/0.04/0/1.1, dsn=4.3.0, status=deferred (temporary failure)
    May 20 10:03:09 braveheart postfix/pipe[15083]: 49A1E480F6E: to=<[email protected]>, relay=dovecot, delay=1026, delays=1025/0.06/0/1, dsn=4.3.0, status=deferred (temporary failure)
    May 20 10:03:09 braveheart dovecot: lda: Error: userdb lookup: connect(/var/run/dovecot/auth-userdb) failed: Connection refused
    May 20 10:03:09 braveheart dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information.
    May 20 10:03:09 braveheart postfix/pipe[15087]: 81393480BC6: to=<[email protected]>, relay=dovecot, delay=168388, delays=168387/0.02/0/1.1, dsn=4.3.0, status=deferred (temporary failure)
     
    Last edited by a moderator: Feb 1, 2015

Share This Page