Uploaded image for project: 'JBoss A-MQ'
  1. JBoss A-MQ
  2. ENTMQ-2153

[AMQ6, VMTransport] java.io.IOException: Server TransportAcceptListener is null.

    XMLWordPrintable

Details

    • Bug
    • Resolution: Not a Bug
    • Major
    • None
    • JBoss A-MQ 6.2.1
    • broker
    • None
      • restart the broker appeared to resolve the issue (have not been able to replicate so far).
      • have not been able to replicate so far.

    Description

      The co located camel routes (using VMTransport) did not appear to be able to connect to the queues.

      The broker logged the following

      Oct 13, 2017 11:04:43 AM org.apache.karaf.main.SimpleFileLock lock
      2017-10-13 11:04:50,588 | INFO  | AMQ-1-thread-1   | MessageDatabase                  | .kahadb.MessageDatabase$Metadata  167 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | KahaDB is version 5
      2017-10-13 11:04:50,609 | INFO  | AMQ-1-thread-1   | MessageDatabase                  | emq.store.kahadb.MessageDatabase  605 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Recovering from the journal @14:26581213
      2017-10-13 11:04:50,891 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 2415 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | Apache Camel 2.15.1.redhat-621177 (CamelContext:XXXX) is starting
      2017-10-13 11:04:50,893 | INFO  | FelixStartLevel  | ManagedManagementStrategy        | gement.ManagedManagementStrategy  187 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | JMX is enabled
      2017-10-13 11:04:50,951 | INFO  | AMQ-1-thread-1   | MessageDatabase                  | emq.store.kahadb.MessageDatabase  633 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Recovery replayed 34129 operations from the journal in 0.358 seconds.
      2017-10-13 11:04:51,056 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 2635 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | AllowUseOriginalMessage is enabled. If access to the original message is not needed, then its recommended to turn this option off as it may improve performance.
      2017-10-13 11:04:51,056 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 2645 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | StreamCaching is not in use. If using streams then its recommended to enable stream caching. See more details at http://camel.apache.org/stream-caching.html
      2017-10-13 11:04:51,106 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 3166 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | Route: XXXXX started and consuming from: Endpoint[activemq://queue:XXXXX]
      2017-10-13 11:04:51,107 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 2455 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | Total 1 routes, of which 1 is started.
      2017-10-13 11:04:51,108 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 2456 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | Apache Camel 2.15.1.redhat-621177 (CamelContext: XXXXX ) started in 0.216 seconds
      2017-10-13 11:04:51,114 | INFO  | AMQ-1-thread-1   | BrokerService                    | he.activemq.broker.BrokerService  702 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Apache ActiveMQ 5.11.0.redhat-621177 (XXXXXXXX) is starting
      2017-10-13 11:04:51,197 | INFO  | AMQ-1-thread-1   | BrokerPluginSupport              | ivemq.broker.BrokerPluginSupport   41 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Broker Plugin org.apache.activemq.broker.util.TimeStampingBrokerPlugin started
      2017-10-13 11:04:51,225 | INFO  | AMQ-1-thread-1   | TransportServerThreadSupport     | ort.TransportServerThreadSupport   69 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Listening for connections at: tcp://XXXX:61616?maximumConnections=500&wireFormat.maxFrameSize=665600
      2017-10-13 11:04:51,226 | INFO  | FelixStartLevel  | RouteXmlParser                   | 01.router.dynamic.RouteXmlParser   59 | 185 - router-dynamic - 1.0.0 | route.xml parsing completed.
      2017-10-13 11:04:51,229 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 2415 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | Apache Camel 2.15.1.redhat-621177 (CamelContext: XXXXXX ) is starting
      2017-10-13 11:04:51,229 | INFO  | FelixStartLevel  | ManagedManagementStrategy        | gement.ManagedManagementStrategy  187 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | JMX is enabled
      2017-10-13 11:04:51,230 | INFO  | AMQ-1-thread-1   | TransportConnector               | tivemq.broker.TransportConnector  260 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Connector openwire started
      2017-10-13 11:04:51,231 | INFO  | AMQ-1-thread-1   | DiscoveryNetworkConnector        | etwork.DiscoveryNetworkConnector  120 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Establishing network connection from vm://XXXXXasync=false&create=false&network=true to tcp://XXXXX:XXXXX
      2017-10-13 11:04:51,273 | WARN  | AMQ-1-thread-1   | DiscoveryNetworkConnector        | etwork.DiscoveryNetworkConnector  143 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Could not connect to local URI: vm://XXXXX?async=false&create=false&network=true: Server TransportAcceptListener is null.
      2017-10-13 11:04:51,274 | INFO  | AMQ-1-thread-1   | NetworkConnector                 | ctivemq.network.NetworkConnector  172 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Network Connector DiscoveryNetworkConnector:XXXXX_Registration:BrokerService[XXXX] started
      2017-10-13 11:04:51,274 | INFO  | AMQ-1-thread-1   | BrokerService                    | he.activemq.broker.BrokerService  729 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Apache ActiveMQ 5.11.0.redhat-621177 (XXXXX) started
      2017-10-13 11:04:51,274 | INFO  | AMQ-1-thread-1   | BrokerService                    | he.activemq.broker.BrokerService  730 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | For help or more information please see: http://activemq.apache.org
      2017-10-13 11:04:51,283 | INFO  | AMQ-1-thread-1   | ActiveMQServiceFactory           | mq.fabric.ActiveMQServiceFactory  106 | 154 - io.fabric8.mq.mq-fabric - 1.2.0.redhat-621177 | Broker XXXXX has started.
      2017-10-13 11:04:51,307 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 2635 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | AllowUseOriginalMessage is enabled. If access to the original message is not needed, then its recommended to turn this option off as it may improve performance.
      2017-10-13 11:04:51,312 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 2645 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | StreamCaching is not in use. If using streams then its recommended to enable stream caching. See more details at http://camel.apache.org/stream-caching.html
      2017-10-13 11:04:51,329 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 3166 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | Route: XXXXXX started and consuming from: Endpoint[activemq://queue:XXXX.XXXX]
      2017-10-13 11:04:51,336 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 3166 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | Route: XXXXXX started and consuming from: Endpoint[activemq://queue:XXXX.XXXX]
      2017-10-13 11:04:51,336 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 2455 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | Total 2 routes, of which 2 is started.
      2017-10-13 11:04:51,337 | INFO  | FelixStartLevel  | BlueprintCamelContext            | e.camel.impl.DefaultCamelContext 2456 | 172 - org.apache.camel.camel-core - 2.15.1.redhat-621177 | Apache Camel 2.15.1.redhat-621177 (CamelContext: XXXXX) started in 0.107 seconds
      2017-10-13 11:04:51,353 | INFO  | lixDispatchQueue | patch-management                 | ?                                   ? | 1 - io.fabric8.patch.patch-management - 1.2.0.redhat-621177 | Configuring patch management system
      2017-10-13 11:04:52,287 | INFO  | ActiveMQ Task-1  | DiscoveryNetworkConnector        | etwork.DiscoveryNetworkConnector  120 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Establishing network connection from vm://XXXXX?async=false&create=false&network=true to tcp://XXXX:XXXX
      2017-10-13 11:04:52,288 | WARN  | ActiveMQ Task-1  | DiscoveryNetworkConnector        | etwork.DiscoveryNetworkConnector  143 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Could not connect to local URI: vm://XXXX?async=false&create=false&network=true: Server TransportAcceptListener is null.
      2017-10-13 11:04:53,021 | INFO  | lixDispatchQueue | patch-management                 | ?                                   ? | 1 - io.fabric8.patch.patch-management - 1.2.0.redhat-621177 | Storing user changes
      2017-10-13 11:04:54,289 | INFO  | ActiveMQ Task-2  | DiscoveryNetworkConnector        | etwork.DiscoveryNetworkConnector  120 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Establishing network connection from vm://XXXXX?async=false&create=false&network=true to tcp://XXXX:XXXX
      2017-10-13 11:04:54,290 | WARN  | ActiveMQ Task-2  | DiscoveryNetworkConnector        | etwork.DiscoveryNetworkConnector  143 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Could not connect to local URI: vm://XXXXX?async=false&create=false&network=true: Server TransportAcceptListener is null.
      2017-10-13 11:04:56,112 | INFO  | mer[XXXXX] | faultJmsMessageListenerContainer | .DefaultMessageListenerContainer  827 | 171 - org.apache.servicemix.bundles.spring-jms - 3.2.16.RELEASE_1 | JMS message listener invoker needs to establish shared Connection
      2017-10-13 11:04:56,116 | ERROR | mer[XXXXX] | faultJmsMessageListenerContainer | faultJmsMessageListenerContainer  204 | 171 - org.apache.servicemix.bundles.spring-jms - 3.2.16.RELEASE_1 | Could not refresh JMS Connection for destination 'XXXXX' - retrying in 5000 ms. Cause: Error while attempting to add new Connection to the pool; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.io.IOException: Server TransportAcceptListener is null.
      2017-10-13 11:04:56,333 | INFO  | XXXX | faultJmsMessageListenerContainer | .DefaultMessageListenerContainer  827 | 171 - org.apache.servicemix.bundles.spring-jms - 3.2.16.RELEASE_1 | JMS message listener invoker needs to establish shared Connection
      2017-10-13 11:04:56,335 | ERROR | XXXXX | faultJmsMessageListenerContainer | faultJmsMessageListenerContainer  204 | 171 - org.apache.servicemix.bundles.spring-jms - 3.2.16.RELEASE_1 | Could not refresh JMS Connection for destination 'XXXXXX' - retrying in 5000 ms. Cause: Error while attempting to add new Connection to the pool; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.io.IOException: Server TransportAcceptListener is null.
      2017-10-13 11:04:56,336 | INFO  | [XXXXX] | faultJmsMessageListenerContainer | .DefaultMessageListenerContainer  827 | 171 - org.apache.servicemix.bundles.spring-jms - 3.2.16.RELEASE_1 | JMS message listener invoker needs to establish shared Connection
      2017-10-13 11:04:56,337 | ERROR | [XXXXX] | faultJmsMessageListenerContainer | faultJmsMessageListenerContainer  204 | 171 - org.apache.servicemix.bundles.spring-jms - 3.2.16.RELEASE_1 | Could not refresh JMS Connection for destination 'XXXXX' - retrying in 5000 ms. Cause: Error while attempting to add new Connection to the pool; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.io.IOException: Server TransportAcceptListener is null.
      2017-10-13 11:04:58,291 | INFO  | ActiveMQ Task-1  | DiscoveryNetworkConnector        | etwork.DiscoveryNetworkConnector  120 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Establishing network connection from vm://XXXXXX?async=false&create=false&network=true to tcp://XXXXX:XXXXX
      2017-10-13 11:04:58,296 | WARN  | ActiveMQ Task-1  | DiscoveryNetworkConnector        | etwork.DiscoveryNetworkConnector  143 | 137 - org.apache.activemq.activemq-osgi - 5.11.0.redhat-621177 | Could not connect to local URI: vm://XXXX?async=false&create=false&network=true: Server TransportAcceptListener is null.
      2017-10-13 11:05:01,116 | ERROR | mer[XXXXX] | faultJmsMessageListenerContainer | faultJmsMessageListenerContainer  204 | 171 - org.apache.servicemix.bundles.spring-jms - 3.2.16.RELEASE_1 | Could not refresh JMS Connection for destination 'XXXXX' - retrying in 5000 ms. Cause: Error while attempting to add new Connection to the pool; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.io.IOException: Server TransportAcceptListener is null.
      

      After a broker restart the camel routes could connect with the VMTransport.

      Attachments

        Activity

          People

            gtully@redhat.com Gary Tully
            rhn-support-pfox Patrick Fox (Inactive)
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved: