Error:"Found multiple users with login.." - how to check ?

[peko]

Hi,
our open xchange is writing a lot of the following messages:

Found multiple users with login "USERNAME" subscribed to IMAP server "mail.bgc-jena.mpg.de:993": [2, 592]

USERNAME is always the same. I dumped a table of all users and check the field "ImapLogin", but the name only
occurs once!

Any idea which field to check ?

Bye, Peer

[peko]

Hi,

I check the log file (open-xchange.log.0) and it looks like this line is every time written, if a user logs on AND has a folder (imap) subscribed from the user. Looking at the mailserver I saw for one day ~25000 logins from the open xchange. In most cases 4 logins at the same time for a single user.
If you check multiple folders more sessions are created, but isn't it a little bit to much ?
We are using cyrus as mail backend on a dedicated server.

Bye, Peer

[Wolfgang Rosenauer]

I cannot tell anything about the error message but a comment to OX behaviour on IMAP.

OX was designed to only keep IMAP sessions open as short as required. That also means that closing and opening and also opening in parallel is a very old design decision.

Please check imap.properties which has meanwhile quite many settings to influence the behaviour.

[peko]

Hi,

thanks for the answer. Yes we found two things: The open xchange does very often a imap login. The USM client even more .
However it is not clear for me, what the error message means - or if it's simply an unexpected answer from the mail server.
In the imap and in the mail properties there is nothing "interesting" - maybe I could try to change settings from auto -> Cyrus ...

Thanks, Peer

[pro-ite]

Hi Peer,

Hi,

thanks for the answer. Yes we found two things: The open xchange does very often a imap login. The USM client even more .
However it is not clear for me, what the error message means - or if it's simply an unexpected answer from the mail server.
In the imap and in the mail properties there is nothing "interesting" - maybe I could try to change settings from auto -> Cyrus ...

Thanks, Peer

So a user has 4 logins at a time? Are these users logged in with several devices? Mail client, Appsuite, Smartphone ... For me it seems a normal level. The IP addresses could be a hint.

[peko]

No,
all logins are from the same session. You can check not only the inbox for new mails, but also other folder. This seems to trigger it.

Bye, Peer

[Carsten Hoeger]

The error message appears when the imap getacl on a specific folder is resolved to the corresponding ox internal user id. E.g. if there's an acl like

* ACL inbox johndoe@example.com lrwstipekxacd

and in this case johndoe@example.com has multiple internal matches.

Without stacktrace the exact reason is hard to guess.

[peko]

Hi,
thanks for the hint. How can I generate a stacktrace ?

Bye, Peer

[Carsten Hoeger]

The stacktrace should be in the open-xchange log file(s) and is generated at the time the error message appears. It contains file names and line numbers of the corresponding java code generating the error.

[peko]

