De-connection problem IMAP

[serge_pecher]

Hello,

I have an EGW 1.8.006 installation and a postfix/dovecot email server both running on the same server. (ubuntu 12.04LTS)
Sometimes, some days often, other days rarely, we loosing the connection between felalimail and the imap server. You just need to click again on “email” in the menubar and everything is working again. We don’t have it alltogether at the same time, it seems to be randomly, and not all users are subject to it with the same intensity.
The only error I can find in apache/error.log is :

[Fri Mar 14 13:24:04 2014] [error] [client 10.52.30.189] Net_IMAPProtocol::cmdExamine913not connected! (CMD:EXAMINE), referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:24:04 2014] [error] [client 10.52.30.189] PHP Fatal error: Cannot use object of type PEAR_Error as array in /usr/share/egroupware/egw-pear/Net/IMAPProtocol.php on line 1069, referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:28:35 2014] [error] [client 10.52.30.185] Net_IMAPProtocol::cmdExamine913not connected! (CMD:EXAMINE), referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:28:35 2014] [error] [client 10.52.30.185] PHP Fatal error: Cannot use object of type PEAR_Error as array in /usr/share/egroupware/egw-pear/Net/IMAPProtocol.php on line 1069, referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:28:56 2014] [error] [client 10.52.30.189] Net_IMAPProtocol::cmdExamine913not connected! (CMD:EXAMINE), referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:28:56 2014] [error] [client 10.52.30.189] PHP Fatal error: Cannot use object of type PEAR_Error as array in /usr/share/egroupware/egw-pear/Net/IMAPProtocol.php on line 1069, referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:32:35 2014] [error] [client 10.52.30.177] emailadmin_sieve::_connect76 failed for Reason:SIEVE: error in connect(10.52.30.175,2000, NULL, 1): Connection refused, referer: http://10.52.30.176/egroupware/home/index.php
[Fri Mar 14 13:34:05 2014] [error] [client 10.52.30.189] Net_IMAPProtocol::cmdExamine913not connected! (CMD:EXAMINE), referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:34:05 2014] [error] [client 10.52.30.189] PHP Fatal error: Cannot use object of type PEAR_Error as array in /usr/share/egroupware/egw-pear/Net/IMAPProtocol.php on line 1069, referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:38:53 2014] [error] [client 10.52.30.189] Net_IMAPProtocol::cmdExamine913not connected! (CMD:EXAMINE), referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:38:53 2014] [error] [client 10.52.30.189] PHP Fatal error: Cannot use object of type PEAR_Error as array in /usr/share/egroupware/egw-pear/Net/IMAPProtocol.php on line 1069, referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:44:02 2014] [error] [client 10.52.30.189] Net_IMAPProtocol::cmdExamine913not connected! (CMD:EXAMINE), referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:44:02 2014] [error] [client 10.52.30.189] PHP Fatal error: Cannot use object of type PEAR_Error as array in /usr/share/egroupware/egw-pear/Net/IMAPProtocol.php on line 1069, referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:24:04 2014] [error] [client 10.52.30.189] Net_IMAPProtocol::cmdExamine913not connected! (CMD:EXAMINE), referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Fri Mar 14 13:24:04 2014] [error] [client 10.52.30.189] PHP Fatal error: Cannot use object of type PEAR_Error as array in /usr/share/egroupware/egw-pear/Net/IMAPProtocol.php on line 1069, referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen

Can someone help me with this ?

thanks,

SP

[serge_pecher]

No idea ???

I really need a solution…

[Frantisek_Hanzlik]

serge pecher wrote:

No idea ???

I really need a solution…

