Details

    • Type: Bug
    • Status: Closed
    • Priority: Major
    • Resolution: Won't Fix or Usage Issue
    • Affects Version/s: 3.1.1
    • Fix Version/s: None
    • Component/s: Core: (other)
    • Labels:
      None
    • Environment:

      WinXP
      Eclipse Helios Service Release 2
      Mule IDE 2.1.2.201103101711
      JBoss 4.2.3 JMS server

    • User impact:
      Medium
    • Configuration:
      Hide

      <jms:connector persistentDelivery="true" name="jmsConnector" jndiInitialFactory="org.jnp.interfaces.NamingContextFactory"
      jndiProviderUrl="jnp://$

      {JNDIProviderURL}

      :$

      {JNDIProviderPort}

      " connectionFactoryJndiName="java:/$

      {ConnectionFactoryJNDIName}

      "
      jndiDestinations="true" forceJndiDestinations="true" createMultipleTransactedReceivers="true" disableTemporaryReplyToDestinations="true">
      <spring:property name="retryPolicyTemplate">
      <spring:bean class="myPackage.core.JMSRetryPolicyTpl">
      <spring:property name="delay" value="$

      {JMSRetryDelay}

      "/>
      <spring:property name="count" value="$

      {JMSRetryCount}

      "/>
      </spring:bean>
      </spring:property>
      </jms:connector>

      Show
      <jms:connector persistentDelivery="true" name="jmsConnector" jndiInitialFactory="org.jnp.interfaces.NamingContextFactory" jndiProviderUrl="jnp://$ {JNDIProviderURL} :$ {JNDIProviderPort} " connectionFactoryJndiName="java:/$ {ConnectionFactoryJNDIName} " jndiDestinations="true" forceJndiDestinations="true" createMultipleTransactedReceivers="true" disableTemporaryReplyToDestinations="true"> <spring:property name="retryPolicyTemplate"> <spring:bean class="myPackage.core.JMSRetryPolicyTpl"> <spring:property name="delay" value="$ {JMSRetryDelay} "/> <spring:property name="count" value="$ {JMSRetryCount} "/> </spring:bean> </spring:property> </jms:connector>
    • Log Output:
      Hide
      INFO 2011-05-10 15:00:03,562 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.exception.DefaultSystemExceptionStrategy: Exception caught is a ConnectException, attempting to reconnect...
      INFO 2011-05-10 15:00:03,562 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.lifecycle.AbstractLifecycleManager: Stopping connector: jmsConnector
      INFO 2011-05-10 15:00:03,562 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Disconnected: JmsConnector
      {
      name=jmsConnector
      lifecycle=start
      this=1cf243b
      numberOfConcurrentTransactedReceivers=4
      createMultipleTransactedReceivers=true
      connected=true
      supportedProtocols=jms
      serviceOverrides=<none>
      }

      INFO 2011-05-10 15:00:03,562 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Disconnected: JmsConnector
      {
      name=jmsConnector
      lifecycle=stop
      this=1cf243b
      numberOfConcurrentTransactedReceivers=4
      createMultipleTransactedReceivers=true
      connected=false
      supportedProtocols=jms
      serviceOverrides=<none>
      }

      ERROR 2011-05-10 15:00:03,562 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.exception.DefaultSystemExceptionStrategy:
      ********************************************************************************
      Message : Cannot ping the JMS server; - nested throwable: (java.io.IOException: Client is not connected) (org.jboss.mq.SpyJMSException)
      Code : MULE_ERROR--2
      Exception stack is:
      1. Client is not connected (java.io.IOException)
      org.jboss.mq.il.uil2.SocketManager:288 (null)
      2. Cannot ping the JMS server; - nested throwable: (java.io.IOException: Client is not connected)(JMS Code: null) (org.jboss.mq.SpyJMSException)
      org.jboss.mq.SpyJMSException:72 (null)
      3. Cannot ping the JMS server; - nested throwable: (java.io.IOException: Client is not connected) (org.jboss.mq.SpyJMSException) (org.mule.transport.ConnectException)
      org.mule.transport.jms.JmsConnector:510 (http://www.mulesoft.org/docs/site/current3/apidocs/org/mule/transport/ConnectException.html)
      Root Exception stack trace:
      java.io.IOException: Client is not connected
      at org.jboss.mq.il.uil2.SocketManager.internalSendMessage(SocketManager.java:288)
      at org.jboss.mq.il.uil2.SocketManager.sendReply(SocketManager.java:262)
      at org.jboss.mq.il.uil2.UILServerIL.ping(UILServerIL.java:345)
      + 3 more (set debug level logging or '-Dmule.verbose.exceptions=true' for everything)
      ********************************************************************************

      INFO 2011-05-10 15:00:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Connected: JmsConnector
      {
      name=jmsConnector
      lifecycle=stop
      this=1cf243b
      numberOfConcurrentTransactedReceivers=4
      createMultipleTransactedReceivers=true
      connected=true
      supportedProtocols=jms
      serviceOverrides=<none>
      }

      INFO 2011-05-10 15:00:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Starting: JmsConnector
      {
      name=jmsConnector
      lifecycle=stop
      this=1cf243b
      numberOfConcurrentTransactedReceivers=4
      createMultipleTransactedReceivers=true
      connected=true
      supportedProtocols=jms
      serviceOverrides=<none>
      }

      INFO 2011-05-10 15:00:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.lifecycle.AbstractLifecycleManager: Starting connector: jmsConnector
      INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.exception.DefaultSystemExceptionStrategy: Exception caught is a ConnectException, attempting to reconnect...
      INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.lifecycle.AbstractLifecycleManager: Stopping connector: jmsConnector
      INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Disconnected: JmsConnector
      {
      name=jmsConnector
      lifecycle=start
      this=1cf243b
      numberOfConcurrentTransactedReceivers=4
      createMultipleTransactedReceivers=true
      connected=true
      supportedProtocols=jms
      serviceOverrides=<none>
      }

      INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Disconnected: JmsConnector
      {
      name=jmsConnector
      lifecycle=stop
      this=1cf243b
      numberOfConcurrentTransactedReceivers=4
      createMultipleTransactedReceivers=true
      connected=false
      supportedProtocols=jms
      serviceOverrides=<none>
      }

      ERROR 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.exception.DefaultSystemExceptionStrategy:
      ********************************************************************************
      Message : No pong received; - nested throwable: (java.io.IOException: ping timeout.) (org.jboss.mq.SpyJMSException)
      Code : MULE_ERROR--2
      Exception stack is:
      1. ping timeout. (java.io.IOException)
      org.jboss.mq.Connection$PingTask:1313 (null)
      2. No pong received; - nested throwable: (java.io.IOException: ping timeout.)(JMS Code: null) (org.jboss.mq.SpyJMSException)
      org.jboss.mq.Connection$PingTask:1313 (null)
      3. No pong received; - nested throwable: (java.io.IOException: ping timeout.) (org.jboss.mq.SpyJMSException) (org.mule.transport.ConnectException)
      org.mule.transport.jms.JmsConnector:510 (http://www.mulesoft.org/docs/site/current3/apidocs/org/mule/transport/ConnectException.html)
      Root Exception stack trace:
      java.io.IOException: ping timeout.
      at org.jboss.mq.Connection$PingTask.run(Connection.java:1313)
      at EDU.oswego.cs.dl.util.concurrent.ClockDaemon$RunLoop.run(ClockDaemon.java:364)
      at java.lang.Thread.run(Thread.java:619)
      + 3 more (set debug level logging or '-Dmule.verbose.exceptions=true' for everything)
      ********************************************************************************

      INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Connected: JmsConnector
      {
      name=jmsConnector
      lifecycle=stop
      this=1cf243b
      numberOfConcurrentTransactedReceivers=4
      createMultipleTransactedReceivers=true
      connected=true
      supportedProtocols=jms
      serviceOverrides=<none>
      }

      INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Starting: JmsConnector
      {
      name=jmsConnector
      lifecycle=stop
      this=1cf243b
      numberOfConcurrentTransactedReceivers=4
      createMultipleTransactedReceivers=true
      connected=true
      supportedProtocols=jms
      serviceOverrides=<none>
      }

      INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token=ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.lifecycle.AbstractLifecycleManager: Starting connector: jmsConnector
      Show
      INFO 2011-05-10 15:00:03,562 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.exception.DefaultSystemExceptionStrategy: Exception caught is a ConnectException, attempting to reconnect... INFO 2011-05-10 15:00:03,562 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.lifecycle.AbstractLifecycleManager: Stopping connector: jmsConnector INFO 2011-05-10 15:00:03,562 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Disconnected: JmsConnector { name=jmsConnector lifecycle=start this=1cf243b numberOfConcurrentTransactedReceivers=4 createMultipleTransactedReceivers=true connected=true supportedProtocols=jms serviceOverrides=<none> } INFO 2011-05-10 15:00:03,562 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Disconnected: JmsConnector { name=jmsConnector lifecycle=stop this=1cf243b numberOfConcurrentTransactedReceivers=4 createMultipleTransactedReceivers=true connected=false supportedProtocols=jms serviceOverrides=<none> } ERROR 2011-05-10 15:00:03,562 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.exception.DefaultSystemExceptionStrategy: ******************************************************************************** Message : Cannot ping the JMS server; - nested throwable: (java.io.IOException: Client is not connected) (org.jboss.mq.SpyJMSException) Code : MULE_ERROR--2 Exception stack is: 1. Client is not connected (java.io.IOException) org.jboss.mq.il.uil2.SocketManager:288 (null) 2. Cannot ping the JMS server; - nested throwable: (java.io.IOException: Client is not connected)(JMS Code: null) (org.jboss.mq.SpyJMSException) org.jboss.mq.SpyJMSException:72 (null) 3. Cannot ping the JMS server; - nested throwable: (java.io.IOException: Client is not connected) (org.jboss.mq.SpyJMSException) (org.mule.transport.ConnectException) org.mule.transport.jms.JmsConnector:510 ( http://www.mulesoft.org/docs/site/current3/apidocs/org/mule/transport/ConnectException.html ) Root Exception stack trace: java.io.IOException: Client is not connected at org.jboss.mq.il.uil2.SocketManager.internalSendMessage(SocketManager.java:288) at org.jboss.mq.il.uil2.SocketManager.sendReply(SocketManager.java:262) at org.jboss.mq.il.uil2.UILServerIL.ping(UILServerIL.java:345) + 3 more (set debug level logging or '-Dmule.verbose.exceptions=true' for everything) ******************************************************************************** INFO 2011-05-10 15:00:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Connected: JmsConnector { name=jmsConnector lifecycle=stop this=1cf243b numberOfConcurrentTransactedReceivers=4 createMultipleTransactedReceivers=true connected=true supportedProtocols=jms serviceOverrides=<none> } INFO 2011-05-10 15:00:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Starting: JmsConnector { name=jmsConnector lifecycle=stop this=1cf243b numberOfConcurrentTransactedReceivers=4 createMultipleTransactedReceivers=true connected=true supportedProtocols=jms serviceOverrides=<none> } INFO 2011-05-10 15:00:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.lifecycle.AbstractLifecycleManager: Starting connector: jmsConnector INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.exception.DefaultSystemExceptionStrategy: Exception caught is a ConnectException, attempting to reconnect... INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.lifecycle.AbstractLifecycleManager: Stopping connector: jmsConnector INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Disconnected: JmsConnector { name=jmsConnector lifecycle=start this=1cf243b numberOfConcurrentTransactedReceivers=4 createMultipleTransactedReceivers=true connected=true supportedProtocols=jms serviceOverrides=<none> } INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Disconnected: JmsConnector { name=jmsConnector lifecycle=stop this=1cf243b numberOfConcurrentTransactedReceivers=4 createMultipleTransactedReceivers=true connected=false supportedProtocols=jms serviceOverrides=<none> } ERROR 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.exception.DefaultSystemExceptionStrategy: ******************************************************************************** Message : No pong received; - nested throwable: (java.io.IOException: ping timeout.) (org.jboss.mq.SpyJMSException) Code : MULE_ERROR--2 Exception stack is: 1. ping timeout. (java.io.IOException) org.jboss.mq.Connection$PingTask:1313 (null) 2. No pong received; - nested throwable: (java.io.IOException: ping timeout.)(JMS Code: null) (org.jboss.mq.SpyJMSException) org.jboss.mq.Connection$PingTask:1313 (null) 3. No pong received; - nested throwable: (java.io.IOException: ping timeout.) (org.jboss.mq.SpyJMSException) (org.mule.transport.ConnectException) org.mule.transport.jms.JmsConnector:510 ( http://www.mulesoft.org/docs/site/current3/apidocs/org/mule/transport/ConnectException.html ) Root Exception stack trace: java.io.IOException: ping timeout. at org.jboss.mq.Connection$PingTask.run(Connection.java:1313) at EDU.oswego.cs.dl.util.concurrent.ClockDaemon$RunLoop.run(ClockDaemon.java:364) at java.lang.Thread.run(Thread.java:619) + 3 more (set debug level logging or '-Dmule.verbose.exceptions=true' for everything) ******************************************************************************** INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Connected: JmsConnector { name=jmsConnector lifecycle=stop this=1cf243b numberOfConcurrentTransactedReceivers=4 createMultipleTransactedReceivers=true connected=true supportedProtocols=jms serviceOverrides=<none> } INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.transport.jms.JmsConnector: Starting: JmsConnector { name=jmsConnector lifecycle=stop this=1cf243b numberOfConcurrentTransactedReceivers=4 createMultipleTransactedReceivers=true connected=true supportedProtocols=jms serviceOverrides=<none> } INFO 2011-05-10 15:01:03,578 [ExceptionListener Connection@22763395token =ConnectionToken:ID:3/65e864f13b06a66e7c38cbd5048ffd09 rcvstate=STOPPED] org.mule.lifecycle.AbstractLifecycleManager: Starting connector: jmsConnector
    • Similar Issues:

      Description

      I use a simple custom reconnection strategy to try to reconnect every X seconds. It worked like a charm with Mule 2 but now with Mule 3.1.1 the following happen:
      After the reconnection (sucessful as I can send messages in the queue), after 60s, the connector is disconnected, I have a "Client not connected error", and the connector connects again (by itself, not going through the retry policy). Then, every 60s, the connector is disconnected, I have a "ping timeout error", and the connector connects again.

      Despite the error, new messages are sent to the queue without any problem. I don't have this connector disconnect/reconnect behaviour in normal use, only if I first loose the connection with the JMS server and get it back.
      I tried the org.mule.modules.common.retry.policies.ForeverRetryPolicyTemplate but it gives me the same problem.

      My custom reconnection strategy works perfectly on a FTP connector.

      Maybe somehow linked to MULE-5124 ?

        Attachments

          Activity

            People

            • Assignee:
              Unassigned
              Reporter:
              sdan Samuel Danhardt
            • Votes:
              0 Vote for this issue
              Watchers:
              3 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved: