0 Replies Latest reply on Jul 28, 2008 11:59 PM by Rajesh

    Urgent : Hibernate , Jboss cache lock issues on jboss 4.2.0.

    Rajesh Newbie

      Hi,

      I have portal application deployed in jboss cluster having 3 servers in it. When i deploy my .sar file and test it .. some times i get the below exception from the logs and failing to load the page ..

      INFO | jvm 1 | 2008/07/29 02:43:26 | Caused by: org.jboss.cache.lock.TimeoutException: failure acquiring lock: fqn=/org/
      jboss/portal/identity/db/HibernateUserImpl/dynamic/org.jboss.portal.identity.db.HibernateUserImpl.dynamic#13, caller=GlobalTr
      ansaction:<3.57.41.6:38538>:25961, lock=write owner=GlobalTransaction:<3.57.41.5:56335>:25816 (activeReaders=0, activeWriter=
      Thread[UpHandler (STATE_TRANSFER),5,JGroups threads], waitingReaders=0, waitingWriters=0, waitingUpgrader=0)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.Node.acquire(Node.java:500)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.interceptors.PessimisticLockInterceptor.acquireNodeLock(Pe
      ssimisticLockInterceptor.java:379)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.interceptors.PessimisticLockInterceptor.lock(PessimisticLo
      ckInterceptor.java:307)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.interceptors.PessimisticLockInterceptor.invoke(Pessimistic
      LockInterceptor.java:181)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:68)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.interceptors.UnlockInterceptor.invoke(UnlockInterceptor.ja
      va:32)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:68)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.interceptors.ReplicationInterceptor.invoke(ReplicationInte
      rceptor.java:39)

      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.interceptors.ReplicationInterceptor.invoke(ReplicationInte
      rceptor.java:39)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:68)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.interceptors.TxInterceptor.replayModifications(TxIntercept
      or.java:554)
      INFO | jvm 1 | 2008/07/29 02:43:26 | ... 22 more
      INFO | jvm 1 | 2008/07/29 02:43:26 | Caused by: org.jboss.cache.lock.TimeoutException: write lock for /org/jboss/portal/
      identity/db/HibernateUserImpl/dynamic/org.jboss.portal.identity.db.HibernateUserImpl.dynamic#13 could not be acquired after 1
      0000 ms. Locks: Read lock owners: []
      INFO | jvm 1 | 2008/07/29 02:43:26 | Write lock owner: GlobalTransaction:<3.57.41.5:56335>:25816
      INFO | jvm 1 | 2008/07/29 02:43:26 | (caller=GlobalTransaction:<3.57.41.6:38538>:25961, lock info: write owner=GlobalTr
      ansaction:<3.57.41.5:56335>:25816 (activeReaders=0, activeWriter=Thread[UpHandler (STATE_TRANSFER),5,JGroups threads], waitin
      gReaders=0, waitingWriters=0, waitingUpgrader=0))
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.lock.IdentityLock.acquireWriteLock(IdentityLock.java:206)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.Node.acquireWriteLock(Node.java:529)
      INFO | jvm 1 | 2008/07/29 02:43:26 | at org.jboss.cache.Node.acquire(Node.java:476)
      INFO | jvm 1 | 2008/07/29 02:43:26 | ... 31 more
      INFO | jvm 1 | 2008/07/29 02:44:24 | 02:44:24,278 INFO [STDOUT] <2008.07.29 02:44:24.278> {2c39b9} L2 hrssp: --T-->Mon
      itor alive... start system checks - ERR - ERR -
      ERR - ERR LL:2
      INFO | jvm 1 | 2008/07/29 02:46:41 | 02:46:41,021 WARN [BIRTDeleteTimer] --> Run Called 1.2 ()
      INFO | jvm 1 | 2008/07/29 02:46:41 | 02:46:41,023 WARN [BIRTDeleteTimer] <-- Run Call Done 1.2 ()
      INFO | jvm 1 | 2008/07/29 02:49:24 | 02:49:24,284 INFO [STDOUT] <2008.07.29 02:49:24.284> {2c39b9} L2 hrssp: --T-->Mon





      And then returning 500 status messages as

      HTTP Status-Code=500 (org.jboss.cache.lock.TimeoutException: read lock for /org/jboss/portal/identity/db/HibernateUserImpl/dynamic/org.jboss.portal.identity.db.HibernateUserImpl.dynamic#10 could not be acquired by GlobalTransaction:<3.57.41.4:42086>:20771 after 10000 ms. Locks: Read lock owners: [] Write lock owner: GlobalTransaction:<3.57.41.5:56335>:25446 , lock info: write owner=GlobalTransaction:<3.57.41.5:56335>:25446 (activeReaders=0, activeWriter=Thread[UpHandler (STATE_TRANSFER),5,JGroups threads], waitingReaders=1, waitingWriters=0, waitingUpgrader=0)) for "http://abcd.com/aaa/


      Could you please suggest to solve the issue