4 Replies Latest reply on Jul 12, 2004 9:48 AM by Alexei Sadovnikov

    CMP bean hangs on second request

    Alexei Sadovnikov Newbie

      I have one simple CMP entity bean and one session bean. The part of the session bean is here:

       /** Business method.
       * @ejb.interface-method
       */
       public long testCMP() {
       long time = System.currentTimeMillis();
      
       try {
       for (int i = 0; i < 10; i++) {
       System.out.println("CMP " + i);
       AccountCMPLocalHome home = AccountCMPUtil.getLocalHome();
       java.util.Collection accs = home.findAll();
      
       java.util.Iterator itr = accs.iterator();
      
       while (itr.hasNext()) {
       AccountCMPLocal acc = (AccountCMPLocal) itr.next();
       String u = acc.getUserId();
       System.out.println("CMP " + u);
       String p = acc.getPassword();
       }
       }
       } catch (Exception e) {
       throw new javax.ejb.EJBException(e);
       }
      
       return System.currentTimeMillis() - time;
       }
      

      AccountCMPUtil is the cache generated by XDoclet.

      The 'for' cycle executes only once and then the server and the client hang up!

      The last words of the server are:
      2004-07-09 15:03:39,988 INFO [STDOUT] CMP 0
      2004-07-09 15:03:39,988 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCEJBQLQuery.AccountCMP#findAll] Executing SQL: SELECT t0_a.userId FROM T_ACCOUNT t0_a
      2004-07-09 15:03:39,988 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCLoadEntityCommand.AccountCMP] Executing SQL: SELECT pwd FROM T_ACCOUNT WHERE (userId=?)
      2004-07-09 15:03:40,004 INFO [STDOUT] CMP user1
      2004-07-09 15:03:40,004 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCLoadEntityCommand.AccountCMP] Executing SQL: SELECT userId, pwd FROM T_ACCOUNT WHERE (userId=?) OR (userId=?) OR (userId=?) OR (userId=?) OR (userId=?)
      2004-07-09 15:03:40,004 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCLoadEntityCommand.AccountCMP] Executing SQL: SELECT pwd FROM T_ACCOUNT WHERE (userId=?)
      2004-07-09 15:03:40,004 INFO [STDOUT] CMP user2
      2004-07-09 15:03:40,004 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCLoadEntityCommand.AccountCMP] Executing SQL: SELECT pwd FROM T_ACCOUNT WHERE (userId=?)
      2004-07-09 15:03:40,020 INFO [STDOUT] CMP user3
      2004-07-09 15:03:40,020 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCLoadEntityCommand.AccountCMP] Executing SQL: SELECT pwd FROM T_ACCOUNT WHERE (userId=?)
      2004-07-09 15:03:40,020 INFO [STDOUT] CMP user4
      2004-07-09 15:03:40,020 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCLoadEntityCommand.AccountCMP] Executing SQL: SELECT pwd FROM T_ACCOUNT WHERE (userId=?)
      2004-07-09 15:03:40,035 INFO [STDOUT] CMP user5
      2004-07-09 15:03:40,035 INFO [STDOUT] CMP 1
      2004-07-09 15:03:40,035 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCEJBQLQuery.AccountCMP#findAll] Executing SQL: SELECT t0_a.userId FROM T_ACCOUNT t0_a
      2004-07-09 15:08:39,278 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000
      2004-07-09 15:08:39,996 WARN [org.jboss.tm.TransactionImpl] Transaction TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//137, BranchQual=] timed out. status=STATUS_ACTIVE
      


      So, did I make a mistake or a method of a CMP bean can be invoked only once per trasaction?

        • 1. Re: CMP bean hangs on second request
          Alexei Sadovnikov Newbie

          Sorry, forgot to say: this code doesn't work on JBoss 3.2.5, but works fine on JBoss 3.2.3

          • 2. Re: CMP bean hangs on second request
            Alexey Loubyansky Master

            Can you increase log level to TRACE?

            • 3. Re: CMP bean hangs on second request
              Alexei Sadovnikov Newbie

              here is log starting from the second cycle:

              2004-07-12 14:52:16,839 INFO [STDOUT] CMP 1
              2004-07-12 14:52:16,839 TRACE [org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor] invokerBInding is null in ProxyFactoryFinder
              2004-07-12 14:52:16,839 TRACE [org.jboss.ejb.plugins.LogInterceptor] Start method=findAll
              2004-07-12 14:52:16,839 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Current transaction in MI is TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:52:16,839 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TX_REQUIRED for findAll timeout=0
              2004-07-12 14:52:16,839 TRACE [org.jboss.tm.TxManager] suspended tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:52:16,839 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Thread came in with tx TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:52:16,839 TRACE [org.jboss.tm.TxManager] resumed tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:52:16,839 TRACE [org.jboss.ejb.plugins.EntityInstancePool] Get instance org.jboss.ejb.plugins.EntityInstancePool@515979#0#class server.AccountCMPCMP
              2004-07-12 14:52:16,839 TRACE [org.jboss.resource.connectionmanager.CachedConnectionManager] old stack for key: ProxyTarget[org.jboss.ejb.plugins.cmp.bridge.EntityBridgeInvocationHandler@1aa5f9b]
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] attempt(1) was: true for :org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] registerLoaderThread, ucl=org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, t=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], prevT=null
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Begin beginLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@1a13f3d{classname: org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 0, state: 0, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader] getResourceLocally(org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}), name=org/jboss/ejb/GlobalTxEntityMap$GetTCLAction.class, resURL:jar:file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar!/org/jboss/ejb/GlobalTxEntityMap$GetTCLAction.class
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] scheduleTask(1), created subtask: {t=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], ucl=org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, name=org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, requestingThread=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], order=2, releaseInNextTask=false}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] End beginLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@1a13f3d{classname: org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 1, state: 1, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Continue nextTask(1), task=org.jboss.mx.loading.ClassLoadingTask@1a13f3d{classname: org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 1, state: 1, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Begin nextTask(0), loadTask=org.jboss.mx.loading.ClassLoadingTask@1a13f3d{classname: org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 1, state: 1, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Running threadTask={t=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], ucl=org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, name=org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, requestingThread=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], order=2, releaseInNextTask=false}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader] loadClassLocally, name=org.jboss.ejb.GlobalTxEntityMap$GetTCLAction
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader] findClass, name=org.jboss.ejb.GlobalTxEntityMap$GetTCLAction
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.ClassLoadingTask] setLoadedClass, theClass=class org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, order=2
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedLoaderRepository3] cacheLoadedClass, classname: org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, class: class org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, ucl: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, prevClass: null
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Notifying task of thread completion, loadTask:org.jboss.mx.loading.ClassLoadingTask@1a13f3d{classname: org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: class org.jboss.ejb.GlobalTxEntityMap$GetTCLActionorg.jboss.ejb.GlobalTxEntityMap$GetTCLAction@13ef605<CodeSource: (file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar <no certificates>)>, loadOrder: 2, loadException: null, threadTaskCount: 0, state: 1, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] End nextTask(0), loadTask=org.jboss.mx.loading.ClassLoadingTask@1a13f3d{classname: org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: class org.jboss.ejb.GlobalTxEntityMap$GetTCLActionorg.jboss.ejb.GlobalTxEntityMap$GetTCLAction@13ef605<CodeSource: (file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar <no certificates>)>, loadOrder: 2, loadException: null, threadTaskCount: 0, state: 4, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Begin endLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@1a13f3d{classname: org.jboss.ejb.GlobalTxEntityMap$GetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: class org.jboss.ejb.GlobalTxEntityMap$GetTCLActionorg.jboss.ejb.GlobalTxEntityMap$GetTCLAction@13ef605<CodeSource: (file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar <no certificates>)>, loadOrder: 2, loadException: null, threadTaskCount: 0, state: 4, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] release(1) for :org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] released, holds: 0
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] attempt(1) was: true for :org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] registerLoaderThread, ucl=org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, t=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], prevT=null
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Begin beginLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@19cd6b6{classname: org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 0, state: 0, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader] getResourceLocally(org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}), name=org/jboss/ejb/GlobalTxEntityMap$SetTCLAction.class, resURL:jar:file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar!/org/jboss/ejb/GlobalTxEntityMap$SetTCLAction.class
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] scheduleTask(1), created subtask: {t=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], ucl=org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, name=org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, requestingThread=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], order=2, releaseInNextTask=false}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] End beginLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@19cd6b6{classname: org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 1, state: 1, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Continue nextTask(1), task=org.jboss.mx.loading.ClassLoadingTask@19cd6b6{classname: org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 1, state: 1, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Begin nextTask(0), loadTask=org.jboss.mx.loading.ClassLoadingTask@19cd6b6{classname: org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 1, state: 1, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Running threadTask={t=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], ucl=org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, name=org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, requestingThread=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], order=2, releaseInNextTask=false}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader] loadClassLocally, name=org.jboss.ejb.GlobalTxEntityMap$SetTCLAction
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader] findClass, name=org.jboss.ejb.GlobalTxEntityMap$SetTCLAction
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.ClassLoadingTask] setLoadedClass, theClass=class org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, order=2
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedLoaderRepository3] cacheLoadedClass, classname: org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, class: class org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, ucl: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, prevClass: null
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Notifying task of thread completion, loadTask:org.jboss.mx.loading.ClassLoadingTask@19cd6b6{classname: org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: class org.jboss.ejb.GlobalTxEntityMap$SetTCLActionorg.jboss.ejb.GlobalTxEntityMap$SetTCLAction@1d45d18<CodeSource: (file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar <no certificates>)>, loadOrder: 2, loadException: null, threadTaskCount: 0, state: 1, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] End nextTask(0), loadTask=org.jboss.mx.loading.ClassLoadingTask@19cd6b6{classname: org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: class org.jboss.ejb.GlobalTxEntityMap$SetTCLActionorg.jboss.ejb.GlobalTxEntityMap$SetTCLAction@1d45d18<CodeSource: (file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar <no certificates>)>, loadOrder: 2, loadException: null, threadTaskCount: 0, state: 4, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.LoadMgr3] Begin endLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@19cd6b6{classname: org.jboss.ejb.GlobalTxEntityMap$SetTCLAction, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: class org.jboss.ejb.GlobalTxEntityMap$SetTCLActionorg.jboss.ejb.GlobalTxEntityMap$SetTCLAction@1d45d18<CodeSource: (file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar <no certificates>)>, loadOrder: 2, loadException: null, threadTaskCount: 0, state: 4, #CCE: 0}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] release(1) for :org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}
              2004-07-12 14:52:16,839 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] released, holds: 0
              2004-07-12 14:52:16,839 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCEJBQLQuery.AccountCMP#findAll] Executing SQL: SELECT t0_a.userId FROM T_ACCOUNT t0_a
              2004-07-12 14:52:16,839 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] subject: null
              2004-07-12 14:52:16,839 TRACE [org.jboss.resource.connectionmanager.JBossManagedConnectionPool] Getting connection tracked by transaction org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEventListener@1f45022[state=NORMAL mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@b8fba5 handles=0 lastUse=1089636723386 permit=true trackByTx=true mcp=org.jboss.resource.connectionmanager.JBossManagedConnectionPool$OnePool@9133f6 context=org.jboss.resource.connectionmanager.InternalManagedConnectionPool@ea7776] [InUse/Available/Max]: [1/4/5]
              2004-07-12 14:52:16,839 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] currenttx: TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=], already enlisted for ManagedConnection: org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@b8fba5
              2004-07-12 14:52:16,839 TRACE [org.jboss.resource.connectionmanager.CachedConnectionManager] registering connection from org.jboss.resource.connectionmanager.TxConnectionManager@1387c6, connection : org.jboss.resource.adapter.jdbc.WrappedConnection@12bf678, key: org.jboss.resource.connectionmanager.CachedConnectionManager$KeyConnectionAssociation@1427e6e
              2004-07-12 14:52:16,855 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] connectionClosed called mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@b8fba5
              2004-07-12 14:52:16,855 TRACE [org.jboss.resource.connectionmanager.CachedConnectionManager] unregistering connection from org.jboss.resource.connectionmanager.TxConnectionManager@1387c6, object: org.jboss.resource.adapter.jdbc.WrappedConnection@12bf678, key: org.jboss.resource.connectionmanager.CachedConnectionManager$KeyConnectionAssociation@1427e6e
              2004-07-12 14:52:16,855 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] unregisterConnection: 0 handles left
              2004-07-12 14:52:16,855 TRACE [org.jboss.resource.connectionmanager.TxConnectionManager] isManagedConnectionFree=false mc=org.jboss.resource.adapter.jdbc.local.LocalManagedConnection@b8fba5
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.cmp.jdbc.ReadAheadCache.AccountCMP] Add finder results: entity=AccountCMP results=[user1, user2, user3, user4, user5] readahead=[JDBCReadAheadMetaData : strategy=on-load, pageSize=1000, eagerLoadGroup=*, left-join[]]
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.cmp.jdbc.ReadAheadCache.AccountCMP] Removing dereferenced results: [user1, user2, user3, user4, user5]
              2004-07-12 14:52:16,855 TRACE [org.jboss.resource.connectionmanager.CachedConnectionManager] popped object: org.jboss.resource.connectionmanager.CachedConnectionManager$KeyConnectionAssociation@1427e6e
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.EntityInstancePool] 0/100 Free instance:org.jboss.ejb.plugins.EntityInstancePool@515979#null#null#false#class server.AccountCMPCMP
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TxInterceptorCMT: In finally
              2004-07-12 14:52:16,855 TRACE [org.jboss.tm.TxManager] suspended tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:52:16,855 TRACE [org.jboss.tm.TxManager] resumed tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.LogInterceptor] End method=findAll
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor] invokerBInding is null in ProxyFactoryFinder
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.LogInterceptor] Start method=getUserId
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Current transaction in MI is TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TX_NOT_SUPPORTED for getUserId
              2004-07-12 14:52:16,855 TRACE [org.jboss.tm.TxManager] suspended tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Thread came in with tx TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.plugins.EntityLockInterceptor] Begin invoke, key=user1
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.BeanLockManager] Added ref to lock: org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock@1390efd, bean=AccountCMP, id=user1, refs=2, tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=], synched=null, timeout=5000, queue=[]
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.BeanLock] Begin schedule, key=user1
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] attempt(1) was: true for :org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] registerLoaderThread, ucl=org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, t=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], prevT=null
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] Begin beginLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@12f5f0d{classname: org.jboss.util.deadlock.DeadlockDetector, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 0, state: 0, #CCE: 0}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedClassLoader] loadClassLocally, name=org.jboss.util.deadlock.DeadlockDetector
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedLoaderRepository3] cacheLoadedClass, classname: org.jboss.util.deadlock.DeadlockDetector, class: class org.jboss.util.deadlock.DeadlockDetector, ucl: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, prevClass: null
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] End beginLoadTask, loadClassFromClassLoader
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] Begin endLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@12f5f0d{classname: org.jboss.util.deadlock.DeadlockDetector, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: class org.jboss.util.deadlock.DeadlockDetectororg.jboss.util.deadlock.DeadlockDetector@1095c6c<CodeSource: (file:/C:/jboss-3.2.5/lib/jboss-common.jar <no certificates>)>, loadOrder: 2147483647, loadException: null, threadTaskCount: 0, state: 4, #CCE: 0}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] release(1) for :org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] released, holds: 0
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.BeanLock] Transactional contention on contextuser1
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] attempt(1) was: true for :org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] registerLoaderThread, ucl=org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, t=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], prevT=null
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] Begin beginLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@1d9d7be{classname: org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 0, state: 0, #CCE: 0}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedClassLoader] getResourceLocally(org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}), name=org/jboss/ejb/plugins/lock/QueuedPessimisticEJBLock$TxLock.class, resURL:jar:file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar!/org/jboss/ejb/plugins/lock/QueuedPessimisticEJBLock$TxLock.class
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] scheduleTask(1), created subtask: {t=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], ucl=org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, name=org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, requestingThread=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], order=2, releaseInNextTask=false}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] End beginLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@1d9d7be{classname: org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 1, state: 1, #CCE: 0}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] Continue nextTask(1), task=org.jboss.mx.loading.ClassLoadingTask@1d9d7be{classname: org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 1, state: 1, #CCE: 0}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] Begin nextTask(0), loadTask=org.jboss.mx.loading.ClassLoadingTask@1d9d7be{classname: org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: nullnull, loadOrder: 2147483647, loadException: null, threadTaskCount: 1, state: 1, #CCE: 0}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] Running threadTask={t=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], ucl=org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, name=org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, requestingThread=Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], order=2, releaseInNextTask=false}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedClassLoader] loadClassLocally, name=org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedClassLoader] findClass, name=org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.ClassLoadingTask] setLoadedClass, theClass=class org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, order=2
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedLoaderRepository3] cacheLoadedClass, classname: org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, class: class org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, ucl: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, prevClass: null
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] Notifying task of thread completion, loadTask:org.jboss.mx.loading.ClassLoadingTask@1d9d7be{classname: org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: class org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLockorg.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock@e31ec5<CodeSource: (file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar <no certificates>)>, loadOrder: 2, loadException: null, threadTaskCount: 0, state: 1, #CCE: 0}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] End nextTask(0), loadTask=org.jboss.mx.loading.ClassLoadingTask@1d9d7be{classname: org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: class org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLockorg.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock@e31ec5<CodeSource: (file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar <no certificates>)>, loadOrder: 2, loadException: null, threadTaskCount: 0, state: 4, #CCE: 0}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.LoadMgr3] Begin endLoadTask, task=org.jboss.mx.loading.ClassLoadingTask@1d9d7be{classname: org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock, requestingThread: Thread[RMI TCP Connection(2)-193.xxx.xxx.xxx,5,RMI Runtime], requestingClassLoader: org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}, loadedClass: class org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLockorg.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock$TxLock@e31ec5<CodeSource: (file:/C:/jboss-3.2.5/server/xpetstore/lib/jboss.jar <no certificates>)>, loadOrder: 2, loadException: null, threadTaskCount: 0, state: 4, #CCE: 0}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] release(1) for :org.jboss.mx.loading.UnifiedClassLoader3@1901437{ url=file:/C:/jboss-3.2.5/server/xpetstore/tmp/deploy/tmp31196jboss-service.xml ,addedOrder=2}
              2004-07-12 14:52:16,855 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] released, holds: 0
              
              2004-07-12 14:52:16,855 TRACE [org.jboss.ejb.BeanLock] Begin wait on Tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:52:17,261 TRACE [org.jboss.deployment.scanner.URLDeploymentScanner] Scanning for new deployments
              2004-07-12 14:52:17,261 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] Loaded class from cache, org.jboss.net.protocol.file.FileURLLister@1fc1a6<CodeSource: (file:/C:/jboss-3.2.5/lib/jboss-common.jar <no certificates>)>
              2004-07-12 14:52:17,277 TRACE [org.jboss.deployment.scanner.URLDeploymentScanner] Updating existing deployments
              2004-07-12 14:52:17,277 TRACE [org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread] Sleeping...
              
              (...)
              
              2004-07-12 14:57:11,923 TRACE [org.jboss.ejb.BeanLock] End wait on TxLock=TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:57:11,923 TRACE [org.jboss.ejb.BeanLock] Transactional contention on contextuser1
              2004-07-12 14:57:11,923 TRACE [org.jboss.ejb.BeanLock] Begin wait on Tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:57:12,939 TRACE [org.jboss.deployment.scanner.URLDeploymentScanner] Scanning for new deployments
              2004-07-12 14:57:12,939 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] Loaded class from cache, org.jboss.net.protocol.file.FileURLLister@1fc1a6<CodeSource: (file:/C:/jboss-3.2.5/lib/jboss-common.jar <no certificates>)>
              2004-07-12 14:57:12,939 TRACE [org.jboss.deployment.scanner.URLDeploymentScanner] Updating existing deployments
              2004-07-12 14:57:12,954 TRACE [org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread] Sleeping...
              
              2004-07-12 14:57:16,611 WARN [org.jboss.tm.TransactionImpl] Transaction TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=] timed out. status=STATUS_ACTIVE
              
              2004-07-12 14:57:16,611 TRACE [org.jboss.ejb.BeanLock] End wait on TxLock=TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:57:16,611 TRACE [org.jboss.ejb.BeanLock] Transactional contention on contextuser1
              2004-07-12 14:57:16,611 TRACE [org.jboss.ejb.BeanLock] Begin wait on Tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=sysm1029//17, BranchQual=]
              2004-07-12 14:57:17,955 TRACE [org.jboss.deployment.scanner.URLDeploymentScanner] Scanning for new deployments
              2004-07-12 14:57:17,955 TRACE [org.jboss.mx.loading.UnifiedClassLoader3] Loaded class from cache, org.jboss.net.protocol.file.FileURLLister@1fc1a6<CodeSource: (file:/C:/jboss-3.2.5/lib/jboss-common.jar <no certificates>)>
              2004-07-12 14:57:17,955 TRACE [org.jboss.deployment.scanner.URLDeploymentScanner] Updating existing deployments
              2004-07-12 14:57:17,955 TRACE [org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread] Sleeping...
              
              (...)
              


              ejb-jar.xml transactions attributes:
               <container-transaction >
               <method >
               <ejb-name>AccountCMP</ejb-name>
               <method-name>*</method-name>
               </method>
               <trans-attribute>Required</trans-attribute>
               </container-transaction>
               <container-transaction >
               <method >
               <ejb-name>AccountCMP</ejb-name>
               <method-intf>Local</method-intf>
               <method-name>getAccountValue</method-name>
               <method-params>
               </method-params>
               </method>
               <trans-attribute>Supports</trans-attribute>
               </container-transaction>
               <container-transaction >
               <method >
               <ejb-name>AccountCMP</ejb-name>
               <method-intf>Local</method-intf>
               <method-name>getUserId</method-name>
               <method-params>
               </method-params>
               </method>
               <trans-attribute>NotSupported</trans-attribute>
               </container-transaction>
              
               <!-- session bean -->
               <container-transaction >
               <method >
               <ejb-name>Account</ejb-name>
               <method-name>*</method-name>
               </method>
               <trans-attribute>Required</trans-attribute>
               </container-transaction>
              


              userId is the primary key field of AccountCMP

              • 4. Re: CMP bean hangs on second request
                Alexei Sadovnikov Newbie

                I've changed transaction attribute for AccountCMP:

                 <container-transaction >
                 <method >
                 <ejb-name>AccountCMP</ejb-name>
                 <method-name>*</method-name>
                 </method>
                 <trans-attribute>Required</trans-attribute>
                 </container-transaction>
                


                and now it works.
                But should it work with the old configuration where different transaction attributes were set?