Всем привет. Во время выполнения работы с JMS и MDB вылезает вот такая ошибка сервер Jboss 5.1
2009-07-30 17:13:24,496 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-9) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX -3f57fffb:c8b1:4a719b0b:b8 in state RUN
2009-07-30 17:13:24,757 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-10) [com.arjuna.ats.arjuna.coordinator.BasicAction_58] - Abort of action id -3f57fffb:c8b1:4a719b0b:b8 invoked while multiple threads active within it.
2009-07-30 17:13:24,758 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-10) [com.arjuna.ats.arjuna.coordinator.CheckedAction_2] - CheckedAction::check - atomic action -3f57fffb:c8b1:4a719b0b:b8 aborting with 1 threads active!
2009-07-30 17:13:24,997 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-9) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX -3f57fffb:c8b1:4a719b0b:b8 in state CANCEL
2009-07-30 17:13:25,389 ERROR [org.jboss.jms.tx.ResourceManager] (Thread-10) Failed to redeliver
org.jboss.jms.exception.MessagingJMSException: Failed to invoke
at org.jboss.jms.client.delegate.DelegateSupport.handleThrowable(DelegateSupport.java:271)
at org.jboss.jms.client.delegate.DelegateSupport.doInvoke(DelegateSupport.java:205)
at org.jboss.jms.client.delegate.DelegateSupport.doInvoke(DelegateSupport.java:160)
at org.jboss.jms.client.delegate.ClientSessionDelegate.org$jboss$jms$client$delegate$ClientSessionDelegate$cancelDelivery$aop(ClientSessionDelegate.java:514)
at org.jboss.jms.client.delegate.ClientSessionDelegate$cancelDelivery_2980985335510575813.invokeTarget(ClientSessionDelegate$cancelDelivery_2980985335510575813.java)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:111)
at org.jboss.jms.client.container.FailoverValveInterceptor.invoke(FailoverValveInterceptor.java:92)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.delegate.ClientSessionDelegate.cancelDelivery(ClientSessionDelegate.java)
at org.jboss.jms.client.container.SessionAspect.cancelDelivery(SessionAspect.java:892)
at org.jboss.jms.client.container.SessionAspect.handleRedeliver(SessionAspect.java:548)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect_z_handleRedeliver_2378681.invoke(SessionAspect_z_handleRedeliver_2378681.java)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.delegate.ClientSessionDelegate.redeliver(ClientSessionDelegate.java)
at org.jboss.jms.tx.ResourceManager.redeliverMessages(ResourceManager.java:652)
at org.jboss.jms.tx.ResourceManager.rollback(ResourceManager.java:452)
at org.jboss.jms.tx.MessagingXAResource.rollback(MessagingXAResource.java:269)
at com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord.topLevelAbort(XAResourceRecord.java:420)
at com.arjuna.ats.arjuna.coordinator.BasicAction.doAbort(BasicAction.java:3270)
at com.arjuna.ats.arjuna.coordinator.BasicAction.doAbort(BasicAction.java:3248)
at com.arjuna.ats.arjuna.coordinator.BasicAction.Abort(BasicAction.java:1933)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.cancel(TwoPhaseCoordinator.java:114)
at com.arjuna.ats.arjuna.AtomicAction.cancel(AtomicAction.java:230)
at com.arjuna.ats.arjuna.coordinator.TransactionReaper.doCancellations(TransactionReaper.java:563)
at com.arjuna.ats.internal.arjuna.coordinator.ReaperWorkerThread.run(ReaperWorkerThread.java:91)
Caused by: java.lang.InterruptedException
at EDU.oswego.cs.dl.util.concurrent.WriterPreferenceReadWriteLock$ReaderLock.acquire(WriterPreferenceReadWriteLock.java:157)
at org.jboss.jms.server.remoting.JMSServerInvocationHandler.invoke(JMSServerInvocationHandler.java:128)
at org.jboss.remoting.ServerInvoker.invoke(ServerInvoker.java:891)
at org.jboss.remoting.transport.local.LocalClientInvoker.invoke(LocalClientInvoker.java:106)
at org.jboss.remoting.Client.invoke(Client.java:1724)
at org.jboss.remoting.Client.invoke(Client.java:629)
at org.jboss.remoting.Client.invoke(Client.java:617)
at org.jboss.jms.client.delegate.DelegateSupport.doInvoke(DelegateSupport.java:189)
... 30 more
2009-07-30 17:13:27,902 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-9) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX -3f57fffb:c8b1:4a719b0b:bc in state RUN
2009-07-30 17:13:28,184 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-10) [com.arjuna.ats.arjuna.coordinator.BasicAction_58] - Abort of action id -3f57fffb:c8b1:4a719b0b:bc invoked while multiple threads active within it.
2009-07-30 17:13:28,196 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-10) [com.arjuna.ats.arjuna.coordinator.CheckedAction_2] - CheckedAction::check - atomic action -3f57fffb:c8b1:4a719b0b:bc aborting with 1 threads active!
2009-07-30 17:13:28,403 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-9) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX -3f57fffb:c8b1:4a719b0b:bc in state CANCEL
2009-07-30 17:13:28,714 ERROR [org.jboss.messaging.util.ExceptionUtil] (Thread-10) SessionEndpoint[xb-8fv3irxf-1-30m1irxf-i47hts-50o4c5] cancelDelivery [pd-asxairxf-1-30m1irxf-i47hts-50o4c5]
org.jboss.util.NestedSQLException: Unable to get managed connection for DefaultDS; - nested throwable: (javax.resource.ResourceException: Unable to get managed connection for DefaultDS)
at org.jboss.resource.adapter.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:95)
at org.jboss.messaging.core.impl.JDBCSupport$JDBCTxRunner2.execute(JDBCSupport.java:463)
at org.jboss.messaging.core.impl.JDBCSupport$JDBCTxRunner2.executeWithRetry(JDBCSupport.java:512)
at org.jboss.messaging.core.impl.JDBCPersistenceManager.updateDeliveryCount(JDBCPersistenceManager.java:1486)
at org.jboss.messaging.core.impl.ChannelSupport.cancel(ChannelSupport.java:306)
at org.jboss.messaging.core.impl.SimpleDelivery.cancel(SimpleDelivery.java:110)
at org.jboss.jms.server.endpoint.ServerSessionEndpoint.cancelDeliveryInternal(ServerSessionEndpoint.java:1626)
at org.jboss.jms.server.endpoint.ServerSessionEndpoint.cancelDelivery(ServerSessionEndpoint.java:456)
at org.jboss.jms.server.endpoint.advised.SessionAdvised.org$jboss$jms$server$endpoint$advised$SessionAdvised$cancelDelivery$aop(SessionAdvised.java:145)
at org.jboss.jms.server.endpoint.advised.SessionAdvised$cancelDelivery_2980985335510575813.invokeTarget(SessionAdvised$cancelDelivery_2980985335510575813.java)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:111)
at org.jboss.jms.server.container.ServerLogInterceptor.invoke(ServerLogInterceptor.java:105)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.server.endpoint.advised.SessionAdvised.cancelDelivery(SessionAdvised.java)
at org.jboss.jms.wireformat.SessionCancelDeliveryRequest.serverInvoke(SessionCancelDeliveryRequest.java:84)
at org.jboss.jms.server.remoting.JMSServerInvocationHandler.invoke(JMSServerInvocationHandler.java:143)
at org.jboss.remoting.ServerInvoker.invoke(ServerInvoker.java:891)
at org.jboss.remoting.transport.local.LocalClientInvoker.invoke(LocalClientInvoker.java:106)
at org.jboss.remoting.Client.invoke(Client.java:1724)
at org.jboss.remoting.Client.invoke(Client.java:629)
at org.jboss.remoting.Client.invoke(Client.java:617)
at org.jboss.jms.client.delegate.DelegateSupport.doInvoke(DelegateSupport.java:189)
at org.jboss.jms.client.delegate.DelegateSupport.doInvoke(DelegateSupport.java:160)
at org.jboss.jms.client.delegate.ClientSessionDelegate.org$jboss$jms$client$delegate$ClientSessionDelegate$cancelDelivery$aop(ClientSessionDelegate.java:514)
at org.jboss.jms.client.delegate.ClientSessionDelegate$cancelDelivery_2980985335510575813.invokeTarget(ClientSessionDelegate$cancelDelivery_2980985335510575813.java)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:111)
at org.jboss.jms.client.container.FailoverValveInterceptor.invoke(FailoverValveInterceptor.java:92)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.delegate.ClientSessionDelegate.cancelDelivery(ClientSessionDelegate.java)
at org.jboss.jms.client.container.SessionAspect.cancelDelivery(SessionAspect.java:892)
at org.jboss.jms.client.container.SessionAspect.handleRedeliver(SessionAspect.java:548)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect_z_handleRedeliver_2378681.invoke(SessionAspect_z_handleRedeliver_2378681.java)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.delegate.ClientSessionDelegate.redeliver(ClientSessionDelegate.java)
at org.jboss.jms.tx.ResourceManager.redeliverMessages(ResourceManager.java:652)
at org.jboss.jms.tx.ResourceManager.rollback(ResourceManager.java:452)
at org.jboss.jms.tx.MessagingXAResource.rollback(MessagingXAResource.java:269)
at com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord.topLevelAbort(XAResourceRecord.java:420)
at com.arjuna.ats.arjuna.coordinator.BasicAction.doAbort(BasicAction.java:3270)
at com.arjuna.ats.arjuna.coordinator.BasicAction.doAbort(BasicAction.java:3248)
at com.arjuna.ats.arjuna.coordinator.BasicAction.Abort(BasicAction.java:1933)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.cancel(TwoPhaseCoordinator.java:114)
at com.arjuna.ats.arjuna.AtomicAction.cancel(AtomicAction.java:230)
at com.arjuna.ats.arjuna.coordinator.TransactionReaper.doCancellations(TransactionReaper.java:563)
at com.arjuna.ats.internal.arjuna.coordinator.ReaperWorkerThread.run(ReaperWorkerThread.java:91)
Caused by: javax.resource.ResourceException: Unable to get managed connection for DefaultDS
at org.jboss.resource.connectionmanager.BaseConnectionManager2.getManagedConnection(BaseConnectionManager2.java:441)
at org.jboss.resource.connectionmanager.TxConnectionManager.getManagedConnection(TxConnectionManager.java:381)
at org.jboss.resource.connectionmanager.BaseConnectionManager2.allocateConnection(BaseConnectionManager2.java:496)
at org.jboss.resource.connectionmanager.BaseConnectionManager2$ConnectionManagerProxy.allocateConnection(BaseConnectionManager2.java:941)
at org.jboss.resource.adapter.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:89)
... 51 more
Caused by: javax.resource.ResourceException: Interrupted while requesting permit! Waited 0 ms
at org.jboss.resource.connectionmanager.InternalManagedConnectionPool.getConnection(InternalManagedConnectionPool.java:314)
at org.jboss.resource.connectionmanager.JBossManagedConnectionPool$BasePool.getConnection(JBossManagedConnectionPool.java:622)
at org.jboss.resource.connectionmanager.BaseConnectionManager2.getManagedConnection(BaseConnectionManager2.java:404)
... 55 more
2009-07-30 17:13:29,133 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-9) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX -3f57fffb:c8b1:4a719b0b:bc in state CANCEL_INTERRUPTED
2009-07-30 17:13:31,368 ERROR [org.jboss.jms.tx.ResourceManager] (Thread-10) Failed to redeliver
org.jboss.jms.exception.MessagingJMSException: A failure has occurred during processing of the request. Please consult the server logs for more details. SessionEndpoint[xb-8fv3irxf-1-30m1irxf-i47hts-50o4c5] cancelDelivery [pd-asxairxf-1-30m1irxf-i47hts-50o4c5]
at org.jboss.messaging.util.ExceptionUtil.handleJMSInvocation(ExceptionUtil.java:72)
at org.jboss.jms.server.endpoint.ServerSessionEndpoint.cancelDelivery(ServerSessionEndpoint.java:466)
at org.jboss.jms.server.endpoint.advised.SessionAdvised.org$jboss$jms$server$endpoint$advised$SessionAdvised$cancelDelivery$aop(SessionAdvised.java:145)
at org.jboss.jms.server.endpoint.advised.SessionAdvised$cancelDelivery_2980985335510575813.invokeTarget(SessionAdvised$cancelDelivery_2980985335510575813.java)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:111)
at org.jboss.jms.server.container.ServerLogInterceptor.invoke(ServerLogInterceptor.java:105)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.server.endpoint.advised.SessionAdvised.cancelDelivery(SessionAdvised.java)
at org.jboss.jms.wireformat.SessionCancelDeliveryRequest.serverInvoke(SessionCancelDeliveryRequest.java:84)
at org.jboss.jms.server.remoting.JMSServerInvocationHandler.invoke(JMSServerInvocationHandler.java:143)
at org.jboss.remoting.ServerInvoker.invoke(ServerInvoker.java:891)
at org.jboss.remoting.transport.local.LocalClientInvoker.invoke(LocalClientInvoker.java:106)
at org.jboss.remoting.Client.invoke(Client.java:1724)
at org.jboss.remoting.Client.invoke(Client.java:629)
at org.jboss.remoting.Client.invoke(Client.java:617)
at org.jboss.jms.client.delegate.DelegateSupport.doInvoke(DelegateSupport.java:189)
at org.jboss.jms.client.delegate.DelegateSupport.doInvoke(DelegateSupport.java:160)
at org.jboss.jms.client.delegate.ClientSessionDelegate.org$jboss$jms$client$delegate$ClientSessionDelegate$cancelDelivery$aop(ClientSessionDelegate.java:514)
at org.jboss.jms.client.delegate.ClientSessionDelegate$cancelDelivery_2980985335510575813.invokeTarget(ClientSessionDelegate$cancelDelivery_2980985335510575813.java)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:111)
at org.jboss.jms.client.container.FailoverValveInterceptor.invoke(FailoverValveInterceptor.java:92)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.delegate.ClientSessionDelegate.cancelDelivery(ClientSessionDelegate.java)
at org.jboss.jms.client.container.SessionAspect.cancelDelivery(SessionAspect.java:892)
at org.jboss.jms.client.container.SessionAspect.handleRedeliver(SessionAspect.java:548)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect_z_handleRedeliver_2378681.invoke(SessionAspect_z_handleRedeliver_2378681.java)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
at org.jboss.jms.client.delegate.ClientSessionDelegate.redeliver(ClientSessionDelegate.java)
at org.jboss.jms.tx.ResourceManager.redeliverMessages(ResourceManager.java:652)
at org.jboss.jms.tx.ResourceManager.rollback(ResourceManager.java:452)
at org.jboss.jms.tx.MessagingXAResource.rollback(MessagingXAResource.java:269)
at com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord.topLevelAbort(XAResourceRecord.java:420)
at com.arjuna.ats.arjuna.coordinator.BasicAction.doAbort(BasicAction.java:3270)
at com.arjuna.ats.arjuna.coordinator.BasicAction.doAbort(BasicAction.java:3248)
at com.arjuna.ats.arjuna.coordinator.BasicAction.Abort(BasicAction.java:1933)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.cancel(TwoPhaseCoordinator.java:114)
at com.arjuna.ats.arjuna.AtomicAction.cancel(AtomicAction.java:230)
at com.arjuna.ats.arjuna.coordinator.TransactionReaper.doCancellations(TransactionReaper.java:563)
at com.arjuna.ats.internal.arjuna.coordinator.ReaperWorkerThread.run(ReaperWorkerThread.java:91)
Вопрос откуда вылезает:
2009-07-30 17:13:24,496 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-9) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX -3f57fffb:c8b1:4a719b0b:b8 in state RUN
2009-07-30 17:13:24,757 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-10) [com.arjuna.ats.arjuna.coordinator.BasicAction_58] - Abort of action id -3f57fffb:c8b1:4a719b0b:b8 invoked while multiple threads active within it.
2009-07-30 17:13:24,758 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-10) [com.arjuna.ats.arjuna.coordinator.CheckedAction_2] - CheckedAction::check - atomic action -3f57fffb:c8b1:4a719b0b:b8 aborting with 1 threads active!
2009-07-30 17:13:24,997 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-9) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX -3f57fffb:c8b1:4a719b0b:b8 in state CANCEL
Может кто сталкивался с этим?
... << RSDN@Home 1.2.0 alpha 4 rev. 1111>>
откат по таймауту.
причина таймаута из этих логов неясна.
Здравствуйте, EyfelFenk, Вы писали:
EF>Всем привет. Во время выполнения работы с JMS и MDB вылезает вот такая ошибка сервер Jboss 5.1
EF>Вопрос откуда вылезает:
EF>EF>2009-07-30 17:13:24,496 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-9) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX -3f57fffb:c8b1:4a719b0b:b8 in state RUN
EF>2009-07-30 17:13:24,757 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-10) [com.arjuna.ats.arjuna.coordinator.BasicAction_58] - Abort of action id -3f57fffb:c8b1:4a719b0b:b8 invoked while multiple threads active within it.
EF>2009-07-30 17:13:24,758 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-10) [com.arjuna.ats.arjuna.coordinator.CheckedAction_2] - CheckedAction::check - atomic action -3f57fffb:c8b1:4a719b0b:b8 aborting with 1 threads active!
EF>2009-07-30 17:13:24,997 WARN [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-9) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX -3f57fffb:c8b1:4a719b0b:b8 in state CANCEL
EF>
(Thread-10) — имхо, выполняет полезные действия внутри транзакции, а (Thread-9) — следит за таймаутом это транзакции (который выставлен на 500мс, имхо), по истечении которого он эту транзакцию объявляет отменённой, и последующие эксепшоны в 10-м потоке результат отмены транзакции.