7 Replies Latest reply on Mar 25, 2005 12:29 PM by adrian.brock

    XAException: tx=TransactionImpl:XidImpl [FormatId=257, Globa

    sheckler

      When running high volumes of messages using JBossMQ and JDBC2 (Oracle oci 9.2.0.5, JBoss 3.2.7) persistence the following warning appears. What does it mean? The problem does not occur until the application has been running for an extended period of time. JBoss might slow down and even stop processing.


      2005-02-23 17:05:08,063 10050427 WARN [org.jboss.tm.TransactionImpl] (JMS SessionPool Worker-8:) XAException: tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=rwco_as3//26429, BranchQual=] errorCode=XAER_RMERR
      org.jboss.mq.SpyXAException: - nested throwable: (org.jboss.mq.SpyTransactionRolledBackException: Transaction was rolled back.; - nested throwable: (org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows))
      at org.jboss.mq.SpyXAResource.commit(SpyXAResource.java:88)
      at org.jboss.tm.TransactionImpl.commitResources(TransactionImpl.java:1656)
      at org.jboss.tm.TransactionImpl.commit(TransactionImpl.java:375)
      at org.jboss.tm.TxManager.commit(TxManager.java:142)
      at org.jboss.jms.asf.StdServerSession.onMessage(StdServerSession.java:361)
      at org.jboss.mq.SpyMessageConsumer.sessionConsumerProcessMessage(SpyMessageConsumer.java:904)
      at org.jboss.mq.SpyMessageConsumer.addMessage(SpyMessageConsumer.java:159)
      at org.jboss.mq.SpySession.run(SpySession.java:351)
      at org.jboss.jms.asf.StdServerSession.run0(StdServerSession.java:200)
      at org.jboss.jms.asf.StdServerSession.run(StdServerSession.java:180)
      at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
      at java.lang.Thread.run(Thread.java:534)
      Caused by: org.jboss.mq.SpyTransactionRolledBackException: Transaction was rolled back.; - nested throwable: (org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows)
      at org.jboss.mq.server.JMSDestinationManager.transact(JMSDestinationManager.java:459)
      at org.jboss.mq.server.JMSServerInterceptorSupport.transact(JMSServerInterceptorSupport.java:186)
      at org.jboss.mq.security.ServerSecurityInterceptor.transact(ServerSecurityInterceptor.java:182)
      at org.jboss.mq.server.TracingInterceptor.transact(TracingInterceptor.java:438)
      at org.jboss.mq.server.JMSServerInvoker.transact(JMSServerInvoker.java:186)
      at org.jboss.mq.il.jvm.JVMServerIL.transact(JVMServerIL.java:327)
      at org.jboss.mq.Connection.send(Connection.java:1213)
      at org.jboss.mq.SpyXAResourceManager.commit(SpyXAResourceManager.java:150)
      at org.jboss.mq.SpyXAResource.commit(SpyXAResource.java:84)
      ... 11 more
      Caused by: org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows
      at org.jboss.mq.pm.jdbc2.PersistenceManager.remove(PersistenceManager.java:1062)
      at org.jboss.mq.server.BasicQueue.acknowledge(BasicQueue.java:536)
      at org.jboss.mq.server.JMSQueue.acknowledge(JMSQueue.java:123)
      at org.jboss.mq.server.ClientConsumer.acknowledge(ClientConsumer.java:328)
      at org.jboss.mq.server.JMSDestinationManager.acknowledge(JMSDestinationManager.java:536)
      at org.jboss.mq.server.JMSDestinationManager.transact(JMSDestinationManager.java:448)
      ... 19 more
      2005-02-23 17:05:08,070 10050434 WARN [org.jboss.tm.TransactionImpl] (JMS SessionPool Worker-8:) XAException: tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=rwco_as3//26429, BranchQual=] errorCode=XAER_NOTA
      javax.transaction.xa.XAException
      at org.jboss.mq.SpyXAResourceManager.rollback(SpyXAResourceManager.java:230)
      at org.jboss.mq.SpyXAResource.rollback(SpyXAResource.java:154)
      at org.jboss.tm.TransactionImpl.rollbackResources(TransactionImpl.java:1732)
      at org.jboss.tm.TransactionImpl.commit(TransactionImpl.java:405)
      at org.jboss.tm.TxManager.commit(TxManager.java:142)
      at org.jboss.jms.asf.StdServerSession.onMessage(StdServerSession.java:361)
      at org.jboss.mq.SpyMessageConsumer.sessionConsumerProcessMessage(SpyMessa2stefan.txtgeConsumer.java:904)
      at org.jboss.mq.SpyMessageConsumer.addMessage(SpyMessageConsumer.java:159)
      at org.jboss.mq.SpySession.run(SpySession.java:351)
      at org.jboss.jms.asf.StdServerSession.run0(StdServerSession.java:200)
      at org.jboss.jms.asf.StdServerSession.run(StdServerSession.java:180)
      at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
      at java.lang.Thread.run(Thread.java:534)
      2005-02-23 17:05:08,084 10050448 WARN [org.jboss.tm.TransactionImpl] (JMS SessionPool Worker-8:) Lock contention, tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=rwco_as3//26429, BranchQual=]



      Oracle data source:

      <datasources>
      <local-tx-datasource>
      <jndi-name>OracleJMSDS</jndi-name>
      <connection-url>jdbc:oracle:oci8:@mmi_cmd</connection-url>
      <driver-class>oracle.jdbc.driver.OracleDriver</driver-class>
      <security-domain>OracleDbRealm</security-domain>
      <check-valid-connection-sql>select table_name from all_tables where owner = 'RWCO_HAJMS'</check-valid-connection-sql>
      <new-connection-sql>select table_name from all_tables where owner = 'RWCO_HAJMS'</new-connection-sql>
      <exception-sorter-class-name>org.jboss.resource.adapter.jdbc.vendor.OracleExceptionSorter</exception-sorter-class-name>
      <track-statements>nowarn</track-statements>
      <set-tx-query-timeout>false</set-tx-query-timeout>
      <!--pooling parameters-->
      <min-pool-size>1</min-pool-size>
      <max-pool-size>10</max-pool-size>
      <blocking-timeout-millis>30000</blocking-timeout-millis>
      <idle-timeout-minutes>10</idle-timeout-minutes>
      </local-tx-datasource>
      </datasources>



      Any hint appreciated

      Thanks

      Stefan

        • 1. Re: XAException: tx=TransactionImpl:XidImpl [FormatId=257, G

           


          Caused by: org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows


          JBossMQ is attempting to delete a message that no longer exists.

          This is usually reported when two instances of JBossMQ are sharing the same
          database.
          You might also want to check your Oracle logs for errors/warnings.

          Otherwise, use "READ THIS FIRST" to get the TRACE logging. Then we can
          see what happened to the message it is trying to delete.

          • 2. Re: XAException: tx=TransactionImpl:XidImpl [FormatId=257, G
            sheckler

            Finally I got a log and a trace from the above error. It happens in a clustered environment with all JMS nodes using the same oracle database (oci 9.2.0.5). The process causing the error is running on only one node.

            TRACE

            2005-03-14 11:08:07,160 7600985 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] (JMS SessionPool Worker-6:ID:25) Added message 24416 msg=23894 hard STORED PERSISTENT queue=QUEUE.jms/import/localImportData priority=4 lateClone=false hashCode=14517450 transaction=null
            2005-03-14 11:08:07,162 7600987 TRACE [org.jboss.mq.server.BasicQueue] (JMS SessionPool Worker-6:ID:25) addMessage 24416 msg=23894 hard STORED PERSISTENT queue=QUEUE.jms/import/localImportData priority=4 lateClone=false hashCode=14517450 null org.jboss.mq.server.PersistentQueue@1fa385b{id=QUEUE.jms/import/localImportData}
            2005-03-14 11:08:07,162 7600987 TRACE [org.jboss.mq.server.BasicQueue] (JMS SessionPool Worker-6:ID:25) internalAddMessage 24416 msg=23894 hard STORED PERSISTENT queue=QUEUE.jms/import/localImportData priority=4 lateClone=false hashCode=14517450 org.jboss.mq.server.PersistentQueue@1fa385b{id=QUEUE.jms/import/localImportData}
            2005-03-14 11:08:07,162 7600987 TRACE [org.jboss.mq.server.BasicQueue] (JMS SessionPool Worker-6:ID:25) queueMessageForSending Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] 24416 msg=23894 hard STORED PERSISTENT queue=QUEUE.jms/import/localImportData priority=4 lateClone=false hashCode=14517450 org.jboss.mq.server.PersistentQueue@1fa385b{id=QUEUE.jms/import/localImportData}
            2005-03-14 11:08:07,162 7600987 TRACE [org.jboss.mq.server.TracingInterceptor] (JMS SessionPool Worker-6:ID:25) RETURN : addMessage
            2005-03-14 11:08:07,162 7600987 TRACE [org.jboss.mq.Connection] (Message Pushers-1:) Async deliver requests=[ReceiveRequest[message=ID:25-11107948867693795 subscription=-2147483648] Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]
            2005-03-14 11:08:07,162 7600987 TRACE [org.jboss.mq.Connection] (Message Pushers-1:) Processing request=ReceiveRequest[message=ID:25-11107948867693795 subscription=-2147483648 Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]
            2005-03-14 11:08:07,162 7600987 TRACE [org.jboss.mq.Connection] (Message Pushers-1:) Delivering messageid=0 to consumer=SpyConnectionConsumer[sub=Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] messages=0 waitingForMessage=true internalThread=Thread[Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12,5,jboss] sessionPool=org.jboss.jms.asf.StdServerSessionPool@1a63a1c connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]
            2005-03-14 11:08:07,163 7600988 TRACE [org.jboss.mq.SpyMessageProducer] (JMS SessionPool Worker-6:) Sending message SpyMessageProducer@31981668[ dest=QUEUE.jms/import/localImportData delivery=persist priority=4 ttl=0 disableMessageID=false disableTS=false session=SpySession@30489778[tx=false ack=AUTO txid=null RUNNING connection=Connection@17890740[token=ConnectionToken:ID:25/10ac79217460737e59795fdc61af04c0 rcvstate=STARTED]]]
            org.jboss.mq.SpyObjectMessage {
            Header {
            jmsDestination : QUEUE.jms/import/localImportData
            jmsDeliveryMode : 2
            jmsExpiration : 0
            jmsPriority : 4
            jmsMessageID : ID:25-11107948871623796
            jmsTimeStamp : 1110794887162
            jmsCorrelationID: null
            jmsReplyTo : QUEUE.JMS_TQ7
            jmsType : null
            jmsRedelivered : false
            jmsProperties : {withQuittung=false}
            jmsPropReadWrite: true
            msgReadOnly : false
            producerClientId: ID:25
            }
            }
            2005-03-14 11:08:07,163 7600988 TRACE [org.jboss.jms.asf.StdServerSessionPool] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:) getting a server session
            2005-03-14 11:08:07,163 7600988 TRACE [org.jboss.mq.SpySession] (JMS SessionPool Worker-6:) Sending message to server ID:25-11107948871623796 SpySession@30489778[tx=false ack=AUTO txid=null RUNNING connection=Connection@17890740[token=ConnectionToken:ID:25/10ac79217460737e59795fdc61af04c0 rcvstate=STARTED]]
            2005-03-14 11:08:07,163 7600988 TRACE [org.jboss.jms.asf.StdServerSessionPool] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:) using server session: org.jboss.jms.asf.StdServerSession@f50caf
            2005-03-14 11:08:07,163 7600988 TRACE [org.jboss.mq.Connection] (JMS SessionPool Worker-6:) SendToServer message=ID:25-11107948871623796 Connection@17890740[token=ConnectionToken:ID:25/10ac79217460737e59795fdc61af04c0 rcvstate=STARTED]
            2005-03-14 11:08:07,163 7600988 TRACE [org.jboss.mq.SpySession] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:) Add message msgid=ID:25-11107948867693795 SpySession@3992561[tx=true txid=null XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]
            2005-03-14 11:08:07,163 7600988 TRACE [org.jboss.jms.asf.StdServerSession] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:) starting invokes on server session
            2005-03-14 11:08:07,163 7600988 TRACE [org.jboss.mq.server.TracingInterceptor] (JMS SessionPool Worker-6:ID:25) CALLED : addMessage
            2005-03-14 11:08:07,163 7600988 TRACE [org.jboss.jms.asf.StdServerSession] (JMS SessionPool Worker-8:) running...
            2005-03-14 11:08:07,163 7600988 TRACE [org.jboss.mq.Connection] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:) Receive subscription=Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] wait=-1
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.mq.SpySession] (JMS SessionPool Worker-8:) Run messages=1 SpySession@3992561[tx=true txid=null XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.mq.server.TracingInterceptor] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) CALLED : receive
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.mq.SpySession] (JMS SessionPool Worker-8:) Setting current tx xid=-9223372036854751914 previous: null SpySession@3992561[tx=true txid=null XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.mq.server.TracingInterceptor] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) ARG : -2147483648
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.mq.SpyMessageConsumer] (JMS SessionPool Worker-8:) consumer() acking message in tx message=ID:25-11107948867693795 SpyMessageConsumer@20375865[sub=Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] listening=true receiving=false sessionConsumer=true waitingForMessage=false messages=0 listener=org.jboss.jms.asf.StdServerSession@f50caf session=SpySession@3992561[tx=true txid=-9223372036854751914 XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]]
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.mq.server.TracingInterceptor] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) ARG : -1
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.mq.SpyMessageConsumer] (JMS SessionPool Worker-8:) consumer() before onMessage=ID:25-11107948867693795 SpyMessageConsumer@20375865[sub=Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] listening=true receiving=false sessionConsumer=true waitingForMessage=false messages=0 listener=org.jboss.jms.asf.StdServerSession@f50caf session=SpySession@3992561[tx=true txid=-9223372036854751914 XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]]
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.mq.security.ServerSecurityInterceptor] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) Checking receive authorize on ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee subId=-2147483648
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.jms.asf.StdServerSession] (JMS SessionPool Worker-8:) onMessage running (pool, session, xaSession, useLocalTX): , SpySession@3992561[tx=true txid=-9223372036854751914 XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]], SpySession@3992561[tx=true txid=-9223372036854751914 XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]], false
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.mq.security.SecurityManager] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
            Principal: guest
            Principal: Roles(members:j2ee,guest,john)
            ;principal=null;roles=Roles(members:j2ee,guest,john) for rolePrincipals [guest]
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.mq.SpyXAResource] (JMS SessionPool Worker-8:) Start xid=XidImpl [FormatId=257, GlobalId=rwco_as4//99625, BranchQual=1], flags=0 SpyXAResource[session=SpySession@3992561[tx=true txid=-9223372036854751914 XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]]
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.mq.server.BasicQueue] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) receive Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] wait=false org.jboss.mq.server.PersistentQueue@1fa385b{id=QUEUE.jms/import/localImportData}
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.mq.SpySession] (JMS SessionPool Worker-8:) Setting current tx xid=XidImpl [FormatId=257, GlobalId=rwco_as4//99625, BranchQual=1] previous: -9223372036854751914 SpySession@3992561[tx=true txid=-9223372036854751914 XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.mq.server.TracingInterceptor] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) RETURN : receive
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.jms.asf.StdServerSession] (JMS SessionPool Worker-8:) XAResource 'SpyXAResource[session=SpySession@3992561[tx=true txid=XidImpl [FormatId=257, GlobalId=rwco_as4//99625, BranchQual=1] XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]]' enlisted.
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.mq.Connection] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:) Receive subscription=Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] wait=0
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.ejb.plugins.jms.JMSContainerInvoker] (JMS SessionPool Worker-8:) processing message: org.jboss.mq.SpyObjectMessage {
            Header {
            jmsDestination : QUEUE.jms/import/localImportData
            jmsDeliveryMode : 2
            jmsExpiration : 0
            jmsPriority : 4
            jmsMessageID : ID:25-11107948867693795
            jmsTimeStamp : 1110794886769
            jmsCorrelationID: null
            jmsReplyTo : QUEUE.JMS_TQ7
            jmsType : null
            jmsRedelivered : false
            jmsProperties : {withQuittung=false}
            jmsPropReadWrite: false
            msgReadOnly : true
            producerClientId: ID:25
            }
            }
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.mq.server.TracingInterceptor] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) CALLED : receive
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.mq.server.TracingInterceptor] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) ARG : -2147483648
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.mq.server.TracingInterceptor] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) ARG : 0
            2005-03-14 11:08:07,165 7600990 TRACE [org.jboss.mq.security.ServerSecurityInterceptor] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) Checking receive authorize on ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee subId=-2147483648
            2005-03-14 11:08:07,166 7600991 TRACE [org.jboss.mq.security.SecurityManager] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
            Principal: guest
            Principal: Roles(members:j2ee,guest,john)
            ;principal=null;roles=Roles(members:j2ee,guest,john) for rolePrincipals [guest]
            2005-03-14 11:08:07,166 7600991 TRACE [org.jboss.mq.server.BasicQueue] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) receive Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] wait=true org.jboss.mq.server.PersistentQueue@1fa385b{id=QUEUE.jms/import/localImportData}
            2005-03-14 11:08:07,166 7600991 TRACE [org.jboss.mq.server.BasicQueue] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) addReceiver Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] org.jboss.mq.server.PersistentQueue@1fa385b{id=QUEUE.jms/import/localImportData}
            2005-03-14 11:08:07,166 7600991 TRACE [org.jboss.mq.server.TracingInterceptor] (Connection Consumer for dest Subscription[subId=-2147483648connection=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee destination=QUEUE.jms/import/localImportData messageSelector=null Local Create] id=12:ID:5) RETURN : receive
            2005-03-14 11:08:07,164 7600989 TRACE [org.jboss.mq.server.TracingInterceptor] (JMS SessionPool Worker-6:ID:25) ARG : org.jboss.mq.SpyObjectMessage {
            Header {
            jmsDestination : QUEUE.jms/import/localImportData
            jmsDeliveryMode : 2
            jmsExpiration : 0
            jmsPriority : 4
            jmsMessageID : ID:25-11107948871623796
            jmsTimeStamp : 1110794887162
            jmsCorrelationID: null
            jmsReplyTo : QUEUE.JMS_TQ7
            jmsType : null
            jmsRedelivered : false
            jmsProperties : {withQuittung=false}
            jmsPropReadWrite: true
            msgReadOnly : false
            producerClientId: ID:25
            }
            }
            2005-03-14 11:08:07,166 7600991 TRACE [org.jboss.mq.security.SecurityManager] (JMS SessionPool Worker-6:ID:25) Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
            Principal: guest
            Principal: Roles(members:j2ee,guest,john)
            ;principal=null;roles=Roles(members:j2ee,guest,john) for rolePrincipals [guest]
            2005-03-14 11:08:07,166 7600991 TRACE [org.jboss.mq.server.MessageCache] (JMS SessionPool Worker-6:ID:25) Memory usage serverity=1.0
            2005-03-14 11:08:07,166 7600991 TRACE [org.jboss.mq.server.MessageCache] (JMS SessionPool Worker-6:ID:25) Need to soften 3 messages
            2005-03-14 11:08:07,166 7600991 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] (JMS SessionPool Worker-6:ID:25) About to add message 24417 msg=23895 hard NOT_STORED PERSISTENT queue=QUEUE.jms/import/localImportData priority=4 lateClone=false hashCode=8560399 transaction=null
            2005-03-14 11:08:07,170 7600995 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] (JMS SessionPool Worker-6:ID:25) Inserting message 24417 msg=23895 hard NOT_STORED PERSISTENT queue=QUEUE.jms/import/localImportData priority=4 lateClone=false hashCode=8560399 transaction=null
            2005-03-14 11:08:07,180 7601005 TRACE [org.jboss.jms.asf.StdServerSession] (JMS SessionPool Worker-8:) Commiting the JMS transaction
            2005-03-14 11:08:07,184 7601009 TRACE [org.jboss.mq.SpySession] (JMS SessionPool Worker-8:) Unsetting current tx xid=XidImpl [FormatId=257, GlobalId=rwco_as4//99625, BranchQual=1] previous: XidImpl [FormatId=257, GlobalId=rwco_as4//99625, BranchQual=1] SpySession@3992561[tx=true txid=XidImpl [FormatId=257, GlobalId=rwco_as4//99625, BranchQual=1] XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]
            2005-03-14 11:08:07,184 7601009 TRACE [org.jboss.mq.SpyXAResource] (JMS SessionPool Worker-8:) Prepare xid=XidImpl [FormatId=257, GlobalId=rwco_as4//99625, BranchQual=1] SpyXAResource[session=SpySession@3992561[tx=true txid=null XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]]
            2005-03-14 11:08:07,185 7601010 TRACE [org.jboss.mq.Connection] (JMS SessionPool Worker-8:) Transact request=org.jboss.mq.TransactionRequest@12fd015 Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]
            2005-03-14 11:08:07,185 7601010 TRACE [org.jboss.mq.server.TracingInterceptor] (JMS SessionPool Worker-8:ID:5) CALLED : transact
            2005-03-14 11:08:07,185 7601010 TRACE [org.jboss.mq.server.TracingInterceptor] (JMS SessionPool Worker-8:ID:5) ARG : org.jboss.mq.TransactionRequest@12fd015
            2005-03-14 11:08:07,185 7601010 TRACE [org.jboss.mq.server.BasicQueue] (JMS SessionPool Worker-8:ID:5) acknowledge AcknowledgementRequest:ACK,QUEUE.jms/import/localImportData,ID:25-11107948867693795 24213 org.jboss.mq.server.PersistentQueue@1fa385b{id=QUEUE.jms/import/localImportData}
            2005-03-14 11:08:07,185 7601010 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] (JMS SessionPool Worker-8:ID:5) Removing message 24416 msg=23894 hard STORED PERSISTENT queue=QUEUE.jms/import/localImportData priority=4 lateClone=false hashCode=14517450 transaction=24213
            2005-03-14 11:08:07,198 7601023 DEBUG [org.jboss.mq.server.JMSDestinationManager] (JMS SessionPool Worker-8:ID:5) Exception occured, rolling back transaction:
            org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows
            at org.jboss.mq.pm.jdbc2.PersistenceManager.remove(PersistenceManager.java:1062)
            at org.jboss.mq.server.BasicQueue.acknowledge(BasicQueue.java:536)
            at org.jboss.mq.server.JMSQueue.acknowledge(JMSQueue.java:123)
            at org.jboss.mq.server.ClientConsumer.acknowledge(ClientConsumer.java:328)
            at org.jboss.mq.server.JMSDestinationManager.acknowledge(JMSDestinationManager.java:536)
            at org.jboss.mq.server.JMSDestinationManager.transact(JMSDestinationManager.java:481)
            at org.jboss.mq.server.JMSServerInterceptorSupport.transact(JMSServerInterceptorSupport.java:186)
            at org.jboss.mq.security.ServerSecurityInterceptor.transact(ServerSecurityInterceptor.java:182)
            at org.jboss.mq.server.TracingInterceptor.transact(TracingInterceptor.java:447)
            at org.jboss.mq.server.JMSServerInvoker.transact(JMSServerInvoker.java:186)
            at org.jboss.mq.il.jvm.JVMServerIL.transact(JVMServerIL.java:327)
            at org.jboss.mq.Connection.send(Connection.java:1213)
            at org.jboss.mq.SpyXAResourceManager.prepare(SpyXAResourceManager.java:208)
            at org.jboss.mq.SpyXAResource.prepare(SpyXAResource.java:131)
            at org.jboss.tm.TransactionImpl.prepareResources(TransactionImpl.java:1556)
            at org.jboss.tm.TransactionImpl.commit(TransactionImpl.java:385)
            at org.jboss.tm.TxManager.commit(TxManager.java:142)
            at org.jboss.jms.asf.StdServerSession.onMessage(StdServerSession.java:361)
            at org.jboss.mq.SpyMessageConsumer.sessionConsumerProcessMessage(SpyMessageConsumer.java:904)
            at org.jboss.mq.SpyMessageConsumer.addMessage(SpyMessageConsumer.java:159)
            at org.jboss.mq.SpySession.run(SpySession.java:351)
            at org.jboss.jms.asf.StdServerSession.run0(StdServerSession.java:200)
            at org.jboss.jms.asf.StdServerSession.run(StdServerSession.java:180)
            at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
            at java.lang.Thread.run(Thread.java:534)
            2005-03-14 11:08:07,209 7601034 TRACE [org.jboss.mq.server.TracingInterceptor] (JMS SessionPool Worker-8:ID:5) EXCEPTION : transact:
            org.jboss.mq.SpyTransactionRolledBackException: Transaction was rolled back.; - nested throwable: (org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows)
            at org.jboss.mq.server.JMSDestinationManager.transact(JMSDestinationManager.java:490)
            at org.jboss.mq.server.JMSServerInterceptorSupport.transact(JMSServerInterceptorSupport.java:186)
            at org.jboss.mq.security.ServerSecurityInterceptor.transact(ServerSecurityInterceptor.java:182)
            at org.jboss.mq.server.TracingInterceptor.transact(TracingInterceptor.java:447)
            at org.jboss.mq.server.JMSServerInvoker.transact(JMSServerInvoker.java:186)
            at org.jboss.mq.il.jvm.JVMServerIL.transact(JVMServerIL.java:327)
            at org.jboss.mq.Connection.send(Connection.java:1213)
            at org.jboss.mq.SpyXAResourceManager.prepare(SpyXAResourceManager.java:208)
            at org.jboss.mq.SpyXAResource.prepare(SpyXAResource.java:131)
            at org.jboss.tm.TransactionImpl.prepareResources(TransactionImpl.java:1556)
            at org.jboss.tm.TransactionImpl.commit(TransactionImpl.java:385)
            at org.jboss.tm.TxManager.commit(TxManager.java:142)
            at org.jboss.jms.asf.StdServerSession.onMessage(StdServerSession.java:361)
            at org.jboss.mq.SpyMessageConsumer.sessionConsumerProcessMessage(SpyMessageConsumer.java:904)
            at org.jboss.mq.SpyMessageConsumer.addMessage(SpyMessageConsumer.java:159)
            at org.jboss.mq.SpySession.run(SpySession.java:351)
            at org.jboss.jms.asf.StdServerSession.run0(StdServerSession.java:200)
            at org.jboss.jms.asf.StdServerSession.run(StdServerSession.java:180)
            at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
            at java.lang.Thread.run(Thread.java:534)
            Caused by: org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows
            at org.jboss.mq.pm.jdbc2.PersistenceManager.remove(PersistenceManager.java:1062)
            at org.jboss.mq.server.BasicQueue.acknowledge(BasicQueue.java:536)
            at org.jboss.mq.server.JMSQueue.acknowledge(JMSQueue.java:123)
            at org.jboss.mq.server.ClientConsumer.acknowledge(ClientConsumer.java:328)
            at org.jboss.mq.server.JMSDestinationManager.acknowledge(JMSDestinationManager.java:536)
            at org.jboss.mq.server.JMSDestinationManager.transact(JMSDestinationManager.java:481)
            ... 19 more
            2005-03-14 11:08:07,210 7601035 TRACE [org.jboss.mq.server.TracingInterceptor] (JMS SessionPool Worker-8:ID:5) RETURN : transact
            2005-03-14 11:08:07,212 7601037 TRACE [org.jboss.mq.SpyXAResource] (JMS SessionPool Worker-8:) Rollback xid=XidImpl [FormatId=257, GlobalId=rwco_as4//99625, BranchQual=1] SpyXAResource[session=SpySession@3992561[tx=true txid=null XA RUNNING connection=Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]]]
            2005-03-14 11:08:07,212 7601037 TRACE [org.jboss.mq.Connection] (JMS SessionPool Worker-8:) Transact request=org.jboss.mq.TransactionRequest@1885a98 Connection@7266357[token=ConnectionToken:ID:5/89ba4a92346c9c1a5d806827a1b641ee rcvstate=STARTED]
            2005-03-14 11:08:07,212 7601037 TRACE [org.jboss.mq.server.TracingInterceptor] (JMS SessionPool Worker-8:ID:5) CALLED : transact
            2005-03-14 11:08:07,212 7601037 TRACE [org.jboss.mq.server.TracingInterceptor] (JMS SessionPool Worker-8:ID:5) ARG : org.jboss.mq.TransactionRequest@1885a98
            2005-03-14 11:08:07,212 7601037 TRACE [org.jboss.mq.server.BasicQueue] (JMS SessionPool Worker-8:ID:5) acknowledge AcknowledgementRequest:NACK,QUEUE.jms/import/localImportData,ID:25-11107948867693795 24214 org.jboss.mq.server.PersistentQueue@1fa385b{id=QUEUE.jms/import/localImportData}
            2005-03-14 11:08:07,212 7601037 TRACE [org.jboss.mq.server.TracingInterceptor] (JMS SessionPool Worker-8:ID:5) RETURN : transact
            2005-03-14 11:08:07,216 7601041 ERROR [org.jboss.jms.asf.StdServerSession] (JMS SessionPool Worker-8:) failed to commit/rollback
            org.jboss.tm.JBossRollbackException: Unable to commit, tx=TransactionImpl:XidImpl [FormatId=257, GlobalId=rwco_as4//99625, BranchQual=] status=STATUS_NO_TRANSACTION; - nested throwable: (org.jboss.mq.SpyXAException: - nested throwable: (org.jboss.mq.SpyTransactionRolledBackException: Transaction was rolled back.; - nested throwable: (org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows)))
            at org.jboss.tm.TransactionImpl.commit(TransactionImpl.java:415)
            at org.jboss.tm.TxManager.commit(TxManager.java:142)
            at org.jboss.jms.asf.StdServerSession.onMessage(StdServerSession.java:361)
            at org.jboss.mq.SpyMessageConsumer.sessionConsumerProcessMessage(SpyMessageConsumer.java:904)
            at org.jboss.mq.SpyMessageConsumer.addMessage(SpyMessageConsumer.java:159)
            at org.jboss.mq.SpySession.run(SpySession.java:351)
            at org.jboss.jms.asf.StdServerSession.run0(StdServerSession.java:200)
            at org.jboss.jms.asf.StdServerSession.run(StdServerSession.java:180)
            at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
            at java.lang.Thread.run(Thread.java:534)
            Caused by: org.jboss.mq.SpyXAException: - nested throwable: (org.jboss.mq.SpyTransactionRolledBackException: Transaction was rolled back.; - nested throwable: (org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows))
            at org.jboss.mq.SpyXAResource.prepare(SpyXAResource.java:135)
            at org.jboss.tm.TransactionImpl.prepareResources(TransactionImpl.java:1556)
            at org.jboss.tm.TransactionImpl.commit(TransactionImpl.java:385)
            ... 9 more
            Caused by: org.jboss.mq.SpyTransactionRolledBackException: Transaction was rolled back.; - nested throwable: (org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows)
            at org.jboss.mq.server.JMSDestinationManager.transact(JMSDestinationManager.java:490)
            at org.jboss.mq.server.JMSServerInterceptorSupport.transact(JMSServerInterceptorSupport.java:186)
            at org.jboss.mq.security.ServerSecurityInterceptor.transact(ServerSecurityInterceptor.java:182)
            at org.jboss.mq.server.TracingInterceptor.transact(TracingInterceptor.java:447)
            at org.jboss.mq.server.JMSServerInvoker.transact(JMSServerInvoker.java:186)
            at org.jboss.mq.il.jvm.JVMServerIL.transact(JVMServerIL.java:327)
            at org.jboss.mq.Connection.send(Connection.java:1213)
            at org.jboss.mq.SpyXAResourceManager.prepare(SpyXAResourceManager.java:208)
            at org.jboss.mq.SpyXAResource.prepare(SpyXAResource.java:131)
            ... 11 more
            Caused by: org.jboss.mq.SpyJMSException: Could not mark the message as deleted in the database: update affected 0 rows
            at org.jboss.mq.pm.jdbc2.PersistenceManager.remove(PersistenceManager.java:1062)
            at org.jboss.mq.server.BasicQueue.acknowledge(BasicQueue.java:536)
            at org.jboss.mq.server.JMSQueue.acknowledge(JMSQueue.java:123)
            at org.jboss.mq.server.ClientConsumer.acknowledge(ClientConsumer.java:328)
            at org.jboss.mq.server.JMSDestinationManager.acknowledge(JMSDestinationManager.java:536)
            at org.jboss.mq.server.JMSDestinationManager.transact(JMSDestinationManager.java:481)
            ... 19 more


            • 3. Re: XAException: tx=TransactionImpl:XidImpl [FormatId=257, G

               

              "adrian@jboss.org" wrote:
              This is usually reported when two instances of JBossMQ are sharing the same database.


              They must not.


              • 4. Re: XAException: tx=TransactionImpl:XidImpl [FormatId=257, G
                sheckler

                Ok, I will check this. Thanks Adrian

                • 5. Re: XAException: tx=TransactionImpl:XidImpl [FormatId=257, G
                  sheckler

                  Yes there are two instances of JBossMQ sharing the same tablesm (1 HA JMS, 1 local JMS. I now use Hypersonic for the local JMS and one oracle scheme for the HA JMS) . Since I fixed this misconfiguration, the error did not occure again. Thanks
                  Stefan
                  sheckler@psi.de

                  • 6. Re: XAException: tx=TransactionImpl:XidImpl [FormatId=257, G
                    bonanza4000

                    I'm experiencing the same problem. But the situation is that two physically separate instances of our Business Tier (where each instance of our business tier contains a single MDB that reads and writes to the same Queue) are running concurrently, for redundancy and throughput reasons. And fwiw, we're not using clustering.

                    Should I interpret your response to the previous post ("They cannot.") as indicating that this is not allowed? Are you saying that we cannot have two MDBs writing to and listening on the same Queue? So can you help meunderstand what the work around would be?

                    Thank you for your help. I'm a little new to JMS.

                    • 7. Re: XAException: tx=TransactionImpl:XidImpl [FormatId=257, G

                      Moderated: What does "READ THIS FIRST" say about hijacking/ME TOO

                      "bonanza4000" wrote:
                      Are you saying that we cannot have two MDBs writing to and listening on the same Queue?


                      Just to avoid the blind leading the blind; the original question is about sharing the
                      persistent store NOT queues.

                      Thank you for your help. I'm a little new to JMS.


                      Then use the beginner's forum.