Java Development Tools

Hi All

We are experiencing an issue in ADF. Basically the ADF application hangs on a request, it seems to occur when the user is navigating from a bounded task flow to another. For example, a user was in a bounded task flow displaying a table, they then clicked the edit button which resulted in calling another bounded task flow for editing a record.

To the user the system hangs on the button click, the application appears to be doing something in the background as the hour glass is displayed in the browser. This lasts for around 10 minutes and ends up being a stuck thread. Unfortunately this is not a consistent issue, as I follow the steps the tester has taken and it will work fine.

From the logs I can see the thread is stuck on the SessionCookieImpl reservePassivationId method. See below for full stack trace.

Has anyone experienced this before? From looking at all of the incidents logged by Weblogic we have experienced this around 8 times in a 2 month period all stuck at the same point, SessionCookieImpl reservePassivationId.

Our application is relatively straight forward we have one jspx and about 15 bounded task flows inserted as regions.

Environment is as follows:
Developed in JDeveloper 11g Release 1 (11.1.1.2.0).
Deployed on:
WebLogic Server Temporary Patch for 9324911 Wed Feb 03 23:35:30 PST 2010 WebLogic Server 10.3.2.0 Tue Oct 20 12:16:15 PDT 2009 1267925
Deployed on OS: Red Hat Enterprise Linux Server release 5. Server-5.4.0.3.
Testers using Firefox 3.6.12 on Windows XP.

Any help would be much appreciated.

Regards
Robert



Stack trace:

[2010-11-05T12:31:25.650+00:00] [soa_server1] [WARNING:7] [BEA-320068] [Diagnostics] [host: xxxx] [nwaddr: 127.0.0.1] [tid: [ACTIVE].ExecuteThread: '12' for queue: 'weblogic.kernel.Default (self-tuning)'] [userId: <WLS Kernel>] [LOG_FILE: /app/oracle/middleware/user_projects/domains/xxxx/servers/soa_server1/logs/soa_server1.log] Watch 'StuckThread' with severity 'Notice' on server 'soa_server1' has triggered at Nov 5, 2010 12:31:25 PM GMT. Notification details: [[
WatchRuleType: Log
WatchRule: (SEVERITY = 'Error') AND (MSGID = 'BEA-000337')
WatchData: DATE = Nov 5, 2010 12:31:25 PM GMT SERVER = soa_server1 MESSAGE = [STUCK] ExecuteThread: '10' for queue: 'weblogic.kernel.Default (self-tuning)' has been busy for "650" seconds working on the request "weblogic.servlet.internal.ServletRequestImpl@13c755e[
POST /Admin/faces/Admin.jspx?_adf.ctrl-state=uhkqtw8vu_4 HTTP/1.1
User-Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1; en-GB; rv:1.9.2.12) Gecko/20101026 Firefox/3.6.12 ( .NET CLR 3.5.30729; .NET4.0E)
Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
Accept-Language: en-gb,en;q=0.5
Accept-Encoding: gzip,deflate
Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
Keep-Alive: 115
Connection: keep-alive
Content-Type: application/x-www-form-urlencoded; charset=UTF-8
Adf-Ads-Page-Id: 1
Adf-Rich-Message: true
Referer: http://xxxx:8001/Admin/faces/Admin.jspx?_afrWindowMode=0&_afrLoop=8736607776458692&_adf.ctrl-state=uhkqtw8vu_4
Content-Length: 353
Cookie: __utma=119826014.354041712.1277374005.1277374005.1277374005.1; __utmz=119826014.1277374005.1.1.utmccn=(direct)|utmcsr=(direct)|utmcmd=(none); JSESSIONID=Lyg1MTlB6GLmSbfQnhRn9hkXhXG3XclxhdvGfMTLQ1vK7l2QZ8Wx!580793240
Pragma: no-cache
Cache-Control: no-cache

]", which is more than the configured time (StuckThreadMaxTime) of "600" seconds. Stack trace:
oracle.jbo.common.ampool.SessionCookieImpl.reservePassivationId(SessionCookieImpl.java:964)
oracle.jbo.common.ampool.SessionCookieImpl.reservePassivationId(SessionCookieImpl.java:952)
oracle.adf.model.bc4j.DCJboDataControl.initializeApplicationModule(DCJboDataControl.java:491)
oracle.adf.model.bc4j.DCJboDataControl.beginRequestInternal(DCJboDataControl.java:2029)
oracle.adf.model.bc4j.DCJboDataControl.beginRequest(DCJboDataControl.java:2006)
oracle.adf.model.dcframe.DataControlFrameImpl.beginRequest(DataControlFrameImpl.java:258)
oracle.adf.model.BindingContext.setCurrentFrame(BindingContext.java:1975)
oracle.adf.model.BindingContext.setCurrentDataControlFrame(BindingContext.java:1891)
oracle.adfinternal.controller.util.model.DCFrameImpl.makeCurrent(DCFrameImpl.java:126)
oracle.adfinternal.controller.state.ViewPortContextImpl.makeCurrent(ViewPortContextImpl.java:1006)
oracle.adfinternal.controller.state.RequestState.setCurrentViewPortContext(RequestState.java:149)
oracle.adfinternal.controller.state.ControllerState.setCurrentViewPort(ControllerState.java:1218)
oracle.adfinternal.controller.ControllerContextImpl.setCurrentViewPort(ControllerContextImpl.java:133)
oracle.adfinternal.controller.ControllerContextImpl.setCurrentViewPort(ControllerContextImpl.java:52)
oracle.adf.controller.internal.binding.TaskFlowRegionModel.doProcessBeginRegion(TaskFlowRegionModel.java:154)
oracle.adf.controller.internal.binding.TaskFlowRegionModel.processBeginRegion(TaskFlowRegionModel.java:92)
oracle.adf.view.rich.component.fragment.UIXRegion$RegionContextChange.doChangeImpl(UIXRegion.java:1182)
oracle.adf.view.rich.context.DoableContextChange.doChange(DoableContextChange.java:91)
oracle.adf.view.rich.component.fragment.UIXRegion._beginInterruptibleRegion(UIXRegion.java:682)
oracle.adf.view.rich.component.fragment.UIXRegion.processRegion(UIXRegion.java:487)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:625)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl.access$000(LifecycleImpl.java:63)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl$1.call(LifecycleImpl.java:630)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl$1.call(LifecycleImpl.java:626)
oracle.adf.view.rich.component.fragment.UIXRegion.processRegion(UIXRegion.java:492)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:625)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executeBindings(LifecycleImpl.java:660)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._restoreView(LifecycleImpl.java:565)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl._executePhase(LifecycleImpl.java:280)
oracle.adfinternal.view.faces.lifecycle.LifecycleImpl.execute(LifecycleImpl.java:177)
javax.faces.webapp.FacesServlet.service(FacesServlet.java:265)
weblogic.servlet.internal.StubSecurityHelper$ServletServiceAction.run(StubSecurityHelper.java:227)
weblogic.servlet.internal.StubSecurityHelper.invokeServlet(StubSecurityHelper.java:125)
weblogic.servlet.internal.ServletStubImpl.execute(ServletStubImpl.java:292)
weblogic.servlet.internal.TailFilter.doFilter(TailFilter.java:26)
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
oracle.adf.model.servlet.ADFBindingFilter.doFilter(ADFBindingFilter.java:191)
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
oracle.adfinternal.view.faces.webapp.rich.RegistrationFilter.doFilter(RegistrationFilter.java:97)
org.apache.myfaces.trinidadinternal.webapp.TrinidadFilterImpl$FilterListChain.doFilter(TrinidadFilterImpl.java:420)
oracle.adfinternal.view.faces.activedata.AdsFilter.doFilter(AdsFilter.java:60)
org.apache.myfaces.trinidadinternal.webapp.TrinidadFilterImpl$FilterListChain.doFilter(TrinidadFilterImpl.java:420)
org.apache.myfaces.trinidadinternal.webapp.TrinidadFilterImpl._doFilterImpl(TrinidadFilterImpl.java:247)
org.apache.myfaces.trinidadinternal.webapp.TrinidadFilterImpl.doFilter(TrinidadFilterImpl.java:157)
org.apache.myfaces.trinidad.webapp.TrinidadFilter.doFilter(TrinidadFilter.java:92)
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
oracle.security.jps.ee.http.JpsAbsFilter$1.run(JpsAbsFilter.java:94)
java.security.AccessController.doPrivileged(Native Method)
oracle.security.jps.util.JpsSubject.doAsPrivileged(JpsSubject.java:313)
oracle.security.jps.ee.util.JpsPlatformUtil.runJaasMode(JpsPlatformUtil.java:413)
oracle.security.jps.ee.http.JpsAbsFilter.doFilter(JpsAbsFilter.java:138)
oracle.security.jps.ee.http.JpsFilter.doFilter(JpsFilter.java:70)
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
oracle.adf.library.webapp.LibraryFilter.doFilter(LibraryFilter.java:159)
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
oracle.dms.wls.DMSServletFilter.doFilter(DMSServletFilter.java:326)
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
weblogic.servlet.internal.RequestEventsFilter.doFilter(RequestEventsFilter.java:27)
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
weblogic.servlet.internal.WebAppServletContext$ServletInvocationAction.run(WebAppServletContext.java:3592)
weblogic.security.acl.internal.AuthenticatedSubject.doAs(AuthenticatedSubject.java:321)
weblogic.security.service.SecurityManager.runAs(SecurityManager.java:121)
weblogic.servlet.internal.WebAppServletContext.securedExecute(WebAppServletContext.java:2202)
weblogic.servlet.internal.WebAppServletContext.execute(WebAppServletContext.java:2108)
weblogic.servlet.internal.ServletRequestImpl.run(ServletRequestImpl.java:1432)
weblogic.work.ExecuteThread.execute(ExecuteThread.java:201)
weblogic.work.ExecuteThread.run(ExecuteThread.java:173)
SUBSYSTEM = WebLogicServer USERID = <WLS Kernel> SEVERITY = Error THREAD = [ACTIVE] ExecuteThread: '14' for queue: 'weblogic.kernel.Default (self-tuning)' MSGID = BEA-000337 MACHINE = xxxx TXID = CONTEXTID = TIMESTAMP = 1288960285583
WatchAlarmType: AutomaticReset
WatchAlarmResetPeriod: 600000
]]