[JBoss Cache: Core Edition] - Re: Passivation false problem
by rachna_agg
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#4161121
Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4161121
18 years, 2 months