[jboss-user] [JBoss Messaging] - Re: Message delivery delay > 30 sec sometimes

[bodrin]

There is no firewall.
I have tried to isolate this and I think I succeeded at least partialy.

The testcase can be downloaded at: http://85.187.184.114/~hongildon/test-case-delays.zip

I'm running the examples (test-case) queue-consumer and queue-producer on machine A while the JBM is running on machine B.
How to reproduce:
1) start queue-consumer
Details: 
-Transacted event driven consumer.
-It shows the delay of the message.

2) start queue-producer multiple times to check the delay
Details:
Two sessions:
-First session (transacted) used to just create a consumer which is never used!
-Second session (transacted) used to send the message to the queue.


The strange thing is that if you comment the not used consumer creation in queue-producer (there is a line in the code
/** !!! if you comment the line below the delay is not present any more !!!*/
) you get this durations:

  | [java] 11.04.2008 09:54:24 (duration: 63 ms ) onMessage: delegator->JBossMessage[96256]:PERSISTENT, deliveryId=20
  | [java] -----------------------------------------
  | [java] 11.04.2008 09:54:30 (duration: 46 ms ) onMessage: delegator->JBossMessage[96768]:PERSISTENT, deliveryId=21
  | [java] -----------------------------------------
  | [java] 11.04.2008 09:54:36 (duration: 47 ms ) onMessage: delegator->JBossMessage[97280]:PERSISTENT, deliveryId=22
  | [java] -----------------------------------------
  | [java] 11.04.2008 09:54:42 (duration: 47 ms ) onMessage: delegator->JBossMessage[97792]:PERSISTENT, deliveryId=23
  | [java] -----------------------------------------
  | [java] 11.04.2008 09:54:50 (duration: 63 ms ) onMessage: delegator->JBossMessage[98304]:PERSISTENT, deliveryId=24
  | [java] -----------------------------------------
  | [java] 11.04.2008 09:54:58 (duration: 47 ms ) onMessage: delegator->JBossMessage[98816]:PERSISTENT, deliveryId=25
  | [java] -----------------------------------------
  | [java] 11.04.2008 09:55:04 (duration: 63 ms ) onMessage: delegator->JBossMessage[99328]:PERSISTENT, deliveryId=26
  | [java] -----------------------------------------
  | [java] 11.04.2008 09:55:10 (duration: 63 ms ) onMessage: delegator->JBossMessage[99840]:PERSISTENT, deliveryId=27
  | [java] -----------------------------------------
  | [java] 11.04.2008 09:55:17 (duration: 63 ms ) onMessage: delegator->JBossMessage[100352]:PERSISTENT, deliveryId=28
  | [java] -----------------------------------------
  | [java] 11.04.2008 09:55:27 (duration: 63 ms ) onMessage: delegator->JBossMessage[100864]:PERSISTENT, deliveryId=29
  | 
  | 

And if you leave the same line uncommented you get:

  | [java] 11.04.2008 10:04:59 (duration: 47 ms ) onMessage: delegator->JBossMessage[101376]:PERSISTENT, deliveryId=30
  | [java] -----------------------------------------
  | [java] 11.04.2008 10:05:12 (duration: 5093 ms ) onMessage: delegator->JBossMessage[101888]:PERSISTENT, deliveryId=31
  | [java] -----------------------------------------
  | [java] 11.04.2008 10:05:17 (duration: 47 ms ) onMessage: delegator->JBossMessage[102400]:PERSISTENT, deliveryId=32
  | [java] -----------------------------------------
  | [java] 11.04.2008 10:05:30 (duration: 5093 ms ) onMessage: delegator->JBossMessage[102912]:PERSISTENT, deliveryId=33
  | [java] -----------------------------------------
  | [java] 11.04.2008 10:05:34 (duration: 62 ms ) onMessage: delegator->JBossMessage[103424]:PERSISTENT, deliveryId=34
  | [java] -----------------------------------------
  | [java] 11.04.2008 10:05:49 (duration: 5078 ms ) onMessage: delegator->JBossMessage[103936]:PERSISTENT, deliveryId=35
  | [java] -----------------------------------------
  | [java] 11.04.2008 10:05:55 (duration: 31 ms ) onMessage: delegator->JBossMessage[104448]:PERSISTENT, deliveryId=36
  | [java] -----------------------------------------
  | [java] 11.04.2008 10:06:08 (duration: 5094 ms ) onMessage: delegator->JBossMessage[104960]:PERSISTENT, deliveryId=37
  | [java] -----------------------------------------
  | [java] 11.04.2008 10:06:14 (duration: 62 ms ) onMessage: delegator->JBossMessage[105472]:PERSISTENT, deliveryId=38
  | [java] -----------------------------------------
  | [java] 11.04.2008 10:06:25 (duration: 5078 ms ) onMessage: delegator->JBossMessage[105984]:PERSISTENT, deliveryId=39
  | 

Not that it is not as the original case where I got much bigger delays, but currently this is what I have managed to isolate ~ 5sec delay (and note the order - small delay, bigger delay, small delay,bigger delay,...).


View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4143364#4143364

Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4143364