Closed ponmanikandanb closed 4 years ago
Those logs seem to be about a JMS resource's lifecyle in a transaction. I fail to see anything wrong, and you didn't explain what your problem exactly is so there's little I can do to help.
Please be more specific: what are you trying to achieve? What is happening? What did you expect?
I developed my custom connector with dev-kit, my connector act as a source it connect to ejb and extract the data, and send to the another end point.
I am using bitronix for transaction manager.
I used the below code to register my ejb in the mule transaction context.
` public static void registerXaResource(MuleContext muleContext) { EJBClientTransactionContext txContext = EJBClientTransactionContext.create(muleContext.getTransactionManager(), getSynchronizationRegistry()); EJBClientTransactionContext.setGlobalContext(txContext); XaResourceProducer.registerXAResource("dummyResource", new DummyXaResource()); }
/**
* @return
*/
private static TransactionSynchronizationRegistry getSynchronizationRegistry() {
return TransactionManagerServices.getTransactionSynchronizationRegistry();
}`
After that am using next end point as JMS and configured with XA,always join. But it not behave as XA. It looks like bitronix delisting the JMS resource.
final commit its saying only one resource. Why its not taking JMS. In the below log while sending messages to Queue, it saying the delisting the Queue. why its delisting. and also saying resource is not in enlisting global transaction Why its saying like that.
2019-12-11 17:00:40,564 [Receiving Thread] DEBUG bitronix.tm.BitronixTransactionManager - committing transaction a Bitronix Transaction with GTRID [31363035383232353635000000002582E13C00000001], status=ACTIVE, 1 resource(s) enlisted (started Thu Jan 08 12:18:54 IST 1970)
2019-12-11 16:59:48,350 [Receiving Thread] INFO org.mule.transport.service.DefaultTransportServiceDescriptor - Loading default outbound transformer: org.mule.transport.jms.transformers.ObjectToJMSMessage 2019-12-11 16:59:48,350 [Receiving Thread] INFO org.mule.transport.service.DefaultTransportServiceDescriptor - Loading default response transformer: org.mule.transport.jms.transformers.ObjectToJMSMessage 2019-12-11 16:59:48,350 [Receiving Thread] WARN com.mulesoft.mule.transport.jms.EeJmsMessageDispatcher - Starting patched JmsMessageReceiver 2019-12-11 16:59:48,351 [Receiving Thread] INFO org.mule.lifecycle.AbstractLifecycleManager - Initialising: 'JMS.dispatcher.119843459'. Object is: EeJmsMessageDispatcher 2019-12-11 16:59:48,352 [Receiving Thread] INFO org.mule.lifecycle.AbstractLifecycleManager - Starting: 'JMS.dispatcher.119843459'. Object is: EeJmsMessageDispatcher 2019-12-11 16:59:48,353 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - 0 session(s) open from a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,353 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - no session handle found in NOT_ACCESSIBLE state, creating new session 2019-12-11 16:59:48,354 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - getting session handle from a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,354 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 0 stateChangeEventListener(s) about state changing from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,354 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,354 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 0 stateChangeEventListener(s) about state changed from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,356 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - choosing XA session 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - closing a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - delisting a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 from a Bitronix Transaction with GTRID [31363035383232353635000000002582E13C00000001], status=ACTIVE, 1 resource(s) enlisted (started Thu Jan 08 12:18:54 IST 1970) 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource is not in enlisting global transaction context: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - requeuing a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 from a Bitronix Transaction with GTRID [31363035383232353635000000002582E13C00000001], status=ACTIVE, 1 resource(s) enlisted (started Thu Jan 08 12:18:54 IST 1970) 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource is not in enlisting global transaction context: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - looking in in-flight transactions for XAResourceHolderState of a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAResourceHolder - resource not enlisted in any transaction: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource not in enlisting global transaction context, immediately releasing to pool a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changing from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changed from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changing from IN_POOL to CLOSED in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from IN_POOL to CLOSED in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changed from IN_POOL to CLOSED in a DualSessionWrapper in state CLOSED of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - session state changing to CLOSED, cleaning it up: a DualSessionWrapper in state CLOSED of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - DualSessionWrapper has been closed, 0 session(s) left open in pooled connection 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - 0 session(s) open from a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - no session handle found in NOT_ACCESSIBLE state, creating new session 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - getting session handle from a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 0 stateChangeEventListener(s) about state changing from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 0 stateChangeEventListener(s) about state changed from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,398 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - choosing XA session 2019-12-11 16:59:48,410 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - looking for producer based on a MessageProducerConsumerKey on ActiveMQQueue[sampleReplyQueue] 2019-12-11 16:59:48,410 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - found no producer based on a MessageProducerConsumerKey on ActiveMQQueue[sampleReplyQueue], creating it 2019-12-11 16:59:48,411 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - choosing XA session 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - closing a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - delisting a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 from a Bitronix Transaction with GTRID [31363035383232353635000000002582E13C00000001], status=ACTIVE, 1 resource(s) enlisted (started Thu Jan 08 12:18:54 IST 1970) 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource is not in enlisting global transaction context: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - requeuing a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 from a Bitronix Transaction with GTRID [31363035383232353635000000002582E13C00000001], status=ACTIVE, 1 resource(s) enlisted (started Thu Jan 08 12:18:54 IST 1970) 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource is not in enlisting global transaction context: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - looking in in-flight transactions for XAResourceHolderState of a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAResourceHolder - resource not enlisted in any transaction: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource not in enlisting global transaction context, immediately releasing to pool a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changing from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changed from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changing from IN_POOL to CLOSED in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from IN_POOL to CLOSED in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changed from IN_POOL to CLOSED in a DualSessionWrapper in state CLOSED of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - session state changing to CLOSED, cleaning it up: a DualSessionWrapper in state CLOSED of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,461 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - DualSessionWrapper has been closed, 0 session(s) left open in pooled connection
Well, it looks like your problem has a lot more to do with Mule rather than JTA. I'll try to help you by hopefully deciphering a bit what I understand from what you posted, but I don't expect to do much more than hopefully pointing you toward the right direction.
Connectors with dev-kit is definitely related to Mule, at least according to google as I'm not familiar at all with Mule. Another quick google'ing found no result when I searched for Anypoint Connectors and XA / JTA, so maybe that isn't supported, or those two work orthogonaly, again I couldn't tell. My first advice would be to check that Mule allows you to write your own XA-enabled connector, and hopefully find some documentation about how to properly integrate it with the transaction manager.
The code of the methods you pasted doesn't tell me much, but it looks like it is about registering your XA resource with BTM, only using Mule-specific API I'm not familiar with. So that's my guess.
The debug logs you pasted look normal in a way, and odd in another. What I find normal is that the JMS session gets enlisted and delisted: this is a totally normal behavior that is supposed to happen when you use a JMS session (enlisting happens) then close it (delisting happens). What I find odd is that the sessions figures it is in some XA context when it is enlisted (choosing XA session
indicates this) but can't find the XA context back when it's time for delisting (resource not enlisted in any transaction
). The only reason I can think of that could make this happen is some classloader issue where BTM is loaded twice and depending on which classloader is the current one when the session is being closed, two different instances of BTM are servicing the call, both lacking all the session-to-transaction tracking data.
The only thing I can conclude from the above is that there is something fishy about the way your app behaves transaction-wise, and I'm sure it's down to something related to Mule, either you've misconfigured something, or you forgot something in your connector or a million other possibilities that I cannot help you with. So once again I highly encourage you to see help from the Mule community, or their support line.
JTA is a tough subject in itself. If you're not intimately familiar with it, a lot of what's happening under the hood will look counter-intuitive and sometimes just plain wrong. I would advise you to start with something less daunting than a Mule connector to better understand how BTM works and what it does to get a better feel of it.
Good luck!
Thanks @lorban
@lorban can you give some samples to implement the XA transaction in JMS
Have a look at the doc maybe? https://github.com/bitronix/btm/blob/master/btm-docs/src/main/asciidoc/JmsConfiguration12.adoc
I tried by using the resource loader, but i cant able to get the connection, as mentioned. I tried by creating polling objects but it doesn't commit the transaction.
Please find my below post.
https://help.mulesoft.com/s/question/0D52T00004tJ6KqSAK/using-bitronox-manager-xa-not-working-with-my-custom-devkit-adapter Please find the logs, it delisting my resource.
2019-12-11 16:59:48,350 [Receiving Thread] INFO org.mule.transport.service.DefaultTransportServiceDescriptor - Loading default outbound transformer: org.mule.transport.jms.transformers.ObjectToJMSMessage 2019-12-11 16:59:48,350 [Receiving Thread] INFO org.mule.transport.service.DefaultTransportServiceDescriptor - Loading default response transformer: org.mule.transport.jms.transformers.ObjectToJMSMessage 2019-12-11 16:59:48,350 [Receiving Thread] WARN com.mulesoft.mule.transport.jms.EeJmsMessageDispatcher - Starting patched JmsMessageReceiver 2019-12-11 16:59:48,351 [Receiving Thread] INFO org.mule.lifecycle.AbstractLifecycleManager - Initialising: 'JMS.dispatcher.119843459'. Object is: EeJmsMessageDispatcher 2019-12-11 16:59:48,352 [Receiving Thread] INFO org.mule.lifecycle.AbstractLifecycleManager - Starting: 'JMS.dispatcher.119843459'. Object is: EeJmsMessageDispatcher 2019-12-11 16:59:48,353 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - 0 session(s) open from a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,353 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - no session handle found in NOT_ACCESSIBLE state, creating new session 2019-12-11 16:59:48,354 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - getting session handle from a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,354 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 0 stateChangeEventListener(s) about state changing from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,354 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,354 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 0 stateChangeEventListener(s) about state changed from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,356 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - choosing XA session 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - closing a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - delisting a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 from a Bitronix Transaction with GTRID [31363035383232353635000000002582E13C00000001], status=ACTIVE, 1 resource(s) enlisted (started Thu Jan 08 12:18:54 IST 1970) 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource is not in enlisting global transaction context: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - requeuing a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 from a Bitronix Transaction with GTRID [31363035383232353635000000002582E13C00000001], status=ACTIVE, 1 resource(s) enlisted (started Thu Jan 08 12:18:54 IST 1970) 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource is not in enlisting global transaction context: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - looking in in-flight transactions for XAResourceHolderState of a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAResourceHolder - resource not enlisted in any transaction: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource not in enlisting global transaction context, immediately releasing to pool a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changing from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changed from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changing from IN_POOL to CLOSED in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from IN_POOL to CLOSED in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changed from IN_POOL to CLOSED in a DualSessionWrapper in state CLOSED of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,393 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - session state changing to CLOSED, cleaning it up: a DualSessionWrapper in state CLOSED of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - DualSessionWrapper has been closed, 0 session(s) left open in pooled connection 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - 0 session(s) open from a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - no session handle found in NOT_ACCESSIBLE state, creating new session 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - getting session handle from a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 0 stateChangeEventListener(s) about state changing from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,397 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 0 stateChangeEventListener(s) about state changed from IN_POOL to ACCESSIBLE in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,398 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - choosing XA session 2019-12-11 16:59:48,410 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - looking for producer based on a MessageProducerConsumerKey on ActiveMQQueue[sampleReplyQueue] 2019-12-11 16:59:48,410 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - found no producer based on a MessageProducerConsumerKey on ActiveMQQueue[sampleReplyQueue], creating it 2019-12-11 16:59:48,411 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - choosing XA session 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - closing a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - delisting a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 from a Bitronix Transaction with GTRID [31363035383232353635000000002582E13C00000001], status=ACTIVE, 1 resource(s) enlisted (started Thu Jan 08 12:18:54 IST 1970) 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource is not in enlisting global transaction context: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - requeuing a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 from a Bitronix Transaction with GTRID [31363035383232353635000000002582E13C00000001], status=ACTIVE, 1 resource(s) enlisted (started Thu Jan 08 12:18:54 IST 1970) 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource is not in enlisting global transaction context: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - looking in in-flight transactions for XAResourceHolderState of a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAResourceHolder - resource not enlisted in any transaction: a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.TransactionContextHelper - resource not in enlisting global transaction context, immediately releasing to pool a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changing from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state ACCESSIBLE of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changed from ACCESSIBLE to IN_POOL in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changing from IN_POOL to CLOSED in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - state changing from IN_POOL to CLOSED in a DualSessionWrapper in state IN_POOL of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.common.AbstractXAStatefulHolder - notifying 2 stateChangeEventListener(s) about state changed from IN_POOL to CLOSED in a DualSessionWrapper in state CLOSED of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,447 [Receiving Thread] DEBUG bitronix.tm.resource.jms.DualSessionWrapper - session state changing to CLOSED, cleaning it up: a DualSessionWrapper in state CLOSED of a JmsPooledConnection of pool 1605822565-inboundtest-JMS in state ACCESSIBLE with underlying connection org.apache.activemq.artemis.jms.client.ActiveMQXAConnection@207dd1b7 2019-12-11 16:59:48,461 [Receiving Thread] DEBUG bitronix.tm.resource.jms.JmsPooledConnection - DualSessionWrapper has been closed, 0 session(s) left open in pooled connection