Hello,

I am trying to use a tree cache as a second level hibernate cache in an existing weblogic 9.2 app. For some reasons, the code is unable to acquire a write lock in the cache, so it fails to put the data in. This is probably just a configuration problem, because it works fine if I switch to EhCache. I also tried a couple of variations (use bind_addr on my multi ip'ed laptop, deploying on some unix boxes etc) without much luck..

The only thing I can get printed in the logs is a "TreeCache:78 - ignoring write lock acquisition failure" that's not particularly helpful to resolve this.

That would be great if someone could look at this/provide some hints or some existing configuration files that do work for them.

Thanks in advance,
Sebastien

Here are the details:
Hibernate version: 3.2.5

Mapping documents:

hibernate.cfg.xml
<?xml version='1.0' encoding='utf-8'?>
<!DOCTYPE hibernate-configuration PUBLIC
"-//Hibernate/Hibernate Configuration DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd">

<hibernate-configuration>

<session-factory>

<!-- Datasource and transaction settings -->
<property name="hibernate.connection.datasource">StampsDS</property>
<property name="hibernate.jndi.class">weblogic.jndi.WLInitialContextFactory</property>
<property name="hibernate.transaction.factory_class">org.hibernate.transaction.CMTTransactionFactory</property>
<property name="hibernate.transaction.manager_lookup_class">org.hibernate.transaction.WeblogicTransactionManagerLookup</property>
<!--
<property name="hibernate.transaction.auto_close_session">true</property>
<property name="hibernate.transaction.flush_before_completion">true</property>
-->

<!-- SQL dialect -->
<property name="dialect">org.hibernate.dialect.SybaseAnywhereDialect</property>

<!-- Configure the second-level cache and query cache -->
<!--
<property name="cache.provider_class">org.hibernate.cache.EhCacheProvider</property>
-->
<property name="cache.provider_class">org.hibernate.cache.TreeCacheProvider</property>

<property name="cache.use_query_cache">true</property>

<!-- Echo all executed SQL to stdout -->
<property name="show_sql">true</property>

<!-- Echo all executed SQL to stdoutPretty print the SQL in the log and console. -->
<property name="format_sql">false</property>

<!-- mapping attribute resources -->
<mapping resource="object/com/xxx/domain/attribute/Attribute.hbm.xml"/>
<mapping resource="object/com/xxx/domain/attribute/AttributeAllowedValues.hbm.xml"/>
<mapping resource="object/com/xxx/domain/attribute/AttributeDataType.hbm.xml"/>
<mapping resource="object/com/xxx/domain/attribute/AttributeDefaultValueLink.hbm.xml"/>
<mapping resource="object/com/xxx/domain/attribute/AttributeGroup.hbm.xml"/>

<!-- cache configuration -->
<class-cache class="com.xxx.domain.attribute.Attribute" region="ATTRIBUTE" usage="transactional"/>
<class-cache class="com.xxx.domain.attribute.AttributeAllowedValues" region="ATTRIBUTE" usage="transactional"/>
<class-cache class="com.xxx.domain.attribute.AttributeDataType" region="ATTRIBUTE" usage="transactional"/>
<class-cache class="com.xxx.domain.attribute.AttributeGroup" region="ATTRIBUTE" usage="transactional"/>

<!-- cache collection configuration -->
<collection-cache collection="com.xxx.domain.attribute.Attribute.childAttributes" region="ATTRIBUTE" usage="transactional"/>
<collection-cache collection="com.xxx.domain.attribute.Attribute.allowedValues" region="ATTRIBUTE" usage="transactional"/>
<collection-cache collection="com.xxx.domain.attribute.Attribute.subAttrDefaults" region="ATTRIBUTE" usage="transactional"/>
<collection-cache collection="com.xxx.domain.attribute.AttributeGroup.attributes" region="ATTRIBUTE" usage="transactional"/>

</session-factory>
</hibernate-configuration>

treecache.xml:
<?xml version="1.0" encoding="UTF-8"?>

<!-- ===================================================================== -->
<!-- -->
<!-- Sample JBoss Cache Service Configuration -->
<!-- -->
<!-- ===================================================================== -->

<server>
<classpath codebase="." archives="jboss-cache.jar, jgroups.jar" />

<!-- ==================================================================== -->
<!-- Defines JBoss Cache configuration -->
<!-- ==================================================================== -->

<!-- Note the value of the 'code' attribute has changed since JBC 1.x -->
<mbean code="org.jboss.cache.TreeCache" name="jboss.cache:service=Cache">

<!-- Ensure JNDI and the TransactionManager are started before the
cache. Only works inside JBoss AS; ignored otherwise -->
<depends>jboss:service=Naming</depends>
<depends>jboss:service=TransactionManager</depends>

<!-- Configure the TransactionManager -->
<!--
<attribute name="TransactionManagerLookupClass">org.hibernate.transaction.WeblogicTransactionManagerLookup</attribute>
-->

<!-- Node locking level : SERIALIZABLE
REPEATABLE_READ (default)
READ_COMMITTED
READ_UNCOMMITTED
NONE -->
<attribute name="IsolationLevel">REPEATABLE_READ</attribute>

<!-- Lock parent before doing node additions/removes -->
<attribute name="LockParentForChildInsertRemove">true</attribute>

<!-- Valid modes are LOCAL (default)
REPL_ASYNC (original)
REPL_SYNC
INVALIDATION_ASYNC
INVALIDATION_SYNC -->
<attribute name="CacheMode">REPL_ASYNC</attribute>

<!-- Name of cluster. Needs to be the same for all JBoss Cache nodes in a
cluster in order to find each other.
-->
<attribute name="ClusterName">STTS-cluster</attribute>

<!--Uncomment next three statements to use the JGroups multiplexer.
This configuration is dependent on the JGroups multiplexer being
registered in an MBean server such as JBossAS. This type of
dependency injection only works in the AS; outside it's up to
your code to inject a ChannelFactory if you want to use one.
-->
<!--
<depends optional-attribute-name="MultiplexerService"
proxy-type="attribute">jgroups.mux:name=Multiplexer</depends>
<attribute name="MultiplexerStack">tcp</attribute>
-->

<!-- JGroups protocol stack properties.
ClusterConfig isn't used if the multiplexer is enabled above.
-->
<attribute name="ClusterConfig">
<config>
<!-- UDP: if you have a multihomed machine, set the bind_addr
attribute to the appropriate NIC IP address bind_addr="192.168.1.2" -->
<!-- UDP: On Windows machines, because of the media sense feature
being broken with multicast (even after disabling media sense)
set the loopback attribute to true -->
<UDP mcast_addr="239.255.16.2" mcast_port="57141"
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="false"
/>
<PING timeout="2000" num_initial_members="3"/>
<MERGE2 min_interval="10000" max_interval="20000"/>
<FD shun="true"/>
<FD_SOCK/>
<VERIFY_SUSPECT timeout="1500"/>
<pbcast.NAKACK gc_lag="50" retransmit_timeout="600,1200,2400,4800"
max_xmit_size="8192"/>
<UNICAST timeout="600,1200,2400,4800"/>
<pbcast.STABLE desired_avg_gossip="400000"/>
<FC max_credits="2000000" min_threshold="0.10"/>
<FRAG2 frag_size="8192"/>
<pbcast.GMS join_timeout="5000" join_retry_timeout="2000"
shun="true" print_local_addr="true"/>
<pbcast.STATE_TRANSFER/>
</config>
</attribute>

<!--
The max amount of time (in milliseconds) we wait until the
initial state (ie. the contents of the cache) are retrieved from
existing members in a clustered environment
-->
<attribute name="StateRetrievalTimeout">20000</attribute>

<!--
Number of milliseconds to wait until all responses for a
synchronous call have been received.
-->
<attribute name="SyncReplTimeout">20000</attribute>

<!-- Max number of milliseconds to wait for a lock acquisition -->
<attribute name="LockAcquisitionTimeout">15000</attribute>

<!-- Specific eviction policy configurations. This is LRU -->
<attribute name="EvictionConfig">
<config>
<attribute name="wakeUpIntervalSeconds">5</attribute>
<!-- This defaults to 200000 if not specified -->
<attribute name="eventQueueSize">200000</attribute>
<attribute name="policyClass">org.jboss.cache.eviction.LRUPolicy</attribute>

<!-- Cache wide default -->
<!--
<region name="ATTRIBUTE">
<attribute name="maxNodes">5000</attribute>
<attribute name="timeToLiveSeconds">10000</attribute>
<attribute name="maxAgeSeconds">10000</attribute>
</region>
<region name="ATTRIBUTE_QUERY">
<attribute name="maxNodes">5000</attribute>
<attribute name="timeToLiveSeconds">10000</attribute>
<attribute name="maxAgeSeconds">10000</attribute>
</region>
-->

</config>
</attribute>
</mbean>
</server>


Code between sessionFactory.openSession() and session.close():
The code is using CMT, and basically does the following:
Session session = sessionFactory.getCurrentSession();
Query query = session.createQuery("select ag from AttributeGroup as ag");
query.setCacheable(true);
query.setCacheRegion("ATTRIBUTE_QUERY");
List groups = query.list();

Full stack trace of any exception that occurs:
N/A

Name and version of the database you are using:
Sybase

The generated SQL (show_sql=true):
N/A

Debug level Hibernate log excerpt:
00:11:13,925 INFO Environment:514 - Hibernate 3.2.5
00:11:13,941 INFO Environment:532 - loaded properties from resource hibernate.properties: {hibernate.bytecode.use_refle
ction_optimizer=false}
00:11:13,941 INFO Environment:681 - Bytecode provider name : cglib
00:11:13,957 INFO Environment:598 - using JDK 1.4 java.sql.Timestamp handling
00:11:14,175 INFO Configuration:1426 - configuring from resource: hibernate.cfg.xml
00:11:14,175 INFO Configuration:1403 - Configuration resource: hibernate.cfg.xml
00:11:15,081 INFO Configuration:553 - Reading mappings from resource : object/com/xxx/domain/attribute/Attribute.hbm.xml
00:11:15,238 INFO HbmBinder:300 - Mapping class: com.xxx.domain.attribute.Attribute -> ST_ATTR
00:11:15,628 INFO Configuration:553 - Reading mappings from resource : object/com/xxx/domain/attribute/AttributeAllowedValues.hbm.xml
00:11:15,706 INFO HbmBinder:300 - Mapping class: com.xxx.domain.attribute.AttributeAllowedValues -> ST_ATTR_ALWD_VALS
00:11:15,706 INFO Configuration:553 - Reading mappings from resource : object/com/xxx/domain/attribute/AttributeDataType.hbm.xml
00:11:15,784 INFO HbmBinder:300 - Mapping class: com.xxx.domain.attribute.AttributeDataType -> ST_ATTR_DATA_TYP
00:11:15,784 INFO Configuration:553 - Reading mappings from resource : object/com/xxx/domain/attribute/AttributeDefaultValueLink.hbm.xml
00:11:15,862 INFO HbmBinder:300 - Mapping class: com.xxx.domain.attribute.AttributeDefaultValueLink -> ST_ATTR_DFLT_VAL_LNK
00:11:15,862 INFO Configuration:553 - Reading mappings from resource : object/com/xxx/domain/attribute/AttributeGroup.hbm.xml
00:11:15,941 INFO HbmBinder:300 - Mapping class: com.xxx.domain.attribute.AttributeGroup -> ST_ATTR_GRP
00:11:15,941 INFO Configuration:1541 - Configured SessionFactory: null
00:11:15,956 INFO HbmBinder:2385 - Mapping collection: com.xxx.domain.attribute.Attribute.childAttributes -> ST_ATTR
00:11:15,956 INFO HbmBinder:2385 - Mapping collection: com.xxx.domain.attribute.Attribute.allowedValues -> ST_ATTR_ALWD_VALS
00:11:15,956 INFO HbmBinder:2385 - Mapping collection: com.xxx.domain.attribute.Attribute.subAttrDefaults -> ST_ATTR_DFLT_VAL_LNK
00:11:15,972 INFO HbmBinder:2385 - Mapping collection: com.xxx.domain.attribute.AttributeGroup.attributes -> ST_ATTR
00:11:16,003 INFO NamingHelper:26 - JNDI InitialContext properties:{java.naming.factory.initial=weblogic.jndi.WLInitialContextFactory}
00:11:16,003 INFO DatasourceConnectionProvider:61 - Using datasource: StampsDS
00:11:16,456 INFO SettingsFactory:89 - RDBMS: Adaptive Server Enterprise, version: Adaptive Server Enterprise/12.5.1/EBF 11559 ESD#1/P/Sun_svr4/OS 5.8/ase1251/1831/32-bit/FBO/Sat Nov 22 06:08:20 2003
00:11:16,456 INFO SettingsFactory:90 - JDBC driver: jConnect (TM) for JDBC (TM), version: jConnect (TM) for JDBC(TM)/6.05(Build 25828)/P/EBF13044/JDK14/Fri Sep 30 1:05:16 2005
00:11:16,565 INFO Dialect:152 - Using dialect: org.hibernate.dialect.SybaseAnywhereDialect00:11:16,597 INFO TransactionFactoryFactory:34 - Transaction strategy: org.hibernate.transaction.CMTTransactionFactory
00:11:16,612 INFO TransactionManagerLookupFactory:38 - instantiating TransactionManagerLookup: org.hibernate.transaction.WeblogicTransactionManagerLookup
00:11:16,612 INFO TransactionManagerLookupFactory:42 - instantiated TransactionManagerLookup
00:11:16,612 INFO SettingsFactory:143 - Automatic flush during beforeCompletion(): disabled
00:11:16,628 INFO SettingsFactory:147 - Automatic session close at end of transaction: disabled
00:11:16,628 INFO SettingsFactory:162 - Scrollable result sets: enabled
00:11:16,628 INFO SettingsFactory:170 - JDBC3 getGeneratedKeys(): disabled
00:11:16,628 INFO SettingsFactory:178 - Connection release mode: auto
00:11:16,628 INFO SettingsFactory:205 - Default batch fetch size: 1
00:11:16,644 INFO SettingsFactory:209 - Generate SQL with comments: disabled
00:11:16,644 INFO SettingsFactory:213 - Order SQL updates by primary key: disabled
00:11:16,644 INFO SettingsFactory:217 - Order SQL inserts for batching: disabled
00:11:16,644 INFO SettingsFactory:386 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
00:11:16,659 INFO ASTQueryTranslatorFactory:24 - Using ASTQueryTranslatorFactory
00:11:16,659 INFO SettingsFactory:225 - Query language substitutions: {}
00:11:16,659 INFO SettingsFactory:230 - JPA-QL strict compliance: disabled
00:11:16,659 INFO SettingsFactory:235 - Second-level cache: enabled
00:11:16,659 INFO SettingsFactory:239 - Query cache: enabled
00:11:16,659 INFO SettingsFactory:373 - Cache provider: org.hibernate.cache.TreeCacheProvider
00:11:16,675 INFO SettingsFactory:254 - Optimize cache for minimal puts: enabled
00:11:16,675 INFO SettingsFactory:263 - Structured second-level cache entries: disabled
00:11:16,675 INFO SettingsFactory:360 - Query cache factory: org.hibernate.cache.StandardQueryCacheFactory
00:11:16,690 INFO SettingsFactory:283 - Echoing all SQL to stdout
00:11:16,690 INFO SettingsFactory:290 - Statistics: disabled
00:11:16,690 INFO SettingsFactory:294 - Deleted entity synthetic identifier rollback: disabled
00:11:16,690 INFO SettingsFactory:309 - Default entity-mode: pojo
00:11:16,690 INFO SettingsFactory:313 - Named query checking : enabled
00:11:16,784 INFO SessionFactoryImpl:161 - building session factory
00:11:16,784 DEBUG TreeCacheProvider:65 - Configuring TreeCache from resource [treecache.xml]
00:11:18,315 INFO TransactionManagerLookupFactory:38 - instantiating TransactionManagerLookup: org.hibernate.transaction.WeblogicTransactionManagerLookup
00:11:18,315 INFO TransactionManagerLookupFactory:42 - instantiated TransactionManagerLookup
00:11:18,331 INFO NamingHelper:26 - JNDI InitialContext properties:{java.naming.factory.initial=weblogic.jndi.WLInitialContextFactory}
00:11:18,346 INFO NamingHelper:26 - JNDI InitialContext properties:{java.naming.factory.initial=weblogic.jndi.WLInitialContextFactory}

-------------------------------------------------------
GMS: address is 10.128.200.24:2801
-------------------------------------------------------
00:11:22,064 DEBUG CacheFactory:39 - instantiating cache region: com.xxx.domain.attribute.AttributeGroup usage strategy: transactional
00:11:22,111 DEBUG CacheFactory:39 - instantiating cache region: com.xxx.domain.attribute.AttributeDataType usage strategy: transactional
00:11:22,127 DEBUG CacheFactory:39 - instantiating cache region: com.xxx.domain.attribute.AttributeAllowedValues usage strategy: transactional
00:11:22,158 DEBUG CacheFactory:39 - instantiating cache region: com.xxx.domain.attribute.Attribute usage strategy: transactional
00:11:22,205 DEBUG CacheFactory:39 - instantiating cache region: com.xxx.domain.attribute.Attribute.subAttrDefaults usage strategy: transactional
00:11:22,236 DEBUG CacheFactory:39 - instantiating cache region: com.xxx.domain.attribute.Attribute.allowedValues usage strategy: transactional
00:11:22,236 DEBUG CacheFactory:39 - instantiating cache region: com.xxx.domain.attribute.AttributeGroup.attributes usage strategy: transactional
00:11:22,236 DEBUG CacheFactory:39 - instantiating cache region: com.xxx.domain.attribute.Attribute.childAttributes usage strategy: transactional
00:11:23,314 INFO SessionFactoryObjectFactory:82 - Not binding factory to JNDI, no JNDI name configured
00:11:23,314 INFO NamingHelper:26 - JNDI InitialContext properties:{java.naming.factory.initial=weblogic.jndi.WLInitialContextFactory}
00:11:23,330 INFO UpdateTimestampsCache:41 - starting update timestamps cache at region: org.hibernate.cache.UpdateTimestampsCache
00:11:23,345 INFO StandardQueryCache:52 - starting query cache at region: org.hibernate.cache.StandardQueryCache
00:11:23,970 DEBUG StandardQueryCache:102 - checking cached query results in region: org.hibernate.cache.StandardQueryCache
00:11:23,986 DEBUG StandardQueryCache:107 - query results were not found in cache
Hibernate: select attributeg0_.ST_ATTR_GRP_ID as ST1_4_, attributeg0_.UPD_DT as UPD2_4_, attributeg0_.UPD_USR_ID as UPD3_4_, attributeg0_.ST_ATTR_GRP_NME as ST4_4_ from ST_ATTR_GRP attributeg0_ where attributeg0_.ST_ATTR_GRP_ID=1
00:11:24,502 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeGroup#1
00:11:24,502 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:24,517 DEBUG TransactionalCache:29 - cache lookup: com.xxx.domain.attribute.AttributeGroup.attributes#1
00:11:24,517 DEBUG TransactionalCache:33 - cache miss
Hibernate: select attributes0_.ST_GRP_ATTR_ID as ST15_1_, attributes0_.ST_ATTR_ID as ST1_1_, attributes0_.ST_ATTR_ID asST1_0_0_, attributes0_.UPD_DT as UPD2_0_0_, attributes0_.UPD_USR_ID as UPD3_0_0_, attributes0_.ST_ATTR_NME as ST4_0_0_,attributes0_.ST_ATTR_DISP_ORDR_NO as ST5_0_0_, attributes0_.ST_ATTR_DISP_TXT as ST6_0_0_, attributes0_.ST_ATTR_LRG_DISP_TXT as ST7_0_0_, attributes0_.ST_ATTR_MIN_VAL as ST8_0_0_, attributes0_.ST_ATTR_MAX_VAL as ST9_0_0_, attributes0_.ST_ATTR_MIN_WRNG_VAL as ST10_0_0_, attributes0_.ST_ATTR_MAX_WRNG_VAL as ST11_0_0_, attributes0_.ST_ATTR_DFLT_VAL as ST12_0_0_, attributes0_.ST_ATTR_REQD_IND as ST13_0_0_, attributes0_.ST_ATTR_MULT_VAL_IND as ST14_0_0_, attributes0_.ST_GRP_ATTR_ID as ST15_0_0_, attributes0_.ST_PRNT_ATTR_ID as ST16_0_0_, attributes0_.ST_ATTR_DATA_TYP_CD as ST17_0_0_ from ST_ATTR attributes0_ where attributes0_.ST_GRP_ATTR_ID=?
00:11:24,892 DEBUG TransactionalCache:29 - cache lookup: com.xxx.domain.attribute.AttributeDataType#ENUMERATED
00:11:24,892 DEBUG TransactionalCache:33 - cache miss
Hibernate: select attributed0_.ST_ATTR_DATA_TYP_CD as ST1_2_0_, attributed0_.UPD_DT as UPD2_2_0_, attributed0_.UPD_USR_ID as UPD3_2_0_, attributed0_.ST_ATTR_DATA_TYP_DESC as ST4_2_0_ from ST_ATTR_DATA_TYP attributed0_ where attributed0_.ST_ATTR_DATA_TYP_CD=?
00:11:24,908 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#ENUMERATED
00:11:24,908 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:24,923 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute#1
00:11:24,923 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:24,923 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute#2
00:11:24,923 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:24,923 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute#3
00:11:24,923 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:24,939 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeGroup.attributes#1
00:11:24,939 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:24,939 DEBUG TransactionalCache:29 - cache lookup: com.xxx.domain.attribute.Attribute.subAttrDefaults#3
00:11:24,939 DEBUG TransactionalCache:33 - cache miss
Hibernate: select subattrdef0_.ST_ATTR_ID as ST4_1_, subattrdef0_.ST_ATTR_DFLT_VAL_LNK_ID as ST1_1_, subattrdef0_.ST_ATTR_DFLT_VAL_LNK_ID as ST1_3_0_, subattrdef0_.UPD_DT as UPD2_3_0_, subattrdef0_.UPD_USR_ID as UPD3_3_0_, subattrdef0_.ST_ATTR_ID as ST4_3_0_, subattrdef0_.TRD_TYP_CD as TRD5_3_0_, subattrdef0_.ST_ATTR_DFLT_VAL as ST6_3_0_ from ST_ATTR_DFLT_VAL_LNK subattrdef0_ where subattrdef0_.ST_ATTR_ID in (?, ?, ?)
00:11:24,970 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute.subAttrDefaults#1
00:11:24,970 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:24,970 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute.subAttrDefaults#3
00:11:24,970 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:24,970 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute.subAttrDefaults#2
00:11:24,970 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:24,986 DEBUG TransactionalCache:29 - cache lookup: com.xxx.domain.attribute.Attribute.allowedValues#3
00:11:24,986 DEBUG TransactionalCache:33 - cache miss
Hibernate: select allowedval0_.ST_ATTR_ID as ST7_1_, allowedval0_.ST_ALWD_VAL_ID as ST1_1_, allowedval0_.ST_ALWD_VAL_IDas ST1_1_0_, allowedval0_.UPD_DT as UPD2_1_0_, allowedval0_.UPD_USR_ID as UPD3_1_0_, allowedval0_.ST_ALWD_VAL_TXT as ST4_1_0_, allowedval0_.ST_ALWD_VAL_DISP_ORDR_NO as ST5_1_0_, allowedval0_.ST_ALWD_VAL_DFLT_IND as ST6_1_0_, allowedval0_.ST_ATTR_ID as ST7_1_0_ from ST_ATTR_ALWD_VALS allowedval0_ where allowedval0_.ST_ATTR_ID in (?, ?, ?)
00:11:25,017 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeAllowedValues#1
00:11:25,017 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,017 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeAllowedValues#2
00:11:25,017 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,017 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeAllowedValues#3
00:11:25,017 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,017 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeAllowedValues#4
00:11:25,017 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,033 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeAllowedValues#5
00:11:25,033 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,033 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeAllowedValues#6
00:11:25,033 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,033 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeAllowedValues#7
00:11:25,033 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,033 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeAllowedValues#8
00:11:25,033 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,033 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeAllowedValues#9
00:11:25,048 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,048 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute.allowedValues#3
00:11:25,048 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,048 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute.allowedValues#1
00:11:25,048 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,048 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute.allowedValues#2
00:11:25,048 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,048 DEBUG TransactionalCache:29 - cache lookup: com.xxx.domain.attribute.Attribute.childAttributes#3
00:11:25,048 DEBUG TransactionalCache:33 - cache miss
Hibernate: select childattri0_.ST_PRNT_ATTR_ID as ST16_1_, childattri0_.ST_ATTR_ID as ST1_1_, childattri0_.ST_ATTR_ID as ST1_0_0_, childattri0_.UPD_DT as UPD2_0_0_, childattri0_.UPD_USR_ID as UPD3_0_0_, childattri0_.ST_ATTR_NME as ST4_0_0_, childattri0_.ST_ATTR_DISP_ORDR_NO as ST5_0_0_, childattri0_.ST_ATTR_DISP_TXT as ST6_0_0_, childattri0_.ST_ATTR_LRG_DISP_TXT as ST7_0_0_, childattri0_.ST_ATTR_MIN_VAL as ST8_0_0_, childattri0_.ST_ATTR_MAX_VAL as ST9_0_0_, childattri0_.ST_ATTR_MIN_WRNG_VAL as ST10_0_0_, childattri0_.ST_ATTR_MAX_WRNG_VAL as ST11_0_0_, childattri0_.ST_ATTR_DFLT_VAL as ST12_0_0_, childattri0_.ST_ATTR_REQD_IND as ST13_0_0_, childattri0_.ST_ATTR_MULT_VAL_IND as ST14_0_0_, childattri0_.ST_GRP_ATTR_ID as ST15_0_0_, childattri0_.ST_PRNT_ATTR_ID as ST16_0_0_, childattri0_.ST_ATTR_DATA_TYP_CD as ST17_0_0_ from ST_ATTR childattri0_ where childattri0_.ST_PRNT_ATTR_ID in (?, ?, ?)
00:11:25,080 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute.childAttributes#3
00:11:25,080 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,080 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute.childAttributes#2
00:11:25,095 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,095 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.Attribute.childAttributes#1
00:11:25,095 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,095 DEBUG StandardQueryCache:73 - caching query results in region: org.hibernate.cache.StandardQueryCache; timestamp=11866326833
00:11:25,095 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,205 DEBUG StandardQueryCache:102 - checking cached query results in region: org.hibernate.cache.StandardQueryCache
00:11:25,205 DEBUG StandardQueryCache:107 - query results were not found in cache
Hibernate: select attributed0_.ST_ATTR_DATA_TYP_CD as ST1_2_, attributed0_.UPD_DT as UPD2_2_, attributed0_.UPD_USR_ID as UPD3_2_, attributed0_.ST_ATTR_DATA_TYP_DESC as ST4_2_ from ST_ATTR_DATA_TYP attributed0_
00:11:25,236 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#CHECKLISTITEM
00:11:25,236 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,236 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#CLASSFEE
00:11:25,251 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,251 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#CONTACT
00:11:25,251 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,251 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#DATE
00:11:25,251 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,251 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#DEALCODE
00:11:25,251 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,251 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#DYNAMICFEE
00:11:25,251 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,251 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#FEE
00:11:25,251 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,267 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#FLOAT
00:11:25,267 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,267 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#MONEY
00:11:25,267 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,267 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#MONTH
00:11:25,267 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,267 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#N/A
00:11:25,267 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,267 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#NUMERIC
00:11:25,267 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,267 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#PARTYROLE
00:11:25,283 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,283 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#RATING
00:11:25,283 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,283 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#SIGNOFFITEM
00:11:25,283 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,283 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#STDCHECKLISTITEM
00:11:25,283 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,283 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#TEXT
00:11:25,283 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,283 DEBUG TransactionalCache:52 - caching: com.xxx.domain.attribute.AttributeDataType#TRACKEDITEM
00:11:25,298 DEBUG TreeCache:78 - ignoring write lock acquisition failure
00:11:25,298 DEBUG StandardQueryCache:73 - caching query results in region: org.hibernate.cache.StandardQueryCache; timestamp=11866326833
00:11:25,298 DEBUG TreeCache:78 - ignoring write lock acquisition failure
XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
getEntityDeleteCount = 0
getEntityInsertCount = 0
getEntityLoadCount = 32
getEntityFetchCount = 1
getEntityUpdateCount = 0
getQueryExecutionCount = 2
getQueryCacheHitCount = 0
getQueryCacheMissCount = 2
getQueryCachePutCount = 2
getFlushCount = 0
getConnectCount = 7
getSecondLevelCacheHitCount = 0
getSecondLevelCacheMissCount = 5
getSecondLevelCachePutCount = 42
getSessionCloseCount = 0
getSessionOpenCount = 0
getCollectionLoadCount = 10
getCollectionFetchCount = 4
getCollectionUpdateCount = 0
getCollectionRemoveCount = 0
getCollectionRecreateCount = 0
getSuccessfulTransactionCount = 0
getTransactionCount = 0
getCloseStatementCount = 7
getPrepareStatementCount = 7
getOptimisticFailureCount = 0

getCollectionRoleNames = com.xxx.domain.attribute.Attribute.allowedValues, com.xxx.domain.attribute.Attribute.subAttrDefaults, com.xxx.domain.attribute.AttributeGroup.attributes, com.xxx.domain.attribute.Attribute.childAttributes,
getEntityNames = com.xxx.domain.attribute.AttributeDefaultValueLink, com.xxx.domain.attribute.AttributeGroup, com.xxx.domain.attribute.AttributeDataType, com.xxx.domain.attribute.AttributeAllowedValues, com.xxx.domain.attribute.Attribute,
getQueries = select adt from AttributeDataType as adt, select ag from AttributeGroup as ag where groupID = 1,
getSecondLevelCacheRegionNames = org.hibernate.cache.StandardQueryCache, com.xxx.domain.attribute.Attribute.subAttrDefaults, com.xxx.domain.attribute.Attribute.allowedValues, com.xxx.domain.attribute.AttributeGroup.attributes, com.xxx.domain.attribute.AttributeGroup, com.xxx.domain.attribute.Attribute.childAttributes, com.xxx.domain.attribute.AttributeDataType, com.xxx.domain.attribute.AttributeAllowedValues, org.hibernate.cache.UpdateTimestampsCache, com.xxx.domain.attribute.Attribute,
CACHEMODE = NORMAL
00:11:25,330 DEBUG CacheSynchronization:40 - transaction before completion callback
00:11:25,330 DEBUG CacheSynchronization:58 - automatically flushing session
00:11:25,361 DEBUG CacheSynchronization:82 - transaction after completion callback, status: 3
00:11:25,361 DEBUG CacheSynchronization:89 - automatically closing session
<Aug 9, 2007 12:11:59 AM EDT> <Warning> <netuix> <BEA-423420> <Redirect is executed in begin or refresh action. Redirect url is /console/console.portal.>