Hi copied a few log lines, but they are not helpful for me.
Maybe you can see what's wrong ?
------
2018-10-18T16:17:56,099+0200 WARN [OXWorker-0248182] com.openexchange.imap.entity2acl.CyrusEntity2ACL.g etUserIDInternal(CyrusEntity2ACL.java:158)
Found multiple users with login "pkoch" subscribed to IMAP server "mail.bgc-jena.mpg.de:143": [2, 592]
The session user's ID is returned.
com.openexchange.ajax.action=list
com.openexchange.ajax.module=folders
com.openexchange.grizzly.method=GET
com.openexchange.grizzly.queryString=action=list&s ession=076dc7f433014e31b830501fabf5f113&sort=5&ord er=desc&left_hand_limit=0&columns=1%2C20%2C300%2C3 01%2C302%2C304%2C306&parent=default0%2FINBOX&allow ed_modules=contacts%2Ctasks%2Ccalendar%2Cmail%2C&r ight_hand_limit=65535&timezone=UTC
com.openexchange.grizzly.remoteAddress=127.0.0.1
com.openexchange.grizzly.remotePort=41420
com.openexchange.grizzly.requestURI=/ajax/folders
com.openexchange.grizzly.serverName=localhost
com.openexchange.grizzly.servletPath=/ajax/folders
com.openexchange.grizzly.threadName=OXWorker-0248182
com.openexchange.grizzly.userAgent=Open-Xchange USM HTTP Client
com.openexchange.hostname=localhost
com.openexchange.mail.accountId=0
com.openexchange.mail.host=mail.bgc-jena.mpg.de:143
com.openexchange.mail.host.remoteAddress=195.37.22 9.42
com.openexchange.mail.login=pkoch
com.openexchange.mail.session=076dc7f433014e31b830 501fabf5f113-592-1-K4bdzehnCVnV
com.openexchange.request.trackingId=1010050045-513797094
com.openexchange.session.authId=c268a3fb-0407-4ef9-9744-e17f9f3982ff
com.openexchange.session.clientId=USM-EAS
com.openexchange.session.contextId=1
com.openexchange.session.loginName=pkoch
com.openexchange.session.sessionId=076dc7f433014e3 1b830501fabf5f113
com.openexchange.session.userId=592
com.openexchange.session.userName=pkoch
2018-10-18T16:17:56,849+0200 WARN [OXWorker-0248291] com.openexchange.imap.entity2acl.CyrusEntity2ACL.g etUserIDInternal(CyrusEntity2ACL.java:158)
Found multiple users with login "pkoch" subscribed to IMAP server "mail.bgc-jena.mpg.de:143": [2, 592]
The session user's ID is returned.
com.openexchange.ajax.action=list
com.openexchange.ajax.module=folders
com.openexchange.grizzly.method=GET
com.openexchange.grizzly.queryString=action=list&s ession=076dc7f433014e31b830501fabf5f113&sort=5&ord er=desc&left_hand_limit=0&columns=1%2C20%2C300%2C3 01%2C302%2C304%2C306&parent=default0%2Fuser&allowe d_modules=contacts%2Ctasks%2Ccalendar%2Cmail%2C&ri ght_hand_limit=65535&timezone=UTC
com.openexchange.grizzly.remoteAddress=127.0.0.1
com.openexchange.grizzly.remotePort=41420
com.openexchange.grizzly.requestURI=/ajax/folders
com.openexchange.grizzly.serverName=localhost
com.openexchange.grizzly.servletPath=/ajax/folders
com.openexchange.grizzly.threadName=OXWorker-0248291
com.openexchange.grizzly.userAgent=Open-Xchange USM HTTP Client
com.openexchange.hostname=localhost
com.openexchange.mail.accountId=0
com.openexchange.mail.host=mail.bgc-jena.mpg.de:143
com.openexchange.mail.host.remoteAddress=195.37.22 9.42
com.openexchange.mail.login=pkoch
com.openexchange.mail.session=076dc7f433014e31b830 501fabf5f113-592-1-K4bdzehnCVnV
com.openexchange.request.trackingId=1010050045-513797189
com.openexchange.session.authId=c268a3fb-0407-4ef9-9744-e17f9f3982ff
com.openexchange.session.clientId=USM-EAS
com.openexchange.session.contextId=1
com.openexchange.session.loginName=pkoch
com.openexchange.session.sessionId=076dc7f433014e3 1b830501fabf5f113
com.openexchange.session.userId=592
com.openexchange.session.userName=pkoch
2018-10-18T16:17:56,857+0200 WARN [OXWorker-0248291] com.openexchange.imap.entity2acl.CyrusEntity2ACL.g etUserIDInternal(CyrusEntity2ACL.java:158)
Found multiple users with login "pkoch" subscribed to IMAP server "mail.bgc-jena.mpg.de:143": [2, 592]
The session user's ID is returned.
com.openexchange.ajax.action=list
com.openexchange.ajax.module=folders

[Carsten Hoeger]

Still hard to find the cause without access to the system.

At the beginning, you wrote that you checked imaplogin from the user table. Did you also check
for duplicates in the user_mail_account table? That's also considered.