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

CMP bean hangs on second request

pualsa Jul 9, 2004 9:25 AM

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

pualsa Jul 9, 2004 9:35 AM (in response to pualsa)

Sorry, forgot to say: this code doesn't work on JBoss 3.2.5, but works fine on JBoss 3.2.3
Actions
2. Re: CMP bean hangs on second request

aloubyansky Jul 10, 2004 5:47 AM (in response to pualsa)

Can you increase log level to TRACE?
Actions

3. Re: CMP bean hangs on second request

pualsa Jul 12, 2004 9:40 AM (in response to pualsa)

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

pualsa Jul 12, 2004 9:48 AM (in response to pualsa)
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?
Actions

Go to original post