MQJMS 1024 on server shutdown

Details

  • Type: Bug Bug
  • Status: Closed Closed
  • Priority: Major Major
  • Resolution: Fixed
  • Affects Version/s: None
  • Fix Version/s: 1.3-rc3
  • Component/s: Transport: JMS
  • Labels:
    None
  • Similar Issues:
    None

Description

This looks like an issue in the polling message receiver. Can you raise
a Jira and we'll have a look at it.

Cheers,

Ross

Loka, Murlidhar wrote:

>I get MQJMS 1024 when the server is stopped - is there any config param
>to be set to resolve the error?
>
>Thanks,
>Loka.
>
>
>
>
>
>
>
>[7/7/05 13:05:48:768 EDT] 1c101c10 SystemOut O [07/07/2005 13:05:48.768]
>[Thread-1] [INFO ] [JmxAgent]: Registered Endpoint Service with
>name: asyncReceive:type=control,name=RECEIVE.QUEUE EndpointService
>[7/7/05 13:05:48:830 EDT] 1c101c10 SystemOut O [07/07/2005 13:05:48.830]
>[Thread-1] [INFO ] [MuleManager]:
>
>**********************************************************************
>* Mule Version Info not set *
>* *
>* Server started: Thu Jul 07 13:05:48 EDT 2005 *
>* JDK: 1.4.2 (J2RE 1.4.2 IBM J9 2.2 Windows XP x86-32 j9n142-2004091 *
>* 7 (JIT enabled)
>J9VM - 20040916_0908_lHdSMR
>JIT - r7_level2004091 *
>* 5_1801) *
>* *
>* Agents Running: *
>* JMX Agent *
>* Jdmk Http adaptor: http://localhost:9092 *
>**********************************************************************
>[7/7/05 13:05:48:830 EDT] 1c101c10 ApplicationMg A WSVR0221I:
>Application
>started: MinimalApp
>[7/7/05 13:05:49:236 EDT] 1c101c10 HttpTransport A SRVE0171I: Transport http
>is listening on port 9,080.
>[7/7/05 13:05:51:221 EDT] 1c101c10 HttpTransport A SRVE0171I: Transport
>https is listening on port 9,443.
>[7/7/05 13:05:51:236 EDT] 1c101c10 HttpTransport A SRVE0171I: Transport http
>is listening on port 9,090.
>[7/7/05 13:05:51:268 EDT] 1c101c10 HttpTransport A SRVE0171I: Transport
>https is listening on port 9,043.
>[7/7/05 13:05:51:377 EDT] 1c101c10 RMIConnectorC A ADMC0026I: RMI Connector
>available at port 2809
>[7/7/05 13:05:51:471 EDT] 1c101c10 WsServer A WSVR0001I: Server server1
>open for e-business
>[7/7/05 13:09:53:247 EDT] 24632463 AdminHelper A ADMN1021I: Attempt made
>to stop the server1 server.
>[7/7/05 13:09:53:434 EDT] 60386038 HttpTransport A SRVE0172I: Stopped
>transport http on port 9,080.
>[7/7/05 13:09:53:590 EDT] 60386038 HttpTransport A SRVE0172I: Stopped
>transport https on port 9,443.
>[7/7/05 13:09:53:606 EDT] 60386038 HttpTransport A SRVE0172I: Stopped
>transport http on port 9,090.
>[7/7/05 13:09:53:606 EDT] 60386038 HttpTransport A SRVE0172I: Stopped
>transport https on port 9,043.
> [7/7/05 13:09:58:903 EDT] 60386038 SystemOut O [07/07/2005
>13:09:58.903] [Alarm : 2] [INFO ] [XmlWebApplicationContext]:
>Closing application context [Root XmlWebApplicationContext]
>[7/7/05 13:09:58:903 EDT] 60386038 SystemOut O [07/07/2005 13:09:58.903]
>[7/7/05 13:09:58:918 EDT] 60386038 SystemOut O [07/07/2005 13:09:58.918]
>[Alarm : 2] [INFO ] [VMConnector]: Stopping Connector:
>org.mule.providers.vm.VMConnector
>[7/7/05 13:09:58:918 EDT] 60386038 SystemOut O [07/07/2005 13:09:58.918]
>[Alarm : 2] [INFO ] [VMConnector]: Connector
>org.mule.providers.vm.VMConnector has been stopped
>[7/7/05 13:09:58:918 EDT] 60386038 SystemOut O [07/07/2005 13:09:58.918]
>[Alarm : 2] [INFO ] [JmsConnector]: Stopping Connector:
>org.mule.providers.jms.JmsConnector
>[7/7/05 13:10:06:559 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.559]
>[Alarm : 2] [INFO ] [JmsConnector]: Connector
>org.mule.providers.jms.JmsConnector has been stopped
>[7/7/05 13:10:06:559 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.559]
>[Alarm : 2] [INFO ] [MuleManager]: Connectors have been stopped
>successfully
>[7/7/05 13:10:06:559 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.559]
>[Alarm : 2] [INFO ] [MuleManager]: Stopping agents...
>[7/7/05 13:10:06:559 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.559]
>[Alarm : 2] [INFO ] [MuleManager]: Agents Successfully Stopped
>[7/7/05 13:10:06:559 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.559]
>[Alarm : 2] [INFO ] [TransactionalQueueManager]: Stopping
>ResourceManager
>[7/7/05 13:10:06:559 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.559]
>[Alarm : 2] [INFO ] [TransactionalQueueManager]: Stopped
>ResourceManager
>[7/7/05 13:10:06:575 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.575]
>[Alarm : 2] [INFO ] [MuleModel]: Component RemoverUMO has been
>stopped successfully
>[7/7/05 13:10:06:575 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.575]
>[Alarm : 2] [INFO ] [MuleModel]: Component MessageProcessorUMO has
>been stopped successfully
>[7/7/05 13:10:06:575 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.575]
>[Alarm : 2] [INFO ] [VMConnector]: Disposing Connector:
>org.mule.providers.vm.VMConnector
>[7/7/05 13:10:06:575 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.575]
>[Alarm : 2] [INFO ] [VMConnector]: Connector
>org.mule.providers.vm.VMConnector has been disposed.
>[7/7/05 13:10:06:590 EDT] 60386038 SystemOut O [07/07/2005 13:10:06.575]
>[Alarm : 2] [INFO ] [JmsConnector]: Disposing Connector:
>org.mule.providers.jms.JmsConnector
>[7/7/05 13:10:11:590 EDT] 32ec32ec SystemOut O [07/07/2005 13:10:11.590]
>[jmsConnector1. RECEIVE.QUEUE.receiver.1] [INFO ]
>[TransactionTemplate]: Exception Caught in Transaction template. Handing
>of to exception handler: org.mule.impl.DefaultExceptionStrategy@34d934d9
>[7/7/05 13:10:11:621 EDT] 32ec32ec SystemOut O [07/07/2005 13:10:11.621]
>[jmsConnector1.RECEIVE.QUEUE.receiver.1] [ERROR]
>[DefaultExceptionStrategy]: Caught exception in Exception Strategy:
>MQJMS1024: session closed
>com.ibm.mq.jms.SessionClosedException: MQJMS1024: session closed
> at com.ibm.mq.jms.MQSession.waitForStart(MQSession.java:2225)
> at
>com.ibm.mq.jms.MQMessageConsumer.receiveInternalQ(MQMessageConsumer.java:238
>6)
> at
>com.ibm.mq.jms.MQMessageConsumer.receiveQ(MQMessageConsumer.java:2233)
> at
>com.ibm.mq.jms.MQMessageConsumer.receive(MQMessageConsumer.java:4227)
> at
>org.mule.providers.jms.JmsMessageReceiver.getMessages(JmsMessageReceiver.jav
>a:161)
> at
>org.mule.providers.TransactedPollingMessageReceiver$1.doInTransaction(Transa
>ctedPollingMessageReceiver.java:80)
> at
>org.mule.transaction.TransactionTemplate.execute(TransactionTemplate.java:72
>)
> at
>org.mule.providers.TransactedPollingMessageReceiver.poll(TransactedPollingMe
>ssageReceiver.java:93)
> at
>org.mule.providers.jms.JmsMessageReceiver.poll(JmsMessageReceiver.java:137)
> at
>org.mule.providers.PollingMessageReceiver.run(PollingMessageReceiver.java:71
>)
> at org.mule.impl.work.WorkerContext.run(WorkerContext.java:290)
> at
>EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(Unknown Source)
> at java.lang.Thread.run(Thread.java:813)
>[7/7/05 13:10:11:684 EDT] 60386038 SystemOut O [07/07/2005 13:10:11.684]
>[Alarm : 2] [INFO ] [JmsConnector]: Connector
>org.mule.providers.jms.JmsConnector has been disposed.
>[7/7/05 13:10:11:684 EDT] 60386038 SystemOut O [07/07/2005 13:10:11.684]
>[Alarm : 2] [INFO ] [MuleModel]: HeaderRemoverUMO has been destroyed
>successfully
>[7/7/05 13:10:11:684 EDT] 60386038 SystemOut O [07/07/2005 13:10:11.684]
>[Alarm : 2] [INFO ] [MuleModel]: MessageProcessorUMO has been
>destroyed successfully
>[7/7/05 13:10:11:684 EDT] 60386038 SystemOut O [07/07/2005 13:10:11.684]
>[Alarm : 2] [INFO ] [MuleManager]: disposing agents...
>[7/7/05 13:10:11:699 EDT] 60386038 SystemOut O [07/07/2005 13:10:11.699]
>[Alarm : 2] [INFO ] [MuleManager]: Agents Successfully Disposed
>[7/7/05 13:10:11:715 EDT] 60386038 SystemOut O [07/07/2005 13:10:11.715]
>[Alarm : 2] [INFO ] [MuleManager]:
>
>***********************************************************************
>*****
>****
>* Mule shut down normally on: Thu Jul 07 13:10:11 EDT 2005
>*
>* Server was up for: 0 days, 0 hours, 4 mins, 23.541 sec
>*
>****************************************************************************
>****
>
>-----------------------------------------
>This e-mail message and any attachments contain confidential
>information from Medco. If you are not the intended recipient, you are
>hereby notified that disclosure, printing, copying, distribution, or
>the taking of any action in reliance on the contents of this electronic
>information is strictly prohibited. If you have received this e-mail
>message in error, please immediately notify the sender by reply message
>and then delete the electronic message and any attachments.
>
>
>
>
>

