From users-return-27717-apmail-activemq-users-archive=activemq.apache.org@activemq.apache.org Thu Jun 2 11:41:29 2011 Return-Path: X-Original-To: apmail-activemq-users-archive@www.apache.org Delivered-To: apmail-activemq-users-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 51E874C68 for ; Thu, 2 Jun 2011 11:41:29 +0000 (UTC) Received: (qmail 27448 invoked by uid 500); 2 Jun 2011 11:41:28 -0000 Delivered-To: apmail-activemq-users-archive@activemq.apache.org Received: (qmail 27403 invoked by uid 500); 2 Jun 2011 11:41:28 -0000 Mailing-List: contact users-help@activemq.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: users@activemq.apache.org Delivered-To: mailing list users@activemq.apache.org Received: (qmail 27395 invoked by uid 99); 2 Jun 2011 11:41:28 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 02 Jun 2011 11:41:28 +0000 X-ASF-Spam-Status: No, hits=2.0 required=5.0 tests=FREEMAIL_FROM,RFC_ABUSE_POST,SPF_NEUTRAL,T_TO_NO_BRKTS_FREEMAIL,URI_HEX X-Spam-Check-By: apache.org Received-SPF: neutral (athena.apache.org: local policy) Received: from [216.139.236.26] (HELO sam.nabble.com) (216.139.236.26) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 02 Jun 2011 11:41:23 +0000 Received: from joe.nabble.com ([192.168.236.139]) by sam.nabble.com with esmtp (Exim 4.72) (envelope-from ) id 1QS6Gs-0002F6-Fc for users@activemq.apache.org; Thu, 02 Jun 2011 04:41:02 -0700 Date: Thu, 2 Jun 2011 04:41:02 -0700 (PDT) From: James Black To: users@activemq.apache.org Message-ID: <1307014862475-3568151.post@n4.nabble.com> In-Reply-To: <1306927111700-3565540.post@n4.nabble.com> References: <1306927111700-3565540.post@n4.nabble.com> Subject: Re: Transaction not started XA problems MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: 7bit 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.