0 Replies Latest reply on Feb 10, 2006 2:26 AM by joseph yu

    org.jboss.cache.lock.TimeoutException

    joseph yu Newbie

      Hello,
      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