Sorry, here are the logs after Step 2: Jun 27, 2008 05:09:02.046 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:09:02.046 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:09:02.046 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:09:02.046 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 50090ms | Jun 27, 2008 05:09:02.046 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:02.046 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 50090ms | Jun 27, 2008 05:09:02.046 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:03.108 PM DEBUG org.apache.catalina.session.ManagerBase - Start expire sessions StandardManager at 1214566743108 sessioncount 0 | Jun 27, 2008 05:09:03.108 PM DEBUG org.apache.catalina.session.ManagerBase - End expire sessions StandardManager processingTime 0 expired sessions: 0 | Jun 27, 2008 05:09:07.046 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:09:07.046 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:09:07.046 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:09:07.046 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 55090ms | Jun 27, 2008 05:09:07.046 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:07.046 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 55090ms | Jun 27, 2008 05:09:07.046 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance [null] and InvocationContext [InvocationContext{methodCall=MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185)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 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional context. | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as null | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null) call on method [MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185)] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor invoked for method MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - Attempting to lock node /3256@rachna/42/1214566690893/1883577710211620771185 for owner Thread[http-8080-Processor24,5,main] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked> | Jun 27, 2008 05:09:07.186 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 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked> | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked> | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42/1214566690893, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked> | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42/1214566690893, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42/1214566690893/1883577710211620771185, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked> | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42/1214566690893/1883577710211620771185, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - invoke MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - don't load, key requested is null | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - load element /3256@rachna/42/1214566690893/1883577710211620771185 mustLoad=false | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor - invoke MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.CallInterceptor - Passing up method MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) so it gets invoked on cache. | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Invoking EvictionInterceptor | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Updating node/element events with no tx | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Adding event EvictedEN[fqn=/3256@rachna/42/1214566690893/1883577710211620771185 event=VISIT_NODE_EVENT diff=0] to region at / | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Finished updating node | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Finished invoking EvictionInterceptor | Jun 27, 2008 05:09:07.186 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 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42/1214566690893/1883577710211620771185: read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42/1214566690893: read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42: read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna: read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /: read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope options | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance [null] and InvocationContext [InvocationContext{methodCall=MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , 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 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional context. | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as null | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null) call on method [MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , true)] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor invoked for method MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , true) | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - Attempting to lock node /3256@rachna/42/1214566690893/1883577710211620771185 for owner Thread[http-8080-Processor24,5,main] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked> | Jun 27, 2008 05:09:07.186 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 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked> | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked> | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42/1214566690893, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked> | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42/1214566690893, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42/1214566690893/1883577710211620771185, caller=Thread[http-8080-Processor24,5,main], lock=<unlocked> | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42/1214566690893/1883577710211620771185, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - invoke MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , true) | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - must Load, uninitialized | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - load element /3256@rachna/42/1214566690893/1883577710211620771185 mustLoad=true | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance [null] and InvocationContext [InvocationContext{methodCall=MethodName: _lock; MethodIdInteger: 33; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, 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 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional context. | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as null | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null) call on method [MethodName: _lock; MethodIdInteger: 33; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, WRITE, false)] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor invoked for method MethodName: _lock; MethodIdInteger: 33; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, WRITE, false) | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - Attempting to lock node /3256@rachna/42/1214566690893/1883577710211620771185 for owner Thread[http-8080-Processor24,5,main] | Jun 27, 2008 05:09:07.186 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 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquireReadLock(): caller Thread[http-8080-Processor24,5,main] already owns lock for / | Jun 27, 2008 05:09:07.186 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 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquireReadLock(): caller Thread[http-8080-Processor24,5,main] already owns lock for /3256@rachna | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquireReadLock(): caller Thread[http-8080-Processor24,5,main] already owns lock for /3256@rachna/42 | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42/1214566690893, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquireReadLock(): caller Thread[http-8080-Processor24,5,main] already owns lock for /3256@rachna/42/1214566690893 | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42/1214566690893, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42/1214566690893/1883577710211620771185, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquireReadLock(): caller Thread[http-8080-Processor24,5,main] already owns lock for /3256@rachna/42/1214566690893/1883577710211620771185 | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42/1214566690893/1883577710211620771185, caller=Thread[http-8080-Processor24,5,main], lock=read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope options | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - loadNode /3256@rachna/42/1214566690893/1883577710211620771185 | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.loader.JDBCCacheLoader - executing sql: select node from jbosscache where fqn=? (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.loader.ManagedConnectionFactory - Connection checked out: org.apache.tomcat.dbcp.dbcp.PoolableConnection@423606 | Jun 27, 2008 05:09:07.186 PM DEBUG org.jboss.cache.marshall.VersionAwareMarshaller - Read version 20 | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.marshall.CacheMarshaller200 - Unmarshalled object {somedata} | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - nodeExists true | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - Node data is not null, loading | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - Setting dataLoaded to true | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor - invoke MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , true) | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.CallInterceptor - Passing up method MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , true) so it gets invoked on cache. | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.CacheImpl.JBossCache-Cluster - _get("/3256@rachna/42/1214566690893/1883577710211620771185", "", "true") | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Invoking EvictionInterceptor | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Updating node/element events with no tx | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - No event added. Element does not exist | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Finished invoking EvictionInterceptor | Jun 27, 2008 05:09:07.202 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 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42/1214566690893/1883577710211620771185: read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42/1214566690893: read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42: read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna: read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /: read owners=[Thread[http-8080-Processor24,5,main]] | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope options | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance [null] and InvocationContext [InvocationContext{methodCall=MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185)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 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional context. | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as null | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null) call on method [MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185)] | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor invoked for method MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - bypassed locking as method _getData() doesn't require locking | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - invoke MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor - invoke MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.CallInterceptor - Passing up method MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) so it gets invoked on cache. | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Invoking EvictionInterceptor | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Updating node/element events with no tx | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Adding event EvictedEN[fqn=/3256@rachna/42/1214566690893/1883577710211620771185 event=VISIT_NODE_EVENT diff=0] to region at / | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Finished updating node | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Finished invoking EvictionInterceptor | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - Attempting to release locks on current thread. Lock table is {} | Jun 27, 2008 05:09:07.202 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope options | Jun 27, 2008 05:09:12.061 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:09:12.061 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 2 node events in region: / | Jun 27, 2008 05:09:12.061 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:09:12.061 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 0ms | Jun 27, 2008 05:09:12.061 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:12.061 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 0ms | Jun 27, 2008 05:09:12.061 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:17.061 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:09:17.061 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:09:17.061 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:09:17.061 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 5000ms | Jun 27, 2008 05:09:17.061 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:17.061 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 5000ms | Jun 27, 2008 05:09:17.061 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | and after Step 3: Jun 27, 2008 05:09:32.091 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:09:32.091 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:09:32.091 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:09:32.091 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 20030ms | Jun 27, 2008 05:09:32.091 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:32.091 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 20030ms | Jun 27, 2008 05:09:32.091 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:37.106 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:09:37.106 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:09:37.106 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:09:37.106 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 25045ms | Jun 27, 2008 05:09:37.106 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:37.106 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 25045ms | Jun 27, 2008 05:09:37.106 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:42.106 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:09:42.106 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:09:42.106 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:09:42.106 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 30045ms | Jun 27, 2008 05:09:42.106 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:42.106 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 30045ms | Jun 27, 2008 05:09:42.106 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:47.121 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:09:47.121 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:09:47.121 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:09:47.121 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 35060ms | Jun 27, 2008 05:09:47.121 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:47.121 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 35060ms | Jun 27, 2008 05:09:47.121 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:52.121 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:09:52.121 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:09:52.121 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:09:52.121 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 40060ms | Jun 27, 2008 05:09:52.121 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:52.121 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 40060ms | Jun 27, 2008 05:09:52.121 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:57.136 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:09:57.136 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:09:57.136 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:09:57.136 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 45075ms | Jun 27, 2008 05:09:57.136 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:09:57.136 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 45075ms | Jun 27, 2008 05:09:57.136 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:02.136 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:10:02.136 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:10:02.136 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:10:02.136 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 50075ms | Jun 27, 2008 05:10:02.136 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:02.136 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 50075ms | Jun 27, 2008 05:10:02.136 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:03.151 PM DEBUG org.apache.catalina.session.ManagerBase - Start expire sessions StandardManager at 1214566803151 sessioncount 0 | Jun 27, 2008 05:10:03.151 PM DEBUG org.apache.catalina.session.ManagerBase - End expire sessions StandardManager processingTime 0 expired sessions: 0 | Jun 27, 2008 05:10:07.151 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:10:07.151 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:10:07.151 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:10:07.151 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 55090ms | Jun 27, 2008 05:10:07.151 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:07.151 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 55090ms | Jun 27, 2008 05:10:07.151 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:12.151 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:10:12.151 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:10:12.151 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:10:12.151 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 60090ms | Jun 27, 2008 05:10:12.151 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:12.151 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 60090ms | Jun 27, 2008 05:10:12.151 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:17.166 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:10:17.166 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:10:17.166 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:10:17.166 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 65105ms | Jun 27, 2008 05:10:17.166 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:17.166 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 65105ms | Jun 27, 2008 05:10:17.166 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:22.181 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:10:22.181 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:10:22.181 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:10:22.181 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 70120ms | Jun 27, 2008 05:10:22.181 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:22.181 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 70120ms | Jun 27, 2008 05:10:22.181 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:27.181 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:10:27.181 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:10:27.181 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:10:27.181 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 75120ms | Jun 27, 2008 05:10:27.181 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:27.181 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 75120ms | Jun 27, 2008 05:10:27.181 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance [null] and InvocationContext [InvocationContext{methodCall=MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185)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 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional context. | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as null | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null) call on method [MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185)] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor invoked for method MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - Attempting to lock node /3256@rachna/42/1214566690893/1883577710211620771185 for owner Thread[http-8080-Processor25,5,main] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked> | Jun 27, 2008 05:10:31.290 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 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked> | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna, caller=Thread[http-8080-Processor25,5,main], lock=read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked> | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42, caller=Thread[http-8080-Processor25,5,main], lock=read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42/1214566690893, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked> | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42/1214566690893, caller=Thread[http-8080-Processor25,5,main], lock=read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42/1214566690893/1883577710211620771185, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked> | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42/1214566690893/1883577710211620771185, caller=Thread[http-8080-Processor25,5,main], lock=read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - invoke MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - don't load, key requested is null | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - load element /3256@rachna/42/1214566690893/1883577710211620771185 mustLoad=false | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor - invoke MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CallInterceptor - Passing up method MethodName: _get; MethodIdInteger: 31; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) so it gets invoked on cache. | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Invoking EvictionInterceptor | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Updating node/element events with no tx | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Adding event EvictedEN[fqn=/3256@rachna/42/1214566690893/1883577710211620771185 event=VISIT_NODE_EVENT diff=0] to region at / | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Finished updating node | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Finished invoking EvictionInterceptor | Jun 27, 2008 05:10:31.290 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 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42/1214566690893/1883577710211620771185: read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42/1214566690893: read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42: read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna: read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /: read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope options | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance [null] and InvocationContext [InvocationContext{methodCall=MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , 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 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional context. | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as null | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null) call on method [MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , true)] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor invoked for method MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , true) | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - Attempting to lock node /3256@rachna/42/1214566690893/1883577710211620771185 for owner Thread[http-8080-Processor25,5,main] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked> | Jun 27, 2008 05:10:31.290 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 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked> | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna, caller=Thread[http-8080-Processor25,5,main], lock=read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked> | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42, caller=Thread[http-8080-Processor25,5,main], lock=read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42/1214566690893, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked> | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42/1214566690893, caller=Thread[http-8080-Processor25,5,main], lock=read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquiring RL: fqn=/3256@rachna/42/1214566690893/1883577710211620771185, caller=Thread[http-8080-Processor25,5,main], lock=<unlocked> | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.lock.IdentityLock - acquired RL: fqn=/3256@rachna/42/1214566690893/1883577710211620771185, caller=Thread[http-8080-Processor25,5,main], lock=read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - invoke MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , true) | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - load element /3256@rachna/42/1214566690893/1883577710211620771185 mustLoad=false | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor - invoke MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , true) | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CallInterceptor - Passing up method MethodName: _get; MethodIdInteger: 26; Args: (/3256@rachna/42/1214566690893/1883577710211620771185, , true) so it gets invoked on cache. | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.CacheImpl.JBossCache-Cluster - _get("/3256@rachna/42/1214566690893/1883577710211620771185", "", "true") | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Invoking EvictionInterceptor | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Updating node/element events with no tx | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - No event added. Element does not exist | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Finished invoking EvictionInterceptor | Jun 27, 2008 05:10:31.290 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 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42/1214566690893/1883577710211620771185: read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42/1214566690893: read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna/42: read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /3256@rachna: read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - releasing lock for /: read owners=[Thread[http-8080-Processor25,5,main]] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope options | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Invoked on cache instance [null] and InvocationContext [InvocationContext{methodCall=MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185)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 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting up transactional context. | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Setting tx as null and gtx as null | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.TxInterceptor - (null) call on method [MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185)] | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - PessimisticLockInterceptor invoked for method MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.PessimisticLockInterceptor - bypassed locking as method _getData() doesn't require locking | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CacheLoaderInterceptor - invoke MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CacheStoreInterceptor - invoke MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.CallInterceptor - Passing up method MethodName: _getData; MethodIdInteger: 24; Args: (/3256@rachna/42/1214566690893/1883577710211620771185) so it gets invoked on cache. | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Invoking EvictionInterceptor | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Updating node/element events with no tx | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Adding event EvictedEN[fqn=/3256@rachna/42/1214566690893/1883577710211620771185 event=VISIT_NODE_EVENT diff=0] to region at / | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Finished updating node | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.EvictionInterceptor - Finished invoking EvictionInterceptor | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.UnlockInterceptor - Attempting to release locks on current thread. Lock table is {} | Jun 27, 2008 05:10:31.290 PM DEBUG org.jboss.cache.interceptors.InvocationContextInterceptor - Resetting invocation-scope options | Jun 27, 2008 05:10:32.196 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:10:32.196 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 2 node events in region: / | Jun 27, 2008 05:10:32.196 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:10:32.196 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 0ms | Jun 27, 2008 05:10:32.196 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:32.196 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 0ms | Jun 27, 2008 05:10:32.196 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:37.196 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - process(): region: / | Jun 27, 2008 05:10:37.196 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - processed 0 node events in region: / | Jun 27, 2008 05:10:37.196 PM DEBUG org.jboss.cache.eviction.BaseEvictionAlgorithm - Recycle queue is empty | Jun 27, 2008 05:10:37.196 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 5000ms | Jun 27, 2008 05:10:37.196 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | Jun 27, 2008 05:10:37.196 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 has been idle for 5000ms | Jun 27, 2008 05:10:37.196 PM DEBUG org.jboss.cache.eviction.LRUAlgorithm - Node /3256@rachna/42/1214566690893/1883577710211620771185 should not be evicted | View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4161121#... Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&a...