Loginfehler beim Open Xchange (USR-0015)

Apps & App Center
#1

Guten Tag,

nach dem Anlagen eines neuen Benutzers im OX, bekomme ich beim Login folgende Fehlermeldung:
“Ein Benutzer mit der ID “ox-test” konnte nicht im Kontext 10 gefunden werden. (USR-0015)”

Das Anlegen selbst funktioniert noch ohne Fehlermeldung. Erst beim Login wird der Nutzer nicht gefunden, obwohl er im System existiert.
Im Forum fand ich bereits den Beitrag:

Das brachte leider keinen Erfolg.

Nach weiterer Fehlersuche in den Logs habe ich folgendes gefunden:

Fehlermeldung /var/log/open-xchange/open-xchange.log.0

2022-04-04T10:56:08,364+0200 ERROR [OXWorker-0202632] com.openexchange.ajax.login.AbstractLoginRequestHandler.loginOperation(AbstractLoginRequestHandler.java:351)

 com.openexchange.database.schema=localhost_5
 com.openexchange.grizzly.method=POST
 com.openexchange.grizzly.queryString=<none>
 com.openexchange.grizzly.remoteAddress=192.168.xx.xx
 com.openexchange.grizzly.remotePort=56512
 com.openexchange.grizzly.requestURI=/ajax/login
 com.openexchange.grizzly.serverName=192.168.xx.xx
 com.openexchange.grizzly.servletPath=/ajax/login
 com.openexchange.grizzly.session=8864253491814613162.OX0
 com.openexchange.grizzly.threadName=OXWorker-0202632
 com.openexchange.grizzly.userAgent=Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:98.0) Gecko/20100101 Firefox/98.0
 com.openexchange.localhost.ipAddress=192.168.53.11
 com.openexchange.localhost.version=7.10.5-Rev33
 com.openexchange.login.authId=c74b3c3dc95f4e6694b30704761af298
 com.openexchange.login.client=open-xchange-appsuite
 com.openexchange.login.clientIp=192.168.52.20
 com.openexchange.login.login=ox-test
 com.openexchange.login.userAgent=Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:98.0) Gecko/20100101 Firefox/98.0
 com.openexchange.login.version=7.10.5-31
 com.openexchange.request.trackingId=1752656307-882031399
com.openexchange.exception.OXException: USR-0015 Categories=ERROR Message='Cannot find user with identifier ox-test in context 10.' exceptionID=-1752656307-3106827
        at com.openexchange.exception.OXExceptionFactory.create(OXExceptionFactory.java:154)
        at com.openexchange.exception.OXExceptionFactory.create(OXExceptionFactory.java:144)
        at com.openexchange.exception.OXExceptionFactory.create(OXExceptionFactory.java:117)
        at com.openexchange.groupware.ldap.LdapExceptionCode.create(LdapExceptionCode.java:205)
        at com.openexchange.groupware.ldap.RdbUserStorage.getUserId(RdbUserStorage.java:149)
        at com.openexchange.groupware.ldap.CachingUserStorage.getUserId(CachingUserStorage.java:521)
        at com.openexchange.login.internal.LoginPerformer.findUser(LoginPerformer.java:464)
        at com.openexchange.login.internal.LoginPerformer.doLogin(LoginPerformer.java:242)
        at com.openexchange.login.internal.LoginPerformer.doLogin(LoginPerformer.java:132)
        at com.openexchange.login.internal.LoginPerformer.doLogin(LoginPerformer.java:120)
        at com.openexchange.ajax.login.Login$1.doLogin(Login.java:82)
        at com.openexchange.ajax.login.AbstractLoginRequestHandler.loginOperation(AbstractLoginRequestHandler.java:207)
        at com.openexchange.ajax.login.AbstractLoginRequestHandler.loginOperation(AbstractLoginRequestHandler.java:164)
        at com.openexchange.ajax.login.Login.doLogin(Login.java:70)
        at com.openexchange.ajax.login.Login.handleRequest(Login.java:62)
        at com.openexchange.ajax.LoginServlet.doJSONAuth(LoginServlet.java:877)
        at com.openexchange.ajax.LoginServlet.doGet(LoginServlet.java:815)
        at com.openexchange.ajax.LoginServlet.doPost(LoginServlet.java:955)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:706)
        at com.openexchange.ajax.AJAXServlet.doService(AJAXServlet.java:549)
        at com.openexchange.ajax.LoginServlet.service(LoginServlet.java:788)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:791)
        at org.glassfish.grizzly.servlet.FilterChainImpl.doFilter(FilterChainImpl.java:124)
        at com.openexchange.http.grizzly.servletfilter.RequestReportingFilter.doFilter(RequestReportingFilter.java:109)
        at org.glassfish.grizzly.servlet.FilterChainImpl.doFilter(FilterChainImpl.java:114)
        at com.openexchange.http.grizzly.servletfilter.WrappingFilter.doFilter(WrappingFilter.java:195)
        at org.glassfish.grizzly.servlet.FilterChainImpl.doFilter(FilterChainImpl.java:114)
        at com.openexchange.http.grizzly.service.http.OSGiAuthFilter.doFilter(OSGiAuthFilter.java:111)
        at org.glassfish.grizzly.servlet.FilterChainImpl.doFilter(FilterChainImpl.java:114)
        at org.glassfish.grizzly.servlet.FilterChainImpl.invokeFilterChain(FilterChainImpl.java:83)
        at org.glassfish.grizzly.servlet.ServletHandler.doServletService(ServletHandler.java:202)
        at org.glassfish.grizzly.servlet.ServletHandler.service(ServletHandler.java:154)
        at com.openexchange.http.grizzly.service.http.OSGiMainHandler.service(OSGiMainHandler.java:274)
        at org.glassfish.grizzly.http.server.HttpHandler$1.run(HttpHandler.java:200)
        at com.openexchange.threadpool.internal.CustomThreadPoolExecutor$MDCProvidingRunnable.run(CustomThreadPoolExecutor.java:2593)
        at com.openexchange.threadpool.internal.CustomThreadPoolExecutor$Worker.runTask(CustomThreadPoolExecutor.java:806)
        at com.openexchange.threadpool.internal.CustomThreadPoolExecutor$Worker.run(CustomThreadPoolExecutor.java:833)
        at java.lang.Thread.run(Thread.java:748)

