1 Reply Latest reply on Aug 16, 2010 4:26 AM by jaikiran

    Strange Exception (6.0.0.M3)  What is it?

    gwzoller

      Hello,

       

      I am getting a very strange exception and I don't know what/why I'm getting it.  Exception is below.

       

      Basically I have a trivial EAR file, which contains a WAR.  In the WAR is a ServletContextListener that starts a Scala Actor (essentially a thread) that fires periodically and calls a local EJB method to a stateless session bean.  Pretty harmless stuff, and it works great... for a while.

       

      After running for some time (maybe 15-20 minutes?) I get the exception below.  The exception also kills my Actor so the calls to my session bean stop at that point. 

       

      I'm seeing logging stuff in the exception, as well as a lot of transaction stuff--rollbacks, etc.  (I'm not explicitly creating/using any kind of transaction (I'm not even using persistance)...can't say what's going on automatically in the background though.)

       

      Googling around gave me a hint this may have something to do with passivation, and that might explain why it runs fine for a while, but I really don't know.

       

      Any ideas welcome.

      Thanks!

      Greg

       

      18:38:44,369 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX 0:ffffac1242e5:126a:4c671768:18fe in state  RUN
      18:38:44,401 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.coordinator.BasicAction_58] - Abort of action id 0:ffffac1242e5:126a:4c671768:18fe invoked while multiple threads active within it.
      18:38:44,401 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.coordinator.CheckedAction_2] - CheckedAction::check - atomic action 0:ffffac1242e5:126a:4c671768:18fe aborting with 1 threads active!
      18:38:44,401 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.coordinator.BasicAction_35] - End called illegally on atomic action 0:ffffac1242e5:126a:4c671768:18fe
      18:38:44,401 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.coordinator.TransactionReaper_7] - TransactionReaper::doCancellations worker Thread[Thread-10,5,jboss] successfully canceled TX 0:ffffac1242e5:126a:4c671768:18fe
      18:38:44,479 ERROR [STDERR] scala.actors.Actor$$anon$1@141774: caught javax.ejb.EJBTransactionRolledbackException: Transaction rolled back
      18:38:44,479 ERROR [STDERR] javax.ejb.EJBTransactionRolledbackException: Transaction rolled back
      18:38:44,479 ERROR [STDERR]     at org.jboss.ejb3.tx.Ejb3TxPolicy.handleEndTransactionException(Ejb3TxPolicy.java:54)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aspects.tx.TxPolicy.endTransaction(TxPolicy.java:175)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:87)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:190)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:76)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
      18:38:44,479 ERROR [STDERR]     at org.jboss.ejb3.tx.NullInterceptor.invoke(NullInterceptor.java:42)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
      18:38:44,479 ERROR [STDERR]     at org.jboss.ejb3.security.Ejb3AuthenticationInterceptorv2.invoke(Ejb3AuthenticationInterceptorv2.java:186)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
      18:38:44,479 ERROR [STDERR]     at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:41)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
      18:38:44,479 ERROR [STDERR]     at org.jboss.ejb3.BlockContainerShutdownInterceptor.invoke(BlockContainerShutdownInterceptor.java:67)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
      18:38:44,479 ERROR [STDERR]     at org.jboss.ejb3.core.context.CurrentInvocationContextInterceptor.invoke(CurrentInvocationContextInterceptor.java:47)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aspects.currentinvocation.CurrentInvocationInterceptor.invoke(CurrentInvocationInterceptor.java:67)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
      18:38:44,479 ERROR [STDERR]     at org.jboss.ejb3.interceptor.EJB3TCCLInterceptor.invoke(EJB3TCCLInterceptor.java:86)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
      18:38:44,479 ERROR [STDERR]     at org.jboss.ejb3.session.SessionSpecContainer.invoke(SessionSpecContainer.java:180)
      18:38:44,479 ERROR [STDERR]     at org.jboss.ejb3.session.SessionSpecContainer.invoke(SessionSpecContainer.java:237)
      18:38:44,479 ERROR [STDERR]     at org.jboss.ejb3.proxy.impl.handler.session.SessionProxyInvocationHandlerBase.invoke(SessionProxyInvocationHandlerBase.java:188)
      18:38:44,479 ERROR [STDERR]     at $Proxy136.reloadSecurity_1_0(Unknown Source)
      18:38:44,479 ERROR [STDERR]     at com.aviall.servlet.StartupShutdown$$anonfun$1$$anonfun$apply$mcV$sp$1$$anonfun$apply$mcV$sp$2.apply(StartupShutdown.scala:27)
      18:38:44,479 ERROR [STDERR]     at com.aviall.servlet.StartupShutdown$$anonfun$1$$anonfun$apply$mcV$sp$1$$anonfun$apply$mcV$sp$2.apply(StartupShutdown.scala:25)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Actor$class.receive(Actor.scala:524)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Actor$$anon$1.receive(Actor.scala:134)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Actor$.receive(Actor.scala:189)
      18:38:44,479 ERROR [STDERR]     at com.aviall.servlet.StartupShutdown$$anonfun$1$$anonfun$apply$mcV$sp$1.apply$mcV$sp(StartupShutdown.scala:25)
      18:38:44,479 ERROR [STDERR]     at com.aviall.servlet.StartupShutdown$$anonfun$1$$anonfun$apply$mcV$sp$1.apply(StartupShutdown.scala:25)
      18:38:44,479 ERROR [STDERR]     at com.aviall.servlet.StartupShutdown$$anonfun$1$$anonfun$apply$mcV$sp$1.apply(StartupShutdown.scala:25)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Reactor$class.seq(Reactor.scala:280)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Actor$$anon$1.seq(Actor.scala:134)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Actor$$anon$6.andThen(Actor.scala:298)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Combinators$class.loop(Combinators.scala:26)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Actor$.loop(Actor.scala:23)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Combinators$$anonfun$loop$1.apply(Combinators.scala:26)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Combinators$$anonfun$loop$1.apply(Combinators.scala:26)
      18:38:44,479 ERROR [STDERR]     at scala.actors.Reactor$$anonfun$seq$1$$anonfun$apply$1.apply(Reactor.scala:277)
      18:38:44,479 ERROR [STDERR]     at scala.actors.ReactorTask.run(ReactorTask.scala:34)
      18:38:44,479 ERROR [STDERR]     at scala.actors.ReactorTask.compute(ReactorTask.scala:66)
      18:38:44,479 ERROR [STDERR]     at scala.concurrent.forkjoin.RecursiveAction.exec(RecursiveAction.java:147)
      18:38:44,479 ERROR [STDERR]     at scala.concurrent.forkjoin.ForkJoinTask.quietlyExec(ForkJoinTask.java:422)
      18:38:44,479 ERROR [STDERR]     at scala.concurrent.forkjoin.ForkJoinWorkerThread.mainLoop(ForkJoinWorkerThread.java:340)
      18:38:44,479 ERROR [STDERR]     at scala.concurrent.forkjoin.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:325)
      18:38:44,479 ERROR [STDERR] Caused by: javax.transaction.RollbackException: [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] Could not commit transaction.
      18:38:44,479 ERROR [STDERR]     at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1408)
      18:38:44,479 ERROR [STDERR]     at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:138)
      18:38:44,479 ERROR [STDERR]     at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75)
      18:38:44,479 ERROR [STDERR]     at org.jboss.aspects.tx.TxPolicy.endTransaction(TxPolicy.java:170)
      18:38:44,479 ERROR [STDERR]     ... 45 more

        • 1. Re: Strange Exception (6.0.0.M3)  What is it?
          jaikiran

          Greg Zoller wrote:

           


          18:38:44,401 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.coordinator.BasicAction_58] - Abort of action id 0:ffffac1242e5:126a:4c671768:18fe invoked while multiple threads active within it.
          18:38:44,401 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.coordinator.CheckedAction_2] - CheckedAction::check - atomic action 0:ffffac1242e5:126a:4c671768:18fe aborting with 1 threads active!

          See this http://community.jboss.org/wiki/TxMultipleThreads

           

          Also from what I remember, JBoss AS 6.0.0.M3 had some transaction related bugs which I think have been fixed in the latest released JBoss AS 6.0.0.M4. You might want to upgrade to M4.