[jboss-jira] [JBoss JIRA] (WFLY-6420) 30secs delay on consumer.receive() in spite of shorter timeout

[Tomohisa igarashi (JIRA)]

     [ https://issues.jboss.org/browse/WFLY-6420?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Tomohisa igarashi reopened WFLY-6420:
-------------------------------------


It worked fine without delay also for me at first time, but it did reproduced at second time after restarting WildFly instance. (It looks even worse... \{0,1,1,3,3} were received while \{0,1,2,3,4} were sent)
[~jmesnil] could you try it again?
{code}
02:12:54,411 INFO  [ArtemisHornetQClientTest] [	13]: Starting to send/receive using HornetQ client API - VMName=24404 at localhost.localdomain
02:12:54,528 INFO  [ArtemisHornetQClientTest] [	131]: * * *  Sent a message 'foobar-0' to 'HornetQQueue[DLQ]'
02:12:54,545 INFO  [ArtemisHornetQClientTest] [	148]: * * *  Sent a message 'foobar-1' to 'HornetQQueue[DLQ]'
02:12:54,547 INFO  [ArtemisHornetQClientTest] [	150]: * * *  Sent a message 'foobar-2' to 'HornetQQueue[DLQ]'
02:12:54,549 INFO  [ArtemisHornetQClientTest] [	152]: * * *  Sent a message 'foobar-3' to 'HornetQQueue[DLQ]'
02:12:54,551 INFO  [ArtemisHornetQClientTest] [	154]: * * *  Sent a message 'foobar-4' to 'HornetQQueue[DLQ]'
02:13:24,598 INFO  [ArtemisHornetQClientTest] [	30201]: * * *  Received a message 'foobar-0' to 'HornetQQueue[DLQ]'
02:13:24,599 INFO  [ArtemisHornetQClientTest] [	30202]: * * *  Received a message 'foobar-1' to 'HornetQQueue[DLQ]'
02:13:24,601 INFO  [ArtemisHornetQClientTest] [	30203]: * * *  Received a message 'foobar-1' to 'HornetQQueue[DLQ]'
02:13:24,602 INFO  [ArtemisHornetQClientTest] [	30205]: * * *  Received a message 'foobar-3' to 'HornetQQueue[DLQ]'
02:13:24,603 INFO  [ArtemisHornetQClientTest] [	30206]: * * *  Received a message 'foobar-3' to 'HornetQQueue[DLQ]'
02:13:24,639 INFO  [ArtemisHornetQClientTest] [	30231]: Finished (HornetQ client)
02:13:25,694 INFO  [ArtemisHornetQClientTest] [	0]: Starting to send/receive using Artemis client API
02:13:25,728 INFO  [ArtemisHornetQClientTest] [	34]: * * *  Sent a message 'foobar-0' to 'ActiveMQQueue[DLQ]'
02:13:25,730 INFO  [ArtemisHornetQClientTest] [	36]: * * *  Sent a message 'foobar-1' to 'ActiveMQQueue[DLQ]'
02:13:25,731 INFO  [ArtemisHornetQClientTest] [	38]: * * *  Sent a message 'foobar-2' to 'ActiveMQQueue[DLQ]'
02:13:25,732 INFO  [ArtemisHornetQClientTest] [	39]: * * *  Sent a message 'foobar-3' to 'ActiveMQQueue[DLQ]'
02:13:25,735 INFO  [ArtemisHornetQClientTest] [	42]: * * *  Sent a message 'foobar-4' to 'ActiveMQQueue[DLQ]'
02:13:25,758 INFO  [ArtemisHornetQClientTest] [	65]: * * *  Received a message 'foobar-0' to 'ActiveMQQueue[DLQ]'
02:13:25,759 INFO  [ArtemisHornetQClientTest] [	66]: * * *  Received a message 'foobar-1' to 'ActiveMQQueue[DLQ]'
02:13:25,761 INFO  [ArtemisHornetQClientTest] [	68]: * * *  Received a message 'foobar-2' to 'ActiveMQQueue[DLQ]'
02:13:25,762 INFO  [ArtemisHornetQClientTest] [	69]: * * *  Received a message 'foobar-3' to 'ActiveMQQueue[DLQ]'
02:13:25,763 INFO  [ArtemisHornetQClientTest] [	70]: * * *  Received a message 'foobar-4' to 'ActiveMQQueue[DLQ]'
02:13:25,773 INFO  [ArtemisHornetQClientTest] [	80]: Finished (Artemis client)
{code}
\\
If I added @Ignore on testHornetQClient(), then it reproduced with testArtemisClient() as well.
{code}
02:27:17,216 INFO  [ArtemisHornetQClientTest] [	0]: Starting to send/receive using Artemis client API
02:27:17,293 INFO  [ArtemisHornetQClientTest] [	78]: * * *  Sent a message 'foobar-0' to 'ActiveMQQueue[DLQ]'
02:27:17,311 INFO  [ArtemisHornetQClientTest] [	97]: * * *  Sent a message 'foobar-1' to 'ActiveMQQueue[DLQ]'
02:27:17,315 INFO  [ArtemisHornetQClientTest] [	101]: * * *  Sent a message 'foobar-2' to 'ActiveMQQueue[DLQ]'
02:27:17,319 INFO  [ArtemisHornetQClientTest] [	104]: * * *  Sent a message 'foobar-3' to 'ActiveMQQueue[DLQ]'
02:27:17,322 INFO  [ArtemisHornetQClientTest] [	108]: * * *  Sent a message 'foobar-4' to 'ActiveMQQueue[DLQ]'
02:27:47,351 INFO  [ArtemisHornetQClientTest] [	30137]: * * *  Received a message 'foobar-0' to 'ActiveMQQueue[DLQ]'
02:27:47,352 INFO  [ArtemisHornetQClientTest] [	30138]: * * *  Received a message 'foobar-1' to 'ActiveMQQueue[DLQ]'
02:27:47,355 INFO  [ArtemisHornetQClientTest] [	30141]: * * *  Received a message 'foobar-2' to 'ActiveMQQueue[DLQ]'
02:27:47,356 INFO  [ArtemisHornetQClientTest] [	30142]: * * *  Received a message 'foobar-3' to 'ActiveMQQueue[DLQ]'
02:27:47,358 INFO  [ArtemisHornetQClientTest] [	30143]: * * *  Received a message 'foobar-4' to 'ActiveMQQueue[DLQ]'
02:27:47,389 INFO  [ArtemisHornetQClientTest] [	30175]: Finished (Artemis client)
{code}

> 30secs delay on consumer.receive() in spite of shorter timeout
> --------------------------------------------------------------
>
>                 Key: WFLY-6420
>                 URL: https://issues.jboss.org/browse/WFLY-6420
>             Project: WildFly
>          Issue Type: Bug
>          Components: JMS
>    Affects Versions: 10.0.0.Final
>            Reporter: Tomohisa igarashi
>            Assignee: Jeff Mesnil
>             Fix For: 10.1.0.Final
>
>         Attachments: artemis-hornetq-client.tgz
>
>
> consumer.receive() is blocked 30secs even if shorter timeout is specified. Attached a maven project including testcases. 
> The ArtemisStandaloneTest do same against embedded server, but this in contrast works fine. So the issue seems to happen only on WildFly via http upgrade connection.



--
This message was sent by Atlassian JIRA
(v6.4.11#64026)