Ich konnte den Fehler auf einen Aufruf in einem Python-Script eingrenzen.

/var/log/univention/listener.log

04.04.22 12:26:49.344  LISTENER    ( INFO    ) : ox-user: Loading transaction 1649068009.3254783154
Traceback (most recent call last):
  File "/usr/lib/python3/dist-packages/univention/ox/listener_tools.py", line 728, in wrapper
    return self.process(func)
  File "/usr/lib/python3/dist-packages/univention/ox/listener_tools.py", line 764, in process
    entry = self._load_entry(timestamp)
  File "/usr/lib/python3/dist-packages/univention/ox/listener_tools.py", line 829, in _load_entry
    return pickle.load(fd)
UnicodeDecodeError: 'ascii' codec can't decode byte 0xa1 in position 2: ordinal not in range(128)

Der Fehler tritt im Python-Aufruf “pickle.load(fd)” auf. Alle Pakete auf dem Server sind aktuell. Es wird Python3.7 genutzt.
Bei der Fehleranalyse sind mir noch weitere Probleme aufgefallen. Jedoch vermute ich, hängen die alle mit dem Ersten zusammen.

/usr/share/univention-ox/manage_listener_queue --list

Traceback (most recent call last):
  File "/usr/share/univention-ox/manage_listener_queue", line 266, in <module>
    sys.exit(main())
  File "/usr/share/univention-ox/manage_listener_queue", line 220, in main
    handler.collect_pickle_files()
  File "/usr/share/univention-ox/manage_listener_queue", line 160, in collect_pickle_files
    dn_s
TypeError: unsupported format string passed to bytes.__format__

/usr/sbin/spamrunner

Traceback (most recent call last):
  File "/usr/sbin/spamrunner", line 47, in <module>
    from univention.ox.listener_tools import default_context, get_context_admin_user
ImportError: No module named ox.listener_tools

Wie kann ich das Problem beheben?

Vielen Dank im voraus
Mike Seliger

#2

Ich habe auch das Problem, dass neu angelegte Benutzer sich mit obiger Meldung nicht einloggen können.
Die listener-transactions bleiben in /var/spool/univention-directory-listener/ox-user/ bzw. os-groups hängen.

Ich kann die User auch nicht listen:

/opt/open-xchange/sbin/listuser -c 10 -A oxadmin -P "$(cat '/etc/ox-secrets/context10.secret')"
users in context 10 could not be listed:
Server response:
 Look-up failed. Service "OXUser_V2" is not available.
        at sun.rmi.registry.RegistryImpl.lookup(RegistryImpl.java:227)
      ...
#3

Ich habe den Mailserver, auf dem das OX installiert ist, neu gestartet. Danach ging alles wieder.
Ich musste eine Änderung im Benutzer (z.B. Stadt hinzugefügt) machen, damit er als OX-Benutzer erscheint. Die Transactions wurden abgearbeitet. Dann lief auch der Login.
Warum er sich verschluckt hat, bleibt allerdings im Dunkeln.

#4

Der Mailserver wurde bereits mehrfach neugestartet.
Auch Änderungen am Benutzer haben weiterhin nichts gebracht.

Gruß M. Seliger

Mastodon