Activity

Ascending order - Click to sort in descending order
Hide
Permalink
Ross Mason added a comment -

Hi,

Are you still having this issue?

Cheers,

Ross

Show
Ross Mason added a comment - Hi, Are you still having this issue? Cheers, Ross
Hide
Permalink
Aaron Bell added a comment -

Confirmed still occurring in nightly build (03 Feb) using WebSphere MQ v5.3 CSD11:

2006-02-06 11:38:21,670 INFO [Thread-0] [AbstractConnector] Stopping Connector: org.mule.providers.jms.JmsConnector
2006-02-06 11:38:26,126 INFO jmsConnector._jmsEndpoint#-1229783067.receiver.1 [TransactionTemplate] Exception Caught in Transaction template. Handing off to exception handler: org.mule.impl.DefaultExceptionStrategy@1aaf0b3
2006-02-06 11:38:26,126 ERROR jmsConnector._jmsEndpoint#-1229783067.receiver.1 [AbstractExceptionListener]
********************************************************************************
Message : Transaction rollback failed
Type : org.mule.umo.TransactionException
Code : 90098
JMS Code : MQJMS1024
JavaDoc : http://mule.codehaus.org/docs/apidocs/org/mule/umo/TransactionException.html
********************************************************************************
Exception stack is:
1. MQJMS1024: session closed(JMS Code: MQJMS1024) (javax.jms.IllegalStateException)
com.ibm.mq.jms.MQSession:3856 (http://java.sun.com/j2ee/sdk_1.3/techdocs/api/javax/jms/IllegalStateException.html)
2. Transaction rollback failed (org.mule.umo.TransactionException)
org.mule.providers.jms.JmsTransaction:98 (http://mule.codehaus.org/docs/apidocs/org/mule/umo/TransactionException.html)
********************************************************************************
Root Exception stack trace:
javax.jms.IllegalStateException: MQJMS1024: session closed
at com.ibm.mq.jms.MQSession.rollbackQ(MQSession.java:3856)
at com.ibm.mq.jms.MQQueueSession.rollback(MQQueueSession.java:187)
at org.mule.providers.jms.JmsTransaction.doRollback(JmsTransaction.java:96)
at org.mule.transaction.AbstractTransaction.rollback(AbstractTransaction.java:113)
at org.mule.transaction.AbstractSingleResourceTransaction.rollback(AbstractSingleResourceTransaction.java:70)
at org.mule.transaction.TransactionTemplate.execute(TransactionTemplate.java:76)
at org.mule.providers.TransactedPollingMessageReceiver.poll(TransactedPollingMessageReceiver.java:104)
at org.mule.providers.jms.TransactedJmsMessageReceiver.poll(TransactedJmsMessageReceiver.java:143)
at org.mule.providers.PollingMessageReceiver.run(PollingMessageReceiver.java:73)
at org.mule.impl.work.WorkerContext.run(WorkerContext.java:290)
at edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:643)
at edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:668)
at java.lang.Thread.run(Thread.java:595)

