Strange Exception (6.0.0.M3) What is it?
gwzoller Aug 14, 2010 7:56 PMHello,
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