that is a little odd. All I can think of is that the underlying
connection is dropped in the exception case, wonder if that is the
case. It would cause any unprepared transactions to be rolledback.
Also, the xa start is sent async to the broker, but the timing from
the logs does not indicate a race here.
If you can enable debug level logging for the broker:
org.apache.activemq.transaction.XATransaction
it could provide an insight and the connection drop would be visible
in the broker log also.
Think we need to see the logs from the broker for a failure case.
On 2 June 2011 12:41, James Black <be_st...@yahoo.co.uk> wrote:
> Hi,
>
> first of thanks very much for the prompt suggestions. I have enabled
> Atomikos, ActiveMQ, and Spring JMS logging.
>
> The posts below show the logs both for a transaction that succeeds and one
> that fails. In both cases there is nothing on the queues to consume.
>
> In both cases the transaction is committed but in the failing case the
> exception occurs after Prepare is called by ActiveMQ.
>
> Also in the failed exception the InactivityMonitor has logged some activity
> after the transaction was started and before it was committed.
>
> ===== Failed Transaction to consume from a queue =====
>
> 2011-06-02 11:33:57,809 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning NULL!
> 2011-06-02 11:33:57,812 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning NULL!
> 2011-06-02 11:33:57,814 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning NULL!
> 2011-06-02 11:33:57,814 [ListenerContainer-3] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : createCompositeTransaction ( 10000
> ): created new ROOT transaction with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,814 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,816 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,817 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,820 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,820 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,820 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,821 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,822 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,824 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,824 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,825 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,830 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,830 [ListenerContainer-3] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : registerSynchronization (
> com.atomikos.jms.ConsumerProducerSupport$JmsRequeueSynchronization@39b7f681
> ) for transaction 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,830 [ListenerContainer-3] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : addParticipant (
> XAResourceTransaction:
> 3139322E3136382E302E31372E746D30303437373030303031:3139322E3136382E302E31372E746D343737
> ) for transaction 192.168.0.17.tm0047700001
> 2011-06-02 11:33:57,830 [ListenerContainer-3] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : XAResource.start (
> 3139322E3136382E302E31372E746D30303437373030303031:3139322E3136382E302E31372E746D343737
> , XAResource.TMNOFLAGS ) on resource XAJMS_PROC represented by XAResource
> instance TransactionContext{transactionId=null}
> 2011-06-02 11:33:57,831 [ListenerContainer-3] DEBUG -
> [org.apache.activemq.TransactionContext] : Start:
> 192.168.0.17.tm0047700001192.168.0.17.tm477
> 2011-06-02 11:33:57,831 [ListenerContainer-3] DEBUG -
> [org.apache.activemq.TransactionContext] : Started XA transaction:
> XID:1096044365:3139322e3136382e302e31372e746d30303437373030303031:3139322e3136382e302e31372e746d343737
> 2011-06-02 11:33:57,916 [InactivityMonitor WriteCheck] DEBUG -
> [org.apache.activemq.transport.InactivityMonitor$2] :
> org.apache.activemq.transport.InactivityMonitor$2@517ff60e 10000 ms elapsed
> since last write check.
> 2011-06-02 11:33:57,916 [InactivityMonitor WriteCheck] TRACE -
> [org.apache.activemq.transport.InactivityMonitor] :
> tcp://iodine/192.168.0.53:61617 message sent since last write check,
> resetting flag
> 2011-06-02 11:33:58,832 [ListenerContainer-3] TRACE -
> [org.springframework.jms.listener.AbstractPollingMessageListenerContainer] :
> Consumer [atomikos MessageConsumer proxy for ActiveMQMessageConsumer {
> value=ID:servername-56564-1307010782619-0:6:96:1, started=true }] of
> transactional session [ActiveMQSession
> {id=ID:servername-56564-1307010782619-0:6:96,started=true}] did not receive
> a message
> 2011-06-02 11:33:58,833 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:58,833 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:58,833 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:58,834 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:58,834 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:58,834 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0047700001
> 2011-06-02 11:33:58,835 [ListenerContainer-3] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : commit() done (by application) of
> transaction 192.168.0.17.tm0047700001
> 2011-06-02 11:33:58,835 [ListenerContainer-3] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : XAResource.end (
> 3139322E3136382E302E31372E746D30303437373030303031:3139322E3136382E302E31372E746D343737
> , XAResource.TMSUCCESS ) on resource XAJMS_PROC represented by XAResource
> instance
> TransactionContext{transactionId=XID:1096044365:3139322e3136382e302e31372e746d30303437373030303031:3139322e3136382e302e31372e746d343737}
> 2011-06-02 11:33:58,837 [ListenerContainer-3] DEBUG -
> [org.apache.activemq.TransactionContext] : End:
> 192.168.0.17.tm0047700001192.168.0.17.tm477
> 2011-06-02 11:33:58,843 [ListenerContainer-3] DEBUG -
> [org.apache.activemq.TransactionContext] : Ended XA transaction:
> XID:1096044365:3139322e3136382e302e31372e746d30303437373030303031:3139322e3136382e302e31372e746d343737
> 2011-06-02 11:33:58,844 [ListenerContainer-3] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : About to call prepare on
> XAResource instance: TransactionContext{transactionId=null}
> 2011-06-02 11:33:58,844 [ListenerContainer-3] DEBUG -
> [org.apache.activemq.TransactionContext] : Prepare:
> 192.168.0.17.tm0047700001192.168.0.17.tm477
> javax.jms.JMSException: Transaction
> 'XID:1096044365:3139322e3136382e302e31372e746d30303437373030303031:3139322e3136382e302e31372e746d343737'
> has not been started.
> at
> org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:49)
> at
> org.apache.activemq.ActiveMQConnection.syncSendPacket(ActiveMQConnection.java:1295)
> at
> org.apache.activemq.TransactionContext.syncSendPacketWithInterruptionHandling(TransactionContext.java:707)
> at
> org.apache.activemq.TransactionContext.prepare(TransactionContext.java:428)
> at
> com.atomikos.datasource.xa.XAResourceTransaction.prepare(XAResourceTransaction.java:622)
> at com.atomikos.icatch.imp.PrepareMessage.send(PrepareMessage.java:61)
> at
> com.atomikos.icatch.imp.PropagationMessage.submit(PropagationMessage.java:111)
> at
> com.atomikos.icatch.imp.Propagator$PropagatorThread.run(Propagator.java:87)
> at
> com.atomikos.icatch.imp.Propagator.submitPropagationMessage(Propagator.java:66)
> at
> com.atomikos.icatch.imp.ActiveStateHandler.prepare(ActiveStateHandler.java:173)
> at
> com.atomikos.icatch.imp.CoordinatorImp.prepare(CoordinatorImp.java:832)
> at
> com.atomikos.icatch.imp.CoordinatorImp.terminate(CoordinatorImp.java:1159)
> at
> com.atomikos.icatch.imp.CompositeTerminatorImp.commit(CompositeTerminatorImp.java:92)
> at
> com.atomikos.icatch.jta.TransactionImp.commit(TransactionImp.java:236)
> at
> com.atomikos.icatch.jta.TransactionManagerImp.commit(TransactionManagerImp.java:498)
> at
> com.atomikos.icatch.jta.UserTransactionImp.commit(UserTransactionImp.java:129)
> at
> org.springframework.transaction.jta.JtaTransactionManager.doCommit(JtaTransactionManager.java:1009)
> at
> org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:754)
> at
> org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723)
> at
> org.springframework.jms.listener.AbstractPollingMessageListenerContainer.receiveAndExecute(AbstractPollingMessageListenerContainer.java:257)
> at
> org.springframework.jms.listener.DefaultMessageListenerContainer$AsyncMessageListenerInvoker.invokeListener(DefaultMessageListenerContainer.java:1058)
> at
> org.springframework.jms.listener.DefaultMessageListenerContainer$AsyncMessageListenerInvoker.executeOngoingLoop(DefaultMessageListenerContainer.java:1050)
> at
> org.springframework.jms.listener.DefaultMessageListenerContainer$AsyncMessageListenerInvoker.run(DefaultMessageListenerContainer.java:947)
> at java.lang.Thread.run(Thread.java:662)
> Caused by: javax.transaction.xa.XAException: Transaction
> 'XID:1096044365:3139322e3136382e302e31372e746d30303437373030303031:3139322e3136382e302e31372e746d343737'
> has not been started.
> at
> org.apache.activemq.broker.TransactionBroker.getTransaction(TransactionBroker.java:290)
> at
> org.apache.activemq.broker.TransactionBroker.prepareTransaction(TransactionBroker.java:167)
> at
> org.apache.activemq.broker.MutableBrokerFilter.prepareTransaction(MutableBrokerFilter.java:115)
> at
> org.apache.activemq.broker.TransportConnection.processPrepareTransaction(TransportConnection.java:402)
> at
> org.apache.activemq.command.TransactionInfo.visit(TransactionInfo.java:98)
> at
> org.apache.activemq.broker.TransportConnection.service(TransportConnection.java:306)
> at
> org.apache.activemq.broker.TransportConnection$1.onCommand(TransportConnection.java:179)
> at
> org.apache.activemq.transport.TransportFilter.onCommand(TransportFilter.java:69)
> at
> org.apache.activemq.transport.WireFormatNegotiator.onCommand(WireFormatNegotiator.java:113)
> at
> org.apache.activemq.transport.TransportSupport.doConsume(TransportSupport.java:83)
> at
> org.apache.activemq.transport.tcp.TcpTransport.doRun(TcpTransport.java:220)
> at
> org.apache.activemq.transport.tcp.TcpTransport.run(TcpTransport.java:202)
> at java.lang.Thread.run(Unknown Source)
> 2011-06-02 11:33:58,868 [ListenerContainer-3] DEBUG -
> [org.apache.activemq.ActiveMQMessageConsumer] : remove:
> ID:servername-56564-1307010782619-0:6:96:1, lastDeliveredSequenceId:0
> 2011-06-02 11:33:58,871 [ListenerContainer-3] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : XAResource.rollback (
> 3139322E3136382E302E31372E746D30303437373030303031:3139322E3136382E302E31372E746D343737
> ) on resource XAJMS_PROC represented by XAResource instance
> TransactionContext{transactionId=null}
> 2011-06-02 11:33:58,871 [ListenerContainer-3] DEBUG -
> [org.apache.activemq.TransactionContext] : Rollback:
> 192.168.0.17.tm0047700001192.168.0.17.tm477
> 2011-06-02 11:33:58,876 [ListenerContainer-3] WARN -
> [com.atomikos.diagnostics.Slf4jConsole] : XA resource 'XAJMS_PROC': rollback
> for XID
> '3139322E3136382E302E31372E746D30303437373030303031:3139322E3136382E302E31372E746D343737'
> raised 0: unknown
> javax.transaction.xa.XAException: Transaction
> 'XID:1096044365:3139322e3136382e302e31372e746d30303437373030303031:3139322e3136382e302e31372e746d343737'
> has not been started.
> at
> org.apache.activemq.TransactionContext.toXAException(TransactionContext.java:732)
> at
> org.apache.activemq.TransactionContext.rollback(TransactionContext.java:497)
> at
> com.atomikos.datasource.xa.XAResourceTransaction.rollback(XAResourceTransaction.java:690)
> at
> com.atomikos.icatch.imp.RollbackMessage.send(RollbackMessage.java:72)
> at
> com.atomikos.icatch.imp.PropagationMessage.submit(PropagationMessage.java:111)
> at
> com.atomikos.icatch.imp.Propagator$PropagatorThread.run(Propagator.java:87)
> at
> com.atomikos.icatch.imp.Propagator.submitPropagationMessage(Propagator.java:66)
> at
> com.atomikos.icatch.imp.CoordinatorStateHandler.rollback(CoordinatorStateHandler.java:746)
> at
> com.atomikos.icatch.imp.ActiveStateHandler.prepare(ActiveStateHandler.java:218)
> at
> com.atomikos.icatch.imp.CoordinatorImp.prepare(CoordinatorImp.java:832)
> at
> com.atomikos.icatch.imp.CoordinatorImp.terminate(CoordinatorImp.java:1159)
> at
> com.atomikos.icatch.imp.CompositeTerminatorImp.commit(CompositeTerminatorImp.java:92)
> at
> com.atomikos.icatch.jta.TransactionImp.commit(TransactionImp.java:236)
> at
> com.atomikos.icatch.jta.TransactionManagerImp.commit(TransactionManagerImp.java:498)
> at
> com.atomikos.icatch.jta.UserTransactionImp.commit(UserTransactionImp.java:129)
> at
> org.springframework.transaction.jta.JtaTransactionManager.doCommit(JtaTransactionManager.java:1009)
> at
> org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:754)
> at
> org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723)
> at
> org.springframework.jms.listener.AbstractPollingMessageListenerContainer.receiveAndExecute(AbstractPollingMessageListenerContainer.java:257)
> at
> org.springframework.jms.listener.DefaultMessageListenerContainer$AsyncMessageListenerInvoker.invokeListener(DefaultMessageListenerContainer.java:1058)
> at
> org.springframework.jms.listener.DefaultMessageListenerContainer$AsyncMessageListenerInvoker.executeOngoingLoop(DefaultMessageListenerContainer.java:1050)
> at
> org.springframework.jms.listener.DefaultMessageListenerContainer$AsyncMessageListenerInvoker.run(DefaultMessageListenerContainer.java:947)
> at java.lang.Thread.run(Thread.java:662)
> Caused by: javax.transaction.xa.XAException: Transaction
> 'XID:1096044365:3139322e3136382e302e31372e746d30303437373030303031:3139322e3136382e302e31372e746d343737'
> has not been started.
> at
> org.apache.activemq.broker.TransactionBroker.getTransaction(TransactionBroker.java:290)
> at
> org.apache.activemq.broker.TransactionBroker.rollbackTransaction(TransactionBroker.java:177)
> at
> org.apache.activemq.broker.MutableBrokerFilter.rollbackTransaction(MutableBrokerFilter.java:131)
> at
> org.apache.activemq.broker.TransportConnection.processRollbackTransaction(TransportConnection.java:436)
> at
> org.apache.activemq.command.TransactionInfo.visit(TransactionInfo.java:104)
> at
> org.apache.activemq.broker.TransportConnection.service(TransportConnection.java:306)
> at
> org.apache.activemq.broker.TransportConnection$1.onCommand(TransportConnection.java:179)
> at
> org.apache.activemq.transport.TransportFilter.onCommand(TransportFilter.java:69)
> at
> org.apache.activemq.transport.WireFormatNegotiator.onCommand(WireFormatNegotiator.java:113)
> at
> org.apache.activemq.transport.TransportSupport.doConsume(TransportSupport.java:83)
> at
> org.apache.activemq.transport.tcp.TcpTransport.doRun(TcpTransport.java:220)
> at
> org.apache.activemq.transport.tcp.TcpTransport.run(TcpTransport.java:202)
> at java.lang.Thread.run(Unknown Source)
>
>
> ===== Successful Transaction to consume from a queue =====
>
> 602 2011-06-02 10:20:58,956 [listenerContainer-4] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : createCompositeTransaction ( 10000
> ): created new ROOT transaction with id 192.168.0.17.tm0001400001
> 629 2011-06-02 10:20:58,965 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 633 2011-06-02 10:20:58,968 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 638 2011-06-02 10:20:58,969 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 639 2011-06-02 10:20:58,970 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 640 2011-06-02 10:20:58,971 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 642 2011-06-02 10:20:58,971 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 643 2011-06-02 10:20:58,971 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 645 2011-06-02 10:20:58,971 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 649 2011-06-02 10:20:58,971 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 651 2011-06-02 10:20:58,973 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 659 2011-06-02 10:20:58,974 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 672 2011-06-02 10:20:58,980 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 674 2011-06-02 10:20:58,981 [listenerContainer-4] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : registerSynchronization (
> com.atomikos.jms.ConsumerProducerSupport$JmsRequeueSynchronization@57af55a2
> ) for transaction 192.168.0.17.tm0001400001
> 676 2011-06-02 10:20:58,981 [listenerContainer-4] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : addParticipant (
> XAResourceTransaction:
> 3139322E3136382E302E31372E746D30303031343030303031:3139322E3136382E302E31372E746D3133
> ) for transaction 192.168.0.17.tm0001400001
> 677 2011-06-02 10:20:58,982 [listenerContainer-4] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : XAResource.start (
> 3139322E3136382E302E31372E746D30303031343030303031:3139322E3136382E302E31372E746D3133
> , XAResource.TMNOFLAGS ) on resource XAJMS_PROC represented by XAResource
> instance TransactionContext{transactionId=null}
> 678 2011-06-02 10:20:58,982 [listenerContainer-4] DEBUG -
> [org.apache.activemq.TransactionContext] : Start:
> 192.168.0.17.tm0001400001192.168.0.17.tm13
> 681 2011-06-02 10:20:58,982 [listenerContainer-4] DEBUG -
> [org.apache.activemq.TransactionContext] : Started XA transaction:
> XID:1096044365:3139322e3136382e302e31372e746d30303031343030303031:3139322e3136382e302e31372e746d3133
> 828 2011-06-02 10:20:59,984 [listenerContainer-4] TRACE -
> [org.springframework.jms.listener.AbstractPollingMessageListenerContainer] :
> Consumer [atomikos MessageConsumer proxy for ActiveMQMessageConsumer {
> value=ID:servername-54601-1307006451773-0:3:4:1, started=true }] of
> transactional session [ActiveMQSession
> {id=ID:servername-54601-1307006451773-0:3:4,started=true}] did not receive a
> message
> 829 2011-06-02 10:20:59,985 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 830 2011-06-02 10:20:59,986 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 831 2011-06-02 10:20:59,986 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 832 2011-06-02 10:20:59,986 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 833 2011-06-02 10:20:59,987 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 834 2011-06-02 10:20:59,987 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : getCompositeTransaction()
> returning instance with id 192.168.0.17.tm0001400001
> 835 2011-06-02 10:20:59,987 [listenerContainer-4] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : commit() done (by application) of
> transaction 192.168.0.17.tm0001400001
> 840 2011-06-02 10:20:59,987 [listenerContainer-4] INFO -
> [com.atomikos.diagnostics.Slf4jConsole] : XAResource.end (
> 3139322E3136382E302E31372E746D30303031343030303031:3139322E3136382E302E31372E746D3133
> , XAResource.TMSUCCESS ) on resource XAJMS_PROC represented by XAResource
> instance
> TransactionContext{transactionId=XID:1096044365:3139322e3136382e302e31372e746d30303031343030303031:3139322e3136382e302e31372e746d3133}
> 848 2011-06-02 10:20:59,990 [listenerContainer-4] DEBUG -
> [org.apache.activemq.TransactionContext] : End:
> 192.168.0.17.tm0001400001192.168.0.17.tm13
> 853 2011-06-02 10:20:59,991 [listenerContainer-4] DEBUG -
> [org.apache.activemq.TransactionContext] : Ended XA transaction:
> XID:1096044365:3139322e3136382e302e31372e746d30303031343030303031:3139322e3136382e302e31372e746d3133
> 855 2011-06-02 10:20:59,992 [listenerContainer-4] DEBUG -
> [com.atomikos.diagnostics.Slf4jConsole] : About to call prepare on
> XAResource instance: TransactionContext{transactionId=null}
> 856 2011-06-02 10:20:59,992 [listenerContainer-4] DEBUG -
> [org.apache.activemq.TransactionContext] : Prepare:
> 192.168.0.17.tm0001400001192.168.0.17.tm13
> 859 2011-06-02 10:20:59,993 [listenerContainer-4] DEBUG -
> [org.apache.activemq.TransactionContext] : firing afterCommit callbacks on
> XA_RDONLY from prepare: 192.168.0.17.tm0001400001192.168.0.17.tm13
> 868 2011-06-02 10:20:59,993 [listenerContainer-4] DEBUG -
> [org.apache.activemq.ActiveMQMessageConsumer] : remove:
> ID:servername-54601-1307006451773-0:3:4:1, lastDeliveredSequenceId:0
>
> --
> View this message in context:
> http://activemq.2283324.n4.nabble.com/Transaction-not-started-XA-problems-tp3565540p3568151.html
> Sent from the ActiveMQ - User mailing list archive at Nabble.com.
>
--
http://fusesource.com
http://blog.garytully.com
