[jboss-user] [Persistence, JBoss/CMP, Hibernate, Database] - Urgent : Hibernate , Jboss cache lock issues on jboss 4.2.0.

[Rajesh.Gajula]

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

View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4167207#4167207

Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4167207