[jboss-jira] [JBoss JIRA] Closed: (JBMESSAGING-489) stress tests fail on dual core

stress tests fail on dual core ------------------------------ Key: JBMESSAGING-489 URL: http://jira.jboss.com/jira/browse/JBMESSAGING-489 Project: JBoss Messaging Issue Type: Bug Reporter: Ovidiu Feodorov Assigned To: Ovidiu Feodorov Priority: Blocker Fix For: 1.0.1.CR4 [junit] main 18:19:35,503 INFO [StressTest] ####################################################### Start IN-VM test: testTopicMultipleReceivers [junit] main 18:19:35,503 INFO [ServerManagement] IN-VM TEST [junit] main 18:19:35,596 INFO [WrapperDataSourceService] Bound ConnectionManager 'jboss.jca:name=DefaultDS,service=DataSourceBinding' to JNDI name 'java:/DefaultDS' [junit] main 18:19:39,143 INFO [ServiceContainer] remoting = "socket", serialization = "jboss", database = "mysql" [junit] main 18:19:39,643 INFO [ServerPeer] JBoss Messaging 1.0.1.CR4 server [server.0] started [junit] main 18:19:39,643 INFO [ConnectionFactory] Connector socket://127.0.0.1:9111 has leasing enabled, lease period 20000 milliseconds [junit] main 18:19:39,643 INFO [ConnectionFactory] [/ConnectionFactory, /XAConnectionFactory, java:/ConnectionFactory, java:/XAConnectionFactory] deployed [junit] main 18:19:39,675 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:name=JCAConnectionFactory,service=ConnectionFactoryBinding' to JNDI name 'java:/JCAConnectionFactory' [junit] main 18:19:39,675 INFO [LocalTestServer] server started [junit] main 18:19:39,675 INFO [LocalTestServer] deploying queue, fullsize:1000, ps:100 dc size:100 [junit] main 18:19:39,706 INFO [Queue] Queue[/queue/Queue1] started, fullSize=1000, pageSize=100, downCacheSize=100 [junit] main 18:19:39,706 INFO [LocalTestServer] deploying queue, fullsize:1000, ps:100 dc size:100 [junit] main 18:19:39,737 INFO [Queue] Queue[/queue/Queue2] started, fullSize=1000, pageSize=100, downCacheSize=100 [junit] main 18:19:39,737 INFO [LocalTestServer] deploying queue, fullsize:1000, ps:100 dc size:100 [junit] main 18:19:39,768 INFO [Queue] Queue[/queue/Queue3] started, fullSize=1000, pageSize=100, downCacheSize=100 [junit] main 18:19:39,768 INFO [LocalTestServer] deploying queue, fullsize:1000, ps:100 dc size:100 [junit] main 18:19:39,784 INFO [Queue] Queue[/queue/Queue4] started, fullSize=1000, pageSize=100, downCacheSize=100 [junit] main 18:19:39,784 INFO [LocalTestServer] deploying queue, fullsize:1000, ps:100 dc size:100 [junit] main 18:19:39,815 INFO [Topic] Topic[/topic/Topic1] started, fullSize=1000, pageSize=100, downCacheSize=100 [junit] main 18:19:39,815 INFO [LocalTestServer] deploying queue, fullsize:1000, ps:100 dc size:100 [junit] main 18:19:39,846 INFO [Topic] Topic[/topic/Topic2] started, fullSize=1000, pageSize=100, downCacheSize=100 [junit] main 18:19:39,846 INFO [LocalTestServer] deploying queue, fullsize:1000, ps:100 dc size:100 [junit] main 18:19:39,878 INFO [Topic] Topic[/topic/Topic3] started, fullSize=1000, pageSize=100, downCacheSize=100 [junit] main 18:19:39,878 INFO [LocalTestServer] deploying queue, fullsize:1000, ps:100 dc size:100 [junit] main 18:19:39,893 INFO [Topic] Topic[/topic/Topic4] started, fullSize=1000, pageSize=100, downCacheSize=100 [junit] Thread-31 18:20:48,878 WARN [JDBCPersistenceManager] SQLException caught - assuming deadlock detected, try:1 [junit] java.sql.SQLException: Deadlock found when trying to get lock; try restarting transaction [junit] at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:2975) [junit] at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1600) [junit] at com.mysql.jdbc.ServerPreparedStatement.serverExecute(ServerPreparedStatement.java:1125) [junit] at com.mysql.jdbc.ServerPreparedStatement.executeInternal(ServerPreparedStatement.java:677) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1357) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1274) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1259) [junit] at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251) [junit] at org.jboss.messaging.core.plugin.JDBCPersistenceManager.updateReliableReferencesLoadedInRange(JDBCPersistenceManager.java:492) [junit] at org.jboss.messaging.core.ChannelSupport.load(ChannelSupport.java:1450) [junit] at org.jboss.messaging.core.ChannelSupport.removeFirstInMemory(ChannelSupport.java:1036) [junit] at org.jboss.messaging.core.ChannelSupport.deliverInternal(ChannelSupport.java:771) [junit] at org.jboss.messaging.core.ChannelSupport$DeliveryRunnable.run(ChannelSupport.java:1755) [junit] at EDU.oswego.cs.dl.util.concurrent.QueuedExecutor$RunLoop.run(QueuedExecutor.java:89) [junit] at java.lang.Thread.run(Thread.java:534) [junit] Thread-31 18:20:48,878 WARN [JDBCPersistenceManager] Trying again after a pause [junit] Thread-31 18:20:49,128 WARN [JDBCPersistenceManager] Update worked after retry [junit] Thread-31 18:20:49,471 WARN [JDBCPersistenceManager] SQLException caught - assuming deadlock detected, try:1 [junit] java.sql.SQLException: Deadlock found when trying to get lock; try restarting transaction [junit] at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:2975) [junit] at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1600) [junit] at com.mysql.jdbc.ServerPreparedStatement.serverExecute(ServerPreparedStatement.java:1125) [junit] at com.mysql.jdbc.ServerPreparedStatement.executeInternal(ServerPreparedStatement.java:677) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1357) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1274) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1259) [junit] at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251) [junit] at org.jboss.messaging.core.plugin.JDBCPersistenceManager.updateReliableReferencesLoadedInRange(JDBCPersistenceManager.java:492) [junit] at org.jboss.messaging.core.ChannelSupport.load(ChannelSupport.java:1450) [junit] at org.jboss.messaging.core.ChannelSupport.removeFirstInMemory(ChannelSupport.java:1036) [junit] at org.jboss.messaging.core.ChannelSupport.deliverInternal(ChannelSupport.java:771) [junit] at org.jboss.messaging.core.ChannelSupport$DeliveryRunnable.run(ChannelSupport.java:1755) [junit] at EDU.oswego.cs.dl.util.concurrent.QueuedExecutor$RunLoop.run(QueuedExecutor.java:89) [junit] at java.lang.Thread.run(Thread.java:534) [junit] Thread-31 18:20:49,471 WARN [JDBCPersistenceManager] Trying again after a pause [junit] Thread-31 18:20:49,737 WARN [JDBCPersistenceManager] Update worked after retry [junit] Thread-31 18:20:51,331 WARN [JDBCPersistenceManager] SQLException caught - assuming deadlock detected, try:1 [junit] java.sql.SQLException: Deadlock found when trying to get lock; try restarting transaction [junit] at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:2975) [junit] at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1600) [junit] at com.mysql.jdbc.ServerPreparedStatement.serverExecute(ServerPreparedStatement.java:1125) [junit] at com.mysql.jdbc.ServerPreparedStatement.executeInternal(ServerPreparedStatement.java:677) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1357) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1274) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1259) [junit] at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251) [junit] at org.jboss.messaging.core.plugin.JDBCPersistenceManager.updateReliableReferencesLoadedInRange(JDBCPersistenceManager.java:492) [junit] at org.jboss.messaging.core.ChannelSupport.load(ChannelSupport.java:1450) [junit] at org.jboss.messaging.core.ChannelSupport.removeFirstInMemory(ChannelSupport.java:1036) [junit] at org.jboss.messaging.core.ChannelSupport.deliverInternal(ChannelSupport.java:771) [junit] at org.jboss.messaging.core.ChannelSupport$DeliveryRunnable.run(ChannelSupport.java:1755) [junit] at EDU.oswego.cs.dl.util.concurrent.QueuedExecutor$RunLoop.run(QueuedExecutor.java:89) [junit] at java.lang.Thread.run(Thread.java:534) [junit] Thread-31 18:20:51,331 WARN [JDBCPersistenceManager] Trying again after a pause [junit] Thread-31 18:20:51,487 WARN [JDBCPersistenceManager] Update worked after retry [junit] Thread-31 18:20:51,706 WARN [JDBCPersistenceManager] SQLException caught - assuming deadlock detected, try:1 [junit] java.sql.SQLException: Deadlock found when trying to get lock; try restarting transaction [junit] at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:2975) [junit] at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1600) [junit] at com.mysql.jdbc.ServerPreparedStatement.serverExecute(ServerPreparedStatement.java:1125) [junit] at com.mysql.jdbc.ServerPreparedStatement.executeInternal(ServerPreparedStatement.java:677) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1357) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1274) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1259) [junit] at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251) [junit] at org.jboss.messaging.core.plugin.JDBCPersistenceManager.updateReliableReferencesLoadedInRange(JDBCPersistenceManager.java:492) [junit] at org.jboss.messaging.core.ChannelSupport.load(ChannelSupport.java:1450) [junit] at org.jboss.messaging.core.ChannelSupport.removeFirstInMemory(ChannelSupport.java:1036) [junit] at org.jboss.messaging.core.ChannelSupport.deliverInternal(ChannelSupport.java:771) [junit] at org.jboss.messaging.core.ChannelSupport$DeliveryRunnable.run(ChannelSupport.java:1755) [junit] at EDU.oswego.cs.dl.util.concurrent.QueuedExecutor$RunLoop.run(QueuedExecutor.java:89) [junit] at java.lang.Thread.run(Thread.java:534) [junit] Thread-31 18:20:51,706 WARN [JDBCPersistenceManager] Trying again after a pause [junit] Thread-31 18:20:52,018 WARN [JDBCPersistenceManager] Update worked after retry [junit] Thread-31 18:20:52,253 WARN [JDBCPersistenceManager] SQLException caught - assuming deadlock detected, try:1 [junit] java.sql.SQLException: Deadlock found when trying to get lock; try restarting transaction [junit] at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:2975) [junit] at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1600) [junit] at com.mysql.jdbc.ServerPreparedStatement.serverExecute(ServerPreparedStatement.java:1125) [junit] at com.mysql.jdbc.ServerPreparedStatement.executeInternal(ServerPreparedStatement.java:677) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1357) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1274) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1259) [junit] at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251) [junit] at org.jboss.messaging.core.plugin.JDBCPersistenceManager.updateReliableReferencesLoadedInRange(JDBCPersistenceManager.java:492) [junit] at org.jboss.messaging.core.ChannelSupport.load(ChannelSupport.java:1450) [junit] at org.jboss.messaging.core.ChannelSupport.removeFirstInMemory(ChannelSupport.java:1036) [junit] at org.jboss.messaging.core.ChannelSupport.deliverInternal(ChannelSupport.java:771) [junit] at org.jboss.messaging.core.ChannelSupport$DeliveryRunnable.run(ChannelSupport.java:1755) [junit] at EDU.oswego.cs.dl.util.concurrent.QueuedExecutor$RunLoop.run(QueuedExecutor.java:89) [junit] at java.lang.Thread.run(Thread.java:534) [junit] Thread-31 18:20:52,253 WARN [JDBCPersistenceManager] Trying again after a pause [junit] Thread-31 18:20:52,659 WARN [JDBCPersistenceManager] Update worked after retry [junit] Thread-31 18:20:52,862 WARN [JDBCPersistenceManager] SQLException caught - assuming deadlock detected, try:1 [junit] java.sql.SQLException: Deadlock found when trying to get lock; try restarting transaction [junit] at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:2975) [junit] at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1600) [junit] at com.mysql.jdbc.ServerPreparedStatement.serverExecute(ServerPreparedStatement.java:1125) [junit] at com.mysql.jdbc.ServerPreparedStatement.executeInternal(ServerPreparedStatement.java:677) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1357) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1274) [junit] at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1259) [junit] at org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:251) [junit] at org.jboss.messaging.core.plugin.JDBCPersistenceManager.updateReliableReferencesLoadedInRange(JDBCPersistenceManager.java:492) [junit] at org.jboss.messaging.core.ChannelSupport.load(ChannelSupport.java:1450) [junit] at org.jboss.messaging.core.ChannelSupport.removeFirstInMemory(ChannelSupport.java:1036) [junit] at org.jboss.messaging.core.ChannelSupport.deliverInternal(ChannelSupport.java:771) [junit] at org.jboss.messaging.core.ChannelSupport$DeliveryRunnable.run(ChannelSupport.java:1755) [junit] at EDU.oswego.cs.dl.util.concurrent.QueuedExecutor$RunLoop.run(QueuedExecutor.java:89) [junit] at java.lang.Thread.run(Thread.java:534) [junit] Thread-31 18:20:52,862 WARN [JDBCPersistenceManager] Trying again after a pause [junit] Thread-31 18:20:53,378 WARN [JDBCPersistenceManager] Update worked after retry [junit] Thread-53 18:23:09,956 ERROR [RecoveringReceiver] Message is null [junit] Thread-51 18:23:09,956 ERROR [Receiver] Message is null [junit] Thread-59 18:23:09,956 ERROR [TransactionalReceiver] Message is null [junit] Thread-55 18:23:09,956 ERROR [RecoveringReceiver] Message is null [junit] Thread-57 18:23:09,956 ERROR [TransactionalReceiver] Message is null [junit] Thread-45 18:23:09,956 ERROR [Receiver] Message is null