-
1. Re: HornetQ - Destroying connection that could not be successfully matched
jbertram Aug 16, 2012 2:42 PM (in response to henry.short)Do you have a reproducer you can share or at the very least the code which is using JmsXA?
-
2. Re: HornetQ - Destroying connection that could not be successfully matched
jbertram Aug 16, 2012 2:56 PM (in response to jbertram)Also, could you activate TRACE logging for "org.hornetq.ra", reproduce the problem, and attach the log?
-
3. Re: HornetQ - Destroying connection that could not be successfully matched
henry.short Aug 16, 2012 3:12 PM (in response to jbertram)Here is an example of our client sending a message to a topic MDB. Let me know if you need anymore info.
TopicConnectionFactory factory = (TopicConnectionFactory)context.lookup("java:/JmsXA");
Topic topic = (Topic)context.lookup("topic/Status");
TopicConnection connection = factory.createTopicConnection();TopicSession session = connection.createTopicSession(false, Session.AUTO_ACKNOWLEDGE);
TopicPublisher publisher = session.createPublisher(topic);
publisher.setDeliveryMode(DeliveryMode.NON_PERSISTENT);MapMessage message = session.createMapMessage();
..
publisher.send(message);standalone xml:
<jms-topic name="StatusService">
<entry name="topic/Status"/>
<entry name="java:jboss/exported/jms/topic/Status"/>
</jms-topic> -
4. Re: HornetQ - Destroying connection that could not be successfully matched
jbertram Aug 16, 2012 3:17 PM (in response to henry.short)Could you supply the logging I requested previously?
-
5. Re: HornetQ - Destroying connection that could not be successfully matched
jbertram Aug 16, 2012 3:18 PM (in response to henry.short)Also, where is this code being executed (e.g. EJB, Servlet, etc.)?
-
6. Re: HornetQ - Destroying connection that could not be successfully matched
henry.short Aug 16, 2012 3:27 PM (in response to jbertram)I will generate the log file now. The code is being executed inside an EJB.
-
7. Re: HornetQ - Destroying connection that could not be successfully matched
henry.short Aug 16, 2012 3:55 PM (in response to henry.short)I have attached the trace file. Let me know if you need anything else. Thanks for your help.
-
hornet-trace.txt.zip 14.0 KB
-
-
8. Re: HornetQ - Destroying connection that could not be successfully matched
henry.short Aug 17, 2012 1:21 PM (in response to henry.short)Did the trace file provide any insight Justin?
-
9. Re: HornetQ - Destroying connection that could not be successfully matched
jbertram Aug 17, 2012 2:24 PM (in response to henry.short)It looks like the match is failing because the "type" of the connection doesn't match. In other words, you are trying to use a Topic connection but the connection in the pool is a Queue connection (or vice versa).
Could you switch your code to use the generic javax.jms.Connection, javax.jms.Session, and javax.jms.MessageProducer? If that doesn't help then please provide a test-case I can use to reproduce the issue and investigate further.
-
10. Re: HornetQ - Destroying connection that could not be successfully matched
henry.short Aug 17, 2012 5:49 PM (in response to jbertram)Thanks for the suggestion. I will give that a try today.
-
11. Re: HornetQ - Destroying connection that could not be successfully matched
jbertram Aug 23, 2012 11:53 AM (in response to henry.short)Any luck with my suggestion?
-
12. Re: HornetQ - Destroying connection that could not be successfully matched
nawazahamed Sep 5, 2012 1:38 PM (in response to jbertram)Hi Justin,
I am facing the same issue and I have already tried modifying our code to use generic javax.jms.Connection, javax.jms.Session, and javax.jms.MessageProducer but no luck. Iam getting the same warning messages . I have enabled debug on hornetq and attaching the log files.
2012.09.05 13:03:51,130 WARN [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (TrapHandler) IJ000612: Destroying connection that could not be successfully matched: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@2a5224[state=NORMAL managed connection=org.hornetq.ra.HornetQRAManagedConnection@1953d98 connection handles=0 lastUse=1346830431007 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fed240 pool internal context=SemaphoreArrayListManagedConnectionPool@52ba79[pool=HornetQConnectionDefinition] xaResource=XAResourceWrapperImpl@76e6b4[xaResource=org.hornetq.ra.HornetQRAXAResource@19d8c2a pad=false overrideRmValue=false productName=HornetQ productVersion=2.0 jndiName=java:/JmsXA] txSync=null]
2012.09.05 13:03:51,130 WARN [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (TrapHandler) IJ000612: Destroying connection that could not be successfully matched: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@2a5224[state=NORMAL managed connection=org.hornetq.ra.HornetQRAManagedConnection@1953d98 connection handles=0 lastUse=1346830431007 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fed240 pool internal context=SemaphoreArrayListManagedConnectionPool@52ba79[pool=HornetQConnectionDefinition] xaResource=XAResourceWrapperImpl@76e6b4[xaResource=org.hornetq.ra.HornetQRAXAResource@19d8c2a pad=false overrideRmValue=false productName=HornetQ productVersion=2.0 jndiName=java:/JmsXA] txSync=null]
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) Calling close on session ClientSessionImpl [name=059025fe-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@96d618
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) Calling close on session ClientSessionImpl [name=059025fe-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@96d618
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) calling cleanup on ClientSessionImpl [name=059025fe-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@96d618
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) calling cleanup on ClientSessionImpl [name=059025fe-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@96d618
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) Calling close on session ClientSessionImpl [name=059025fd-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@7472f4
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) Calling close on session ClientSessionImpl [name=059025fd-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@7472f4
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) calling cleanup on ClientSessionImpl [name=059025fd-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@7472f4
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) calling cleanup on ClientSessionImpl [name=059025fd-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@7472f4
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) Calling close on session ClientSessionImpl [name=059025fc-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@4e7bdf
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) Calling close on session ClientSessionImpl [name=059025fc-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@4e7bdf
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) calling cleanup on ClientSessionImpl [name=059025fc-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@4e7bdf
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) calling cleanup on ClientSessionImpl [name=059025fc-f72c-11e1-ad69-1d22de11aebb, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@4e7bdf
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) Session was already closed, giving up now, this=ClientSessionImpl [name=059025fc-f72c-11e1-ad69-1d22de11aebb, username=null, closed=true, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@4e7bdf
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionImpl] (TrapHandler) Session was already closed, giving up now, this=ClientSessionImpl [name=059025fc-f72c-11e1-ad69-1d22de11aebb, username=null, closed=true, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(jms-session=,)]@4e7bdf
2012.09.05 13:03:51,130 DEBUG [org.hornetq.ra.HornetQResourceAdapter] (TrapHandler) Creating Connection Factory on the resource adapter for transport=[Lorg.hornetq.api.core.TransportConfiguration;@363d9a with ha=false
2012.09.05 13:03:51,130 DEBUG [org.hornetq.ra.HornetQResourceAdapter] (TrapHandler) Creating Connection Factory on the resource adapter for transport=[Lorg.hornetq.api.core.TransportConfiguration;@363d9a with ha=false
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionFactoryImpl] (TrapHandler) Trying reconnection attempt 0/1
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionFactoryImpl] (TrapHandler) Trying reconnection attempt 0/1
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionFactoryImpl] (TrapHandler) Trying to connect with connector = org.hornetq.core.remoting.impl.invm.InVMConnectorFactory@1ba3a45, parameters = {server-id=0} connector = org.hornetq.core.remoting.impl.invm.InVMConnector@147b815
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionFactoryImpl] (TrapHandler) Trying to connect with connector = org.hornetq.core.remoting.impl.invm.InVMConnectorFactory@1ba3a45, parameters = {server-id=0} connector = org.hornetq.core.remoting.impl.invm.InVMConnector@147b815
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionFactoryImpl] (TrapHandler) Trying to connect at the main server using connector :org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionFactoryImpl] (TrapHandler) Trying to connect at the main server using connector :org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionFactoryImpl] (TrapHandler) Reconnection successfull
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionFactoryImpl] (TrapHandler) Reconnection successfull
2012.09.05 13:03:51,130 DEBUG [org.hornetq.core.client.impl.ClientSessionFactoryImpl] (Thread-7 (HornetQ-client-global-threads-21696366)) Node 0dee393e-f5e4-11e1-8b8a-23a17b961024 going up, connector = Pair[a=null, b=null], isLast=true csf created at
serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null]: java.lang.Exception
at org.hornetq.core.client.impl.ClientSessionFactoryImpl.<init>(ClientSessionFactoryImpl.java:181) [hornetq-core-2.2.19.Mera-patch.jar:2.2.19.SNAPSHOT (HQ_2_2_19_SNAPSHOT, 122)]
at org.hornetq.core.client.impl.ServerLocatorImpl.createSessionFactory(ServerLocatorImpl.java:737) [hornetq-core-2.2.19.Mera-patch.jar:2.2.19.SNAPSHOT (HQ_2_2_19_SNAPSHOT, 122)]
at org.hornetq.jms.client.HornetQConnectionFactory.createConnectionInternal(HornetQConnectionFactory.java:601) [hornetq-jms-2.2.19.Mera-patch.jar:2.2.19.SNAPSHOT (HQ_2_2_19_SNAPSHOT, 122)]
at org.hornetq.jms.client.HornetQConnectionFactory.createXAConnection(HornetQConnectionFactory.java:155) [hornetq-jms-2.2.19.Mera-patch.jar:2.2.19.SNAPSHOT (HQ_2_2_19_SNAPSHOT, 122)]
at org.hornetq.jms.client.HornetQConnectionFactory.createXAConnection(HornetQConnectionFactory.java:150) [hornetq-jms-2.2.19.Mera-patch.jar:2.2.19.SNAPSHOT (HQ_2_2_19_SNAPSHOT, 122)]
at org.hornetq.ra.HornetQRAManagedConnection.setup(HornetQRAManagedConnection.java:824)
at org.hornetq.ra.HornetQRAManagedConnection.<init>(HornetQRAManagedConnection.java:148)
at org.hornetq.ra.HornetQRAManagedConnectionFactory.createManagedConnection(HornetQRAManagedConnectionFactory.java:172)
at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.createConnectionEventListener(SemaphoreArrayListManagedConnectionPool.java:761)
at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.getConnection(SemaphoreArrayListManagedConnectionPool.java:343)
at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getSimpleConnection(AbstractPool.java:397)
at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getConnection(AbstractPool.java:365)
at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:329)
at org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl.getManagedConnection(TxConnectionManagerImpl.java:368)
at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.allocateConnection(AbstractConnectionManager.java:464)
at org.hornetq.ra.HornetQRASessionFactoryImpl.allocateConnection(HornetQRASessionFactoryImpl.java:837)
at org.hornetq.ra.HornetQRASessionFactoryImpl.createSession(HornetQRASessionFactoryImpl.java:470)
at com.extremenetworks.epicenter.server.common.jms.JmsTopicPublisher.initTopicSession(JmsTopicPublisher.java:85)
at com.extremenetworks.epicenter.server.common.jms.JmsTopicPublisher.setupTopicConnection(JmsTopicPublisher.java:78)
at com.extremenetworks.epicenter.server.common.jms.JmsTopicPublisher.initialize(JmsTopicPublisher.java:67)
at com.extremenetworks.epicenter.server.common.jms.JmsTopicPublisher.<init>(JmsTopicPublisher.java:61)
at com.extremenetworks.epicenter.server.common.jms.JmsTopicPublisher.<init>(JmsTopicPublisher.java:52)
at com.extremenetworks.everest.server.legacyinteraction.TrapForwarderToJMSThread.setupQueueConnection(TrapForwarderToJMSThread.java:121)
at com.extremenetworks.everest.server.legacyinteraction.TrapForwarderToJMSThread.<init>(TrapForwarderToJMSThread.java:114)
at com.extremenetworks.everest.server.dataServer.TrapManager.run(TrapManager.java:494)
at com.extremenetworks.everest.server.transaction.TransactionThread.run(TransactionThread.java:122)
-
13. Re: HornetQ - Destroying connection that could not be successfully matched
hypheng Nov 21, 2012 3:19 AM (in response to henry.short)Could anyone answer this problem?
I have the same issue.
-
14. Re: HornetQ - Destroying connection that could not be successfully matched
nawazahamed Dec 4, 2012 2:19 AM (in response to hypheng)Hi,
Upgrade to https://github.com/hornetq/hornetq/archive/HornetQ_2_2_22_AS7_Final.zip, this has fixes for few major issues in messaging. I figured out that the
messages"Destroying connection that could not be successfully matched" was a side effect for another bug in hornetq.