IdentityLock timeouts occuring after 0 ms and no locks?

[timferro]

We have a jboss cluster with a Hibernate 2nd level cache via JBossCache. I'm receiving a lot of the following type of error and I'm unsure how to approach resolving it. It seems like the write lock times out instantly, but there aren't any existing locks:

[IdentityLock] write lock for ... could not be acquired after 0 ms. Locks: Read lock owners: [] Write lock owner: null (caller=Thread[http-0.0.0.0-8443-30], lock info: (org.jboss.cache.lock.LockStrategyReadCommitted@75fae343))

JBoss version is 4.0.5 GA, JGroups & JBossCache are the versions that shipped with it.

I have included our cache configuration file in case anything can be seen as obviously wrong therein:

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



jboss:service=Naming
jboss:service=TransactionManager

Hibernate-${jboss.partition.name:Cluster}
READ_COMMITTED
REPL_ASYNC

false
false

org.jboss.cache.BatchModeTransactionManagerLookup



<UDP mcast_addr="${partition.udpgroup.hib3:228.1.2.3}"
mcast_port="${partition.udpport.hib3:48866}"
ucast_recv_buf_size="20000000"
ucast_send_buf_size="640000"
mcast_recv_buf_size="25000000"
mcast_send_buf_size="640000"
loopback="false"
max_bundle_size="64000"
max_bundle_timeout="30"
use_incoming_packet_handler="true"
use_outgoing_packet_handler="true"
ip_ttl="${jgroups.mcast.ip_ttl:2}"
enable_bundling="true"/>
<PING timeout="2000"
down_thread="false" num_initial_members="3"/>
<MERGE2 max_interval="100000"
down_thread="false" min_interval="20000"/>
<FD_SOCK down_thread="false" />
<FD shun="true" down_thread="false"
timeout="20000" max_tries="5"/>
<VERIFY_SUSPECT timeout="1500"
down_thread="false"/>
<pbcast.NAKACK max_xmit_size="60000"
use_mcast_xmit="false" gc_lag="50"
retransmit_timeout="300,600,1200,2400,4800"
down_thread="false"
discard_delivered_msgs="true"/>
<UNICAST timeout="300,600,1200,2400,3600"
down_thread="false" />
<pbcast.STABLE stability_delay="1000" desired_avg_gossip="50000"
down_thread="false"
max_bytes="400000"/>
<pbcast.GMS print_local_addr="true" join_timeout="3000"
down_thread="false"
join_retry_timeout="2000" shun="true"/>
<FC max_credits="2000000" down_thread="false"
min_threshold="0.10"/>
<FRAG2 frag_size="60000" down_thread="false" up_thread="true"/>
<pbcast.STATE_TRANSFER down_thread="false" up_thread="false"/>




false
20000
20000

15000



false
org.jboss.cache.buddyreplication.NextMemberBuddyLocator

numBuddies = 1
ignoreColocatedBuddies = true


default
2000

false
true
true

[timferro]

Sorry, didn't use the code tag -

<?xml version="1.0" encoding="UTF-8"?>
<server>
 <mbean code="org.jboss.cache.TreeCache"
 name="jboss.cache:service=HibernateTreeCache">

 <depends>jboss:service=Naming</depends>
 <depends>jboss:service=TransactionManager</depends>

 <attribute name="ClusterName">Hibernate-${jboss.partition.name:Cluster}</attribute>
 <attribute name="IsolationLevel">READ_COMMITTED</attribute>
 <attribute name="CacheMode">REPL_ASYNC</attribute>

 <attribute name="UseRegionBasedMarshalling">false</attribute>
 <attribute name="InactiveOnStartup">false</attribute>

 <attribute name="TransactionManagerLookupClass">org.jboss.cache.BatchModeTransactionManagerLookup</attribute>

 <attribute name="ClusterConfig">
 <config>
 <UDP mcast_addr="${partition.udpgroup.hib3:228.1.2.3}"
 mcast_port="${partition.udpport.hib3:48866}"
 ucast_recv_buf_size="20000000"
 ucast_send_buf_size="640000"
 mcast_recv_buf_size="25000000"
 mcast_send_buf_size="640000"
 loopback="false"
 max_bundle_size="64000"
 max_bundle_timeout="30"
 use_incoming_packet_handler="true"
 use_outgoing_packet_handler="true"
 ip_ttl="${jgroups.mcast.ip_ttl:2}"
 enable_bundling="true"/>
 <PING timeout="2000"
 down_thread="false" num_initial_members="3"/>
 <MERGE2 max_interval="100000"
 down_thread="false" min_interval="20000"/>
 <FD_SOCK down_thread="false" />
 <FD shun="true" down_thread="false"
 timeout="20000" max_tries="5"/>
 <VERIFY_SUSPECT timeout="1500"
 down_thread="false"/>
 <pbcast.NAKACK max_xmit_size="60000"
 use_mcast_xmit="false" gc_lag="50"
 retransmit_timeout="300,600,1200,2400,4800"
 down_thread="false"
 discard_delivered_msgs="true"/>
 <UNICAST timeout="300,600,1200,2400,3600"
 down_thread="false" />
 <pbcast.STABLE stability_delay="1000" desired_avg_gossip="50000"
 down_thread="false"
 max_bytes="400000"/>
 <pbcast.GMS print_local_addr="true" join_timeout="3000"
 down_thread="false"
 join_retry_timeout="2000" shun="true"/>
 <FC max_credits="2000000" down_thread="false"
 min_threshold="0.10"/>
 <FRAG2 frag_size="60000" down_thread="false" up_thread="true"/>
 <pbcast.STATE_TRANSFER down_thread="false" up_thread="false"/>
 </config>

 </attribute>

 <attribute name="FetchInMemoryState">false</attribute>
 <attribute name="InitialStateRetrievalTimeout">20000</attribute>
 <attribute name="SyncReplTimeout">20000</attribute>

 <attribute name="LockAcquisitionTimeout">15000</attribute>

 <attribute name="BuddyReplicationConfig">
 <config>
 <buddyReplicationEnabled>false</buddyReplicationEnabled>
 <buddyLocatorClass>org.jboss.cache.buddyreplication.NextMemberBuddyLocator</buddyLocatorClass>
 <buddyLocatorProperties>
 numBuddies = 1
 ignoreColocatedBuddies = true
 </buddyLocatorProperties>

 <buddyPoolName>default</buddyPoolName>
 <buddyCommunicationTimeout>2000</buddyCommunicationTimeout>

 <autoDataGravitation>false</autoDataGravitation>
 <dataGravitationRemoveOnFind>true</dataGravitationRemoveOnFind>
 <dataGravitationSearchBackupTrees>true</dataGravitationSearchBackupTrees>

 </config>
 </attribute>

 </mbean>

</server>

[manik]

This is not an error. In newer versions the log message has been downgraded to a debug or lower.

[timferro]

That's good news - thanks!