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

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

 

Are you using dovecot?
What about

Code:

service dovecot restart

and logs in syslog when doing this?

 

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

 

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)

 

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)