3 Replies Latest reply on Oct 15, 2008 6:52 PM by Clebert Suconic

    JBossMQ errors in performance tests

    Sergey Koshcheyev Newbie

      I'm running Throughput-INVM performance test using JDK 1.4.2_12 on Windows XP SP2 and I'm getting these errors:

      2007-05-02 19:01:59,140 DEBUG [org.jboss.mq.il.uil2.SocketManager] Failed to handle: org.jboss.mq.il.uil2.msgs.CloseMsg4615122[msgType: m_connectionClosing, msgID: 189760, error: null]
      java.io.IOException: Client is not connected
       at org.jboss.mq.il.uil2.SocketManager.internalSendMessage(SocketManager.java:264)
       at org.jboss.mq.il.uil2.SocketManager.sendReply(SocketManager.java:238)
       at org.jboss.mq.il.uil2.ServerSocketManagerHandler.handleMsg(ServerSocketManagerHandler.java:131)
       at org.jboss.mq.il.uil2.SocketManager$ReadTask.handleMsg(SocketManager.java:395)
       at org.jboss.mq.il.uil2.msgs.BaseMsg.run(BaseMsg.java:398)
       at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
       at java.lang.Thread.run(Thread.java:534)
      2007-05-02 19:01:59,140 DEBUG [org.jboss.mq.il.uil2.SocketManager] Failed to send error reply
      java.io.IOException: Client is not connected
       at org.jboss.mq.il.uil2.SocketManager.internalSendMessage(SocketManager.java:264)
       at org.jboss.mq.il.uil2.SocketManager.access$800(SocketManager.java:53)
       at org.jboss.mq.il.uil2.SocketManager$ReadTask.handleMsg(SocketManager.java:408)
       at org.jboss.mq.il.uil2.msgs.BaseMsg.run(BaseMsg.java:398)
       at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
       at java.lang.Thread.run(Thread.java:534)
      2007-05-02 19:01:59,140 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Exiting on IOE
      java.net.SocketException: Socket closed
       at java.net.SocketInputStream.read(SocketInputStream.java:162)
       at java.io.BufferedInputStream.fill(BufferedInputStream.java:183)
       at java.io.BufferedInputStream.read(BufferedInputStream.java:201)
       at org.jboss.util.stream.NotifyingBufferedInputStream.read(NotifyingBufferedInputStream.java:79)
       at java.io.ObjectInputStream$PeekInputStream.peek(ObjectInputStream.java:2123)
       at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(ObjectInputStream.java:2303)
       at java.io.ObjectInputStream$BlockDataInputStream.refill(ObjectInputStream.java:2370)
       at java.io.ObjectInputStream$BlockDataInputStream.read(ObjectInputStream.java:2442)
       at java.io.ObjectInputStream$BlockDataInputStream.readByte(ObjectInputStream.java:2591)
       at java.io.ObjectInputStream.readByte(ObjectInputStream.java:845)
       at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:316)
       at java.lang.Thread.run(Thread.java:534)
      


      After lots of the errors similar to those above, there comes a new kind of errors:
      2007-05-02 19:04:12,250 DEBUG [org.jboss.jms.perf.framework.protocol.ReceiveJob] session SpySession@29586713[tx=false ack=AUTO txid=null RUNNING connection=Connection@20768318[token=ConnectionToken:ID:63/b64a67e9af85014ede3698d664233a55 rcvstate=STARTED]] created
      2007-05-02 19:04:12,250 DEBUG [org.jboss.jms.perf.framework.protocol.SendJob] start sending using sender job
       JNDI properties
       java.naming.factory.initial: org.jnp.interfaces.NamingContextFactory
       java.naming.provider.url: jnp://localhost:1199
       java.naming.factory.url.pkg: org.jboss.naming:org.jnp.interfaces
       destination name: /queue/simpleQueue
       connection factory name: /ConnectionFactory
       transacted: false
       message factory class: org.jboss.jms.perf.framework.factories.MessageMessageFactory
       delivery mode: NON_PERSISTENT
       message size: 0 bytes
       message count: 0
       duration: 30000
       rate: 9000 messages/second
      2007-05-02 19:04:12,250 DEBUG [org.jboss.jms.perf.framework.protocol.ThroughputJobSupport] initialized RECEIVE JOB
      2007-05-02 19:04:12,250 DEBUG [org.jboss.jms.perf.framework.protocol.ThroughputJobSupport] RECEIVE JOB runs test with 1 parallel sessions
      2007-05-02 19:04:12,250 DEBUG [org.jboss.jms.perf.framework.protocol.ReceiveJob] start receiving using receiver job
       JNDI properties
       java.naming.factory.initial: org.jnp.interfaces.NamingContextFactory
       java.naming.provider.url: jnp://localhost:1199
       java.naming.factory.url.pkg: org.jboss.naming:org.jnp.interfaces
       destination name: /queue/simpleQueue
       connection factory name: /ConnectionFactory
       transacted: false
       acknowledgmentMode: AUTO_ACKNOWLEDGE
       message size: 0 bytes
       message count: 0
       duration: 30000
      
      2007-05-02 19:04:36,109 ERROR [STDERR] java.io.IOException
      2007-05-02 19:04:36,109 ERROR [STDERR] at java.nio.MappedByteBuffer.force0(Native Method)
      2007-05-02 19:04:36,109 ERROR [STDERR] at java.nio.MappedByteBuffer.force(MappedByteBuffer.java:138)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.NIOScaledRAFile.enlargeBuffer(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.NIOScaledRAFile.seek(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.DataFileCache.saveRow(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.DataFileCache.saveRows(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.Cache.saveRows(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.Cache.saveAll(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.DataFileCache.close(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.Log.checkpoint(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.Log.writeInsertStatement(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.Logger.writeInsertStatement(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.Table.insertNoCheck(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.Table.insertRow(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.Table.insert(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.CompiledStatementExecutor.executeInsertValuesStatement(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.CompiledStatementExecutor.executeImpl(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.CompiledStatementExecutor.execute(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.Session.sqlExecute(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.Session.execute(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.jdbc.jdbcPreparedStatement.executeUpdate(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.resource.adapter.jdbc.CachedPreparedStatement.executeUpdate(CachedPreparedStatement.java:95)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.pm.jdbc2.PersistenceManager.add(PersistenceManager.java:1136)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.pm.jdbc2.PersistenceManager.saveToStorage(PersistenceManager.java:1632)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.MessageCache.saveToStorage(MessageCache.java:420)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.MessageReference.makeSoft(MessageReference.java:312)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.MessageCache.validateSoftReferenceDepth(MessageCache.java:367)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.MessageCache.addInternal(MessageCache.java:158)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.MessageCache.add(MessageCache.java:128)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.JMSQueue.addMessage(JMSQueue.java:179)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.JMSDestinationManager.addMessage(JMSDestinationManager.java:415)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.JMSDestinationManager.addMessage(JMSDestinationManager.java:399)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.JMSServerInterceptorSupport.addMessage(JMSServerInterceptorSupport.java:106)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.security.ServerSecurityInterceptor.addMessage(ServerSecurityInterceptor.java:168)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.TracingInterceptor.addMessage(TracingInterceptor.java:226)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.JMSServerInvoker.addMessage(JMSServerInvoker.java:112)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.il.uil2.ServerSocketManagerHandler.handleMsg(ServerSocketManagerHandler.java:111)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.il.uil2.SocketManager$ReadTask.handleMsg(SocketManager.java:395)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.il.uil2.msgs.BaseMsg.run(BaseMsg.java:398)
      2007-05-02 19:04:36,109 ERROR [STDERR] at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
      2007-05-02 19:04:36,109 ERROR [STDERR] at java.lang.Thread.run(Thread.java:534)
      2007-05-02 19:04:36,109 DEBUG [org.jboss.util.NestedThrowable] org.jboss.util.NestedThrowable.parentTraceEnabled=true
      2007-05-02 19:04:36,109 DEBUG [org.jboss.util.NestedThrowable] org.jboss.util.NestedThrowable.nestedTraceEnabled=false
      2007-05-02 19:04:36,109 DEBUG [org.jboss.util.NestedThrowable] org.jboss.util.NestedThrowable.detectDuplicateNesting=true
      2007-05-02 19:04:36,109 ERROR [STDERR] java.lang.NullPointerException
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.scriptio.ScriptWriterText.writeDeleteStatement(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.Log.writeDeleteStatement(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.persist.Logger.writeDeleteStatement(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.Table.deleteNoCheck(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.Table.deleteNoRefCheck(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.Table.delete(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.CompiledStatementExecutor.executeDeleteStatement(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.CompiledStatementExecutor.executeImpl(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.CompiledStatementExecutor.execute(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.Session.sqlExecute(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.Session.execute(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.hsqldb.jdbc.jdbcPreparedStatement.executeUpdate(Unknown Source)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.resource.adapter.jdbc.CachedPreparedStatement.executeUpdate(CachedPreparedStatement.java:95)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.pm.jdbc2.PersistenceManager.removeFromStorage(PersistenceManager.java:1576)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.MessageCache.removeFromStorage(MessageCache.java:425)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.MessageReference.clear(MessageReference.java:267)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.MessageCache.removeInternal(MessageCache.java:212)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.MessageCache.remove(MessageCache.java:169)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.BasicQueue$RemoveMessageTask.run(BasicQueue.java:1403)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.pm.TxManager.addPostCommitTask(TxManager.java:162)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.BasicQueue.performOrPrepareAcknowledgeMessage(BasicQueue.java:790)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.BasicQueue.acknowledge(BasicQueue.java:761)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.JMSQueue.acknowledge(JMSQueue.java:172)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.ClientConsumer.acknowledge(ClientConsumer.java:334)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.JMSDestinationManager.acknowledge(JMSDestinationManager.java:514)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.JMSDestinationManager.acknowledge(JMSDestinationManager.java:507)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.JMSServerInterceptorSupport.acknowledge(JMSServerInterceptorSupport.java:131)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.TracingInterceptor.acknowledge(TracingInterceptor.java:385)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.server.JMSServerInvoker.acknowledge(JMSServerInvoker.java:137)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.il.uil2.ServerSocketManagerHandler.handleMsg(ServerSocketManagerHandler.java:105)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.il.uil2.SocketManager$ReadTask.handleMsg(SocketManager.java:395)
      2007-05-02 19:04:36,109 ERROR [STDERR] at org.jboss.mq.il.uil2.msgs.BaseMsg.run(BaseMsg.java:398)
      2007-05-02 19:04:36,109 ERROR [STDERR] at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
      2007-05-02 19:04:36,109 ERROR [STDERR] at java.lang.Thread.run(Thread.java:534)
      2007-05-02 19:04:36,125 ERROR [org.jboss.mq.server.MessageCache] Message Cache Thread Stopped:
      org.jboss.mq.SpyJMSException: Could not store message: 753142 msg=583141 hard NOT_STORED NON_PERSISTENT queue=QUEUE.simpleQueue priority=4 lateClone=false hashCode=17705154; - nested throwable: (java.sql.SQLException: Unique constraint violation: in statement [INSERT INTO JMS_MESSAGES (MESSAGEID, DESTINATION, MESSAGEBLOB, TXID, TXOP) VALUES(?,?,?,?,?)])
       at org.jboss.mq.pm.jdbc2.PersistenceManager.saveToStorage(PersistenceManager.java:1646)
       at org.jboss.mq.server.MessageCache.saveToStorage(MessageCache.java:420)
       at org.jboss.mq.server.MessageReference.makeSoft(MessageReference.java:312)
       at org.jboss.mq.server.MessageCache.validateSoftReferenceDepth(MessageCache.java:367)
       at org.jboss.mq.server.MessageCache.run(MessageCache.java:274)
       at java.lang.Thread.run(Thread.java:534)
      Caused by: java.sql.SQLException: Unique constraint violation: in statement [INSERT INTO JMS_MESSAGES (MESSAGEID, DESTINATION, MESSAGEBLOB, TXID, TXOP) VALUES(?,?,?,?,?)]
       at org.hsqldb.jdbc.Util.throwError(Unknown Source)
       at org.hsqldb.jdbc.jdbcPreparedStatement.executeUpdate(Unknown Source)
       at org.jboss.resource.adapter.jdbc.CachedPreparedStatement.executeUpdate(CachedPreparedStatement.java:95)
       at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251)
       at org.jboss.mq.pm.jdbc2.PersistenceManager.add(PersistenceManager.java:1136)
       at org.jboss.mq.pm.jdbc2.PersistenceManager.saveToStorage(PersistenceManager.java:1632)
       ... 5 more
      2007-05-02 19:04:36,125 DEBUG [org.jboss.mq.server.MessageCache] Thread exiting.
      2007-05-02 19:04:36,125 ERROR [org.jboss.mq.server.BasicQueue] Could not remove an acknowleged message from the message cache:
      org.jboss.mq.SpyJMSException: Could not remove message: 726918 msg=556917 hard STORED NON_PERSISTENT queue=QUEUE.simpleQueue priority=4 lateClone=false hashCode=11133785; - nested throwable: (java.sql.SQLException: S1000 General error java.lang.NullPointerException in statement [DELETE FROM JMS_MESSAGES WHERE MESSAGEID=? AND DESTINATION=?])
       at org.jboss.mq.pm.jdbc2.PersistenceManager.removeFromStorage(PersistenceManager.java:1585)
       at org.jboss.mq.server.MessageCache.removeFromStorage(MessageCache.java:425)
       at org.jboss.mq.server.MessageReference.clear(MessageReference.java:267)
       at org.jboss.mq.server.MessageCache.removeInternal(MessageCache.java:212)
       at org.jboss.mq.server.MessageCache.remove(MessageCache.java:169)
       at org.jboss.mq.server.BasicQueue$RemoveMessageTask.run(BasicQueue.java:1403)
       at org.jboss.mq.pm.TxManager.addPostCommitTask(TxManager.java:162)
       at org.jboss.mq.server.BasicQueue.performOrPrepareAcknowledgeMessage(BasicQueue.java:790)
       at org.jboss.mq.server.BasicQueue.acknowledge(BasicQueue.java:761)
       at org.jboss.mq.server.JMSQueue.acknowledge(JMSQueue.java:172)
       at org.jboss.mq.server.ClientConsumer.acknowledge(ClientConsumer.java:334)
       at org.jboss.mq.server.JMSDestinationManager.acknowledge(JMSDestinationManager.java:514)
       at org.jboss.mq.server.JMSDestinationManager.acknowledge(JMSDestinationManager.java:507)
       at org.jboss.mq.server.JMSServerInterceptorSupport.acknowledge(JMSServerInterceptorSupport.java:131)
       at org.jboss.mq.server.TracingInterceptor.acknowledge(TracingInterceptor.java:385)
       at org.jboss.mq.server.JMSServerInvoker.acknowledge(JMSServerInvoker.java:137)
       at org.jboss.mq.il.uil2.ServerSocketManagerHandler.handleMsg(ServerSocketManagerHandler.java:105)
       at org.jboss.mq.il.uil2.SocketManager$ReadTask.handleMsg(SocketManager.java:395)
       at org.jboss.mq.il.uil2.msgs.BaseMsg.run(BaseMsg.java:398)
       at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
       at java.lang.Thread.run(Thread.java:534)
      Caused by: java.sql.SQLException: S1000 General error java.lang.NullPointerException in statement [DELETE FROM JMS_MESSAGES WHERE MESSAGEID=? AND DESTINATION=?]
       at org.hsqldb.jdbc.Util.throwError(Unknown Source)
       at org.hsqldb.jdbc.jdbcPreparedStatement.executeUpdate(Unknown Source)
       at org.jboss.resource.adapter.jdbc.CachedPreparedStatement.executeUpdate(CachedPreparedStatement.java:95)
       at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251)
       at org.jboss.mq.pm.jdbc2.PersistenceManager.removeFromStorage(PersistenceManager.java:1576)
       ... 20 more
      2007-05-02 19:04:36,125 ERROR [STDERR] java.lang.NullPointerException
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.scriptio.ScriptWriterText.writeDeleteStatement(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.persist.Log.writeDeleteStatement(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.persist.Logger.writeDeleteStatement(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.Table.deleteNoCheck(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.Table.deleteNoRefCheck(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.Table.delete(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.CompiledStatementExecutor.executeDeleteStatement(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.CompiledStatementExecutor.executeImpl(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.CompiledStatementExecutor.execute(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.Session.sqlExecute(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.Session.execute(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.hsqldb.jdbc.jdbcPreparedStatement.executeUpdate(Unknown Source)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.resource.adapter.jdbc.CachedPreparedStatement.executeUpdate(CachedPreparedStatement.java:95)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.pm.jdbc2.PersistenceManager.removeFromStorage(PersistenceManager.java:1576)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.MessageCache.removeFromStorage(MessageCache.java:425)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.MessageReference.clear(MessageReference.java:267)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.MessageCache.removeInternal(MessageCache.java:212)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.MessageCache.remove(MessageCache.java:169)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.BasicQueue$RemoveMessageTask.run(BasicQueue.java:1403)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.pm.TxManager.addPostCommitTask(TxManager.java:162)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.BasicQueue.performOrPrepareAcknowledgeMessage(BasicQueue.java:790)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.BasicQueue.acknowledge(BasicQueue.java:761)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.JMSQueue.acknowledge(JMSQueue.java:172)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.ClientConsumer.acknowledge(ClientConsumer.java:334)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.JMSDestinationManager.acknowledge(JMSDestinationManager.java:514)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.JMSDestinationManager.acknowledge(JMSDestinationManager.java:507)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.JMSServerInterceptorSupport.acknowledge(JMSServerInterceptorSupport.java:131)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.TracingInterceptor.acknowledge(TracingInterceptor.java:385)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.server.JMSServerInvoker.acknowledge(JMSServerInvoker.java:137)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.il.uil2.ServerSocketManagerHandler.handleMsg(ServerSocketManagerHandler.java:105)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.il.uil2.SocketManager$ReadTask.handleMsg(SocketManager.java:395)
      2007-05-02 19:04:36,125 ERROR [STDERR] at org.jboss.mq.il.uil2.msgs.BaseMsg.run(BaseMsg.java:398)
      2007-05-02 19:04:36,125 ERROR [STDERR] at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
      2007-05-02 19:04:36,125 ERROR [STDERR] at java.lang.Thread.run(Thread.java:534)
      


      What can this caused by? Is this JBoss MQ dying because of HSQLDB or some misconfiguration or something else?

        • 1. Re: JBossMQ errors in performance tests
          Tim Fox Master

          I have seen this before when JBossMQ gets overwhelmed and starts trying to save "softened" references to disk and fails.

          I assumed it was a bug with JBossMQ - i.e. JBossMQ folds under pressure. In these cases you just mark it as JBosMQ can't deal with the throughput.

          • 2. Re: JBossMQ errors in performance tests
            Mike Arney Newbie

            We are seeing one of these errors running JBoss 4.2.2 with an Oracle database behind the jdbc2.PersistenceManager. The unique constraint violation (SYS_C0010983) is on JMS_MESSAGES. I am wondering how the MESSAGEID column in JMS_MESSAGES is generated (since the primary key is on MESSAGEID, DESTINATION):

            15 Oct 2008 09:31:09,702 ERROR [TMMessagingProxy pool-2-thread-12] Error sending aynch message
            org.jboss.mq.SpyJMSException: Could not store message: 65 msg=0 hard NOT_STORED NON_PERSISTENT queue=TOPIC.ecJobTopic.ID:4.-2147483648 priority=4 lateClone=false hashCode=821178610; - nested throwable: (java.sql.SQLException: ORA-00001: unique constraint (XXX.SYS_C0010983) violated
            )
             at org.jboss.mq.pm.jdbc2.PersistenceManager.saveToStorage(PersistenceManager.java:1638)
             at org.jboss.mq.server.MessageCache.saveToStorage(MessageCache.java:420)
             at org.jboss.mq.server.MessageReference.makeSoft(MessageReference.java:312)
             at org.jboss.mq.server.MessageCache.validateSoftReferenceDepth(MessageCache.java:367)
             at org.jboss.mq.server.MessageCache.addInternal(MessageCache.java:158)
             at org.jboss.mq.server.MessageCache.add(MessageCache.java:128)
             at org.jboss.mq.server.JMSQueue.addMessage(JMSQueue.java:179)
             at org.jboss.mq.server.JMSDestinationManager.addMessage(JMSDestinationManager.java:415)
             at org.jboss.mq.server.JMSDestinationManager.addMessage(JMSDestinationManager.java:399)
             at org.jboss.mq.server.JMSServerInterceptorSupport.addMessage(JMSServerInterceptorSupport.java:106)
             at org.jboss.mq.security.ServerSecurityInterceptor.addMessage(ServerSecurityInterceptor.java:168)
             at org.jboss.mq.server.TracingInterceptor.addMessage(TracingInterceptor.java:226)
             at org.jboss.mq.server.JMSServerInvoker.addMessage(JMSServerInvoker.java:112)
             at org.jboss.mq.il.jvm.JVMServerIL.addMessage(JVMServerIL.java:101)
             at org.jboss.mq.Connection.sendToServer(Connection.java:920)
             at org.jboss.mq.SpySession.sendMessage(SpySession.java:924)
             at org.jboss.mq.SpyMessageProducer.send(SpyMessageProducer.java:272)
             at org.jboss.mq.SpyMessageProducer.send(SpyMessageProducer.java:219)
             at com.teramedica.messaging.TMMessagingProxy.send(TMMessagingProxy.java:139)
             at com.teramedica.util.logging.TMAuditTrailManager.sendAudits(TMAuditTrailManager.java:214)
             at com.teramedica.util.logging.TMAuditTrailManager.flush(TMAuditTrailManager.java:166)
             at com.teramedica.util.logging.TMAuditTrailManager.update(TMAuditTrailManager.java:138)
             at com.teramedica.util.TMObservable.notifyObservers(TMObservable.java:91)
             at com.teramedica.db.transaction.TMTransactionHelper.transact(TMTransactionHelper.java:101)
             at com.teramedica.db.transaction.interceptor.TMTransactionInterceptor.invoke(TMTransactionInterceptor.java:120)
             at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
             at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
             at $Proxy324.patientOperation(Unknown Source)
             at com.teramedica.hl7.proxy.TMPatientOperationProcess.patientOperation(TMPatientOperationProcess.java:88)
             at com.teramedica.hl7.proxy.TMPatientOperationProcess.processMessage(TMPatientOperationProcess.java:51)
             at com.teramedica.aurora.HL7.server.TMAuroraPatientUpdateAction.doAction(TMAuroraPatientUpdateAction.java:65)
             at com.teramedica.aurora.HL7.server.TMAuroraHL7ParserActionBase.perform(TMAuroraHL7ParserActionBase.java:94)
             at com.teramedica.hl7.service.TMHL7Worker.processData(TMHL7Worker.java:133)
             at com.teramedica.hl7.service.TMHL7Worker.run(TMHL7Worker.java:77)
             at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:665)
             at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:690)
             at java.lang.Thread.run(Thread.java:810)
            Caused by:
            java.sql.SQLException: ORA-00001: unique constraint (XXX.SYS_C0010983) violated
            
             at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:125)
             at oracle.jdbc.driver.T4CTTIoer.processError(T4CTTIoer.java:316)
             at oracle.jdbc.driver.T4CTTIoer.processError(T4CTTIoer.java:282)
             at oracle.jdbc.driver.T4C8Oall.receive(T4C8Oall.java:639)
             at oracle.jdbc.driver.T4CPreparedStatement.doOall8(T4CPreparedStatement.java:185)
             at oracle.jdbc.driver.T4CPreparedStatement.execute_for_rows(T4CPreparedStatement.java:633)
             at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:1086)
             at oracle.jdbc.driver.OraclePreparedStatement.executeInternal(OraclePreparedStatement.java:2984)
             at oracle.jdbc.driver.OraclePreparedStatement.executeUpdate(OraclePreparedStatement.java:3057)
             at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251)
             at org.jboss.mq.pm.jdbc2.PersistenceManager.add(PersistenceManager.java:1132)
             at org.jboss.mq.pm.jdbc2.PersistenceManager.saveToStorage(PersistenceManager.java:1624)
             ... 36 more
            


            • 3. Re: JBossMQ errors in performance tests
              Clebert Suconic Master

              You would be better looking for that on the JBossMQ user's forum. This is the JBossMessaging developer's forum.

              This old thread was about the performance framework usage when we were comparing JBossMQ against JBoss Messaging. (That's the only reason why we were talking about MQ on this forum).