8 Replies Latest reply on Jul 30, 2014 4:52 AM by npeder

    JMS error message in the log

    ohmygod

      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
          ohmygod

          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
            ohmygod

            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
              ohmygod

              No one has ever experienced such messages?

              • 4. Re: JMS error message in the log
                ohmygod

                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
                  akdabas

                  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
                    ohmygod

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

                    • 7. Re: JMS error message in the log
                      ohmygod

                      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.

                      • 8. Re: JMS error message in the log
                        npeder

                        Session.SESSION_TRANSACTED is not a legal value for QueueConnection.createQueueSession, see http://docs.oracle.com/javaee/6/api/javax/jms/QueueConnection.html and http://docs.oracle.com/javaee/6/api/javax/jms/Session.html#SESSION_TRANSACTED


                        Session.SESSION_TRANSACTED is only used as a return value. If the first parameter of createQueueSession is set to true (meaning the session is transacted), the second parameter will be ignored.