7 Replies Latest reply: Feb 20, 2013 2:34 AM by mike just RSS

JMS error message in the log

mike just Master

Hi guys,

 

Anybody knows what this error message means? It seems my function does not break but there is such error info in the log. I am using JBoss 7.1.1.Final.

 

14:56:11,788 WARN  [org.hornetq.core.protocol.core.ServerSessionPacketHandler] (Thread-9 (HornetQ-remoting-threads-HornetQServerImpl::serverUUID=ca189863-c4f0-11e1-92a0-287c20524153-55737418-475914234)) Sending unexpected exception to the client: java.lang.NullPointerException
          at org.hornetq.core.server.impl.ServerSessionImpl.xaEnd(ServerSessionImpl.java:718) [hornetq-core-2.2.13.Final.jar:]
          at org.hornetq.core.protocol.core.ServerSessionPacketHandler.handlePacket(ServerSessionPacketHandler.java:309) [hornetq-core-2.2.13.Final.jar:]
          at org.hornetq.core.protocol.core.impl.ChannelImpl.handlePacket(ChannelImpl.java:508) [hornetq-core-2.2.13.Final.jar:]
          at org.hornetq.core.protocol.core.impl.RemotingConnectionImpl.doBufferReceived(RemotingConnectionImpl.java:556) [hornetq-core-2.2.13.Final.jar:]
          at org.hornetq.core.protocol.core.impl.RemotingConnectionImpl.bufferReceived(RemotingConnectionImpl.java:517) [hornetq-core-2.2.13.Final.jar:]
          at org.hornetq.core.remoting.server.impl.RemotingServiceImpl$DelegatingBufferHandler.bufferReceived(RemotingServiceImpl.java:533) [hornetq-core-2.2.13.Final.jar:]
          at org.hornetq.core.remoting.impl.invm.InVMConnection$1.run(InVMConnection.java:166) [hornetq-core-2.2.13.Final.jar:]
          at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:100) [hornetq-core-2.2.13.Final.jar:]
          at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885) [rt.jar:1.6.0_07]
          at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907) [rt.jar:1.6.0_07]
          at java.lang.Thread.run(Thread.java:619) [rt.jar:1.6.0_07]


14:56:11,801 ERROR [org.hornetq.core.client.impl.ClientSessionImpl] (Thread-15 (HornetQ-client-global-threads-370754169)) Caught Exception : HornetQException[errorCode=0 message=null]
          at org.hornetq.core.protocol.core.impl.ChannelImpl.sendBlocking(ChannelImpl.java:312)
          at org.hornetq.core.client.impl.ClientSessionImpl.end(ClientSessionImpl.java:1365)
          at org.hornetq.core.client.impl.DelegatingSession.end(DelegatingSession.java:333)
          at com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord.topLevelOnePhaseCommit(XAResourceRecord.java:597)
          at com.arjuna.ats.arjuna.coordinator.BasicAction.onePhaseCommit(BasicAction.java:2283)
          at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1466)
          at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:98)
          at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:164)
          at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1165)
          at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:117)
          at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75)
          at org.jboss.as.ejb3.inflow.MessageEndpointInvocationHandler.afterDelivery(MessageEndpointInvocationHandler.java:72) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
          at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.6.0_07]
          at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [rt.jar:1.6.0_07]
          at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_07]
          at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_07]
          at org.jboss.as.ejb3.inflow.AbstractInvocationHandler.handle(AbstractInvocationHandler.java:60) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
          at org.jboss.as.ejb3.inflow.MessageEndpointInvocationHandler.doInvoke(MessageEndpointInvocationHandler.java:136) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
          at org.jboss.as.ejb3.inflow.AbstractInvocationHandler.invoke(AbstractInvocationHandler.java:73) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
          at $Proxy13.afterDelivery(Unknown Source)          at org.hornetq.ra.inflow.HornetQMessageHandler.onMessage(HornetQMessageHandler.java:287)
          at org.hornetq.core.client.impl.ClientConsumerImpl.callOnMessage(ClientConsumerImpl.java:983)
          at org.hornetq.core.client.impl.ClientConsumerImpl.access$400(ClientConsumerImpl.java:48)
          at org.hornetq.core.client.impl.ClientConsumerImpl$Runner.run(ClientConsumerImpl.java:1113)
          at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:100)
          at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885) [rt.jar:1.6.0_07]
          at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907) [rt.jar:1.6.0_07]
          at java.lang.Thread.run(Thread.java:619) [rt.jar:1.6.0_07]


