[ 
https://issues.apache.org/jira/browse/OPENMEETINGS-2301?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17093970#comment-17093970
 ] 

Konstantin Kuzov commented on OPENMEETINGS-2301:
------------------------------------------------

It happens on both 5.0.0-M4 and 5.0.0-M5-SNAPSHOT around april 26. The problem 
presents itself only around 10% in my setup at random. It happens when you try 
to enter any room. If error doesn't trigger then just exit the room and try to 
enter again. Maybe try to alternating with different rooms and not the same one 
every time. I'm assuming glancing briefly at the code there is some race 
between adding client to a room and ajax responder changes introduced in [this 
commit|https://github.com/apache/openmeetings/commit/ec83d0efd339ad17707c4020d39d5490d261445a#diff-30370611bfba8228bd57d8a777a16671].
 But I'm unfamiliar with codebase to say for sure.

I'm currently rolled back to 5.0.0-M3 which doesn't exhibit that problem. I'll 
try to setup separate instance today hopefully with some debugging capabilities 
to narrow down the problem.

> Sporadic internal error on room entering
> ----------------------------------------
>
>                 Key: OPENMEETINGS-2301
>                 URL: https://issues.apache.org/jira/browse/OPENMEETINGS-2301
>             Project: Openmeetings
>          Issue Type: Bug
>    Affects Versions: 5.0.0-M4
>            Reporter: Konstantin Kuzov
>            Assignee: Maxim Solodovnik
>            Priority: Major
>
> Sometimes when entering a room it throws Internal error page.
> {code:java}
> DEBUG 04-26 05:00:31.359 o.a.o.w.a.ClientManager:386 [nio-5443-exec-2] - 
> Cluster:: The only server found
> DEBUG 04-26 05:00:31.360 o.a.o.w.c.MainPanel:401 [nio-5443-exec-2] - 
> updateContents:: npanels IS null ? false, client IS null ? false
> DEBUG 04-26 05:00:31.376 o.a.o.w.r.RoomPanel:345 [nio-5443-exec-2] - public ? 
> true, ownedId ? null true
> DEBUG 04-26 05:00:31.377 o.a.o.d.d.l.ConferenceLogDao:52 [nio-5443-exec-2] - 
> [add]: org.apache.openmeetings.db.entity.log.ConferenceLog@4abadb1e
> DEBUG 04-26 05:00:31.400 o.a.o.w.a.ClientManager:232 [nio-5443-exec-2] - 
> Adding online room client: 170bfba9-781c-40da-91b0-6db499ee5679, room: 3
> DEBUG 04-26 05:00:31.402 o.a.o.w.a.ClientManager:250 [nio-5443-exec-2] - 
> Cluster:: room 3 was not found for server 'server-1', adding ...
> DEBUG 04-26 05:00:31.403 o.a.o.w.a.ClientManager:479 [nio-5443-exec-2] - 
> Cluster:: room 3 is added to server, whole list [3]
> DEBUG 04-26 05:00:31.403 o.a.o.w.a.ClientManager:117 [erver-1.event-1] - 
> Cluster:: Server was updated server-1 -> ServerInfo[rooms: [3]]
> DEBUG 04-26 05:00:31.405 o.a.o.d.u.AuthLevelUtil:40 [nio-5443-exec-2] - Level 
> ADMIN :: [GRANTED]
>  INFO 04-26 05:00:31.406 o.a.o.w.r.RoomPanel:394 [nio-5443-exec-2] - Setting 
> rights for client:: [SUPER_MODERATOR] -> true
> DEBUG 04-26 05:00:31.407 o.a.o.d.u.AuthLevelUtil:40 [nio-5443-exec-2] - Level 
> ADMIN :: [GRANTED]
> DEBUG 04-26 05:00:31.409 o.a.o.d.d.r.PollDao:87 [nio-5443-exec-2] -  :: 
> getPoll :: 3
> DEBUG 04-26 05:00:31.415 o.a.o.d.d.r.PollDao:98 [nio-5443-exec-2] -  :: 
> getArchived :: 3
> DEBUG 04-26 05:00:31.440 o.a.o.d.d.f.FileItemDao:55 [nio-5443-exec-2] - 
> getByOwner() started
> DEBUG 04-26 05:00:31.446 o.a.o.d.d.f.FileItemDao:50 [nio-5443-exec-2] - 
> getByRoom roomId :: 3
> DEBUG 04-26 05:00:31.716 o.a.o.w.r.RoomPanel:136 [nio-5443-exec-3] - 
> RoomPanel::roomEnter
> DEBUG 04-26 05:00:31.726 o.a.o.w.r.RoomPanel:166 [nio-5443-exec-3] - 
> RoomPanel::roomEnter, mods IS EMPTY ? false, is MOD ? true
> DEBUG 04-26 05:00:31.735 o.a.o.d.d.r.PollDao:104 [Thread-127] -  :: hasPoll 
> :: 3
> DEBUG 04-26 05:00:31.740 o.a.o.d.d.r.PollDao:98 [Thread-127] -  :: 
> getArchived :: 3
> DEBUG 04-26 05:00:31.741 o.a.o.c.r.KurentoHandler:222 [Thread-127] - 
> Searching for room 3
> DEBUG 04-26 05:00:31.741 o.a.o.c.r.KurentoHandler:226 [Thread-127] - Room 3 
> does not exist. Will create now!
>  INFO 04-26 05:00:31.763 o.a.o.c.r.KRoom:75 [Thread-127] - ROOM 3 has been 
> created
> DEBUG 04-26 05:00:31.763 o.a.o.c.r.KurentoHandler:236 [Thread-127] - Room 3 
> found!
> DEBUG 04-26 05:00:31.764 o.a.o.c.r.KurentoHandler:397 [EventExec-e2-t4] - 
> Kurento::ObjectCreated -> [RemoteObject: type=MediaPipeline 
> remoteRef=fec772df-072e-4a32-8f5a-c94150645f2f_kurento.MediaPipeline]
> ERROR 04-26 05:00:31.833 o.a.w.DefaultExceptionMapper:170 [nio-5443-exec-1] - 
> Unexpected error occurred
> java.lang.NullPointerException: null
>         at 
> org.apache.openmeetings.web.room.sidebar.RoomSidebar$1.respond(RoomSidebar.java:94)
>         at 
> org.apache.wicket.ajax.AbstractDefaultAjaxBehavior.onRequest(AbstractDefaultAjaxBehavior.java:597)
>         at 
> org.apache.wicket.core.request.handler.ListenerRequestHandler.internalInvoke(ListenerRequestHandler.java:306)
>         at 
> org.apache.wicket.core.request.handler.ListenerRequestHandler.invoke(ListenerRequestHandler.java:280)
>         at 
> org.apache.wicket.core.request.handler.ListenerRequestHandler.invokeListener(ListenerRequestHandler.java:222)
>         at 
> org.apache.wicket.core.request.handler.ListenerRequestHandler.respond(ListenerRequestHandler.java:208)
>         at 
> org.apache.wicket.request.cycle.RequestCycle$HandlerExecutor.respond(RequestCycle.java:917)
>         at 
> org.apache.wicket.request.RequestHandlerExecutor.execute(RequestHandlerExecutor.java:63)
>         at 
> org.apache.wicket.request.cycle.RequestCycle.execute(RequestCycle.java:283)
>         at 
> org.apache.wicket.request.cycle.RequestCycle.processRequest(RequestCycle.java:254)
>         at 
> org.apache.wicket.request.cycle.RequestCycle.processRequestAndDetach(RequestCycle.java:222)
>         at 
> org.apache.wicket.protocol.ws.AbstractUpgradeFilter.processRequestCycle(AbstractUpgradeFilter.java:71)
>         at 
> org.apache.wicket.protocol.http.WicketFilter.processRequest(WicketFilter.java:207)
>         at 
> org.apache.wicket.protocol.http.WicketFilter.doFilter(WicketFilter.java:300)
>         at 
> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
>         at 
> org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
>         at 
> org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:202)
>         at 
> org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
>         at 
> org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:666)
>         at 
> org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:139)
>         at 
> org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:92)
>         at 
> org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:690)
>         at 
> org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74)
>         at 
> org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:343)
>         at 
> org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:373)
>         at 
> org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)
>         at 
> org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:868)
>         at 
> org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1590)
>         at 
> org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
>         at 
> java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
>         at 
> java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
>         at 
> org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
>         at java.base/java.lang.Thread.run(Thread.java:834)
> DEBUG 04-26 05:00:31.888 o.a.o.d.u.AuthLevelUtil:40 [io-5443-exec-10] - Level 
> ADMIN :: [GRANTED]
> DEBUG 04-26 05:00:31.931 o.a.o.w.c.OmWebSocketPanel:188 [nio-5443-exec-8] - 
> WebSocketBehavior::closeHandler The client closed its connection with code 
> '1001' and message: 'null'
> DEBUG 04-26 05:00:31.936 o.a.o.d.d.l.ConferenceLogDao:52 [nio-5443-exec-8] - 
> [add]: org.apache.openmeetings.db.entity.log.ConferenceLog@3801c6a2
> DEBUG 04-26 05:00:32.023 o.a.o.w.a.ClientManager:263 [nio-5443-exec-8] - 
> Removing online room client: 170bfba9-781c-40da-91b0-6db499ee5679, room: null
> DEBUG 04-26 05:00:32.024 o.a.o.w.a.ClientManager:190 [nio-5443-exec-8] - 
> Removing online client: 170bfba9-781c-40da-91b0-6db499ee5679, roomId: null
> {code}



--
This message was sent by Atlassian Jira
(v8.3.4#803005)

Reply via email to