0 Replies Latest reply on Mar 4, 2004 3:22 AM by pepgrifell

    TransactionRolledbackLocalException when increasing users

    pepgrifell

      Hi,

      I´m using jboss3.2.3-tomcat4.1.29 in linux. I´m doing a stress test to my application. My application has an MDB that receives messages (1000-1500 during the 3 hours). Once a message arrives I call a stateless EJB that treats the message. In the stateless EJB I access some Entity Beans to upate DB and get some information to send a JMS message.
      All the methods of the EJB´s have the tx attribute "required".
      After 3 hours and incresing the number of users to 100 I get an error everytime that tries to access an EJB. The thing is that I access the EJB to do a read(findByPrimaryKey) and I get a timeout here. Almost all the time the same field is read. If I have lots of Entity Beans accessing this field, can this field be blocked ?

      Here is the error I get :


      2004-03-04 07:26:34,602 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Current transaction in MI is TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13307, BranchQual=]
      2004-03-04 07:26:34,602 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TX_REQUIRED for findByPrimaryKey
      2004-03-04 07:26:34,602 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Thread came in with tx TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13307, BranchQual=]
      2004-03-04 07:26:34,602 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCFindByPrimaryKeyQuery.InfoBasicaEntity#findByPrimaryKey] Executing SQL: SELECT COD_PAIS, COD_ENTIDAD, CCC FROM INFO_BASICA WHERE COD_PAIS=? AND COD_ENTIDAD=? AND CCC=?
      2004-03-04 07:26:34,604 WARN [org.jboss.tm.TransactionImpl] Transaction TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13035, BranchQual=] timed out. status=STATUS_ACTIVE
      2004-03-04 07:26:34,606 TRACE [org.jboss.ejb.BeanLock] End wait on TxLock=TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//12885, BranchQual=]
      2004-03-04 07:26:34,606 ERROR [org.jboss.ejb.BeanLock] Thread[Thread Pool Worker-3,5,ASF Session Pool Threads]Saw rolled back tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13035, BranchQual=] waiting for txLock
      2004-03-04 07:26:34,606 WARN [org.jboss.tm.TransactionImpl] Lock contention, tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13035, BranchQual=]
      2004-03-04 07:26:34,607 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TxInterceptorCMT: In finally
      2004-03-04 07:26:34,607 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Current transaction in MI is TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13303, BranchQual=]
      2004-03-04 07:26:34,608 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TX_REQUIRED for getEstado
      2004-03-04 07:26:34,608 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Thread came in with tx TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13303, BranchQual=]
      2004-03-04 07:26:34,608 TRACE [org.jboss.ejb.BeanLock] Begin schedule, key=org.notariado.inti.personales.business.ejb.InfoBasicaEntityPK@2fe2cef2
      2004-03-04 07:26:34,608 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCLoadEntityCommand.InfoBasicaEntity] Executing SQL: SELECT TIPO_PRESTAMO, CANAL_DISTRIBUCION, CANAL_ACCESO, COD_FINALIDAD, COD_ACTIVIDAD_PRODUCTO, GRUPO_TIPO_DE_BIEN, FECHA_CADUCIDAD, FECHA_ABONO, FECHA_FIRMA, PROTOCOLO, MULTIFIRMA, PIDIENDO_DOCS, ESTADO, TELF_ENTIDAD, TELF_CALLCENTER, NOTARIO_TITULAR_PROTOCOLO, NOTARIO_FIRMANTE, COD_ULTIMO_NOTARIO_ACCESO, NOMINAL_OPERACION, COD_DIVISA, DESCR_DIVISA, PERIODICIDAD_AMORTIZACION, PERIODICIDAD_LIQUIDACION, PLAZO_OPERACION, TIPO_INTERES, INDICE_REFERENCIA, DESCR_INDICE_REFERENCIA, DIFERENCIAL, CODIGO_REDONDEO, DESCR_CODIGO_REDONDEO, INTERES_DEMORA, TAE, COD_TIPO_AMORTIZACION, DESCR_TIPO_AMORTIZACION, AVISO_DOCS_MODIFICADOS, EXISTE_DOCUMENTACION, FECHA_ABONO_DOCUMENTACION, EXTRAE_DOCS, RATIFICADO, FECHA_RATIFICACION, NOTARIO_RATIFICADOR FROM INFO_BASICA WHERE (COD_PAIS=? AND COD_ENTIDAD=? AND CCC=?)
      2004-03-04 07:26:34,610 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TxInterceptorCMT: In finally
      2004-03-04 07:26:34,610 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Current transaction in MI is TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13307, BranchQual=]
      2004-03-04 07:26:34,610 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TX_REQUIRED for getEstado
      2004-03-04 07:26:34,610 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Thread came in with tx TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13307, BranchQual=]
      2004-03-04 07:26:34,610 TRACE [org.jboss.ejb.BeanLock] Begin schedule, key=org.notariado.inti.personales.business.ejb.InfoBasicaEntityPK@2fe2cef1
      2004-03-04 07:26:34,611 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCLoadEntityCommand.InfoBasicaEntity] Executing SQL: SELECT TIPO_PRESTAMO, CANAL_DISTRIBUCION, CANAL_ACCESO, COD_FINALIDAD, COD_ACTIVIDAD_PRODUCTO, GRUPO_TIPO_DE_BIEN, FECHA_CADUCIDAD, FECHA_ABONO, FECHA_FIRMA, PROTOCOLO, MULTIFIRMA, PIDIENDO_DOCS, ESTADO, TELF_ENTIDAD, TELF_CALLCENTER, NOTARIO_TITULAR_PROTOCOLO, NOTARIO_FIRMANTE, COD_ULTIMO_NOTARIO_ACCESO, NOMINAL_OPERACION, COD_DIVISA, DESCR_DIVISA, PERIODICIDAD_AMORTIZACION, PERIODICIDAD_LIQUIDACION, PLAZO_OPERACION, TIPO_INTERES, INDICE_REFERENCIA, DESCR_INDICE_REFERENCIA, DIFERENCIAL, CODIGO_REDONDEO, DESCR_CODIGO_REDONDEO, INTERES_DEMORA, TAE, COD_TIPO_AMORTIZACION, DESCR_TIPO_AMORTIZACION, AVISO_DOCS_MODIFICADOS, EXISTE_DOCUMENTACION, FECHA_ABONO_DOCUMENTACION, EXTRAE_DOCS, RATIFICADO, FECHA_RATIFICACION, NOTARIO_RATIFICADOR FROM INFO_BASICA WHERE (COD_PAIS=? AND COD_ENTIDAD=? AND CCC=?)
      2004-03-04 07:26:34,616 WARN [org.jboss.tm.TransactionImpl] Transaction TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13039, BranchQual=] timed out. status=STATUS_ACTIVE
      2004-03-04 07:26:34,622 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TxInterceptorCMT: In finally
      2004-03-04 07:26:34,623 TRACE [org.jboss.ejb.BeanLock] End wait on TxLock=TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//12885, BranchQual=]
      2004-03-04 07:26:34,623 ERROR [org.jboss.ejb.BeanLock] Thread[Thread Pool Worker-18,5,ASF Session Pool Threads]Saw rolled back tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13039, BranchQual=] waiting for txLock
      2004-03-04 07:26:34,623 WARN [org.jboss.tm.TransactionImpl] Lock contention, tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13039, BranchQual=]
      2004-03-04 07:26:34,625 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TxInterceptorCMT: In finally
      2004-03-04 07:26:34,626 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Current transaction in MI is TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13301, BranchQual=]
      2004-03-04 07:26:34,626 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TX_REQUIRED for findByPrimaryKey
      2004-03-04 07:26:34,626 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Thread came in with tx TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13301, BranchQual=]
      2004-03-04 07:26:34,626 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCFindByPrimaryKeyQuery.TextErroresEntity#findByPrimaryKey] Executing SQL: SELECT COD_ERROR FROM TEXT_ERRORES WHERE COD_ERROR=?
      2004-03-04 07:26:34,628 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Current transaction in MI is TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13305, BranchQual=]
      2004-03-04 07:26:34,628 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TX_REQUIRED for findByPrimaryKey
      2004-03-04 07:26:34,628 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Thread came in with tx TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13305, BranchQual=]
      2004-03-04 07:26:34,628 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.JDBCFindByPrimaryKeyQuery.TextErroresEntity#findByPrimaryKey] Executing SQL: SELECT COD_ERROR FROM TEXT_ERRORES WHERE COD_ERROR=?
      2004-03-04 07:26:34,631 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TxInterceptorCMT: In finally
      2004-03-04 07:26:34,631 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Current transaction in MI is TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13301, BranchQual=]
      2004-03-04 07:26:34,631 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TX_REQUIRED for getDescripcion
      2004-03-04 07:26:34,631 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Thread came in with tx TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13301, BranchQual=]
      2004-03-04 07:26:34,631 TRACE [org.jboss.ejb.BeanLock] Begin schedule, key=O005
      2004-03-04 07:26:34,631 TRACE [org.jboss.ejb.BeanLock] Transactional contention on contextO005
      2004-03-04 07:26:34,631 TRACE [org.jboss.ejb.BeanLock] Begin wait on Tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//12885, BranchQual=]
      2004-03-04 07:26:34,632 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TxInterceptorCMT: In finally
      2004-03-04 07:26:34,632 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Current transaction in MI is TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13305, BranchQual=]
      2004-03-04 07:26:34,632 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TX_REQUIRED for getDescripcion
      2004-03-04 07:26:34,632 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] Thread came in with tx TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//13305, BranchQual=]
      2004-03-04 07:26:34,632 TRACE [org.jboss.ejb.BeanLock] Begin schedule, key=O005
      2004-03-04 07:26:34,632 TRACE [org.jboss.ejb.BeanLock] Transactional contention on contextO005
      2004-03-04 07:26:34,632 TRACE [org.jboss.ejb.BeanLock] Begin wait on Tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=osiris//12885, BranchQual=]
      2004-03-04 07:26:34,634 TRACE [org.jboss.ejb.plugins.TxInterceptorCMT] TxInterceptorCMT: In finally
      2004-03-04 07:26:34,634 ERROR [org.jboss.ejb.plugins.LogInterceptor] TransactionRolledbackLocalException in method: public abstract java.lang.String org.notariado.inti.personales.business.ejb.TextErroresEntity.getDescripcion(), causedBy:
      java.lang.RuntimeException: Transaction marked for rollback, possibly a timeout
      at org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock.waitForTx(Unknown Source)
      at org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock.doSchedule(Unknown Source)
      at org.jboss.ejb.plugins.lock.QueuedPessimisticEJBLock.schedule(Unknown Source)
      at org.jboss.ejb.plugins.EntityLockInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.EntityCreationInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(Unknown Source)
      at org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(Unknown Source)
      at org.jboss.ejb.plugins.TxInterceptorCMT.invoke(Unknown Source)
      at org.jboss.ejb.plugins.SecurityInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.LogInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.EntityContainer.internalInvoke(Unknown Source)
      at org.jboss.ejb.Container.invoke(Unknown Source)
      at org.jboss.ejb.plugins.local.BaseLocalProxyFactory.invoke(Unknown Source)
      at org.jboss.ejb.plugins.local.EntityProxy.invoke(Unknown Source)
      at $Proxy91.getDescripcion(Unknown Source)
      at org.notariado.inti.personales.business.ejb.CommonSessionBean.messageAlta(CommonSessionBean.java:138)
      at org.notariado.inti.personales.business.ejb.CommonSessionBean.tratarMensaje(CommonSessionBean.java:46)
      at sun.reflect.GeneratedMethodAccessor114.invoke(Unknown Source)
      at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
      at java.lang.reflect.Method.invoke(Method.java:324)
      at org.jboss.ejb.StatelessSessionContainer$ContainerInterceptor.invoke(Unknown Source)
      at org.jboss.resource.connectionmanager.CachedConnectionInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.StatelessSessionInstanceInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(Unknown Source)
      at org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(Unknown Source)
      at org.jboss.ejb.plugins.TxInterceptorCMT.invoke(Unknown Source)
      at org.jboss.ejb.plugins.SecurityInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.LogInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.StatelessSessionContainer.internalInvoke(Unknown Source)
      at org.jboss.ejb.Container.invoke(Unknown Source)
      at sun.reflect.GeneratedMethodAccessor94.invoke(Unknown Source)
      at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
      at java.lang.reflect.Method.invoke(Method.java:324)
      at org.jboss.mx.capability.ReflectedMBeanDispatcher.invoke(Unknown Source)
      at org.jboss.mx.server.MBeanServerImpl.invoke(Unknown Source)
      at org.jboss.invocation.local.LocalInvoker.invoke(Unknown Source)
      at org.jboss.invocation.InvokerInterceptor.invoke(Unknown Source)
      at org.jboss.proxy.TransactionInterceptor.invoke(Unknown Source)
      at org.jboss.proxy.SecurityInterceptor.invoke(Unknown Source)
      at org.jboss.proxy.ejb.StatelessSessionInterceptor.invoke(Unknown Source)
      at org.jboss.proxy.ClientContainer.invoke(Unknown Source)
      at $Proxy115.tratarMensaje(Unknown Source)
      at org.notariado.inti.personales.business.ejb.CommonMDBean.onMessage(CommonMDBean.java:45)
      at sun.reflect.GeneratedMethodAccessor112.invoke(Unknown Source)
      at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
      at java.lang.reflect.Method.invoke(Method.java:324)
      at org.jboss.ejb.MessageDrivenContainer$ContainerInterceptor.invoke(Unknown Source)
      at org.jboss.resource.connectionmanager.CachedConnectionInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.MessageDrivenInstanceInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(Unknown Source)
      at org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(Unknown Source)
      at org.jboss.ejb.plugins.TxInterceptorCMT.invoke(Unknown Source)
      at org.jboss.ejb.plugins.RunAsSecurityInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.LogInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor.invoke(Unknown Source)
      at org.jboss.ejb.MessageDrivenContainer.internalInvoke(Unknown Source)
      at org.jboss.ejb.Container.invoke(Unknown Source)
      at org.jboss.ejb.plugins.jms.JMSContainerInvoker.invoke(Unknown Source)
      at org.jboss.ejb.plugins.jms.JMSContainerInvoker$MessageListenerImpl.onMessage(Unknown Source)
      at org.jboss.jms.asf.StdServerSession.onMessage(Unknown Source)
      at org.jboss.mq.SpyMessageConsumer.sessionConsumerProcessMessage(Unknown Source)
      at org.jboss.mq.SpyMessageConsumer.addMessage(Unknown Source)
      at org.jboss.mq.SpySession.run(Unknown Source)
      at org.jboss.jms.asf.StdServerSession.run(Unknown Source)
      at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:732)
      at java.lang.Thread.run(Thread.java:536)