[QPID-7122] CloseOnNoRouteForMandatoryMessageTest.testNoRoute_brokerClosesConnection fails sporadically on slow CI

• Type: Bug
• Status: Closed
• Priority: Minor
• Resolution: Fixed
• Fix Version/s: qpid-java-6.1
• Component/s: Java Tests
• Assignee: Unassigned
• Reporter: Keith Wall
• Created: 04/Mar/16 10:32
• Updated: 02/Oct/19 19:21
• Resolved: 28/Mar/16 15:24

Description

The following test failed on Apache CI:

https://builds.apache.org/view/M-R/view/Qpid/job/Qpid-Java-Java-BDB-TestMatrix/2155/jdk=JDK%201.7%20(latest),label=Ubuntu,profile=java-bdb.0-9-1/testReport/junit/org.apache.qpid.test.client/CloseOnNoRouteForMandatoryMessageTest/testNoRoute_brokerClosesConnection/

The test is race because it depends on how quickly the socket is closed after the connection level exception.

org.apache.qpid.test.client.CloseOnNoRouteForMandatoryMessageTest.testNoRoute_brokerClosesConnection

Failing for the past 1 build (Since Failed#2155 )
Took 0.71 sec.
add description
Error Message

javax.jms.JMSException: Session exception occurred while trying to commit: sender for socket /127.0.0.1:47193-localhost/127.0.0.1:33467 is closed message should contain intended queue name
Stacktrace

java.lang.AssertionError: javax.jms.JMSException: Session exception occurred while trying to commit: sender for socket /127.0.0.1:47193-localhost/127.0.0.1:33467 is closed message should contain intended queue name
	at org.junit.Assert.fail(Assert.java:88)
	at org.junit.Assert.assertTrue(Assert.java:41)
	at org.apache.qpid.test.client.UnroutableMessageTestExceptionListener.assertNoRoute(UnroutableMessageTestExceptionListener.java:140)
	at org.apache.qpid.test.client.CloseOnNoRouteForMandatoryMessageTest.testNoRoute_brokerClosesConnection(CloseOnNoRouteForMandatoryMessageTest.java:90)
Standard Output

18:51:19,397 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
18:51:19,397 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-org.apache.qpid.test.client.CloseOnNoRouteForMandatoryMessageTest.testNoRoute_brokerClosesConnection]
18:51:19,397 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
18:51:19,398 |-INFO in ch.qos.logback.core.FileAppender[FILE-org.apache.qpid.test.client.CloseOnNoRouteForMandatoryMessageTest.testNoRoute_brokerClosesConnection] - File property is set to [/home/jenkins/jenkins-slave/workspace/Qpid-Java-Java-BDB-TestMatrix/jdk/JDK 1.7 (latest)/label/Ubuntu/profile/java-bdb.0-9-1/systests/target/surefire-reports/java-bdb.0-9-1/TEST-org.apache.qpid.test.client.CloseOnNoRouteForMandatoryMessageTest.testNoRoute_brokerClosesConnection.txt]

Stack trace:

javax.jms.JMSException: Session exception occurred while trying to commit: sender for socket /127.0.0.1:47193-localhost/127.0.0.1:33467 is closed
	at org.apache.qpid.client.AMQSession.toJMSException(AMQSession.java:3701) ~[qpid-client-6.1.0-SNAPSHOT.jar:na]
	at org.apache.qpid.client.AMQSession.commit(AMQSession.java:880) ~[qpid-client-6.1.0-SNAPSHOT.jar:na]
	at org.apache.qpid.test.client.CloseOnNoRouteForMandatoryMessageTest.testNoRoute_brokerClosesConnection(CloseOnNoRouteForMandatoryMessageTest.java:78) ~[test-classes/:na]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.7.0_80]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.7.0_80]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_80]
	at java.lang.reflect.Method.invoke(Method.java:606) ~[na:1.7.0_80]
	at junit.framework.TestCase.runTest(TestCase.java:176) [junit-4.11.jar:na]
	at org.apache.qpid.test.utils.QpidTestCase.runTest(QpidTestCase.java:171) [qpid-test-utils-6.1.0-SNAPSHOT.jar:na]
	at junit.framework.TestCase.runBare(TestCase.java:141) [junit-4.11.jar:na]
	at org.apache.qpid.test.utils.QpidBrokerTestCase.runBare(QpidBrokerTestCase.java:138) [classes/:na]
	at junit.framework.TestResult$1.protect(TestResult.java:122) [junit-4.11.jar:na]
	at junit.framework.TestResult.runProtected(TestResult.java:142) [junit-4.11.jar:na]
	at junit.framework.TestResult.run(TestResult.java:125) [junit-4.11.jar:na]
	at junit.framework.TestCase.run(TestCase.java:129) [junit-4.11.jar:na]
	at org.apache.qpid.test.utils.QpidTestCase.run(QpidTestCase.java:156) [qpid-test-utils-6.1.0-SNAPSHOT.jar:na]
	at junit.framework.TestSuite.runTest(TestSuite.java:255) [junit-4.11.jar:na]
	at junit.framework.TestSuite.run(TestSuite.java:250) [junit-4.11.jar:na]
	at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:84) [junit-4.11.jar:na]
	at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17]
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17]
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17]
	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17]
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17]
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17]
Caused by: org.apache.qpid.transport.SenderClosedException: sender for socket /127.0.0.1:47193-localhost/127.0.0.1:33467 is closed
	at org.apache.qpid.transport.network.io.IoSender.checkNotAlreadyClosed(IoSender.java:383) ~[qpid-common-6.1.0-SNAPSHOT.jar:6.1.0-SNAPSHOT]
	at org.apache.qpid.transport.network.io.IoSender.send(IoSender.java:128) ~[qpid-common-6.1.0-SNAPSHOT.jar:6.1.0-SNAPSHOT]
	at org.apache.qpid.framing.AMQFrame.writePayload(AMQFrame.java:67) ~[qpid-common-6.1.0-SNAPSHOT.jar:6.1.0-SNAPSHOT]
	at org.apache.qpid.client.AMQProtocolHandler.writeFrame(AMQProtocolHandler.java:557) ~[qpid-client-6.1.0-SNAPSHOT.jar:na]
	at org.apache.qpid.client.AMQProtocolHandler.writeFrame(AMQProtocolHandler.java:550) ~[qpid-client-6.1.0-SNAPSHOT.jar:na]
	at org.apache.qpid.client.AMQProtocolHandler.writeCommandFrameAndWaitForReply(AMQProtocolHandler.java:633) ~[qpid-client-6.1.0-SNAPSHOT.jar:na]
	at org.apache.qpid.client.AMQProtocolHandler.syncWrite(AMQProtocolHandler.java:657) ~[qpid-client-6.1.0-SNAPSHOT.jar:na]
	at org.apache.qpid.client.AMQProtocolHandler.syncWrite(AMQProtocolHandler.java:651) ~[qpid-client-6.1.0-SNAPSHOT.jar:na]
	at org.apache.qpid.client.AMQSession_0_8.commitImpl(AMQSession_0_8.java:276) ~[qpid-client-6.1.0-SNAPSHOT.jar:na]
	at org.apache.qpid.client.AMQSession.commit(AMQSession.java:866) ~[qpid-client-6.1.0-SNAPSHOT.jar:na]
	... 23 common frames omitted