Overview
I have TreeCache working fine with a single instance of Tomcat/Hibernate. But when I start another instance of Tomcat I cannot seem to get the TreeCaches to communicate (at least the log messages do not indicate so).
Any idea what I need to do to get this to work on a single computer?
Hibernate version: 2.1.7c and associated JBoss Cache jars that come with Hibernate.
treecache.xml for both tomcat instances:
I am not sure if bind_addr needs to be unique, the development machine I am trying to get this running on is Windows XP, but production will be SuSE Linux (SLES9).
Code:
<?xml version="1.0" encoding="UTF-8"?>
<server>
<classpath codebase="./lib" archives="jboss-cache.jar, jgroups.jar"/>
<mbean code="org.jboss.cache.TreeCache"
name="jboss.cache:service=TreeCache">
<depends>jboss:service=Naming</depends>
<depends>jboss:service=TransactionManager</depends>
<attribute name="IsolationLevel">REPEATABLE_READ</attribute>
<attribute name="CacheMode">REPL_SYNC</attribute>
<attribute name="ClusterName">TreeCache-Cluster</attribute>
<attribute name="ClusterConfig">
<config>
<UDP mcast_addr="227.0.0.4" mcast_port="45566"
bind_addr="127.0.0.1"
ip_ttl="64" ip_mcast="true"
mcast_send_buf_size="150000" mcast_recv_buf_size="80000"
ucast_send_buf_size="150000" ucast_recv_buf_size="80000"
loopback="true"/>
<PING timeout="2000" num_initial_members="3"
up_thread="false" down_thread="false"/>
<MERGE2 min_interval="10000" max_interval="20000"/>
<FD shun="true" up_thread="true" down_thread="true"/>
<VERIFY_SUSPECT timeout="1500"
up_thread="false" down_thread="false"/>
<pbcast.NAKACK gc_lag="50" retransmit_timeout="600,1200,2400,4800"
up_thread="false" down_thread="false"/>
<pbcast.STABLE desired_avg_gossip="20000"
up_thread="false" down_thread="false"/>
<UNICAST timeout="600,1200,2400" window_size="100" min_threshold="10"
down_thread="false"/>
<FRAG frag_size="8192"
down_thread="false" up_thread="false"/>
<pbcast.GMS join_timeout="5000" join_retry_timeout="2000"
shun="true" print_local_addr="true"/>
<pbcast.STATE_TRANSFER up_thread="false" down_thread="false"/>
</config>
</attribute>
<attribute name="MaxCapacity">20000</attribute>
<attribute name="InitialStateRetrievalTimeout">20000</attribute>
<attribute name="SyncReplTimeout">10000</attribute>
<attribute name="LockAcquisitionTimeout">15000</attribute>
<attribute name="LockLeaseTimeout">60000</attribute>
<attribute name="EvictionPolicyConfig">
<config>
<attribute name="wakeUpIntervalSeconds">5</attribute>
<!-- Cache wide default -->
<region name="/_default_">
<attribute name="maxNodes">5000</attribute>
<attribute name="timeToIdleSeconds">1000</attribute>
</region>
<region name="com.ltoj.persistence.base.PoOrganization">
<attribute named="maxNodes">50</attribute>
<attribute named="timeToIdleSeconds">7200</attribute>
</region>
<!-- ... SNIP ... -->
<region name="com.ltoj.persistence.base.PoCharValue">
<attribute named="maxNodes">3000</attribute>
<attribute named="timeToIdleSeconds">7200</attribute>
</region>
</config>
</attribute>
</mbean>
</server>
Log messages from first Tomcat instance:Code:
Using CATALINA_BASE: c:\jakarta-tomcat-5.5.7
Using CATALINA_HOME: c:\jakarta-tomcat-5.5.7
Using CATALINA_TMPDIR: c:\jakarta-tomcat-5.5.7\temp
Using JAVA_HOME: c:\jdk1.5.0_01
[Filter: profiling] Using parameter [app_profile]
[Filter: profiling] defaulting to off [autostart=false]
[Filter: profiling] Turning filter off [app_profile=off]
19:07:14,343 WARN [main] DeltaManager:602 - Manager[/tcrepl], requesting session state from org.apache.catalina.cluster.mcast.McastMember[tcp://192.168.1.140:4002,192.168.1.140,4002, alive=2125]. This operation will timeout if no session state has been received within 60 seconds
19:07:24,171 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[3]] UserCounterListener:137 - Before increment, User Count: 0
19:07:24,171 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[3]] UserCounterListener:140 - After increment, User Count: 1
19:07:24,171 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[3]] UserCounterListener:73 - sessionCreated - Session info: id: '558B9137D8E2E6AF18A3121572656FD8.srv1'; createdAt '19:07:24'; lastAccessedAt '19:07:24'; currentTime '19:07:24; session count: '1
19:07:24,171 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[3]] UserCounterListener:76 - sessionCreated - Session info: id: '558B9137D8E2E6AF18A3121572656FD8.srv1'; createdAt '19:07:24'; lastAccessedAt '19:07:24'; currentTime '19:07:24; session count: '1
19:07:24,171 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[3]] UserCounterListener:137 - Before increment, User Count: 1
19:07:24,171 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[3]] UserCounterListener:140 - After increment, User Count: 2
19:07:24,171 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[3]] UserCounterListener:73 - sessionCreated - Session info: id: 'C5112282457E04FE37736BFC77C86330.srv2'; createdAt '19:07:24'; lastAccessedAt '19:07:24'; currentTime '19:07:24; session count: '2
19:07:24,171 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[3]] UserCounterListener:76 - sessionCreated - Session info: id: 'C5112282457E04FE37736BFC77C86330.srv2'; createdAt '19:07:24'; lastAccessedAt '19:07:24'; currentTime '19:07:24; session count: '2
19:07:25,937 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[5]] UserCounterListener:137 - Before increment, User Count: 2
19:07:25,937 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[5]] UserCounterListener:140 - After increment, User Count: 3
19:07:25,937 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[5]] UserCounterListener:73 - sessionCreated - Session info: id: '139647F56D6ACAF0F8E76664B86E9954.srv1'; createdAt '19:07:25'; lastAccessedAt '19:07:25'; currentTime '19:07:25; session count: '3
19:07:25,953 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[5]] UserCounterListener:76 - sessionCreated - Session info: id: '139647F56D6ACAF0F8E76664B86E9954.srv1'; createdAt '19:07:25'; lastAccessedAt '19:07:25'; currentTime '19:07:25; session count: '3
19:07:25,953 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[5]] UserCounterListener:137 - Before increment, User Count: 3
19:07:25,953 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[5]] UserCounterListener:140 - After increment, User Count: 4
19:07:25,953 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[5]] UserCounterListener:73 - sessionCreated - Session info: id: 'EAD1C6BCEB05D3EBB5638FA84FF9C688.srv2'; createdAt '19:07:25'; lastAccessedAt '19:07:25'; currentTime '19:07:25; session count: '4
19:07:25,953 INFO [org.apache.catalina.cluster.tcp.TcpReplicationThread[5]] UserCounterListener:76 - sessionCreated - Session info: id: 'EAD1C6BCEB05D3EBB5638FA84FF9C688.srv2'; createdAt '19:07:25'; lastAccessedAt '19:07:25'; currentTime '19:07:25; session count: '4
19:08:14,468 ERROR [main] DeltaManager:616 - Manager[/tcrepl], No session state received, timing out.
Log messages from second Tomcat instance:Code:
Using CATALINA_BASE: c:\jakarta-tomcat-5.5.7b
Using CATALINA_HOME: c:\jakarta-tomcat-5.5.7
Using CATALINA_TMPDIR: c:\jakarta-tomcat-5.5.7b\temp
Using JAVA_HOME: c:\jdk1.5.0_01
19:07:14,953 WARN [main] DeltaManager:602 - Manager[/stars], requesting session state from org.apache.catalina.cluster.mcast.McastMember[tcp://192.168.1.140:4001,192.168.1.140,4001, alive=4546]. This operation will timeout if no session state has been received within 60 seconds
[Filter: profiling] Using parameter [app_profile]
[Filter: profiling] defaulting to off [autostart=false]
[Filter: profiling] Turning filter off [app_profile=off]
19:07:17,046 WARN [main] DeltaManager:602 - Manager[/tcrepl], requesting session state from org.apache.catalina.cluster.mcast.McastMember[tcp://192.168.1.140:4001,192.168.1.140,4001, alive=6546]. This operation will timeout if no session state has been received within 60 seconds
19:07:24,156 INFO [TP-Processor3] UserCounterListener:137 - Before increment, User Count: 0
19:07:24,156 INFO [TP-Processor3] UserCounterListener:140 - After increment, User Count: 1
19:07:24,156 INFO [TP-Processor3] UserCounterListener:73 - sessionCreated - Session info: id: 'C5112282457E04FE37736BFC77C86330.srv2'; createdAt '19:07:24'; lastAccessedAt '19:07:24'; currentTime '19:07:24; session count: '1
19:07:24,156 INFO [TP-Processor3] UserCounterListener:76 - sessionCreated - Session info: id: 'C5112282457E04FE37736BFC77C86330.srv2'; createdAt '19:07:24'; lastAccessedAt '19:07:24'; currentTime '19:07:24; session count: '1
19:07:25,937 INFO [TP-Processor2] UserCounterListener:137 - Before increment, User Count: 1
19:07:25,937 INFO [TP-Processor2] UserCounterListener:140 - After increment, User Count: 2
19:07:25,937 INFO [TP-Processor2] UserCounterListener:73 - sessionCreated - Session info: id: 'EAD1C6BCEB05D3EBB5638FA84FF9C688.srv2'; createdAt '19:07:25'; lastAccessedAt '19:07:25'; currentTime '19:07:25; session count: '2
19:07:25,937 INFO [TP-Processor2] UserCounterListener:76 - sessionCreated - Session info: id: 'EAD1C6BCEB05D3EBB5638FA84FF9C688.srv2'; createdAt '19:07:25'; lastAccessedAt '19:07:25'; currentTime '19:07:25; session count: '2
19:07:28,062 INFO [TP-Processor2] ActionFilter:636 - Handling request URI: '/stars/gridAction.do', user='ltojgst4', host='192.168.1.140', requestParms: method='Refresh', token='c76da154cb07456b11bf039790b6cb2a', base.id='1307', base.classOwner='null', newAssessment.assessmentDate='null', base.sortedAssessment[0].assessmentDate='null', base.sortedAssessment[1].assessmentDate='null', chartType='null', outputFmt='null', user-agent='Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.7.5) Gecko/20041107 Firefox/1.0'
19:07:29,656 WARN [TP-Processor2] CacheFactory:36 - read-only cache configured for mutable: com.ltoj.persistence.base.PoGradeLevel
19:07:29,656 WARN [TP-Processor2] CacheFactory:36 - read-only cache configured for mutable: com.ltoj.persistence.base.PoOrganizationType
19:07:29,656 WARN [TP-Processor2] CacheFactory:36 - read-only cache configured for mutable: com.ltoj.persistence.base.PoRole
19:07:29,656 WARN [TP-Processor2] CacheFactory:36 - read-only cache configured for mutable: com.ltoj.persistence.base.PoStatus
AbandonedObjectPool is used (org.apache.tomcat.dbcp.dbcp.AbandonedObjectPool@15780d9)
LogAbandoned: true
RemoveAbandoned: true
RemoveAbandonedTimeout: 60
19:07:29,937 INFO [TP-Processor2] PropertyConfigurator:172 - configure(): attribute size: 57
19:07:29,937 DEBUG [TP-Processor2] PropertyConfigurator:208 - setting attribute IsolationLevel to REPEATABLE_READ
19:07:29,937 DEBUG [TP-Processor2] PropertyConfigurator:208 - setting attribute CacheMode to REPL_SYNC
19:07:29,937 DEBUG [TP-Processor2] PropertyConfigurator:208 - setting attribute ClusterName to TreeCache-Cluster
19:07:29,953 DEBUG [TP-Processor2] PropertyConfigurator:250 - Invoking setter method: public void org.jboss.cache.TreeCache.setClusterConfig(org.w3c.dom.Element) with parameter "[config: null]" of type class com.sun.org.apache.xerces.internal.dom.DeferredElementImpl
19:07:29,953 INFO [TP-Processor2] TreeCache:621 - setClusterConfig(): setting cluster properties from xml to: UDP(bind_addr=127.0.0.1;ip_mcast=true;ip_ttl=64;loopback=true;mcast_addr=227.0.0.4;mcast_port=45566;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)
19:07:29,953 DEBUG [TP-Processor2] PropertyConfigurator:250 - Invoking setter method: public void org.jboss.cache.TreeCache.setInitialStateRetrievalTimeout(long) with parameter "20000" of type class java.lang.Long
19:07:29,953 DEBUG [TP-Processor2] PropertyConfigurator:250 - Invoking setter method: public void org.jboss.cache.TreeCache.setSyncReplTimeout(long) with parameter "10000" of type class java.lang.Long
19:07:29,953 DEBUG [TP-Processor2] PropertyConfigurator:250 - Invoking setter method: public void org.jboss.cache.TreeCache.setLockAcquisitionTimeout(long) with parameter "15000" of type class java.lang.Long
19:07:29,953 DEBUG [TP-Processor2] PropertyConfigurator:250 - Invoking setter method: public void org.jboss.cache.TreeCache.setEvictionPolicyConfig(org.w3c.dom.Element) with parameter "[config: null]" of type class com.sun.org.apache.xerces.internal.dom.DeferredElementImpl
19:07:29,953 INFO [TP-Processor2] TreeCache:573 - setEvictionPolicyConfig(): [config: null]
19:07:29,968 DEBUG [TP-Processor2] TreeCache:266 - Starting TreeCache
19:07:29,968 WARN [TP-Processor2] TreeCache:1100 - No transaction manager lookup class has been defined. TX will be null
19:07:29,984 INFO [TP-Processor2] TreeCache:1237 - interceptor chain is:
class org.jboss.cache.interceptors.CallInterceptor
class org.jboss.cache.interceptors.ReplicationInterceptor
19:07:29,984 INFO [TP-Processor2] TreeCache:1124 - cache mode is REPL_SYNC
-------------------------------------------------------
GMS: address is localhost:4794
-------------------------------------------------------
19:07:30,765 INFO [TP-Processor3] ActionFilter:636 - Handling request URI: '/stars/instructorChartWizardAction.do', user='ltojgst4', host='192.168.1.140', requestParms: method='Step1', token='0570ffe25506c0e13d69dc62c8757e57', parm1='n/a', measId[0]='1307' , aggrId='null', showCharacteristics='false', datasetCt='', oneDataset='false', user-agent='Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322)'
19:07:32,375 INFO [TP-Processor2] TreeCache:1445 - state could not be retrieved (must be first member in group)
19:07:32,375 DEBUG [TP-Processor2] TreeCache:299 - Started TreeCache
19:07:32,375 INFO [MessageDispatcher up processing thread] TreeCache:2986 - new cache is null (maybe first member in cluster)
19:07:32,390 INFO [MessageDispatcher up processing thread] TreeCache:3079 - viewAccepted(): new members: [localhost:4794]
19:07:34,750 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/2", item, net.sf.hibernate.impl.CacheEntry@63f5af)
19:07:34,765 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/2", item, net.sf.hibernate.impl.CacheEntry@96e599)
19:07:34,765 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoOrgPolicy
19:07:34,765 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoOrgPolicy/2
19:07:34,765 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:34,781 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:34,796 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoRole/4", item, "true")
19:07:34,812 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoRole/4", item, "true")
19:07:34,812 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoRole/4", item, net.sf.hibernate.impl.CacheEntry@3945e2)
19:07:34,812 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoRole
19:07:34,812 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoRole/4
19:07:34,812 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:34,828 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoRole/4", item, net.sf.hibernate.impl.CacheEntry@efe27b)
19:07:34,828 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:34,906 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/632", item, net.sf.hibernate.impl.CacheEntry@1e136a8)
19:07:34,906 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoOrgPolicy/632
19:07:34,906 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:34,921 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/327", item, net.sf.hibernate.impl.CacheEntry@1b5eba4)
19:07:34,921 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoOrgPolicy/327
19:07:34,921 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:34,921 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoOrgPolicy/5", item, "true")
19:07:34,921 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/5", item, net.sf.hibernate.impl.CacheEntry@235e9d)
19:07:34,937 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoOrgPolicy/5
19:07:34,937 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:34,937 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoRole/1", item, "true")
19:07:34,937 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoRole/1", item, net.sf.hibernate.impl.CacheEntry@3b5699)
19:07:34,937 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoRole/1
19:07:34,937 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:34,953 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/632", item, net.sf.hibernate.impl.CacheEntry@4e50ee)
19:07:34,953 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:34,968 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/327", item, net.sf.hibernate.impl.CacheEntry@5074de)
19:07:34,968 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:34,968 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoOrgPolicy/5", item, "true")
19:07:34,984 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoRole/1", item, "true")
19:07:35,000 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/632", item, net.sf.hibernate.impl.CacheEntry@93f13f)
19:07:35,000 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,000 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/2", item, net.sf.hibernate.impl.CacheEntry@1c160cb)
19:07:35,015 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,015 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/327", item, net.sf.hibernate.impl.CacheEntry@1204425)
19:07:35,015 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,015 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoOrgPolicy/5", item, "true")
19:07:35,046 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/632", item, net.sf.hibernate.impl.CacheEntry@52a2e3)
19:07:35,046 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,046 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/2", item, net.sf.hibernate.impl.CacheEntry@ac576f)
19:07:35,046 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,062 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoOrgPolicy/327", item, net.sf.hibernate.impl.CacheEntry@1e6743e)
19:07:35,062 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,062 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoOrgPolicy/5", item, "true")
19:07:35,062 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoRole/4", item, "true")
19:07:35,062 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoRole/1", item, "true")
19:07:35,078 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoRole/4", item, "true")
19:07:35,078 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoRole/1", item, "true")
19:07:35,656 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/1307", item, net.sf.hibernate.impl.CacheEntry@16d3046)
19:07:35,656 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClassMeasurement
19:07:35,656 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClassMeasurement/1307
19:07:35,656 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,656 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/1515", item, net.sf.hibernate.impl.CacheEntry@1a37fac)
19:07:35,656 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClassMeasurement/1515
19:07:35,656 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,656 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/1766", item, net.sf.hibernate.impl.CacheEntry@1ad9b0f)
19:07:35,656 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClassMeasurement/1766
19:07:35,656 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,671 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/660", item, net.sf.hibernate.impl.CacheEntry@edf4c8)
19:07:35,671 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClassMeasurement/660
19:07:35,671 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,671 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/1545", item, net.sf.hibernate.impl.CacheEntry@54f169)
19:07:35,671 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClassMeasurement/1545
19:07:35,671 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,671 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/2247", item, net.sf.hibernate.impl.CacheEntry@825cf3)
19:07:35,671 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClassMeasurement/2247
19:07:35,671 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,671 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/1546", item, net.sf.hibernate.impl.CacheEntry@789d63)
19:07:35,671 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClassMeasurement/1546
19:07:35,671 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,687 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/1307", item, net.sf.hibernate.impl.CacheEntry@1fb9fb3)
19:07:35,687 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,687 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/1515", item, net.sf.hibernate.impl.CacheEntry@5b8e8c)
19:07:35,687 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,703 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/1766", item, net.sf.hibernate.impl.CacheEntry@10c0fa7)
19:07:35,703 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,703 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/660", item, net.sf.hibernate.impl.CacheEntry@10b755d)
19:07:35,703 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,703 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/1545", item, net.sf.hibernate.impl.CacheEntry@4c7e10)
19:07:35,703 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,703 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/2247", item, net.sf.hibernate.impl.CacheEntry@44a013)
19:07:35,718 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:35,718 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClassMeasurement/1546", item, net.sf.hibernate.impl.CacheEntry@2d80ae)
19:07:35,718 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,484 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoClass/277", item, "true")
19:07:36,500 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClass/277", item, net.sf.hibernate.impl.CacheEntry@387202)
19:07:36,500 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClass
19:07:36,500 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClass/277
19:07:36,500 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,515 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoStatus/1", item, "true")
19:07:36,515 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoStatus/1", item, net.sf.hibernate.impl.CacheEntry@10a519)
19:07:36,515 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoStatus
19:07:36,531 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoStatus/1
19:07:36,531 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,531 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoClass/277", item, "true")
19:07:36,546 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoStatus/1", item, "true")
19:07:36,562 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementParms/1140", item, "true")
19:07:36,562 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoMeasurementParms/1140", item, net.sf.hibernate.impl.CacheEntry@6ecec5)
19:07:36,562 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoMeasurementParms
19:07:36,562 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoMeasurementParms/1140
19:07:36,562 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,578 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementParms/1140", item, "true")
19:07:36,578 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoSubject/28", item, "true")
19:07:36,578 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoSubject/28", item, net.sf.hibernate.impl.CacheEntry@1a517bd)
19:07:36,578 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoSubject
19:07:36,578 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoSubject/28
19:07:36,578 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,593 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoSubject/28", item, "true")
19:07:36,593 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementUnit/1", item, "true")
19:07:36,593 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoMeasurementUnit/1", item, net.sf.hibernate.impl.CacheEntry@258c74)
19:07:36,593 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoMeasurementUnit
19:07:36,593 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoMeasurementUnit/1
19:07:36,593 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,609 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoClass/1783", item, "true")
19:07:36,609 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClass/1783", item, net.sf.hibernate.impl.CacheEntry@1b2591c)
19:07:36,609 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClass/1783
19:07:36,609 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,625 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementUnit/1", item, "true")
19:07:36,625 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoClass/1783", item, "true")
19:07:36,625 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementParms/479", item, "true")
19:07:36,625 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoMeasurementParms/479", item, net.sf.hibernate.impl.CacheEntry@1824d2c)
19:07:36,625 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoMeasurementParms/479
19:07:36,625 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,625 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoSubject/123", item, "true")
19:07:36,640 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoSubject/123", item, net.sf.hibernate.impl.CacheEntry@b91602)
19:07:36,640 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoSubject/123
19:07:36,640 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,640 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoClass/37", item, "true")
19:07:36,640 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClass/37", item, net.sf.hibernate.impl.CacheEntry@1c44a6d)
19:07:36,640 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClass/37
19:07:36,640 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,640 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoClass/1803", item, "true")
19:07:36,656 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementParms/479", item, "true")
19:07:36,656 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoSubject/123", item, "true")
19:07:36,656 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoClass/37", item, "true")
19:07:36,656 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoClass/1803", item, "true")
19:07:36,656 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClass/1803", item, net.sf.hibernate.impl.CacheEntry@5facbd)
19:07:36,656 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClass/1803
19:07:36,671 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,671 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementParms/1126", item, "true")
19:07:36,671 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoMeasurementParms/1126", item, net.sf.hibernate.impl.CacheEntry@1f2a9da)
19:07:36,671 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoMeasurementParms/1126
19:07:36,671 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,671 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoSubject/15", item, "true")
19:07:36,671 DEBUG [TP-Processor2] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoSubject/15", item, net.sf.hibernate.impl.CacheEntry@e64795)
19:07:36,671 DEBUG [TP-Processor2] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoSubject/15
19:07:36,687 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementParms/1126", item, "true")
19:07:36,687 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoSubject/15", item, "true")
19:07:36,687 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoSubject/15", item, net.sf.hibernate.impl.CacheEntry@7f5bf9)
19:07:36,687 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,687 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementUnit/28", item, "true")
19:07:36,687 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoMeasurementUnit/28", item, net.sf.hibernate.impl.CacheEntry@1fd25ce)
19:07:36,687 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoMeasurementUnit/28
19:07:36,703 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,703 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoClass/1804", item, "true")
19:07:36,703 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoClass/1804", item, net.sf.hibernate.impl.CacheEntry@f1b25e)
19:07:36,703 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoClass/1804
19:07:36,703 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,703 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementParms/19", item, "true")
19:07:36,703 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoMeasurementParms/19", item, net.sf.hibernate.impl.CacheEntry@1f6f3dc)
19:07:36,703 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoMeasurementParms/19
19:07:36,703 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,718 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoSubject/6", item, "true")
19:07:36,718 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoSubject/6", item, net.sf.hibernate.impl.CacheEntry@252a17)
19:07:36,718 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoSubject/6
19:07:36,718 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,718 DEBUG [TP-Processor3] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementParms/1022", item, "true")
19:07:36,734 DEBUG [TP-Processor3] TreeCache:2344 - _put(null, "//com/ltoj/persistence/base/PoMeasurementParms/1022", item, net.sf.hibernate.impl.CacheEntry@15724a0)
19:07:36,734 DEBUG [TP-Processor3] Node:200 - createChild: fqn=//com/ltoj/persistence/base/PoMeasurementParms/1022
19:07:36,734 DEBUG [TP-Processor3] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,734 DEBUG [TP-Processor2] TreeCache:2139 - callRemoteMethods(): destination list is empty, discarding call
19:07:36,734 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementUnit/28", item, "true")
19:07:36,750 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoClass/1804", item, "true")
19:07:36,750 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementParms/19", item, "true")
19:07:36,750 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoSubject/6", item, "true")
19:07:36,750 DEBUG [TP-Processor2] TreeCache:1577 - get(, "//com/ltoj/persistence/base/PoMeasurementParms/1022", item, "true")
19:07:36,953 INFO [TP-Processor3] ActionFilter:636 - Handled request URI: '/stars/instructorChartWizardAction.do', user='ltojgst4', host='192.168.1.140', requestParms: method='Step1', requestToken='0570ffe25506c0e13d69dc62c8757e57', sessionToken='e26458af81263b8d9640389c189314c6'
19:07:36,984 INFO [TP-Processor2] ActionFilter:636 - Handled request URI: '/stars/gridAction.do', user='ltojgst4', host='192.168.1.140', requestParms: method='Refresh', requestToken='c76da154cb07456b11bf039790b6cb2a', sessionToken='af820b590924f894b2f4856e4dc60b17'
Thank you - Richard[/code]