Uploaded image for project: 'AMQ Clients'
  1. AMQ Clients
  2. ENTMQCL-3506

Setup of JMS message listener invoker failed for destination 'amqp-demo-queue' - trying to recover. Cause: Remote did not respond to a drain request in time

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Not a Bug
    • Icon: Major Major
    • None
    • 2.11.0.GA
    • qpid-jms
    • None
    • False
    • None
    • False

      The following exceptions can be seen in consumer log file when trying to consume messages with  

      amqp:queue:amqp-demo-queue?concurrentConsumers=15

       

      2022-10-21 14:21:18.909  INFO 1 --- [-7861c58d9b77:1] org.apache.qpid.jms.JmsSession           : A JMS MessageConsumer has been closed: JmsConsumerInfo: { ID:c4c9248f-933d-40bd-801f-7861c58d9b77:1:14:1, destination = amqp-demo-queue }
      2022-10-21 14:21:19.003  INFO 1 --- [-7861c58d9b77:1] org.apache.qpid.jms.JmsSession           : A JMS MessageConsumer has been closed: JmsConsumerInfo: { ID:c4c9248f-933d-40bd-801f-7861c58d9b77:1:14:1, destination = amqp-demo-queue }
      2022-10-21 14:21:19.019  INFO 1 --- [-7861c58d9b77:1] org.apache.qpid.jms.JmsSession           : A JMS MessageConsumer has been closed: JmsConsumerInfo: { ID:c4c9248f-933d-40bd-801f-7861c58d9b77:1:13:1, destination = amqp-demo-queue }
      2022-10-21 14:21:19.149  INFO 1 --- [-7861c58d9b77:1] org.apache.qpid.jms.JmsSession           : A JMS MessageConsumer has been closed: JmsConsumerInfo: { ID:c4c9248f-933d-40bd-801f-7861c58d9b77:1:13:1, destination = amqp-demo-queue }
      2022-10-21 14:21:19.150  WARN 1 --- [mqp-demo-queue]] c.c.j.DefaultJmsMessageListenerContainer : Setup of JMS message listener invoker failed for destination 'amqp-demo-queue' - trying to recover. Cause: Remote did not respond to a drain request in time
      2022-10-21 14:21:21.202  INFO 1 --- [mqp-0-svc:5672]] org.apache.qpid.jms.JmsConnection        : Connection ID:d0b68c2a-bf26-448e-8f45-da2c755b9bb2:2 connected to server: amqp://amq-broker-amqp-0-svc:5672
      2022-10-21 14:21:33.465  INFO 1 --- [mqp-demo-queue]] nl.ns.hip.tmpl                           : Received 500 messages
      2022-10-21 14:21:46.219  INFO 1 --- [mqp-demo-queue]] nl.ns.hip.tmpl                           : Received 600 messages
      2022-10-21 14:21:58.389  INFO 1 --- [mqp-demo-queue]] nl.ns.hip.tmpl                           : Received 700 messages
      2022-10-21 14:22:10.882  INFO 1 --- [mqp-demo-queue]] nl.ns.hip.tmpl                           : Received 800 messages
      2022-10-21 14:22:21.086  INFO 1 --- [mqp-demo-queue]] nl.ns.hip.tmpl                           : Received 900 messages
      2022-10-21 14:22:22.527  INFO 1 --- [-da2c755b9bb2:2] org.apache.qpid.jms.JmsSession           : A JMS MessageConsumer has been closed: JmsConsumerInfo: { ID:d0b68c2a-bf26-448e-8f45-da2c755b9bb2:2:14:1, destination = amqp-demo-queue }
      2022-10-21 14:22:26.330  INFO 1 --- [-da2c755b9bb2:2] org.apache.qpid.jms.JmsSession           : A JMS MessageConsumer has been closed: JmsConsumerInfo: { ID:d0b68c2a-bf26-448e-8f45-da2c755b9bb2:2:15:1, destination = amqp-demo-queue }
      2022-10-21 14:22:26.576  WARN 1 --- [mqp-demo-queue]] c.c.j.DefaultJmsMessageListenerContainer : Setup of JMS message listener invoker failed for destination 'amqp-demo-queue' - trying to recover. Cause: Remote did not respond to a drain request in time
      2022-10-21 14:22:30.391  INFO 1 --- [mqp-0-svc:5672]] org.apache.qpid.jms.JmsConnection        : Connection ID:d8e4de8c-1a8a-4511-94d4-908b8c2238f6:3 connected to server: amqp://amq-broker-amqp-0-svc:5672
      2022-10-21 14:22:41.488  INFO 1 --- [mqp-demo-queue]] nl.ns.hip.tmpl                           : Received 1000 messages
      2022-10-21 14:22:52.908  INFO 1 --- [mqp-demo-queue]] nl.ns.hip.tmpl                           : Received 1100 messages
      2022-10-21 14:23:03.544  INFO 1 --- [mqp-demo-queue]] nl.ns.hip.tmpl                           : Received 1200 messages
      2022-10-21 14:23:15.216  INFO 1 --- [mqp-demo-queue]] nl.ns.hip.tmpl                           : Received 1300 messages
      2022-10-21 14:23:25.897  INFO 1 --- [mqp-demo-queue]] nl.ns.hip.tmpl                           : Received 1400 messages
      2022-10-21 14:23:31.681  INFO 1 --- [-908b8c2238f6:3] org.apache.qpid.jms.JmsSession           : A JMS MessageConsumer has been closed: JmsConsumerInfo: { ID:d8e4de8c-1a8a-4511-94d4-908b8c2238f6:3:12:1, destination = amqp-demo-queue }
      2022-10-21 14:23:32.174  INFO 1 --- [-908b8c2238f6:3] org.apache.qpid.jms.JmsSession           : A JMS MessageConsumer has been closed: JmsConsumerInfo: { ID:d8e4de8c-1a8a-4511-94d4-908b8c2238f6:3:14:1, destination = amqp-demo-queue }
      2022-10-21 14:23:32.478  WARN 1 --- [mqp-demo-queue]] c.c.j.DefaultJmsMessageListenerContainer : Setup of JMS message listener invoker failed for destination 'amqp-demo-queue' - trying to recover. Cause: Remote did not respond to a drain request in time
      2022-10-21 14:23:35.980  INFO 1 --- [mqp-0-svc:5672]] org.apache.qpid.jms.JmsConnection        : Connection ID:2dc818ba-c557-463c-b164-f97eb13e9a0c:4 connected to server: amqp://amq-broker-amqp-0-svc:5672
      2022-10-21

      The problem does not seem to manifest itself if I use 10 concurrent consumers.

            gemmellr Robbie Gemmell
            rhn-support-toross Tom Ross
            Votes:
            1 Vote for this issue
            Watchers:
            6 Start watching this issue

              Created:
              Updated:
              Resolved: