8 Replies Latest reply on Feb 13, 2009 4:32 PM by brian.stansberry

    Pessimistic Locking

    nathanmesser

      I'm not sure my understanding of pessimistic locking is correct, as I have a situation which is working but I don't understand why.

      My understanding is that:
      1) Locks are held for the duration of a transaction
      2) Writers must wait until there are no read locks on a node

      I have a transaction T1, which reads a node A from the cache, so it should have a read lock on the node.

      This transaction is then suspended, when an EJB method is called with it's transaction attribute set to requiresNew. This transaction T2 then writes to the node.

      Transaction T2 then commits, and transaction T1 continues, and commits as well.

      I'm using Pessimistic Locking with an Isolation Level of READ_COMMITTED

      Why does T1s read lock not block T2s obtaining of the write lock?

        • 1. Re: Pessimistic Locking
          manik

          Have you got PessimisticLockingInterceptor logs to show this behaviour? To check lock owners, etc. of the data being accessed?

          • 2. Re: Pessimistic Locking
            nathanmesser

            I've turned trace logging on for

            org.jboss.cache.interceptors.PessimisticLockInterceptor

            but all it gave me was the following.

            I'm trying to get more comprehensive logs for you, but it's proving difficult to get the app up with a lot of trace logging turned on.

            [ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::1 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:37:55,342 14011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:37:55,342 14011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::2 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:37:55,827 14496 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:37:55,843 14512 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::3 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:37:56,172 14841 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:37:56,172 14841 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::4 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:37:56,563 15232 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:37:56,563 15232 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::5 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:02,058 20727 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:02,073 20742 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::6 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:02,433 21102 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:02,433 21102 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::7 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:02,480 21149 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:02,480 21149 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::8 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:02,590 21259 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:02,590 21259 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::9 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:02,637 21306 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:02,637 21306 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::10 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:04,875 23544 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:04,875 23544 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::11 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:04,938 23607 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:04,938 23607 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::12 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:04,985 23654 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:04,985 23654 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::13 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:05,048 23717 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:05,048 23717 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::14 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:05,142 23811 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:05,142 23811 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::15 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:05,235 23904 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:05,235 23904 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::16 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:05,376 24045 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:05,376 24045 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::17 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:05,783 24452 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:05,783 24452 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::18 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:05,987 24656 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:05,987 24656 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::19 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:06,081 24750 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:06,096 24765 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::20 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:06,300 24969 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:06,300 24969 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::21 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:06,363 25032 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:06,378 25047 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::22 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:06,410 25079 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:06,410 25079 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::23 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:06,738 25407 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:06,738 25407 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::24 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:06,864 25533 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:06,864 25533 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::25 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:06,957 25626 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:06,957 25626 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::26 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:07,051 25720 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:07,051 25720 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::27 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:07,161 25830 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:07,161 25830 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::28 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:09,916 28585 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:09,916 28585 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::29 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:10,010 28679 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:10,026 28695 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::30 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:10,135 28804 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:10,135 28804 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::31 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:10,198 28867 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:10,198 28867 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::32 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:10,276 28945 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:10,276 28945 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::33 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:10,370 29039 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:10,370 29039 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::34 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:10,636 29305 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:10,636 29305 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::35 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:10,746 29415 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:10,746 29415 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::36 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:10,824 29493 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:10,824 29493 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::37 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:11,028 29697 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:11,028 29697 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::38 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:11,262 29931 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:11,262 29931 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::39 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:38:28,983 47652 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:38:28,999 47668 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::40 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:39:01,623 80292 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:39:01,623 80292 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::41 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:39:04,535 83204 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:39:04,550 83219 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::43 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:39:05,599 84268 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:39:05,599 84268 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::42 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
            2009-02-12 14:39:05,599 84268 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
            2009-02-12 14:39:05,599 84268 DEBUG

            • 3. Re: Pessimistic Locking
              manik

              Hmm, you will probably want PessimisticNodeBasedLockManager as well.

              • 4. Re: Pessimistic Locking
                nathanmesser

                Here are the logs for:
                PessimisticNodeBasedLockManager
                PessimisticLockInterceptor

                2009-02-12 17:33:09,764 830100 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/SYS_PARAM/No of Application Licences for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,764 830100 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/SYS_PARAM/No of Application Licences for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,764 830100 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,764 830100 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/SYS_PARAM/No of Application Licences for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,764 830100 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/SYS_PARAM/No of Application Licences for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,764 830100 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,779 830115 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,779 830115 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/-3091 for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,779 830115 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/-3091 for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,779 830115 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,779 830115 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/SYS_PARAM/No of Application Licences for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,779 830115 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/SYS_PARAM/No of Application Licences/-3091 for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,779 830115 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/SYS_PARAM/No of Application Licences/-3091 for owner GlobalTransaction::40 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:09,779 830115 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::40 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
                2009-02-12 17:33:10,936 831272 DEBUG[ACTIVE] ExecuteThread: '1' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
                2009-02-12 17:33:10,936 831272 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,297 833633 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,297 833633 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues/-222 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,297 833633 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues/-222 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,297 833633 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child PolicyTypeNoCollections of node PessimisticUnversionedNode[ / data=[] children=[Parameters, Branch, MenuItem, Expressions, SchemeTypeGroups, and 5 more]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child PolicyTypeNoCollections of node PessimisticUnversionedNode[ / data=[] children=[Parameters, Branch, MenuItem, Expressions, SchemeTypeGroups, and 5 more]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node PolicyTypeNoCollections org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8820 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[]] WL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:13,312 833648 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,390 833726 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,390 833726 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,406 833742 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8820 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[]] WL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:13,406 833742 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,406 833742 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8820 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[]] WL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:13,406 833742 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,406 833742 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node 8820 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                2009-02-12 17:33:13,406 833742 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,406 833742 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,406 833742 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,453 833789 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,453 833789 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues/-222 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,453 833789 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues/-222 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,453 833789 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,453 833789 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,453 833789 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child PolicyTypeWithCollections of node PessimisticUnversionedNode[ / data=[] children=[Parameters, PolicyTypeNoCollections, Branch, MenuItem, Expressions, and 6 more]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:13,453 833789 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,453 833789 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child PolicyTypeWithCollections of node PessimisticUnversionedNode[ / data=[] children=[Parameters, PolicyTypeNoCollections, Branch, MenuItem, Expressions, and 6 more]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:13,453 833789 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,469 833805 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node PolicyTypeWithCollections org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                2009-02-12 17:33:13,469 833805 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,469 833805 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,469 833805 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,469 833805 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,469 833805 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8820 of node PessimisticUnversionedNode[ /PolicyTypeWithCollections data=[]] WL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:13,469 833805 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8820 of node PessimisticUnversionedNode[ /PolicyTypeWithCollections data=[]] WL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8820 of node PessimisticUnversionedNode[ /PolicyTypeWithCollections data=[]] WL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node 8820 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections/8820 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:15,767 836103 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:15,767 836103 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues/-220 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:15,767 836103 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues/-220 for owner GlobalTransaction::41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:15,767 836103 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction::41 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
                2009-02-12 17:33:16,001 836337 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
                2009-02-12 17:33:16,017 836353 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8820 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8820 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8825 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8825 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8825 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node 8825 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8829 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8829 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8829 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node 8829 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8833 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8833 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8833 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,394 839730 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,394 839730 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node 8833 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                2009-02-12 17:33:19,394 839730 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 4746524 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8833, 8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 4746524 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8833, 8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 4746524 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8833, 8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node 4746524 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4686570 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4686570 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam/4686570 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam/4686570 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4744629 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4744629 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3551022 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3551022 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam/3551022 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam/3551022 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3919618 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3919618 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4271636 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4271636 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4034024 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4034024 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3549866 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3549866 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4045102 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4045102 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3987758 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3987758 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/2867246 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/2867246 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17878 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17878 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/2196294 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/2196294 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/1725735 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/1725735 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/163689 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/163689 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18442 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18442 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18481 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18481 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/90577 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/90577 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18518 for owner GlobalTransaction::42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tunin

                • 5. Re: Pessimistic Locking
                  nathanmesser

                  Forgot to mention, the interesting part is the locking of the node /Parameters/ISchmParam/4686570 in GlobalTransaction::42 and GlobalTransaction::43

                  • 6. Re: Pessimistic Locking
                    mircea.markus

                    The logs you sent do not show how GlobalTransaction::43 tries to acquire locks on /Parameters/ISchmParam/4686570, only how to first one succeeds. They seem incomplete.
                    Is it possible for you to do the following:
                    log this : CachePrinter.printCacheLockingInfo(c) immediately after suspending the first tx and immediately before committing the second one?
                    Also, can I have the full jbosscache log (full meaning everything logged by org.jboss.cache in time frame before starting the first tx and after the second one successfully commits.

                    • 7. Re: Pessimistic Locking
                      nathanmesser

                      You are quite correct, I seem to have lost some of the log while pasting into the forum.

                      I'm reposting the relevant section here.

                      I'll also try and get you the full log you asked for, it may take me a little while though, as it takes quite a long time to get to that point with much trace logging turned on. Is it trace level logging you need?

                      2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeWithCollections/8820 for owner GlobalTransaction:<null>:41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:13,547 833883 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:15,767 836103 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues for owner GlobalTransaction:<null>:41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:15,767 836103 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues/-220 for owner GlobalTransaction:<null>:41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:15,767 836103 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /UDLValues/-220 for owner GlobalTransaction:<null>:41 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:15,767 836103 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' committing cache with gtx GlobalTransaction:<null>:41 org.jboss.cache.interceptors.PessimisticLockInterceptor.commit(PessimisticLockInterceptor.java:389)
                       2009-02-12 17:33:16,001 836337 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' bypassed locking as method commit() doesn't require locking org.jboss.cache.interceptors.PessimisticLockInterceptor.handleCommitCommand(PessimisticLockInterceptor.java:152)
                       2009-02-12 17:33:16,017 836353 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8820 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8820 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,347 839683 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8825 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8825 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8825 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node 8825 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8825 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8829 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8829 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8829 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node 8829 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8829 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8833 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8833 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,362 839698 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 8833 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,394 839730 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,394 839730 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node 8833 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                       2009-02-12 17:33:19,394 839730 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/8833 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 4746524 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8833, 8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 4746524 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8833, 8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' failed to find or create child 4746524 of node PessimisticUnversionedNode[ /PolicyTypeNoCollections data=[] children=[8833, 8825, 8829, 8820, ]] RL org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:152)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Child node was null, so created child node 4746524 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:146)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /PolicyTypeNoCollections/4746524 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,409 839745 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4686570 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4686570 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,597 839933 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam/4686570 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam/4686570 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4744629 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4744629 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3551022 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3551022 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam/3551022 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam/3551022 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3919618 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3919618 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4271636 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4271636 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4034024 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4034024 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3549866 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3549866 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4045102 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/4045102 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,628 839964 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3987758 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/3987758 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/2867246 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/2867246 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17878 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17878 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/2196294 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/2196294 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/1725735 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/1725735 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/163689 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/163689 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18442 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18442 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18481 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18481 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/90577 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/90577 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18518 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18518 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18528 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,644 839980 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18528 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18434 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18434 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/163711 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/163711 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18415 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18415 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18411 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18411 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam/18411 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Parameters/ISchmParam/18411 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18399 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18399 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18395 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18395 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18106 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/18106 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17914 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17914 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17904 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17904 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/90976 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,659 839995 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/90976 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17885 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17885 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17858 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17858 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17846 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17846 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17842 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/17842 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node / for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/15802 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 17:33:19,675 840011 DEBUG[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)' Attempting to lock node /Expressions/15802 for owner GlobalTransaction:<null>:42 org.jboss.cache.lock.PessimisticNodeBasedLockManager.lock(PessimisticNodeBasedLockManager.java:129)
                       2009-02-12 1


                      • 8. Re: Pessimistic Locking
                        brian.stansberry

                         

                        "nathanmesser" wrote:

                        I have a transaction T1, which reads a node A from the cache, so it should have a read lock on the node.

                        This transaction is then suspended, when an EJB method is called with it's transaction attribute set to requiresNew. This transaction T2 then writes to the node.

                        Transaction T2 then commits, and transaction T1 continues, and commits as well.

                        I'm using Pessimistic Locking with an Isolation Level of READ_COMMITTED

                        Why does T1s read lock not block T2s obtaining of the write lock?


                        The semantic you described sounds like REPEATABLE_READ to me, not READ_COMMITTED.