[weld-issues] [JBoss JIRA] (WELD-1607) Unable to load current conversations from the associated request on Tomcat

[Emond Papegaaij (JIRA)]

Title: Message Title

Emond Papegaaij commented on WELD-1607   Re: Unable to load current conversations from the associated request on Tomcat After inspecting the logs for two weeks with a more recent version of Tomcat (7.0.52), we see that the number of times the problem is triggered has been greatly reduced, but it's still not solved. Using the logs, we've been able to accurately reproduce the problem in our application the following way: Trigger many long-running requests for a user session Logout, invalidating the session All requests still running hit the following exception: [2014-05-08 13:30:45,540] [TRACE] [ajp-bio-8009-exec-2507] Servlet | WELD-000709: Destroying request org.apache.catalina.connector.Request@3a2c93f1 [2014-05-08 13:30:45,540] [WARN] [ajp-bio-8009-exec-2507] Servlet | WELD-000712: Unable to dissociate context org.jboss.weld.context.http.LazyHttpConversationContextImpl@5d37a6e7 when destroying request org.apache.catalina.connector.Request@3a2c93f1 [2014-05-08 13:30:45,540] [DEBUG] [ajp-bio-8009-exec-2507] Servlet | Catching java.lang.IllegalStateException: getAttribute: Session already invalidated at org.apache.catalina.session.StandardSession.getAttribute(StandardSession.java:1165) at org.apache.catalina.session.StandardSessionFacade.getAttribute(StandardSessionFacade.java:120) at org.jboss.weld.context.http.HttpConversationContextImpl.getSessionAttribute(HttpConversationContextImpl.java:29) at org.jboss.weld.context.http.HttpConversationContextImpl.getSessionAttribute(HttpConversationContextImpl.java:13) at org.jboss.weld.context.AbstractConversationContext.copyConversationIdGeneratorAndConversationsToSession(AbstractConversationContext.java:180) at org.jboss.weld.context.AbstractConversationContext.dissociate(AbstractConversationContext.java:160) at org.jboss.weld.servlet.ConversationContextActivator.disassociateConversationContext(ConversationContextActivator.java:163) at org.jboss.weld.servlet.HttpContextLifecycle.requestDestroyed(HttpContextLifecycle.java:244) at org.jboss.weld.servlet.WeldInitialListener.requestDestroyed(WeldInitialListener.java:136) at org.jboss.weld.servlet.api.helpers.ForwardingServletListener.requestDestroyed(ForwardingServletListener.java:38) at nl.topicus.eduarte.app.ContainerIndependentCdiListener.requestDestroyed(ContainerIndependentCdiListener.java:67) at org.apache.catalina.core.StandardContext.fireRequestDestroyEvent(StandardContext.java:6163) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:206) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98) at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408) at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:193) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:607) at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:313) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) It seems Tomcat's session-management also is still not free from race conditions, as shown by this piece of logging for webservice requests without session (also notice the ordering of log statements, probably caused by a gc): [2014-05-07 20:52:00,449] [TRACE] [ajp-bio-8009-exec-159] Servlet | WELD-000708: Initializing request org.apache.catalina.connector.Request@4ab5a69b [2014-05-07 20:52:00,494] [TRACE] [ajp-bio-8009-exec-188] Servlet | WELD-000708: Initializing request org.apache.catalina.connector.Request@37a22dc0 [2014-05-07 20:52:00,451] [INFO] [ajp-bio-8009-exec-159] RequestLogger | {"request":{"startDate":1399488720449,"timeTaken":2,"requestedUrl":"/edu08/services/api/algemeen/tabellen","sessionId":"67540D597C6C87B4B8B08BEC4FBD47D8","sessionInfo":null,"activeRequest":0,"sessio [2014-05-07 20:52:00,495] [INFO] [ajp-bio-8009-exec-188] RequestLogger | {"request":{"startDate":1399488720494,"timeTaken":1,"requestedUrl":"/edu08/services/api/algemeen/tabellen","sessionId":"67540D597C6C87B4B8B08BEC4FBD47D8","sessionInfo":null,"activeRequest":0,"sessio [2014-05-07 20:52:00,559] [TRACE] [ajp-bio-8009-exec-159] Servlet | WELD-000709: Destroying request org.apache.catalina.connector.Request@4ab5a69b [2014-05-07 20:52:00,559] [TRACE] [ajp-bio-8009-exec-159] Servlet | WELD-000708: Initializing request org.apache.catalina.connector.Request@4ab5a69b [2014-05-07 20:52:00,563] [INFO] [ajp-bio-8009-exec-159] RequestLogger | {"request":{"startDate":1399488720561,"timeTaken":1,"requestedUrl":"/edu08/services/api/algemeen/tabellen","sessionId":"207EC49B8A318B90F27C41B86E5CFB62","sessionInfo":null,"activeRequest":0,"sessio [2014-05-07 20:52:00,599] [TRACE] [ajp-bio-8009-exec-159] Servlet | WELD-000709: Destroying request org.apache.catalina.connector.Request@4ab5a69b [2014-05-07 20:52:00,613] [TRACE] [ajp-bio-8009-exec-188] Servlet | WELD-000709: Destroying request org.apache.catalina.connector.Request@37a22dc0 [2014-05-07 20:52:00,613] [WARN] [ajp-bio-8009-exec-188] Servlet | WELD-000712: Unable to dissociate context org.jboss.weld.context.http.LazyHttpConversationContextImpl@391bf15b when destroying request org.apache.catalina.connector.Request@37a22dc0 [2014-05-07 20:52:00,613] [DEBUG] [ajp-bio-8009-exec-188] Servlet | Catching java.lang.IllegalStateException: getAttribute: Session already invalidated at org.apache.catalina.session.StandardSession.getAttribute(StandardSession.java:1165) at org.apache.catalina.session.StandardSessionFacade.getAttribute(StandardSessionFacade.java:120) at org.jboss.weld.context.http.HttpConversationContextImpl.getSessionAttribute(HttpConversationContextImpl.java:29) at org.jboss.weld.context.http.HttpConversationContextImpl.getSessionAttribute(HttpConversationContextImpl.java:13) at org.jboss.weld.context.AbstractConversationContext.copyConversationIdGeneratorAndConversationsToSession(AbstractConversationContext.java:180) at org.jboss.weld.context.AbstractConversationContext.dissociate(AbstractConversationContext.java:160) at org.jboss.weld.servlet.ConversationContextActivator.disassociateConversationContext(ConversationContextActivator.java:163) at org.jboss.weld.servlet.HttpContextLifecycle.requestDestroyed(HttpContextLifecycle.java:244) at org.jboss.weld.servlet.WeldInitialListener.requestDestroyed(WeldInitialListener.java:136) at org.jboss.weld.servlet.api.helpers.ForwardingServletListener.requestDestroyed(ForwardingServletListener.java:38) at nl.topicus.eduarte.app.ContainerIndependentCdiListener.requestDestroyed(ContainerIndependentCdiListener.java:67) at org.apache.catalina.core.StandardContext.fireRequestDestroyEvent(StandardContext.java:6163) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:206) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98) at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408) at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:193) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:607) at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:313) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) Add Comment   This message was sent by Atlassian JIRA (v6.2.3#6260-sha1:63ef1d6)

_______________________________________________
weld-issues mailing list
weld-issues@lists.jboss.org
https://lists.jboss.org/mailman/listinfo/weld-issues