Eviction problems with JBossCache 1.4.0

[mimix]

Hi,

We are running some tests in JMeter (2 clustered jboss instances) and monitoring the results with JMX (JBoss 4.0.4GA), and we noticed the following behavior:

jboss-cache 1.3.0

[url=http://img120.imageshack.us/my.php?image=cachenodesandconnectionssg1.png][img=http://img120.imageshack.us/img120/5803/cachenodesandconnectionssg1.th.png][/url]

jboss-cache 1.4.0 GA

[url=http://img259.imageshack.us/my.php?image=cachenodesandconnectionsko1.png][img=http://img259.imageshack.us/img259/2081/cachenodesandconnectionsko1.th.png][/url]

Both are running the same tests, the only change is the version of JBoss Cache jar.

It seems like eviction stopped working as soon as we upgraded the jar... how come?

We are using hibernate 3.0.3cr3 (from CVS), optimistic locking, invalidation async. Thanks again.

[mimix]

To complement the previous post, we are seeing this strange behavior of jboss-cache 1.4 in the logs every time the eviction thread wakes up:

2006-07-31 15:10:08,583 DEBUG [org.jboss.cache.interceptors.TxInterceptor] local transaction exists - registering global tx if not present for Thread[Timer-3,5,jboss]
2006-07-31 15:10:08,583 INFO [org.jboss.cache.interceptors.OptimisticNodeInterceptor] read Method _evict(/com/xyz/model/credit/Proposal/com.xyz.model.credit.Proposal#402884810cc59a9d010cc5b646e000e8) called - don't know how to handle, passing on!
2006-07-31 15:10:08,583 DEBUG [org.jboss.cache.interceptors.TxInterceptor] Running commit phase. One phase? false
2006-07-31 15:10:08,583 DEBUG [org.jboss.cache.interceptors.OptimisticValidatorInterceptor] commiting validated changes
2006-07-31 15:10:08,583 DEBUG [org.jboss.cache.interceptors.TxInterceptor] Finished local commit/rollback method for GlobalTransaction:<xyz-jboss03:35545>:64623
2006-07-31 15:10:08,583 DEBUG [org.jboss.cache.interceptors.TxInterceptor] Finished commit phase

[manik]

Hi,

When the cache starts up, it dumps a list of the interceptors configured into the log. Could you paste this list? Since 1.4.0, all eviction code has been moved to a dedicated EvictionInterceptor.

[mimix]

The log follows:

2006-08-11 18:31:57,926 INFO [org.jboss.cache.TreeCache] setting cluster properties from xml to: UDP(ip_mcast=true;ip_ttl=64;loopback=false;mcast_addr=228.1.2.4;mcast_port=45567;mcast_recv_buf_size=80000;mcast_send_buf_size=150000;ucast_recv_buf_size=80000;ucast_send_buf_size=150000):PING(down_thread=false;num_initial_members=3;timeout=2000;up_thread=false):MERGE2(max_interval=20000;min_interval=10000):FD(down_thread=true;shun=true;up_thread=true):VERIFY_SUSPECT(down_thread=false;timeout=1500;up_thread=false):pbcast.NAKACK(down_thread=false;gc_lag=50;retransmit_timeout=600,1200,2400,4800;up_thread=false):pbcast.STABLE(desired_avg_gossip=20000;down_thread=false;up_thread=false):UNICAST(down_thread=false;min_threshold=10;timeout=600,1200,2400;window_size=100):FRAG(down_thread=false;frag_size=8192;up_thread=false):pbcast.GMS(join_retry_timeout=2000;join_timeout=5000;print_local_addr=true;shun=true):pbcast.STATE_TRANSFER(down_thread=false;up_thread=false)
2006-08-11 18:31:57,930 INFO [org.jboss.cache.TreeCache] setEvictionPolicyConfig(): [config: null]
2006-08-11 18:31:57,930 WARN [org.jboss.cache.TreeCache] Using deprecated configuration element 'UseMarshalling'. See 'UseRegionBasedMarshalling' instead.
2006-08-11 18:31:57,931 DEBUG [org.jboss.cache.TreeCache] Creating jboss.cache:service=HibernateTreeCache
2006-08-11 18:31:57,939 DEBUG [org.jboss.cache.GenericTransactionManagerLookup] Trying to lookup TransactionManager for JBoss, JRun4
2006-08-11 18:31:57,939 INFO [org.jboss.cache.GenericTransactionManagerLookup] Found TransactionManager for JBoss, JRun4
2006-08-11 18:31:57,953 WARN [org.jboss.cache.TreeCache] Using deprecated configuration element 'EvictionPolicyProvider'. This is only provided for 1.2.x backward compatibility and may disappear in future releases.
2006-08-11 18:31:57,963 DEBUG [org.jboss.cache.eviction.LRUConfiguration] parseConfig: name -- /_default_ maxNodes -- 5000 timeToLiveSeconds -- 900 maxAgeSeconds -- 1200
2006-08-11 18:31:57,966 DEBUG [org.jboss.cache.eviction.RegionManager] createRegion(): creating region for fqn- /_default_
2006-08-11 18:31:58,004 INFO [org.jboss.cache.factories.InterceptorChainFactory] interceptor chain is:
class org.jboss.cache.interceptors.CallInterceptor
class org.jboss.cache.interceptors.OptimisticNodeInterceptor
class org.jboss.cache.interceptors.EvictionInterceptor
class org.jboss.cache.interceptors.OptimisticCreateIfNotExistsInterceptor
class org.jboss.cache.interceptors.OptimisticValidatorInterceptor
class org.jboss.cache.interceptors.OptimisticLockingInterceptor
class org.jboss.cache.interceptors.InvalidationInterceptor
class org.jboss.cache.interceptors.TxInterceptor
class org.jboss.cache.interceptors.CacheMgmtInterceptor
2006-08-11 18:31:58,014 INFO [org.jboss.cache.TreeCache] cache mode is INVALIDATION_ASYNC
2006-08-11 18:31:58,018 DEBUG [org.jboss.cache.marshall.VersionAwareMarshaller] Initialised with version 1.4.0 and versionInt 14
2006-08-11 18:31:58,018 DEBUG [org.jboss.cache.marshall.VersionAwareMarshaller] Using default marshaller class org.jboss.cache.marshall.TreeCacheMarshaller140
2006-08-11 18:31:58,018 DEBUG [org.jboss.cache.TreeCache] Created jboss.cache:service=HibernateTreeCache
2006-08-11 18:31:58,018 DEBUG [org.jboss.cache.TreeCache] Starting jboss.cache:service=HibernateTreeCache
2006-08-11 18:31:58,022 INFO [STDOUT]
-------------------------------------------------------
GMS: address is jboss01:33008
-------------------------------------------------------
2006-08-11 18:32:00,032 INFO [org.jboss.cache.TreeCache] TreeCache local address is boss01:33008
2006-08-11 18:32:00,033 INFO [org.jboss.cache.eviction.RegionManager] Starting eviction timer
2006-08-11 18:32:00,033 INFO [org.jboss.cache.TreeCache] viewAccepted(): [jboss01:33008|0] [paggo-jboss01:33008]
2006-08-11 18:32:00,033 DEBUG [org.jboss.cache.TreeCache] Started jboss.cache:service=HibernateTreeCache