********************************************************************************

2006-02-06 11:38:26,197 ERROR jmsConnector._jmsEndpoint#-1229783067.receiver.1 [AbstractExceptionListener]
********************************************************************************
Message : Failed unbind transaction, current transaction and referenced transaction are not the same
Type : org.mule.transaction.IllegalTransactionStateException
Code : 91407
JavaDoc : http://mule.codehaus.org/docs/apidocs/org/mule/transaction/IllegalTransactionStateException.html
********************************************************************************
Exception stack is:
1. Failed unbind transaction, current transaction and referenced transaction are not the same (org.mule.transaction.IllegalTransactionStateException)
org.mule.transaction.TransactionCoordination:76 (http://mule.codehaus.org/docs/apidocs/org/mule/transaction/IllegalTransactionStateException.html)
********************************************************************************
Root Exception stack trace:
org.mule.transaction.IllegalTransactionStateException: Failed unbind transaction, current transaction and referenced transaction are not the same
at org.mule.transaction.TransactionCoordination.unbindTransaction(TransactionCoordination.java:76)
at org.mule.transaction.AbstractTransaction.rollback(AbstractTransaction.java:115)
at org.mule.transaction.AbstractSingleResourceTransaction.rollback(AbstractSingleResourceTransaction.java:70)
at org.mule.transaction.TransactionTemplate.execute(TransactionTemplate.java:104)
at org.mule.providers.TransactedPollingMessageReceiver.poll(TransactedPollingMessageReceiver.java:104)
at org.mule.providers.jms.TransactedJmsMessageReceiver.poll(TransactedJmsMessageReceiver.java:143)
at org.mule.providers.PollingMessageReceiver.run(PollingMessageReceiver.java:73)
at org.mule.impl.work.WorkerContext.run(WorkerContext.java:290)
at edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:643)
at edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:668)
at java.lang.Thread.run(Thread.java:595)

