13 Replies Latest reply on Feb 2, 2009 2:28 AM by mihai.ratiu

    Problem when using EJB3EntityTreeCache for caching entities

    mihai.ratiu Dec 31, 2008 6:05 AM

      Hi,

      I'm new to Jboss Cache and I'm trying to use Jboss Cache 1.4.0SP11 "Cayenne" to cache some of my entities.

      I'm using for my application jboss-4.0.5-GA version of the server, in configuration all. The application is using ejb3 entities and I need to reduce the amounts of DB calls to some tables by using the jboss level 2 cache.

      I already install EJB3 RC9 Patch 1 for my server configuration, and configured the ejb3-entity-cache-service.xml as following:


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


      <!-- ==================================================================== -->
      <!-- Defines TreeCache configuration -->
      <!-- ==================================================================== -->

      jboss:service=Naming
      jboss:service=TransactionManager
      <!--depends>jboss.j2ee:ear=syncml-connector.ear,jar=syncml.jar,name=SyncMLConnectorDataService,service=EJB3</depends-->

      <!-- uncomment next three statements if using JGroups multiplexer,
      requires JGroups 2.3 or later, JBossCache 1.4 or later -->
      <!--
      jgroups.mux:name=Multiplexer
      jgroups.mux:name=Multiplexer
      fc-fast-minimalthreads
      -->

      <!-- Configure the TransactionManager -->
      org.jboss.cache.JBossTransactionManagerLookup

      <!--
      Node locking level : SERIALIZABLE
      REPEATABLE_READ (default)
      READ_COMMITTED
      READ_UNCOMMITTED
      NONE
      -->
      REPEATABLE_READ

      <!-- Valid modes are LOCAL
      REPL_ASYNC
      REPL_SYNC
      -->
      LOCAL

      <!-- Name of cluster. Needs to be the same for all clusters, in order
      to find each other -->
      EJB3-entity-cache

      <!-- Must be true if any entity deployment uses a scoped classloader -->
      true
      <!-- Must match the value of "UseRegionBasedMarshalling" -->
      true

      OPTIMISTIC



      <!-- UDP: if you have a multihomed machine,
      set the bind_addr attribute to the appropriate NIC IP address
      -->
      <!-- 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="${jboss.partition.udpGroup:228.1.2.3}" mcast_port="43333" ip_ttl="${jgroups.mcast.ip_ttl:2}" 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" up_thread="false" down_thread="false" />
      <MERGE2 min_interval="10000" max_interval="20000" />
      <FD_SOCK down_thread="false" up_thread="false"/>
      <FD shun="true" up_thread="false" down_thread="false"
      timeout="20000" max_tries="5"/>
      <VERIFY_SUSPECT timeout="1500" up_thread="false" down_thread="false" />
      <pbcast.NAKACK gc_lag="50" max_xmit_size="8192" retransmit_timeout="600,1200,2400,4800" up_thread="false"
      down_thread="false" />
      <UNICAST timeout="600,1200,2400" window_size="100" min_threshold="10" down_thread="false" />
      <pbcast.STABLE desired_avg_gossip="20000" up_thread="false" 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" />



      <!-- 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
      -->
      5000

      <!-- Number of milliseconds to wait until all responses for a
      synchronous call have been received.
      -->
      10000

      <!-- Max number of milliseconds to wait for a lock acquisition -->
      15000

      <!-- Name of the eviction policy class. -->
      org.jboss.cache.eviction.LRUPolicy

      <!-- Specific eviction policy configurations. This is LRU -->


      5 -->
      <!-- Cache wide default -->

      10000
      86400










      The entities needed to cache were annotated this way:

      @Entity(name = "DataStoresConnEntity")
      @Table(name = "DATASTORES", uniqueConstraints = {})
      @Cache(usage = CacheConcurrencyStrategy.TRANSACTIONAL)
      @NamedQueries(...)
      public class DataStoresConnEntity implements Serializable
      {
      ....
      @OneToMany(mappedBy = "dataStoresEntity", fetch = FetchType.LAZY)
      @Cache(usage = CacheConcurrencyStrategy.TRANSACTIONAL)
      public Collection getPendingDataStoresEntities()
      {
      return pendingDataStoresEntities;
      }
      ....
      }

      and

      @Entity(name = "PendingDataStoresConnEntity")
      @Table(name = "PENDINGDATASTORES")
      @Cache(usage = CacheConcurrencyStrategy.TRANSACTIONAL)
      @NamedQueries(...)
      public class PendingDataStoresConnEntity implements Serializable
      {
      ...

      @ManyToOne(targetEntity = DataStoresConnEntity.class)
      @JoinColumn(name = "DATASTOREID", referencedColumnName = "DATASTOREID")
      public DataStoresConnEntity getDataStoresEntity()
      {
      return dataStoresEntity;
      }

      ...

      }

      We decided that the named queries doesn't need to be cached only the ejb3 entities.

      The problem is that after some processing, when the processing stops, the entities are evicted from the cache very fast , after few seconds(~10s). I tried also to set up wakeUpIntervalSeconds to a large number and also tried a setup for "maxAgeSeconds" to 0.

      Even if I remove the evictionPolicy the behavior is still the same.

      Does anybody have an idea why this strange behavior is happening?


      Since the new year is coming soon I wish you a Happy New Year!

      • 11501 Views
      • Tags:
        • 1. Re: Problem when using EJB3EntityTreeCache for caching entit
          brian.stansberry
          brian.stansberry Jan 1, 2009 2:54 PM (in response to mihai.ratiu)

          Please repost your service.xml, but use the "Code" button above to enclose it in a code block.

            • Actions
          • 2. Re: Problem when using EJB3EntityTreeCache for caching entit
            mihai.ratiu Jan 5, 2009 2:10 AM (in response to mihai.ratiu)

             

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

 <!-- ==================================================================== -->
 <!-- Defines TreeCache configuration -->
 <!-- ==================================================================== -->
 <mbean code="org.jboss.cache.TreeCache" name="jboss.cache:service=EJB3EntityTreeCache">
 <depends>jboss:service=Naming</depends>
 <depends>jboss:service=TransactionManager</depends>

 <!-- uncomment next three statements if using JGroups multiplexer,
 requires JGroups 2.3 or later, JBossCache 1.4 or later -->
 <!--
 <depends>jgroups.mux:name=Multiplexer</depends>
 <attribute name="MultiplexerService">jgroups.mux:name=Multiplexer</attribute>
 <attribute name="MultiplexerStack">fc-fast-minimalthreads</attribute>
 -->

 <!-- Configure the TransactionManager -->
 <attribute name="TransactionManagerLookupClass">org.jboss.cache.JBossTransactionManagerLookup</attribute>

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

 <!-- Valid modes are LOCAL
 REPL_ASYNC
 REPL_SYNC
 -->
 <attribute name="CacheMode">REPL_SYNC</attribute>

 <!-- Name of cluster. Needs to be the same for all clusters, in order
 to find each other -->
 <attribute name="ClusterName">EJB3-entity-cache</attribute>

 <!-- Must be true if any entity deployment uses a scoped classloader -->
 <attribute name="UseRegionBasedMarshalling">true</attribute>
 <!-- Must match the value of "UseRegionBasedMarshalling" -->
 <attribute name="InactiveOnStartup">true</attribute>

 <attribute name="ClusterConfig">
 <config>
 <!-- UDP: if you have a multihomed machine,
 set the bind_addr attribute to the appropriate NIC IP address
 -->
 <!-- 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="${jboss.partition.udpGroup:228.1.2.3}" mcast_port="43333" ip_ttl="${jgroups.mcast.ip_ttl:2}" 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" up_thread="false" down_thread="false" />
 <MERGE2 min_interval="10000" max_interval="20000" />
 <FD_SOCK down_thread="false" up_thread="false"/>
 <FD shun="true" up_thread="false" down_thread="false"
 timeout="20000" max_tries="5"/>
 <VERIFY_SUSPECT timeout="1500" up_thread="false" down_thread="false" />
 <pbcast.NAKACK gc_lag="50" max_xmit_size="8192" retransmit_timeout="600,1200,2400,4800" up_thread="false"
 down_thread="false" />
 <UNICAST timeout="600,1200,2400" window_size="100" min_threshold="10" down_thread="false" />
 <pbcast.STABLE desired_avg_gossip="20000" up_thread="false" 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>

 <!-- 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="InitialStateRetrievalTimeout">5000</attribute>

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

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

 <!-- Name of the eviction policy class. -->
 <attribute name="EvictionPolicyClass">org.jboss.cache.eviction.LRUPolicy</attribute>

 <!-- Specific eviction policy configurations. This is LRU -->
 <attribute name="EvictionPolicyConfig">
 <config>
 <attribute name="wakeUpIntervalSeconds">5</attribute>
 <!-- Cache wide default -->
 <region name="/_default_">
 <attribute name="maxNodes">10000</attribute>
 <attribute name="timeToLiveSeconds">86400</attribute>
 </region>
 </config>
 </attribute>

 </mbean>

</server>


            Sorry for inconvenience... I hope this will work out.

              • Actions
            • 3. Re: Problem when using EJB3EntityTreeCache for caching entit
              mihai.ratiu Jan 5, 2009 3:32 AM (in response to mihai.ratiu)

               

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

 <!-- ==================================================================== -->
 <!-- Defines TreeCache configuration -->
 <!-- ==================================================================== -->
 <mbean code="org.jboss.cache.TreeCache" name="jboss.cache:service=EJB3EntityTreeCache">
 <depends>jboss:service=Naming</depends>
 <depends>jboss:service=TransactionManager</depends>

 <!-- uncomment next three statements if using JGroups multiplexer,
 requires JGroups 2.3 or later, JBossCache 1.4 or later -->
 <!--
 <depends>jgroups.mux:name=Multiplexer</depends>
 <attribute name="MultiplexerService">jgroups.mux:name=Multiplexer</attribute>
 <attribute name="MultiplexerStack">fc-fast-minimalthreads</attribute>
 -->

 <!-- Configure the TransactionManager -->
 <attribute name="TransactionManagerLookupClass">org.jboss.cache.JBossTransactionManagerLookup</attribute>

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

 <!-- Valid modes are LOCAL
 REPL_ASYNC
 REPL_SYNC
 -->
 <attribute name="CacheMode">INVALIDATION_SYNC</attribute>

 <attribute name="NodeLockingScheme">OPTIMISTIC</attribute>

 <!-- Name of cluster. Needs to be the same for all clusters, in order
 to find each other -->
 <attribute name="ClusterName">EJB3-entity-cache</attribute>

 <!-- Must be true if any entity deployment uses a scoped classloader -->
 <attribute name="UseRegionBasedMarshalling">true</attribute>
 <!-- Must match the value of "UseRegionBasedMarshalling" -->
 <attribute name="InactiveOnStartup">true</attribute>

 <attribute name="ClusterConfig">
 <config>
 <!-- UDP: if you have a multihomed machine,
 set the bind_addr attribute to the appropriate NIC IP address
 -->
 <!-- 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="${jboss.partition.udpGroup:228.1.2.3}" mcast_port="43333" ip_ttl="${jgroups.mcast.ip_ttl:2}" 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" up_thread="false" down_thread="false" />
 <MERGE2 min_interval="10000" max_interval="20000" />
 <FD_SOCK down_thread="false" up_thread="false"/>
 <FD shun="true" up_thread="false" down_thread="false"
 timeout="20000" max_tries="5"/>
 <VERIFY_SUSPECT timeout="1500" up_thread="false" down_thread="false" />
 <pbcast.NAKACK gc_lag="50" max_xmit_size="8192" retransmit_timeout="600,1200,2400,4800" up_thread="false"
 down_thread="false" />
 <UNICAST timeout="600,1200,2400" window_size="100" min_threshold="10" down_thread="false" />
 <pbcast.STABLE desired_avg_gossip="20000" up_thread="false" 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>

 <!-- 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="InitialStateRetrievalTimeout">5000</attribute>

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

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

 <!-- Name of the eviction policy class. -->
 <attribute name="EvictionPolicyClass">org.jboss.cache.eviction.LRUPolicy</attribute>

 <!-- Specific eviction policy configurations. This is LRU -->
 <attribute name="EvictionPolicyConfig">
 <config>
 <attribute name="wakeUpIntervalSeconds">5</attribute>
 <!-- Cache wide default -->
 <region name="/_default_">
 <attribute name="maxNodes">10000</attribute>
 <attribute name="timeToLiveSeconds">86400</attribute>
 <attribute name="">0</attribute>
 </region>
 </config>
 </attribute>

 </mbean>


              This is an updated version of ejb3-entity-cache-service.xml that I tried to use and also did not worked. My cache is cleared really fast.

              Is there any configuration that I'm missing under this configuration file? I'm suspecting that I'm missing an configuration to entities, something like @Cache annotation given to the two entities depicted above.

              Thanks in advance,
              Mihai

                • Actions
              • 4. Re: Problem when using EJB3EntityTreeCache for caching entit
                mihai.ratiu Jan 5, 2009 10:23 AM (in response to mihai.ratiu)

                As an update I ran the clustered entity example provided in ejb3 RC9 Patch under the same jboss configuration and it works fine, the information is not removed from the treecache nodes.

                The only difference from my seems to be that after calling persist or merge methods I also call flush for this tables.
                Can this affect the eviction of the cache? I wasn't able to find any reference to this in all the documentation that I have been reading.

                Does anybody have an idea of this behaviour?

                  • Actions
                • 5. Re: Problem when using EJB3EntityTreeCache for caching entit
                  brian.stansberry
                  brian.stansberry Jan 8, 2009 12:11 PM (in response to mihai.ratiu)

                  Please give an example of how you are calling "flush for tables".

                    • Actions
                  • 6. Re: Problem when using EJB3EntityTreeCache for caching entit
                    mihai.ratiu Jan 9, 2009 4:04 AM (in response to mihai.ratiu)

                    After some further investigations, after reading the server logs, i saw that the cache nodes are removed(not evicted after specified time), by calling _remove method, short time after the entities are stored into the cache.

                    I will send you some logs some server logs containing removal process:

                    2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.TreeCache] created new GTX: GlobalTransaction:<192.168.3.183:4838>:30, local TX=TransactionImpl:XidImpl[FormatId=257, GlobalId=P4285/1309, BranchQual=, localId=1309]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.TxInterceptor] (192.168.3.183:4838) call on method [_remove; id:5; Args: (GlobalTransaction:<192.168.3.183:4838>:30, /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, true)]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.TxInterceptor] local transaction exists - registering global tx if not present for Thread[Timer-10,5,jboss]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.TxInterceptor] Associated gtx in txTable is GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.TxInterceptor] Registering sync handler for tx TransactionImpl:XidImpl[FormatId=257, GlobalId=P4285/1309, BranchQual=, localId=1309], gtx GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.TxInterceptor] registering for TX completion: SynchronizationHandler(TxInterceptor.LocalSynchronizationHandler(gtx=GlobalTransaction:<192.168.3.183:4838>:30, tx=org.jboss.tm.TransactionImpl@11715815))
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.PessimisticLockInterceptor] PessimisticLockInterceptor invoked for method _remove; id:5; Args: (GlobalTransaction:<192.168.3.183:4838>:30, /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, true)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.PessimisticLockInterceptor] Attempting to lock node /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity for owner GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo/server, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo/server, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo/server/connectors, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo/server/connectors, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo/server/connectors/syncml, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo/server/connectors/syncml, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity (caller=GlobalTransaction:<192.168.3.183:4838>:30)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3784, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3784, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3786, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3786, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3787, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3787, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3788, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3788, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3789, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3789, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3790, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3790, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3791, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3791, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3792, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3792, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3793, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3793, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3794, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3794, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3795, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3795, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3796, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3796, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3797, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3797, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3798, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3798, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3799, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3799, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3800, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3800, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3801, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3801, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3802, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3802, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3803, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3803, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3804, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3804, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3805, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3805, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3806, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3806, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3807, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3807, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3808, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3808, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3809, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3809, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3810, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3810, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3811, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3811, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3812, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3812, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3813, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3813, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3814, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3814, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3815, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3815, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3816, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3816, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3779, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3779, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3780, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3780, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3781, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3781, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3782, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3782, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3783, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=<unlocked>
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3783, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.CallInterceptor] Invoking method _remove; id:5; Args: (GlobalTransaction:<192.168.3.183:4838>:30, /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, true) on cache.
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.TreeCache] _remove(GlobalTransaction:<192.168.3.183:4838>:30, "/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity")
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.EvictionInterceptor] Invoking EvictionInterceptor
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.EvictionInterceptor] Updating node/element events with no tx
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.eviction.RegionManager] getRegion(): user-specified region not found: / will use the global default region
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.eviction.RegionManager] getRegion(): user-specified region not found: / will use the global default region
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.EvictionInterceptor] Adding event EvictedEN[fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity event=1 diff=0] to region at /_default_/
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.EvictionInterceptor] Finished updating node
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.EvictionInterceptor] Finished invoking EvictionInterceptor
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.InvalidationInterceptor] (192.168.3.183:4838) method call _remove; id:5; Args: (GlobalTransaction:<192.168.3.183:4838>:30, /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, true)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.InvalidationInterceptor] Is a CRUD method
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.TxInterceptor] Running beforeCompletion on gtx GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.PessimisticLockInterceptor] PessimisticLockInterceptor invoked for method prepare; id:10; Args: (GlobalTransaction:<192.168.3.183:4838>:30, [_remove; id:5; Args: (GlobalTransaction:<192.168.3.183:4838>:30, /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, true)], 192.168.3.183:4838, false)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.PessimisticLockInterceptor] bypassed locking as method prepare() doesn't require locking
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.CallInterceptor] Suppressing invocation of method prepare; id:10; Args: (GlobalTransaction:<192.168.3.183:4838>:30, [_remove; id:5; Args: (GlobalTransaction:<192.168.3.183:4838>:30, /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, true)], 192.168.3.183:4838, false) on cache.
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.EvictionInterceptor] Invoking EvictionInterceptor
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.EvictionInterceptor] Updating node/element events with no tx
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.EvictionInterceptor] No node modifications
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.EvictionInterceptor] Finished invoking EvictionInterceptor
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.InvalidationInterceptor] (192.168.3.183:4838) method call prepare; id:10; Args: (GlobalTransaction:<192.168.3.183:4838>:30, [_remove; id:5; Args: (GlobalTransaction:<192.168.3.183:4838>:30, /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, true)], 192.168.3.183:4838, false)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.InvalidationInterceptor] Entering InvalidationInterceptor's prepare phase
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.InvalidationInterceptor] Cache [192.168.3.183:4838] replicating _evict; id:8; Args: (/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.InvalidationInterceptor] Broadcasting call _evict; id:8; Args: (/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity) to recipient list null
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.TreeCache] destination list is empty, discarding call
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.InvalidationInterceptor] responses=null
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.TreeCache] status is COMMITTED; returning null
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.TxInterceptor] (192.168.3.183:4838) call on method [_remove; id:5; Args: (null, /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, true)]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.TxInterceptor] local transaction exists - registering global tx if not present for Thread[Timer-10,5,jboss]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.TxInterceptor] Associated gtx in txTable is GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.TxInterceptor] Transaction TransactionImpl:XidImpl[FormatId=257, GlobalId=P4285/1309, BranchQual=, localId=1309] is already registered.
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.PessimisticLockInterceptor] PessimisticLockInterceptor invoked for method _remove; id:5; Args: (null, /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, true)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.interceptors.PessimisticLockInterceptor] Attempting to lock node /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity for owner GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller GlobalTransaction:<192.168.3.183:4838>:30 already owns lock for /
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller GlobalTransaction:<192.168.3.183:4838>:30 already owns lock for /com
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller GlobalTransaction:<192.168.3.183:4838>:30 already owns lock for /com/snapon
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller GlobalTransaction:<192.168.3.183:4838>:30 already owns lock for /com/snapon/nemo
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo/server, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller GlobalTransaction:<192.168.3.183:4838>:30 already owns lock for /com/snapon/nemo/server
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo/server, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo/server/connectors, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller GlobalTransaction:<192.168.3.183:4838>:30 already owns lock for /com/snapon/nemo/server/connectors
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo/server/connectors, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo/server/connectors/syncml, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller GlobalTransaction:<192.168.3.183:4838>:30 already owns lock for /com/snapon/nemo/server/connectors/syncml
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo/server/connectors/syncml, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller GlobalTransaction:<192.168.3.183:4838>:30 already owns lock for /com/snapon/nemo/server/connectors/syncml/datastore
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller GlobalTransaction:<192.168.3.183:4838>:30 already owns lock for /com/snapon/nemo/server/connectors/syncml/datastore/persistence
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=read owners=[GlobalTransaction:<192.168.3.183:4838>:30]
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity (caller=GlobalTransaction:<192.168.3.183:4838>:30)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity (caller=GlobalTransaction:<192.168.3.183:4838>:30)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3784, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3784 (caller=GlobalTransaction:<192.168.3.183:4838>:30)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3784, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3786, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3786 (caller=GlobalTransaction:<192.168.3.183:4838>:30)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3786, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3787, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3787 (caller=GlobalTransaction:<192.168.3.183:4838>:30)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3787, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3788, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3788 (caller=GlobalTransaction:<192.168.3.183:4838>:30)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3788, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3789, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3789 (caller=GlobalTransaction:<192.168.3.183:4838>:30)
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3789, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30
2009-01-08 10:37:30,156 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/com/snapon/nemo/server/connectors/syncml/datastore/persistence/PendingDataStoresConnEntity/com.snapon.nemo.server.connectors.syncml.datastore.persistence.PendingDataStoresConnEntity#3790, caller=GlobalTransaction:<192.168.3.183:4838>:30, lock=write owner=GlobalTransaction:<192.168.3.183:4838>:30


                    I'm not sure if I understand about what configuration you are talking about when saying "flush on tables". I also can send you the hibernate configurations from my persistence.xml file. 

                    <property name="hibernate.dialect" value="org.hibernate.dialect.Oracle10gDialect"></property>
<property name="hibernate.connection.release_mode" value="on_close"></property>
<property name="hibernate.transaction.auto_close_session" value="true"></property>
<property name="hibernate.show_sql" value="false"></property>
<property name="hibernate.cache.provider_class" value="org.jboss.ejb3.entity.TreeCacheProviderHook"/>
<property name="hibernate.treecache.mbean.object_name" value="jboss.cache:service=EJB3EntityTreeCache"/>


                    Regards,
                    Mihai

                      • Actions
                    • 7. Re: Problem when using EJB3EntityTreeCache for caching entit
                      brian.stansberry
                      brian.stansberry Jan 9, 2009 11:11 AM (in response to mihai.ratiu)

                      My "flush for tables" bit came from this:

                      "mihai.ratiu" wrote:
                      The only difference from my seems to be that after calling persist or merge methods I also call flush for this tables.


                      Please give me a code example of this. Most likely it's what is triggering the JBC remove call. I don't want to poke around guessing when you have code. :)

                        • Actions
                      • 8. Re: Problem when using EJB3EntityTreeCache for caching entit
                        mihai.ratiu Jan 9, 2009 11:29 AM (in response to mihai.ratiu)

                        flush for tables was calling entity manager's flush method. Should this be the problem?

                        I don't think that any code is necessary hence I only annotate the two entities with @Cache and added the two new hibernate props to the persistence.xml.

                        Can this flush() call produce the removal of the cache nodes?

                        Regards,
                        Mihai

                          • Actions
                        • 9. Re: Problem when using EJB3EntityTreeCache for caching entit
                          brian.stansberry
                          brian.stansberry Feb 1, 2009 3:48 PM (in response to mihai.ratiu)

                          I had a chance to poke around a bit. In the Hibernate code the only path I see that results in the entire region for an entity being removed from the cache is via the BulkOperationCleanupAction, which gets clears the cache region during tx commit if the tx executed some "bulk operation". Typically that would be the execution of native SQL whereby Hibernate couldn't track when entity instances were affected by the the SQL, forcing the invalidation of everything all instances of the entity.

                          Is your code doing that?

                            • Actions
                          • 10. Re: Problem when using EJB3EntityTreeCache for caching entit
                            bobbiee
                            bobbiee Feb 1, 2009 4:12 PM (in response to mihai.ratiu)

                            Hi people,

                            I have exactly the same problem as mihai.ratiu does. As I understand, when I execute native query in my code, the cache gets completely clear?? This is the answer I expected, when I try to remove all native queries and replace it by ejb3 queries, the cache remains full.

                            Does it mean I can't use any native sql queries in my code to keep the cache working normally?? Heh, that's pretty not good as native queries are much more faster and for some operations I just can't use normal queries...

                            Another question is here: How can I replace native queries?

                              • Actions
                            • 11. Re: Problem when using EJB3EntityTreeCache for caching entit
                              bobbiee
                              bobbiee Feb 1, 2009 4:12 PM (in response to mihai.ratiu)

                              Hi people,

                              I have exactly the same problem as mihai.ratiu does. As I understand, when I execute native query in my code, the cache gets completely clear?? This is the answer I expected, when I try to remove all native queries and replace it by ejb3 queries, the cache remains full.

                              Does it mean I can't use any native sql queries in my code to keep the cache working normally?? Heh, that's pretty not good as native queries are much more faster and for some operations I just can't use normal queries...

                              Another question is here: How can I replace native queries?

                                • Actions
                              • 12. Re: Problem when using EJB3EntityTreeCache for caching entit
                                bobbiee
                                bobbiee Feb 1, 2009 4:13 PM (in response to mihai.ratiu)

                                Sorry for double post...

                                  • Actions
                                • 13. Re: Problem when using EJB3EntityTreeCache for caching entit
                                  mihai.ratiu Feb 2, 2009 2:28 AM (in response to mihai.ratiu)

                                  Thank you for your reply. That's true I'm using native queries too. After some tests I was able to keep la my cache data into nodes.

                                  Glad that I wasn't the only one that meet that issue and get a solution.

                                    • Actions