3 Replies Latest reply on Oct 15, 2008 6:52 PM by clebert.suconic

JBossMQ errors in performance tests

sergeypk May 2, 2007 1:22 PM

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

timfox May 2, 2007 2:26 PM (in response to sergeypk)

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.
Actions

2. Re: JBossMQ errors in performance tests

marney Oct 15, 2008 5:57 PM (in response to sergeypk)

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 Oct 15, 2008 6:52 PM (in response to sergeypk)

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).
Actions

Go to original post