Delay time problem caused by receiveQ(timeout)() blocks - Websphere

This is a discussion on Delay time problem caused by receiveQ(timeout)() blocks - Websphere ; Hi members I am asking your help about the following situation. I identified a delay time problem when my application sends a message after a long time period of inactivity. I activated the debug on the MQ libraries (log file ...

+ Reply to Thread
Results 1 to 2 of 2

Thread: Delay time problem caused by receiveQ(timeout)() blocks

  1. Delay time problem caused by receiveQ(timeout)() blocks

    Hi members I am asking your help about the following situation. I identified a delay time problem when my application sends a message after a long time period of inactivity. I activated the debug on the MQ libraries (log file attached) with the debug enabled I realize that multiple receiveQ(timeout) messages are causing the delay time. In a brief explanation after the "createQueueConnection" method executes several blocks of "receiveQ(timeout)" messages appears until the "createQM" message appears as is showed in the resumed following log messages:



    07:45:40 1213965940984 createQueueConnection() entry

    07:45:40 1213965940984 constructor() entry

    . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . .

    . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . .

    . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . .

    07:45:41 1213965941024 listenerContainer-1 receiveQ(timeout)() entry

    07:45:41 1213965941024 listenerContainer-1 receiveInternalQ() entry

    07:45:41 1213965941024 listenerContainer-1 callingFromOnMessage() entry

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueSession@ad1355 == callingFromOnMessage() exit

    07:45:41 1213965941024 listenerContainer-1 usingAsyncMode() entry

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueSession@ad1355 == usingAsyncMode() exit

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@661a11 receiveInternalQ - waiting for session start

    07:45:41 1213965941024 listenerContainer-1 waitForStart() entry

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueSession@ad1355 == waitForStart() exit

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@661a11 receiveInternalQ - session started

    07:45:41 1213965941024 listenerContainer-1 getTransactionLock() entry

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueSession@ad1355 == getTransactionLock() exit

    07:45:41 1213965941024 listenerContainer-1 isStarted() entry

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueSession@ad1355 isStarted returning true

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueSession@ad1355 == isStarted() exit

    07:45:41 1213965941024 listenerContainer-1 getMessageQ() entry

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@661a11 from queue sioout_des

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@661a11 Current gmo.waitInterval = 1000

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@661a11 getMessageQ setting waitInterval

    07:45:41 1213965941024 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@661a11 getMessageQ GET

    07:45:41 1213965941067 listenerContainer-1 isClosed() entry

    07:45:41 1213965941067 listenerContainer-1 com.ibm.mq.jms.MQQueueSession@52fdeb isClosed returning false

    07:45:41 1213965941067 listenerContainer-1 com.ibm.mq.jms.MQQueueSession@52fdeb == isClosed() exit

    07:45:41 1213965941067 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@d60225 *** Adding 1000 to totalTimeWaited

    07:45:41 1213965941067 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@d60225 used 1000 of 1000 ms

    07:45:41 1213965941067 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@d60225 == getMessageQ() exit

    07:45:41 1213965941067 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@d60225 == receiveInternalQ() exit

    07:45:41 1213965941067 listenerContainer-1 com.ibm.mq.jms.MQQueueReceiver@d60225 == receiveQ(timeout)() exit

    . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . .

    . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . .

    . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . .

    07:51:41 1213966301762 com.ibm.mq.jms.MQQueueConnection@100a384 == createQM() exit

    07:51:41 1213966301762 com.ibm.mq.jms.MQQueueConnection@100a384 == createQMNonXA() exit

    07:51:41 1213966301762 com.ibm.mq.jms.MQQueueConnection@100a384 == constructor() exit

    07:51:41 1213966301762 com.ibm.mq.jms.MQQueueConnectionFactory@d732334e == createQueueConnection() exit



    If you saw carefully the messages the "createQueueConnection() entry" message is showed at: 07:45:40 and the "createQueueConnection() exit" message is showed at 07:51:41 around 6 min. later. Someone knows what causes this situation or anyone could help me to correct this problem?



    Thanks in advance.

  2. Re: Delay time problem caused by receiveQ(timeout)() blocks

    afs3-prserver PSH, ACK Seq=1 Ack=1 Win=48452 Len=216 | MQOPEN

    2 0.449130 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    3 1.349145 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    4 3.149078 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    5 6.749188 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    6 13.959278 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    7 28.389249 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    8 57.249364 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    9 114.969479 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    10 174.969730 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    11 234.969987 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    12 294.970179 192.168.5.18 10.104.1.35 MQ TCP Retransmission MQOPEN

    13 354.970364 192.168.5.18 10.104.1.35 MQ TCP Retransmission afs3-prserver FIN, ACK Seq=245 Ack=1 Win=48452 Len=0



    Someone could tell me why the retransmission happens: TCP Retransmission MQOPEN?

+ Reply to Thread