[jboss-user] [JBoss Cache: Core Edition] - Re: Passivation false problem

Friday, 27 June 2008

Yes we have enabled JMX in tomcat and are retrieving the statistics from the MBeans
"jboss.cache:service=TreeCache,cache-interceptor=CacheLoaderInterceptor" and
"jboss.cache:service=TreeCache,cache-interceptor=CacheMgmtInterceptor".

I tried using the newer version 2.1.1.GA but got same results. Here are the logs after
Step 2:

Jun 27, 2008 09:36:11.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
process(): region: /
  | Jun 27, 2008 09:36:11.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
processed 0 node events in region: /
  | Jun 27, 2008 09:36:11.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
Recycle queue is empty
  | Jun 27, 2008 09:36:11.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 has been idle for 10000ms
  | Jun 27, 2008 09:36:11.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 should not be evicted
  | Jun 27, 2008 09:36:11.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 has been idle for 10000ms
  | Jun 27, 2008 09:36:11.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 should not be evicted
  | Jun 27, 2008 09:36:16.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
process(): region: /
  | Jun 27, 2008 09:36:16.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
processed 0 node events in region: /
  | Jun 27, 2008 09:36:16.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
Recycle queue is empty
  | Jun 27, 2008 09:36:16.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 has been idle for 15000ms
  | Jun 27, 2008 09:36:16.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 should not be evicted
  | Jun 27, 2008 09:36:16.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 has been idle for 15000ms
  | Jun 27, 2008 09:36:16.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 should not be evicted
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance
[null] and InvocationContext [InvocationContext{methodCall=MethodName: _get;
MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)transaction=null,
globalTransaction=null, optionOverrides=Option{failSilently=false, cacheModeLocal=false,
dataVersion=null, suppressLocking=false, forceDataGravitation=false,
skipDataGravitation=false}, originLocal=true, txHasMods=false}]
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional
context.
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as
null
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null)
call on method [MethodName: _get; MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)]
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor
invoked for method MethodName: _get; MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - Attempting to lock node
/3520@rachna/42/1214582760875/8501033611511983927136 for owner
Thread[http-8080-Processor24,5,main]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked>
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked>
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked>
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42/1214582760875, caller=Thread[http-8080-Processor24,5,main],
lock=<unlocked>
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42/1214582760875, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42/1214582760875/8501033611511983927136,
caller=Thread[http-8080-Processor24,5,main], lock=<unlocked>
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42/1214582760875/8501033611511983927136,
caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- invoke MethodName: _get; MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- don't load, key requested is null
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- load element /3520@rachna/42/1214582760875/8501033611511983927136 mustLoad=false
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor
- invoke MethodName: _get; MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.CallInterceptor -
Passing up method MethodName: _get; MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136) so it gets invoked on cache.
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Invoking EvictionInterceptor
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Updating node/element events with no tx
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Adding event EvictedEN[fqn=/3520@rachna/42/1214582760875/8501033611511983927136
event=VISIT_NODE_EVENT diff=0] to region at /
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Finished updating node
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Finished invoking EvictionInterceptor
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
Attempting to release locks on current thread.  Lock table is
{Thread[http-8080-Processor24,5,main]=[read owners=[Thread[http-8080-Processor24,5,main]],
read owners=[Thread[http-8080-Processor24,5,main]], read
owners=[Thread[http-8080-Processor24,5,main]], read
owners=[Thread[http-8080-Processor24,5,main]], read
owners=[Thread[http-8080-Processor24,5,main]]]}
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42/1214582760875/8501033611511983927136: read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42/1214582760875: read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42: read owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna: read owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /: read owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope
options
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance
[null] and InvocationContext [InvocationContext{methodCall=MethodName: _get;
MethodIdInteger: 26; Args: (/3520@rachna/42/1214582760875/8501033611511983927136, ,
true)transaction=null, globalTransaction=null, optionOverrides=Option{failSilently=false,
cacheModeLocal=false, dataVersion=null, suppressLocking=false, forceDataGravitation=false,
skipDataGravitation=false}, originLocal=true, txHasMods=false}]
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional
context.
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as
null
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null)
call on method [MethodName: _get; MethodIdInteger: 26; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, , true)]
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor
invoked for method MethodName: _get; MethodIdInteger: 26; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, , true)
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - Attempting to lock node
/3520@rachna/42/1214582760875/8501033611511983927136 for owner
Thread[http-8080-Processor24,5,main]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked>
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked>
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked>
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42/1214582760875, caller=Thread[http-8080-Processor24,5,main],
lock=<unlocked>
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42/1214582760875, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42/1214582760875/8501033611511983927136,
caller=Thread[http-8080-Processor24,5,main], lock=<unlocked>
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42/1214582760875/8501033611511983927136,
caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- invoke MethodName: _get; MethodIdInteger: 26; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, , true)
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- must Load, uninitialized
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- load element /3520@rachna/42/1214582760875/8501033611511983927136 mustLoad=true
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance
[null] and InvocationContext [InvocationContext{methodCall=MethodName: _lock;
MethodIdInteger: 33; Args: (/3520@rachna/42/1214582760875/8501033611511983927136, WRITE,
false)transaction=null, globalTransaction=null, optionOverrides=Option{failSilently=false,
cacheModeLocal=false, dataVersion=null, suppressLocking=false, forceDataGravitation=false,
skipDataGravitation=false}, originLocal=true, txHasMods=false}]
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional
context.
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as
null
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null)
call on method [MethodName: _lock; MethodIdInteger: 33; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, WRITE, false)]
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor
invoked for method MethodName: _lock; MethodIdInteger: 33; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, WRITE, false)
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - Attempting to lock node
/3520@rachna/42/1214582760875/8501033611511983927136 for owner
Thread[http-8080-Processor24,5,main]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock -
acquireReadLock(): caller Thread[http-8080-Processor24,5,main] already owns lock for /
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock -
acquireReadLock(): caller Thread[http-8080-Processor24,5,main] already owns lock for
/3520@rachna
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock -
acquireReadLock(): caller Thread[http-8080-Processor24,5,main] already owns lock for
/3520@rachna/42
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42/1214582760875, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock -
acquireReadLock(): caller Thread[http-8080-Processor24,5,main] already owns lock for
/3520@rachna/42/1214582760875
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42/1214582760875, caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42/1214582760875/8501033611511983927136,
caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock -
acquireReadLock(): caller Thread[http-8080-Processor24,5,main] already owns lock for
/3520@rachna/42/1214582760875/8501033611511983927136
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42/1214582760875/8501033611511983927136,
caller=Thread[http-8080-Processor24,5,main], lock=read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope
options
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- loadNode /3520@rachna/42/1214582760875/8501033611511983927136
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.loader.JDBCCacheLoader - executing
sql: select node from jbosscache where fqn=?
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:36:20.765 PM DEBUG org.jboss.cache.loader.ManagedConnectionFactory -
Connection checked out: org.apache.tomcat.dbcp.dbcp.PoolableConnection@1e2f6b0
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.marshall.VersionAwareMarshaller -
Read version 20
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.marshall.CacheMarshaller200 -
Unmarshalled object {somedata}
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- nodeExists true
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- Node data is not null, loading
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- Setting dataLoaded to true
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor
- invoke MethodName: _get; MethodIdInteger: 26; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, , true)
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.CallInterceptor -
Passing up method MethodName: _get; MethodIdInteger: 26; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, , true) so it gets invoked on
cache.
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.CacheImpl.JBossCache-Cluster -
_get("/3520@rachna/42/1214582760875/8501033611511983927136", "",
"true")
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Invoking EvictionInterceptor
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Updating node/element events with no tx
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
No event added. Element does not exist
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Finished invoking EvictionInterceptor
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
Attempting to release locks on current thread.  Lock table is
{Thread[http-8080-Processor24,5,main]=[read owners=[Thread[http-8080-Processor24,5,main]],
read owners=[Thread[http-8080-Processor24,5,main]], read
owners=[Thread[http-8080-Processor24,5,main]], read
owners=[Thread[http-8080-Processor24,5,main]], read
owners=[Thread[http-8080-Processor24,5,main]]]}
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42/1214582760875/8501033611511983927136: read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42/1214582760875: read
owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42: read owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna: read owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /: read owners=[Thread[http-8080-Processor24,5,main]]
  | Jun 27, 2008 09:36:20.781 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope
options
  | Jun 27, 2008 09:36:20.781 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance
[null] and InvocationContext [InvocationContext{methodCall=MethodName: _getData;
MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)transaction=null,
globalTransaction=null, optionOverrides=Option{failSilently=false, cacheModeLocal=false,
dataVersion=null, suppressLocking=false, forceDataGravitation=false,
skipDataGravitation=false}, originLocal=true, txHasMods=false}]
  | Jun 27, 2008 09:36:20.781 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional
context.
  | Jun 27, 2008 09:36:20.781 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as
null
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null)
call on method [MethodName: _getData; MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)]
  | Jun 27, 2008 09:36:20.781 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor
invoked for method MethodName: _getData; MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:36:20.781 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - bypassed locking as method
_getData() doesn't require locking
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- invoke MethodName: _getData; MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor
- invoke MethodName: _getData; MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.CallInterceptor -
Passing up method MethodName: _getData; MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136) so it gets invoked on cache.
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Invoking EvictionInterceptor
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Updating node/element events with no tx
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Adding event EvictedEN[fqn=/3520@rachna/42/1214582760875/8501033611511983927136
event=VISIT_NODE_EVENT diff=0] to region at /
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Finished updating node
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Finished invoking EvictionInterceptor
  | Jun 27, 2008 09:36:20.781 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
Attempting to release locks on current thread.  Lock table is {}
  | Jun 27, 2008 09:36:20.781 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope
options
  | Jun 27, 2008 09:36:21.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
process(): region: /
  | Jun 27, 2008 09:36:21.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
processed 2 node events in region: /
  | Jun 27, 2008 09:36:21.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
Recycle queue is empty
  | Jun 27, 2008 09:36:21.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 has been idle for 0ms
  | Jun 27, 2008 09:36:21.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 should not be evicted
  | Jun 27, 2008 09:36:21.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 has been idle for 0ms
  | Jun 27, 2008 09:36:21.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 should not be evicted
  | Jun 27, 2008 09:36:26.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
process(): region: /
  | Jun 27, 2008 09:36:26.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
processed 0 node events in region: /
  | Jun 27, 2008 09:36:26.531 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
Recycle queue is empty
  | Jun 27, 2008 09:36:26.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 has been idle for 5000ms
  | Jun 27, 2008 09:36:26.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 should not be evicted
  | Jun 27, 2008 09:36:26.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 has been idle for 5000ms
  | Jun 27, 2008 09:36:26.531 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 should not be evicted
  | 

and after Step 3:

Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance
[null] and InvocationContext [InvocationContext{methodCall=MethodName: _get;
MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)transaction=null,
globalTransaction=null, optionOverrides=Option{failSilently=false, cacheModeLocal=false,
dataVersion=null, suppressLocking=false, forceDataGravitation=false,
skipDataGravitation=false}, originLocal=true, txHasMods=false}]
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional
context.
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as
null
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null)
call on method [MethodName: _get; MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)]
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor
invoked for method MethodName: _get; MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - Attempting to lock node
/3520@rachna/42/1214582760875/8501033611511983927136 for owner
Thread[http-8080-Processor25,5,main]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked>
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/, caller=Thread[http-8080-Processor25,5,main], lock=read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked>
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna, caller=Thread[http-8080-Processor25,5,main], lock=read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked>
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42, caller=Thread[http-8080-Processor25,5,main], lock=read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42/1214582760875, caller=Thread[http-8080-Processor25,5,main],
lock=<unlocked>
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42/1214582760875, caller=Thread[http-8080-Processor25,5,main], lock=read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42/1214582760875/8501033611511983927136,
caller=Thread[http-8080-Processor25,5,main], lock=<unlocked>
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42/1214582760875/8501033611511983927136,
caller=Thread[http-8080-Processor25,5,main], lock=read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- invoke MethodName: _get; MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- don't load, key requested is null
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- load element /3520@rachna/42/1214582760875/8501033611511983927136 mustLoad=false
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor
- invoke MethodName: _get; MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CallInterceptor -
Passing up method MethodName: _get; MethodIdInteger: 31; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136) so it gets invoked on cache.
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Invoking EvictionInterceptor
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Updating node/element events with no tx
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Adding event EvictedEN[fqn=/3520@rachna/42/1214582760875/8501033611511983927136
event=VISIT_NODE_EVENT diff=0] to region at /
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Finished updating node
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Finished invoking EvictionInterceptor
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
Attempting to release locks on current thread.  Lock table is
{Thread[http-8080-Processor25,5,main]=[read owners=[Thread[http-8080-Processor25,5,main]],
read owners=[Thread[http-8080-Processor25,5,main]], read
owners=[Thread[http-8080-Processor25,5,main]], read
owners=[Thread[http-8080-Processor25,5,main]], read
owners=[Thread[http-8080-Processor25,5,main]]]}
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42/1214582760875/8501033611511983927136: read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42/1214582760875: read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42: read owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna: read owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /: read owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope
options
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance
[null] and InvocationContext [InvocationContext{methodCall=MethodName: _get;
MethodIdInteger: 26; Args: (/3520@rachna/42/1214582760875/8501033611511983927136, ,
true)transaction=null, globalTransaction=null, optionOverrides=Option{failSilently=false,
cacheModeLocal=false, dataVersion=null, suppressLocking=false, forceDataGravitation=false,
skipDataGravitation=false}, originLocal=true, txHasMods=false}]
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional
context.
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as
null
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null)
call on method [MethodName: _get; MethodIdInteger: 26; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, , true)]
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor
invoked for method MethodName: _get; MethodIdInteger: 26; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, , true)
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - Attempting to lock node
/3520@rachna/42/1214582760875/8501033611511983927136 for owner
Thread[http-8080-Processor25,5,main]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked>
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/, caller=Thread[http-8080-Processor25,5,main], lock=read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked>
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna, caller=Thread[http-8080-Processor25,5,main], lock=read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked>
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42, caller=Thread[http-8080-Processor25,5,main], lock=read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42/1214582760875, caller=Thread[http-8080-Processor25,5,main],
lock=<unlocked>
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42/1214582760875, caller=Thread[http-8080-Processor25,5,main], lock=read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL:
fqn=/3520@rachna/42/1214582760875/8501033611511983927136,
caller=Thread[http-8080-Processor25,5,main], lock=<unlocked>
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL:
fqn=/3520@rachna/42/1214582760875/8501033611511983927136,
caller=Thread[http-8080-Processor25,5,main], lock=read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- invoke MethodName: _get; MethodIdInteger: 26; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, , true)
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- load element /3520@rachna/42/1214582760875/8501033611511983927136 mustLoad=false
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor
- invoke MethodName: _get; MethodIdInteger: 26; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, , true)
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CallInterceptor -
Passing up method MethodName: _get; MethodIdInteger: 26; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136, , true) so it gets invoked on
cache.
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.CacheImpl.JBossCache-Cluster -
_get("/3520@rachna/42/1214582760875/8501033611511983927136", "",
"true")
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Invoking EvictionInterceptor
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Updating node/element events with no tx
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
No event added. Element does not exist
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Finished invoking EvictionInterceptor
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
Attempting to release locks on current thread.  Lock table is
{Thread[http-8080-Processor25,5,main]=[read owners=[Thread[http-8080-Processor25,5,main]],
read owners=[Thread[http-8080-Processor25,5,main]], read
owners=[Thread[http-8080-Processor25,5,main]], read
owners=[Thread[http-8080-Processor25,5,main]], read
owners=[Thread[http-8080-Processor25,5,main]]]}
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42/1214582760875/8501033611511983927136: read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42/1214582760875: read
owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna/42: read owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /3520@rachna: read owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
releasing lock for /: read owners=[Thread[http-8080-Processor25,5,main]]
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope
options
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance
[null] and InvocationContext [InvocationContext{methodCall=MethodName: _getData;
MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)transaction=null,
globalTransaction=null, optionOverrides=Option{failSilently=false, cacheModeLocal=false,
dataVersion=null, suppressLocking=false, forceDataGravitation=false,
skipDataGravitation=false}, originLocal=true, txHasMods=false}]
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional
context.
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as
null
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null)
call on method [MethodName: _getData; MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)]
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor
invoked for method MethodName: _getData; MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.PessimisticLockInterceptor - bypassed locking as method
_getData() doesn't require locking
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor
- invoke MethodName: _getData; MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor
- invoke MethodName: _getData; MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136)
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.CallInterceptor -
Passing up method MethodName: _getData; MethodIdInteger: 24; Args:
(/3520@rachna/42/1214582760875/8501033611511983927136) so it gets invoked on cache.
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Invoking EvictionInterceptor
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Updating node/element events with no tx
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Adding event EvictedEN[fqn=/3520@rachna/42/1214582760875/8501033611511983927136
event=VISIT_NODE_EVENT diff=0] to region at /
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Finished updating node
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor -
Finished invoking EvictionInterceptor
  | Jun 27, 2008 09:40:33.765 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor -
Attempting to release locks on current thread.  Lock table is {}
  | Jun 27, 2008 09:40:33.765 PM DEBUG
org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope
options
  | Jun 27, 2008 09:40:36.593 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
process(): region: /
  | Jun 27, 2008 09:40:36.593 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
processed 2 node events in region: /
  | Jun 27, 2008 09:40:36.593 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm -
Recycle queue is empty
  | Jun 27, 2008 09:40:36.593 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 has been idle for 0ms
  | Jun 27, 2008 09:40:36.593 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 should not be evicted
  | Jun 27, 2008 09:40:36.593 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 has been idle for 0ms
  | Jun 27, 2008 09:40:36.593 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node
/3520@rachna/42/1214582760875/8501033611511983927136 should not be evicted
  | 

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

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

2026

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

[jboss-user] [JBoss Cache: Core Edition] - Re: Passivation false problem