********************************************************************************

2006-02-06 11:38:26,207 WARN jmsConnector._jmsEndpoint#-1229783067.receiver.1 [ExceptionHelper] Failed to load error mappings from: META-INF/services/org/mule/config/jms-exception-mappings.properties. This may be because there are no error code mappings for protocol: jms

Show
Aaron Bell added a comment - Confirmed still occurring in nightly build (03 Feb) using WebSphere MQ v5.3 CSD11: 2006-02-06 11:38:21,670 INFO [Thread-0] [AbstractConnector] Stopping Connector: org.mule.providers.jms.JmsConnector 2006-02-06 11:38:26,126 INFO jmsConnector._jmsEndpoint#-1229783067.receiver.1 [TransactionTemplate] Exception Caught in Transaction template. Handing off to exception handler: org.mule.impl.DefaultExceptionStrategy@1aaf0b3 2006-02-06 11:38:26,126 ERROR jmsConnector._jmsEndpoint#-1229783067.receiver.1 [AbstractExceptionListener] ******************************************************************************** Message : Transaction rollback failed Type : org.mule.umo.TransactionException Code : 90098 JMS Code : MQJMS1024 JavaDoc : http://mule.codehaus.org/docs/apidocs/org/mule/umo/TransactionException.html ******************************************************************************** Exception stack is: 1. MQJMS1024: session closed(JMS Code: MQJMS1024) (javax.jms.IllegalStateException) com.ibm.mq.jms.MQSession:3856 (http://java.sun.com/j2ee/sdk_1.3/techdocs/api/javax/jms/IllegalStateException.html) 2. Transaction rollback failed (org.mule.umo.TransactionException) org.mule.providers.jms.JmsTransaction:98 (http://mule.codehaus.org/docs/apidocs/org/mule/umo/TransactionException.html) ******************************************************************************** Root Exception stack trace: javax.jms.IllegalStateException: MQJMS1024: session closed at com.ibm.mq.jms.MQSession.rollbackQ(MQSession.java:3856) at com.ibm.mq.jms.MQQueueSession.rollback(MQQueueSession.java:187) at org.mule.providers.jms.JmsTransaction.doRollback(JmsTransaction.java:96) at org.mule.transaction.AbstractTransaction.rollback(AbstractTransaction.java:113) at org.mule.transaction.AbstractSingleResourceTransaction.rollback(AbstractSingleResourceTransaction.java:70) at org.mule.transaction.TransactionTemplate.execute(TransactionTemplate.java:76) at org.mule.providers.TransactedPollingMessageReceiver.poll(TransactedPollingMessageReceiver.java:104) at org.mule.providers.jms.TransactedJmsMessageReceiver.poll(TransactedJmsMessageReceiver.java:143) at org.mule.providers.PollingMessageReceiver.run(PollingMessageReceiver.java:73) at org.mule.impl.work.WorkerContext.run(WorkerContext.java:290) at edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:643) at edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:668) at java.lang.Thread.run(Thread.java:595) ******************************************************************************** 2006-02-06 11:38:26,197 ERROR jmsConnector._jmsEndpoint#-1229783067.receiver.1 [AbstractExceptionListener] ******************************************************************************** Message : Failed unbind transaction, current transaction and referenced transaction are not the same Type : org.mule.transaction.IllegalTransactionStateException Code : 91407 JavaDoc : http://mule.codehaus.org/docs/apidocs/org/mule/transaction/IllegalTransactionStateException.html ******************************************************************************** Exception stack is: 1. Failed unbind transaction, current transaction and referenced transaction are not the same (org.mule.transaction.IllegalTransactionStateException) org.mule.transaction.TransactionCoordination:76 (http://mule.codehaus.org/docs/apidocs/org/mule/transaction/IllegalTransactionStateException.html) ******************************************************************************** Root Exception stack trace: org.mule.transaction.IllegalTransactionStateException: Failed unbind transaction, current transaction and referenced transaction are not the same at org.mule.transaction.TransactionCoordination.unbindTransaction(TransactionCoordination.java:76) at org.mule.transaction.AbstractTransaction.rollback(AbstractTransaction.java:115) at org.mule.transaction.AbstractSingleResourceTransaction.rollback(AbstractSingleResourceTransaction.java:70) at org.mule.transaction.TransactionTemplate.execute(TransactionTemplate.java:104) at org.mule.providers.TransactedPollingMessageReceiver.poll(TransactedPollingMessageReceiver.java:104) at org.mule.providers.jms.TransactedJmsMessageReceiver.poll(TransactedJmsMessageReceiver.java:143) at org.mule.providers.PollingMessageReceiver.run(PollingMessageReceiver.java:73) at org.mule.impl.work.WorkerContext.run(WorkerContext.java:290) at edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:643) at edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:668) at java.lang.Thread.run(Thread.java:595) ******************************************************************************** 2006-02-06 11:38:26,207 WARN jmsConnector._jmsEndpoint#-1229783067.receiver.1 [ExceptionHelper] Failed to load error mappings from: META-INF/services/org/mule/config/jms-exception-mappings.properties. This may be because there are no error code mappings for protocol: jms
Hide
Permalink
Ross Mason added a comment -

I've fixed the first error on this ticket. It was casues when a receiver was shut down while doing a blocking receive.

Aaron, the error you are getting is something different. There is a conflicting transaction when a tx is unbound. I've moved this to a separate issue MULE-810. Please add a watch to this issue. Also, can you tell me if you are actually running inside Websphere App Server or just using MQ 5.3. (Comment on MULE-810)

Cheers,

Ross

Show
Ross Mason added a comment - I've fixed the first error on this ticket. It was casues when a receiver was shut down while doing a blocking receive. Aaron, the error you are getting is something different. There is a conflicting transaction when a tx is unbound. I've moved this to a separate issue MULE-810. Please add a watch to this issue. Also, can you tell me if you are actually running inside Websphere App Server or just using MQ 5.3. (Comment on MULE-810) Cheers, Ross

People

  • Assignee:
    Unassigned
    Reporter:
    murlidhar loka
Vote (1)
Watch (3)

Dates

  • Created:
    Updated:
    Resolved: