org.jboss.cache.lock.TimeoutException
joseph.yi Feb 10, 2006 2:26 AMHello,
I have a cluster with two nodes, a web application configured to replicate sessions ,but sometime exception throws
jboss 4.0.0 apache 2.0.46
2006-02-08 17:27:51,024 ERROR [org.jboss.cache.lock.IdentityLock] lock could not be acquired after 15000 ms. Lock map ownership Read lock owners: [] Write lock owner: <qa-lenovo:1170>:1081 2006-02-08 17:27:51,024 ERROR [org.jboss.cache.TreeCache] method invocation failed org.jboss.cache.lock.TimeoutException: lock could not be acquired after 15000 ms. Lock map ownership Read lock owners: [] Write lock owner: <qa-lenovo:1170>:1081 at org.jboss.cache.lock.IdentityLock.acquireWriteLock(IdentityLock.java:146) at org.jboss.cache.Node.acquireWriteLock(Node.java:422) at org.jboss.cache.Node.acquire(Node.java:388) at org.jboss.cache.TreeCache.findNode(TreeCache.java:3284) at org.jboss.cache.TreeCache._put(TreeCache.java:2335) at sun.reflect.GeneratedMethodAccessor275.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:324) at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:236) at org.jboss.cache.TreeCache.prepare(TreeCache.java:2726) at sun.reflect.GeneratedMethodAccessor274.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:324) at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:236) at org.jboss.cache.interceptors.CallInterceptor.invoke(CallInterceptor.java:14) at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:39) at org.jboss.cache.interceptors.ReplicationInterceptor.replicate(ReplicationInterceptor.java:144) at org.jboss.cache.TreeCache._replicate(TreeCache.java:2664) at sun.reflect.GeneratedMethodAccessor271.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:324) at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:236) at org.jgroups.blocks.RpcDispatcher.handle(RpcDispatcher.java:220) at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:615) at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:512) at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:326) at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.handleUp(MessageDispatcher.java:722) at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.access$300(MessageDispatcher.java:554) at org.jgroups.blocks.MessageDispatcher$1.run(MessageDispatcher.java:691) at java.lang.Thread.run(Thread.java:534) 2006-02-08 17:27:51,024 WARN [org.jboss.cache.interceptors.ReplicationInterceptor] beforeCompletion(): runPreparePhase failed. Transaction is now rolled back org.jboss.cache.lock.TimeoutException: lock could not be acquired after 15000 ms. Lock map ownership Read lock owners: [] Write lock owner: <test-server:2762>:1075 at org.jboss.cache.lock.IdentityLock.acquireWriteLock(IdentityLock.java:146) at org.jboss.cache.Node.acquireWriteLock(Node.java:422) at org.jboss.cache.Node.acquire(Node.java:388) at org.jboss.cache.TreeCache.findNode(TreeCache.java:3284) at org.jboss.cache.TreeCache._put(TreeCache.java:2335) at sun.reflect.GeneratedMethodAccessor186.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:324) at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:236) at org.jboss.cache.TreeCache.prepare(TreeCache.java:2726) at sun.reflect.GeneratedMethodAccessor185.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:324) at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:236) at org.jboss.cache.interceptors.CallInterceptor.invoke(CallInterceptor.java:14) at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:39) at org.jboss.cache.interceptors.ReplicationInterceptor.replicate(ReplicationInterceptor.java:144) at org.jboss.cache.TreeCache._replicate(TreeCache.java:2664) at sun.reflect.GeneratedMethodAccessor181.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:324) at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:236) at org.jgroups.blocks.RpcDispatcher.handle(RpcDispatcher.java:220) at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:615) at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:512) at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:326) at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.handleUp(MessageDispatcher.java:722) at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.access$300(MessageDispatcher.java:554) at org.jgroups.blocks.MessageDispatcher$1.run(MessageDispatcher.java:691) at java.lang.Thread.run(Thread.java:534) 2006-02-08 17:27:51,024 ERROR [org.jboss.web.tomcat.tc5.session.JBossCacheManager] processSessionRepl: failed with exception: org.jboss.tm.JBossRollbackException: Unable to commit, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=qa-lenovo/10096, BranchQual=, localId=10096] status=STATUS_NO_TRANSACTION; - nested throwable: (org.jboss.util.NestedRuntimeException: ; - nested throwable: (org.jboss.cache.lock.TimeoutException: lock could not be acquired after 15000 ms. Lock map ownership Read lock owners: [] Write lock owner: <test-server:2762>:1075 )) 2006-02-08 17:27:51,024 INFO [STDOUT] java.lang.IllegalStateException: No transaction. 2006-02-08 17:27:51,024 INFO [STDOUT] at org.jboss.tm.TxManager.rollback(TxManager.java:331) 2006-02-08 17:27:51,024 INFO [STDOUT] at org.jboss.tm.usertx.client.ServerVMClientUserTransaction.rollback(ServerVMClientUserTransaction.java:134) 2006-02-08 17:27:51,024 INFO [STDOUT] at org.jboss.web.tomcat.tc5.session.JBossCacheManager.processSessionRepl(JBossCacheManager.java:371) 2006-02-08 17:27:51,024 INFO [STDOUT] at org.jboss.web.tomcat.tc5.session.JBossCacheManager.storeSession(JBossCacheManager.java:213) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.jboss.web.tomcat.tc5.session.InstantSnapshotManager.snapshot(InstantSnapshotManager.java:37) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.jboss.web.tomcat.tc5.session.ClusteredSessionValve.invoke(ClusteredSessionValve.java:91) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:137) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:118) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.jboss.web.tomcat.tc5.sso.ClusteredSingleSignOn.invoke(ClusteredSingleSignOn.java:389) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:929) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.coyote.tomcat5.CoyoteAdapter.service(CoyoteAdapter.java:160) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.jk.server.JkCoyoteHandler.invoke(JkCoyoteHandler.java:300) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.jk.common.HandlerRequest.invoke(HandlerRequest.java:374) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.jk.common.ChannelSocket.invoke(ChannelSocket.java:743) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.jk.common.ChannelSocket.processConnection(ChannelSocket.java:675) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.jk.common.SocketConnection.runIt(ChannelSocket.java:866) 2006-02-08 17:27:51,039 INFO [STDOUT] at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:683) 2006-02-08 17:27:51,039 INFO [STDOUT] at java.lang.Thread.run(Thread.java:534) 2006-02-08 17:27:51,039 WARN [org.jboss.web.tomcat.tc5.session.InstantSnapshotManager] Failed to replicate sessionID:LX7XyFo8KaODFr2EUjJ1aA**.node2 java.lang.RuntimeException: JBossCacheManager.processSessionRepl(): failed to replicate session. at org.jboss.web.tomcat.tc5.session.JBossCacheManager.processSessionRepl(JBossCacheManager.java:378) at org.jboss.web.tomcat.tc5.session.JBossCacheManager.storeSession(JBossCacheManager.java:213) at org.jboss.web.tomcat.tc5.session.InstantSnapshotManager.snapshot(InstantSnapshotManager.java:37) at org.jboss.web.tomcat.tc5.session.ClusteredSessionValve.invoke(ClusteredSessionValve.java:91) at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102) at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:137) at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:118) at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102) at org.jboss.web.tomcat.tc5.sso.ClusteredSingleSignOn.invoke(ClusteredSingleSignOn.java:389) at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102) at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104) at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520) at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:929) at org.apache.coyote.tomcat5.CoyoteAdapter.service(CoyoteAdapter.java:160) at org.apache.jk.server.JkCoyoteHandler.invoke(JkCoyoteHandler.java:300) at org.apache.jk.common.HandlerRequest.invoke(HandlerRequest.java:374) at org.apache.jk.common.ChannelSocket.invoke(ChannelSocket.java:743) at org.apache.jk.common.ChannelSocket.processConnection(ChannelSocket.java:675) at org.apache.jk.common.SocketConnection.runIt(ChannelSocket.java:866) at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:683) at java.lang.Thread.run(Thread.java:534) Caused by: org.jboss.tm.JBossRollbackException: Unable to commit, tx=TransactionImpl:XidImpl[FormatId=257, GlobalId=qa-lenovo/10096, BranchQual=, localId=10096] status=STATUS_NO_TRANSACTION; - nested throwable: (org.jboss.util.NestedRuntimeException: ; - nested throwable: (org.jboss.cache.lock.TimeoutException: lock could not be acquired after 15000 ms. Lock map ownership Read lock owners: [] Write lock owner: <test-server:2762>:1075 )) at org.jboss.tm.TransactionImpl.commit(TransactionImpl.java:344) at org.jboss.tm.TxManager.commit(TxManager.java:200) at org.jboss.tm.usertx.client.ServerVMClientUserTransaction.commit(ServerVMClientUserTransaction.java:126) at org.jboss.web.tomcat.tc5.session.JBossCacheManager.processSessionRepl(JBossCacheManager.java:364) ... 24 more Caused by: org.jboss.util.NestedRuntimeException: ; - nested throwable: (org.jboss.cache.lock.TimeoutException: lock could not be acquired after 15000 ms. Lock map ownership Read lock owners: [] Write lock owner: <test-server:2762>:1075 ) at org.jboss.cache.interceptors.ReplicationInterceptor$SynchronizationHandler.beforeCompletion(ReplicationInterceptor.java:225) at org.jboss.cache.interceptors.OrderedSynchronizationHandler.beforeCompletion(OrderedSynchronizationHandler.java:71) at org.jboss.tm.TransactionImpl.doBeforeCompletion(TransactionImpl.java:1383) at org.jboss.tm.TransactionImpl.beforePrepare(TransactionImpl.java:1075) at org.jboss.tm.TransactionImpl.commit(TransactionImpl.java:296) ... 27 more Caused by: org.jboss.cache.lock.TimeoutException: lock could not be acquired after 15000 ms. Lock map ownership Read lock owners: [] Write lock owner: <test-server:2762>:1075 at org.jboss.cache.lock.IdentityLock.acquireWriteLock(IdentityLock.java:146) at org.jboss.cache.Node.acquireWriteLock(Node.java:422) at org.jboss.cache.Node.acquire(Node.java:388) at org.jboss.cache.TreeCache.findNode(TreeCache.java:3284) at org.jboss.cache.TreeCache._put(TreeCache.java:2335) at sun.reflect.GeneratedMethodAccessor186.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:324) at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:236) at org.jboss.cache.TreeCache.prepare(TreeCache.java:2726) at sun.reflect.GeneratedMethodAccessor185.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:324) at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:236) at org.jboss.cache.interceptors.CallInterceptor.invoke(CallInterceptor.java:14) at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:39) at org.jboss.cache.interceptors.ReplicationInterceptor.replicate(ReplicationInterceptor.java:144) at org.jboss.cache.TreeCache._replicate(TreeCache.java:2664) at sun.reflect.GeneratedMethodAccessor181.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:324) at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:236) at org.jgroups.blocks.RpcDispatcher.handle(RpcDispatcher.java:220) at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:615) at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:512) at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:326) at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.handleUp(MessageDispatcher.java:722) at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.access$300(MessageDispatcher.java:554) at org.jgroups.blocks.MessageDispatcher$1.run(MessageDispatcher.java:691) ... 1 more
thanks all