[jboss-user] [JBossCache] - Re: TimeoutException: Response timed out errors with jboss/h

[pkorros]

In my test scenario JBoss Cache 1.4.1.BETA seems to work much better than 1.4.0.SP1.

The test scenario is to repeat a specific request 60 times. Previously my test scenario didn't work at all. The server was being locked on the first request and I had to leave only 1 active node for the test to continue.
The errors were like this:
Caused by: org.jboss.cache.lock.TimeoutException: Response timed out: sender=192.168.1.157:2483, retval=null, received=false, suspected=false
at org.jboss.cache.TreeCache.callRemoteMethods(TreeCache.java:4189) 

Now I am getting some errors but the test continues and the system manages to complete 54 out of 60 requests successfully.

I get 6 errors like this:
2006-11-20 10:36:02,883 ERROR [org.jboss.cache.lock.IdentityLock] (UpHandler (STATE_TRANSFER)) write lock for /com/oakgrovesystems/reactor/processMediation/hibernate/HibProcess/currentStatuses/com.oakgrovesystems.reactor.processMediation.hibernate.HibProcess.currentStatuses#165199 could not be acquired after 0 ms. Locks: Read lock owners: [GlobalTransaction:<192.168.1.147:1270>:1153]
Write lock owner: null
 (caller=Thread[UpHandler (STATE_TRANSFER),5,JGroups threads], lock info: read owners=[GlobalTransaction:<192.168.1.147:1270>:1153] (activeReaders=1, activeWriter=null, waitingReaders=0, waitingWriters=0, waitingUpgrader=0))
2006-11-20 10:36:02,898 INFO  [org.jboss.cache.interceptors.TxInterceptor] (UpHandler (STATE_TRANSFER)) There was a problem handling this request
org.jboss.cache.lock.TimeoutException: failure acquiring lock: fqn=/com/oakgrovesystems/reactor/processMediation/hibernate/HibProcess/currentStatuses/com.oakgrovesystems.reactor.processMediation.hibernate.HibProcess.currentStatuses#165199, caller=Thread[UpHandler (STATE_TRANSFER),5,JGroups threads], lock=read owners=[GlobalTransaction:<192.168.1.147:1270>:1153] (activeReaders=1, activeWriter=null, waitingReaders=0, waitingWriters=0, waitingUpgrader=0)
	at org.jboss.cache.Node.acquire(Node.java:475)
	at org.jboss.cache.interceptors.PessimisticLockInterceptor.lock(PessimisticLockInterceptor.java:244)
	at org.jboss.cache.interceptors.PessimisticLockInterceptor.invoke(PessimisticLockInterceptor.java:168)
	at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:68)
	at org.jboss.cache.interceptors.UnlockInterceptor.invoke(UnlockInterceptor.java:32)
	at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:68)
	at org.jboss.cache.interceptors.ReplicationInterceptor.invoke(ReplicationInterceptor.java:39)
	at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:68)
	at org.jboss.cache.interceptors.TxInterceptor.handleNonTxMethod(TxInterceptor.java:364)
	at org.jboss.cache.interceptors.TxInterceptor.invoke(TxInterceptor.java:160)
	at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:68)
	at org.jboss.cache.interceptors.CacheMgmtInterceptor.invoke(CacheMgmtInterceptor.java:157)
	at org.jboss.cache.TreeCache.invokeMethod(TreeCache.java:5611)
	at org.jboss.cache.TreeCache._replicate(TreeCache.java:4957)
	at sun.reflect.GeneratedMethodAccessor337.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:585)
	at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:330)
	at org.jgroups.blocks.RpcDispatcher.handle(RpcDispatcher.java:267)
	at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:623)
	at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:508)
	at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:331)
	at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.up(MessageDispatcher.java:769)
	at org.jgroups.JChannel.up(JChannel.java:1091)
	at org.jgroups.stack.ProtocolStack.up(ProtocolStack.java:377)
	at org.jgroups.stack.ProtocolStack.receiveUpEvent(ProtocolStack.java:393)
	at org.jgroups.stack.Protocol.passUp(Protocol.java:538)
	at org.jgroups.protocols.pbcast.STATE_TRANSFER.up(STATE_TRANSFER.java:162)
	at org.jgroups.stack.UpHandler.run(Protocol.java:60)
Caused by: org.jboss.cache.lock.TimeoutException: write lock for /com/oakgrovesystems/reactor/processMediation/hibernate/HibProcess/currentStatuses/com.oakgrovesystems.reactor.processMediation.hibernate.HibProcess.currentStatuses#165199 could not be acquired after 0 ms. Locks: Read lock owners: [GlobalTransaction:<192.168.1.147:1270>:1153]
Write lock owner: null
 (caller=Thread[UpHandler (STATE_TRANSFER),5,JGroups threads], lock info: read owners=[GlobalTransaction:<192.168.1.147:1270>:1153] (activeReaders=1, activeWriter=null, waitingReaders=0, waitingWriters=0, waitingUpgrader=0))
	at org.jboss.cache.lock.IdentityLock.acquireWriteLock(IdentityLock.java:202)
	at org.jboss.cache.Node.acquireWriteLock(Node.java:504)
	at org.jboss.cache.Node.acquire(Node.java:451)
	... 28 more


Notice that by using another (commercial) replicated cache I manage to successfully complete all 60 requests.


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

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