Maybe You have set some dovecot resource controlling parameters (e.g.
mail_max_userip_connections
default_process_limit
default_client_limit

too low? This could have similar symptoms.
In dovecot logs You then found messages as:
… Maximum number of connections from user+IP exceeded
… process_limit (300) reached, client connections are being dropped
etc.

From my experience, one K9 Mail on Android mobile phone proved himself
consume over 100 simultaneous connections - on mail account with
roughly 10 mail folders.

HTH, Franta Hanzlik

---

---

eGroupWare-users mailing list
eGroupWare-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/egroupware-users

[serge_pecher]

Hello,

Thanks a lot four your answer.
I have 3 logs of dovecot active (messages, error and debug).
Error is empty. In the other logs I can’t find anything looking like what you suggest.
At the moment I have following error in the PHP log file :

[Wed Apr 02 09:45:32 2014] [error] [client 10.52.30.178] Net_IMAP::disconnectnot connected!, referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen
[Wed Apr 02 09:45:32 2014] [error] [client 10.52.30.178] defaultimap::disconnectnot connected!, referer: http://10.52.30.176/egroupware/index.php?menuaction=felamimail.uifelamimail.viewMainScreen

I find the following in the dovecot :

Apr 02 09:43:47 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24066, TLS
Apr 02 09:43:48 imap(sw2@sdmonv.info): Info: Connection closed bytes=1635/3818
Apr 02 09:44:38 imap-login: Info: Login: user=mr2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24151, TLS
Apr 02 09:44:38 imap(mr2@sdmonv.info): Info: Connection closed bytes=1623/3810
Apr 02 09:45:04 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24172, TLS
Apr 02 09:45:04 imap(service.b.nl2@sdmonv.info): Info: Disconnected: Logged out bytes=644/3866
Apr 02 09:45:04 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24174, TLS
Apr 02 09:45:04 imap(service.b.nl2@sdmonv.info): Info: Connection closed bytes=173/1258
Apr 02 09:45:10 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24180, TLS
Apr 02 09:45:10 imap(service.b.nl2@sdmonv.info): Info: Connection closed bytes=173/1258
Apr 02 09:45:10 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24182, TLS
Apr 02 09:45:10 imap(service.b.nl2@sdmonv.info): Info: Disconnected: Logged out bytes=620/3802
Apr 02 09:45:32 imap-login: Info: Disconnected (no auth attempts): rip=10.52.30.176, lip=10.52.30.176, TLS handshaking: SSL_accept() failed: error:14094418:SSL r
outines:SSL3_READ_BYTES:tlsv1 alert unknown ca: SSL alert number 48
Apr 02 09:45:32 imap-login: Info: Disconnected (no auth attempts): rip=10.52.30.176, lip=10.52.30.176, TLS handshaking: SSL_accept() failed: error:14094418:SSL r
outines:SSL3_READ_BYTES:tlsv1 alert unknown ca: SSL alert number 48
Apr 02 09:45:32 imap-login: Info: Disconnected (no auth attempts): rip=10.52.30.176, lip=10.52.30.176, TLS handshaking: SSL_accept() failed: error:14094418:SSL r
outines:SSL3_READ_BYTES:tlsv1 alert unknown ca: SSL alert number 48
Apr 02 09:45:36 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24194, TLS
Apr 02 09:45:36 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24198, TLS
Apr 02 09:45:36 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24202, TLS
Apr 02 09:45:36 imap(sw2@sdmonv.info): Info: Disconnected: Logged out bytes=654/2911
Apr 02 09:45:36 imap(sw2@sdmonv.info): Info: Connection closed bytes=157/1292
Apr 02 09:45:37 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24204, TLS
Apr 02 09:45:37 imap(sw2@sdmonv.info): Info: Disconnected: Logged out bytes=606/20586
Apr 02 09:45:37 imap(sw2@sdmonv.info): Info: Connection closed bytes=598/14360
Apr 02 09:45:37 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24206, TLS
Apr 02 09:45:37 imap(service.b.nl2@sdmonv.info): Info: Disconnected: Logged out bytes=620/3802
Apr 02 09:45:37 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24213, TLS
Apr 02 09:45:37 imap(service.b.nl2@sdmonv.info): Info: Connection closed bytes=173/1258
Apr 02 09:45:37 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24214, TLS
Apr 02 09:45:37 imap(sw2@sdmonv.info): Info: Connection closed bytes=1635/3818
Apr 02 09:45:38 imap-login: Info: Login: user=mr2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24216, TLS
Apr 02 09:45:38 imap(mr2@sdmonv.info): Info: Connection closed bytes=1623/3810
Apr 02 09:45:45 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24226, TLS
Apr 02 09:45:46 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24228, TLS
Apr 02 09:45:46 imap(sw2@sdmonv.info): Info: Connection closed bytes=934/19794
Apr 02 09:45:46 imap(sw2@sdmonv.info): Info: Connection closed bytes=672/17090
Apr 02 09:45:48 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24230, TLS
Apr 02 09:45:48 imap(service.b.nl2@sdmonv.info): Info: Disconnected: Logged out bytes=620/3802
Apr 02 09:45:49 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24232, TLS
Apr 02 09:45:49 imap(service.b.nl2@sdmonv.info): Info: Connection closed bytes=173/1258
Apr 02 09:45:53 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24234, TLS
Apr 02 09:45:53 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24238, TLS
Apr 02 09:45:53 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24242, TLS
Apr 02 09:45:53 imap(service.b.nl2@sdmonv.info): Info: Disconnected: Logged out bytes=346/1996
Apr 02 09:45:53 imap(service.b.nl2@sdmonv.info): Info: Connection closed bytes=171/1295
Apr 02 09:45:53 imap(service.b.nl2@sdmonv.info): Info: Disconnected: Logged out bytes=650/4329
Apr 02 09:45:53 imap-login: Info: Login: user=service.b.nl2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24244, TLS
Apr 02 09:45:54 imap(service.b.nl2@sdmonv.info): Info: Connection closed bytes=1033/2802
Apr 02 09:45:58 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24246, TLS
Apr 02 09:45:59 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24248, TLS
Apr 02 09:45:59 imap(sw2@sdmonv.info): Info: Connection closed bytes=223/2557
Apr 02 09:45:59 imap(sw2@sdmonv.info): Info: Connection closed bytes=187/3772
Apr 02 09:45:59 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24250, TLS
Apr 02 09:45:59 imap(sw2@sdmonv.info): Info: Connection closed bytes=173/1269
Apr 02 09:45:59 imap-login: Info: Login: user=sw2@sdmonv.info, method=LOGIN, rip=10.52.30.176, lip=10.52.30.176, mpid=24252, TLS
Apr 02 09:45:59 imap(sw2@sdmonv.info): Info: Disconnected: Logged out bytes=161/2613

The IP .178 is from the user sw2

It sounds to mee as a disconnection problem, but I think my problem is that it is already disconnected for an unknown reason.

If any other log can help, please tell me.

Thanks for you further help