14:56:11,858 WARN  [com.arjuna.ats.jta] (Thread-15 (HornetQ-client-global-threads-370754169)) ARJUNA016039: onePhaseCommit on < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a80166:40c224a5:5003bac4:38, node_name=1, branch_uid=0:ffffc0a80166:40c224a5:5003bac4:39, subordinatenodename=null, eis_name=unknown eis name > (DelegatingSession [session=ClientSessionImpl [name=2eb7a0fc-cf13-11e1-9a69-ceac20524153, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0], discoveryGroupConfiguration=null], connectorConfig=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory?server-id=0, backupConfig=null], metaData=(resource-adapter=inbound,jms-session=,)]@2b6167ab]) failed with exception XAException.XAER_RMERR: javax.transaction.xa.XAException
          at org.hornetq.core.client.impl.ClientSessionImpl.end(ClientSessionImpl.java:1376)
          at org.hornetq.core.client.impl.DelegatingSession.end(DelegatingSession.java:333)
          at com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord.topLevelOnePhaseCommit(XAResourceRecord.java:597)
          at com.arjuna.ats.arjuna.coordinator.BasicAction.onePhaseCommit(BasicAction.java:2283)
          at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1466)
          at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:98)
          at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:164)
          at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1165)
          at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:117)
          at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75)
          at org.jboss.as.ejb3.inflow.MessageEndpointInvocationHandler.afterDelivery(MessageEndpointInvocationHandler.java:72) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
          at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.6.0_07]
          at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [rt.jar:1.6.0_07]
          at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_07]
          at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_07]
          at org.jboss.as.ejb3.inflow.AbstractInvocationHandler.handle(AbstractInvocationHandler.java:60) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
          at org.jboss.as.ejb3.inflow.MessageEndpointInvocationHandler.doInvoke(MessageEndpointInvocationHandler.java:136) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
          at org.jboss.as.ejb3.inflow.AbstractInvocationHandler.invoke(AbstractInvocationHandler.java:73) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
          at $Proxy13.afterDelivery(Unknown Source)          at org.hornetq.ra.inflow.HornetQMessageHandler.onMessage(HornetQMessageHandler.java:287)
          at org.hornetq.core.client.impl.ClientConsumerImpl.callOnMessage(ClientConsumerImpl.java:983)
          at org.hornetq.core.client.impl.ClientConsumerImpl.access$400(ClientConsumerImpl.java:48)
          at org.hornetq.core.client.impl.ClientConsumerImpl$Runner.run(ClientConsumerImpl.java:1113)
          at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:100)
          at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885) [rt.jar:1.6.0_07]
          at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907) [rt.jar:1.6.0_07]
          at java.lang.Thread.run(Thread.java:619) [rt.jar:1.6.0_07]

  • 1. Re: JMS error message in the log
    mike just Master

    Another message, seems about some configuration problem.

     

    15:00:56,218 ERROR [org.hornetq.core.transaction.impl.ResourceManagerImpl] (Thread-0 (HornetQ-scheduled-threads-1088575502)) failed to timeout transaction, xid:XidImpl (1611090843 bq:0.0.0.0.0.0.0.0.0.0.-1.-1.-64.-88.1.102.64.-62.36.-91.80.3.-70.-60.0.0.0.57.0.0.0.0.0.0.0.0 formatID:131077 gtxid:0.0.0.0.0.0.0.0.0.0.-1.-1.-64.-88.1.102.64.-62.36.-91.80.3.-70.-60.0.0.0.56.49: java.lang.IllegalStateException: Transaction is in invalid state COMMITTED
              at org.hornetq.core.transaction.impl.TransactionImpl.rollback(TransactionImpl.java:345) [hornetq-core-2.2.13.Final.jar:]
              at org.hornetq.core.transaction.impl.ResourceManagerImpl$TxTimeoutHandler.run(ResourceManagerImpl.java:228) [hornetq-core-2.2.13.Final.jar:]
              at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) [rt.jar:1.6.0_07]
              at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317) [rt.jar:1.6.0_07]
              at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150) [rt.jar:1.6.0_07]
              at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98) [rt.jar:1.6.0_07]
              at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:181) [rt.jar:1.6.0_07]
              at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:205) [rt.jar:1.6.0_07]
              at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885) [rt.jar:1.6.0_07]
              at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907) [rt.jar:1.6.0_07]
              at java.lang.Thread.run(Thread.java:619) [rt.jar:1.6.0_07]
    
    
    
  • 2. Re: JMS error message in the log
    mike just Master

    Can anybody help me out of this problem? A lot of such error information stops the application being released. I could not find any documentation about this problem.

  • 3. Re: JMS error message in the log
    mike just Master

    No one has ever experienced such messages?

  • 4. Re: JMS error message in the log
    mike just Master

    I tried not to acknowledge the message after receiving and consuming it and these messages are gone. But why? And if not acknowledge the message, is there any other problem?

     

    p_message.acknowledge(); 
    
  • 5. Re: JMS error message in the log
    Thomas Stark Newbie

    Do you create your JMS Session with mode AUTO_ACKNOWLEDGE?

     

        session = con.createQueueSession(false, Session.AUTO_ACKNOWLEDGE);

     

    Try to use Session.SESSION_TRANSACTED

     

        session = con.createQueueSession(false, Session.SESSION_TRANSACTED);

  • 6. Re: JMS error message in the log
    mike just Master

    Any other code needs to be changed? Just this change is supposed to fix the error?

  • 7. Re: JMS error message in the log
    mike just Master

    I changed to Session.SESSION_TRANSACTED but seems something wrong with the MDB receiver. The MDB cannot receive the message any more. No error in the log. Donot know why.