You are viewing a plain text version of this content. The canonical link for it is here.
Posted to notifications@qpid.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/02/21 04:42:02 UTC

Build failed in Jenkins: Qpid-Java-Java-Test-IBMJDK1.6 #691

See <https://builds.apache.org/job/Qpid-Java-Java-Test-IBMJDK1.6/691/changes>

Changes:

[rgodfrey] QPID-5567 : Move acl checks into the objects being created

[rgodfrey] QPID-5569 : NPE on getStringProperty("JMSXUserId")

[rgodfrey] QPID-5571 : Java Broker AMQP 1.0 deadlock on close consumer

[rgodfrey] QPID-5563 : Ensure vhost is set as early as possible, on receipt of connection open

[rgodfrey] QPID-5567 : Further changes to SecurityMangager

[rgodfrey] QPID-5570 : Client incorrectly acknowledges prefetched messages with client-ack

[rgodfrey] QPID-5570 : Client incorrectly acknowledges prefetched messages with client-ack

[aconway] QPID-5568: HA C++ qpid::messaging AMQP 1.0 client failover logging is not clear

The qpid::messaging AMQP 1.0 protocol logging did not give clear information
about reconnection during failover.

This patch simplifies the reconnect logic by collapsing all known addresses from
broker URL and reconnect URLs into a single URL with no duplicates.

It rationalizes the info and notice logging as follows:

# Initial connection with multiple addresses, info logs show the
# full URL, each attempt to connect and the finally connected address.

[Messaging] info Starting connection to amqp:tcp:20.0.10.33:5672,tcp:20.0.10.34:5672,tcp:20.0.10.35:5672
[Messaging] info Connecting to tcp:20.0.10.33:5672
[Messaging] info Failed to connect to tcp:20.0.10.33:5672
[Messaging] info Connecting to tcp:20.0.10.34:5672
[Messaging] info Failed to connect to tcp:20.0.10.34:5672
[Messaging] info Connecting to tcp:20.0.10.35:5672
[Messaging] info Connected to tcp:20.0.10.35:5672

# Re-connection due to a failure. notice logs for the start of reconnection (with full URL)
# and eventual sucess (with individual address). info logs for individual connection attempts.

[Messaging] notice Auto-reconnecting to amqp:tcp:20.0.10.33:5672,tcp:20.0.10.34:5672,tcp:20.0.10.35:5672
[Messaging] info Connecting to tcp:20.0.10.33:5672
[Messaging] info Failed to connect to tcp:20.0.10.33:5672
....
[Messaging] info Connected to tcp:20.0.10.33:5672
[Messaging] notice Auto-reconnected to amqp:tcp:20.0.10.33:5672

The idea here is that there are no logs by default (info is not on by default)
for "normal" behavior, but failover does get a (short) notice log by default.
By turning on info logs you can follow the detailed blow-by-blow of failover
without getting drowned in the detail of debug logs.

Note that final failure to connect is signalled to the application via an exception.
There was not previously any log message for that and I didn't add one.

Additional changes: updated qpid-ping test client to use the messaging library.

------------------------------------------
[...truncated 5145 lines...]
    [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.268 sec
    [junit] Running org.apache.qpid.test.unit.client.MaxDeliveryCountTest
    [junit] Tests run: 7, Failures: 0, Errors: 0, Time elapsed: 3.76 sec
    [junit] Running org.apache.qpid.test.unit.client.QueueSessionFactoryTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.172 sec
    [junit] Running org.apache.qpid.test.unit.client.TopicSessionFactoryTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.179 sec
    [junit] Running org.apache.qpid.test.unit.client.channelclose.CloseWithBlockingReceiveTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.03 sec
    [junit] Running org.apache.qpid.test.unit.client.connection.BrokerClosesClientConnectionTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 12.148 sec
    [junit] Running org.apache.qpid.test.unit.client.connection.ConnectionFactoryTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.09 sec
    [junit] Running org.apache.qpid.test.unit.client.connection.ConnectionStartTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.064 sec
    [junit] Running org.apache.qpid.test.unit.client.connection.ConnectionTest
    [junit] Tests run: 12, Failures: 0, Errors: 0, Time elapsed: 0.372 sec
    [junit] Running org.apache.qpid.test.unit.client.connection.ExceptionListenerTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.08 sec
    [junit] Running org.apache.qpid.test.unit.client.message.ObjectMessageTest
    [junit] Tests run: 10, Failures: 0, Errors: 0, Time elapsed: 0.385 sec
    [junit] Running org.apache.qpid.test.unit.client.protocol.AMQProtocolSessionTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.16 sec
    [junit] Running org.apache.qpid.test.unit.client.temporaryqueue.TemporaryQueueTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.119 sec
    [junit] Running org.apache.qpid.test.unit.close.JavaServerCloseRaceConditionTest
    [junit] Tests run: 0, Failures: 0, Errors: 0, Time elapsed: 0.001 sec
    [junit] Running org.apache.qpid.test.unit.close.MessageConsumerCloseTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.031 sec
    [junit] Running org.apache.qpid.test.unit.close.MessageRequeueTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 16.842 sec
    [junit] Running org.apache.qpid.test.unit.close.TopicPublisherCloseTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 3.053 sec
    [junit] Running org.apache.qpid.test.unit.ct.DurableSubscriberTest
    [junit] Tests run: 0, Failures: 0, Errors: 0, Time elapsed: 0.001 sec
    [junit] Running org.apache.qpid.test.unit.message.JMSPropertiesTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.063 sec
    [junit] Running org.apache.qpid.test.unit.message.StreamMessageTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.065 sec
    [junit] Running org.apache.qpid.test.unit.message.UTF8Test
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.06 sec
    [junit] Running org.apache.qpid.test.unit.topic.DurableSubscriptionTest
    [junit] Tests run: 17, Failures: 0, Errors: 0, Time elapsed: 28.491 sec
    [junit] Running org.apache.qpid.test.unit.topic.TemporaryTopicTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.177 sec
    [junit] Running org.apache.qpid.test.unit.topic.TopicPublisherTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.031 sec
    [junit] Running org.apache.qpid.test.unit.topic.TopicSessionTest
    [junit] Tests run: 9, Failures: 0, Errors: 0, Time elapsed: 9.699 sec
    [junit] Running org.apache.qpid.test.unit.transacted.CommitRollbackTest
    [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 16.011 sec
    [junit] Running org.apache.qpid.test.unit.transacted.TransactedTest
    [junit] Tests run: 7, Failures: 0, Errors: 0, Time elapsed: 8.062 sec
    [junit] Running org.apache.qpid.test.unit.transacted.TransactionTimeoutDisabledTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 3.564 sec
    [junit] Running org.apache.qpid.test.unit.transacted.TransactionTimeoutTest
    [junit] Tests run: 15, Failures: 0, Errors: 0, Time elapsed: 42.853 sec
    [junit] Running org.apache.qpid.test.unit.xa.FaultTest
    [junit] Tests run: 10, Failures: 0, Errors: 0, Time elapsed: 11.92 sec
    [junit] Running org.apache.qpid.test.unit.xa.QueueTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.045 sec
    [junit] Running org.apache.qpid.test.unit.xa.TopicTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 4.203 sec
    [junit] Running org.apache.qpid.test.utils.BrokerCommandHelperTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.022 sec
    [junit] Running org.apache.qpid.util.LogMonitorTest
    [junit] Tests run: 9, Failures: 0, Errors: 0, Time elapsed: 2.013 sec
    [junit] Tests FAILED
     [echo] Running ant for module : systests

touch-failed:
    [touch] Creating /home/hudson/jenkins-slave/workspace/Qpid-Java-Java-Test-IBMJDK1.6/trunk/qpid/java/build/results/systests/FAILED
    [touch] Creating /home/hudson/jenkins-slave/workspace/Qpid-Java-Java-Test-IBMJDK1.6/trunk/qpid/java/build/results/FAILED
     [echo] Running ant for module : perftests

prepare:

precompile:

compile:
     [echo] Targeting : 1.6

copy-extra-resources:

copy-resources:
     [echo] Copying resources from /home/hudson/jenkins-slave/workspace/Qpid-Java-Java-Test-IBMJDK1.6/trunk/qpid/java/perftests/src/main/resources to /home/hudson/jenkins-slave/workspace/Qpid-Java-Java-Test-IBMJDK1.6/trunk/qpid/java/build/scratch/perftests/classes...

copy-project-resources-metainf:

copy-module-resources-metainf:

copy-module-src-resources-metainf:

copy-files-to-module-metainf:

jar.manifest:

jar.nomanifest:

jar:

precompile-tests:

compile-tests:

copy-test-resources:

jar-tests:

jar-sources:

libs:

copy-bin:

copy-etc:

postbuild:

copy-broker-plugin-jars-deps:

copy-broker-plugin-jars:

build:

test:
     [echo] Using profile:java-mms.0-10
    [junit] Running org.apache.qpid.disttest.ArgumentParserTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.042 sec
    [junit] Running org.apache.qpid.disttest.ConfigFileHelperTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.023 sec
    [junit] Running org.apache.qpid.disttest.ResultsFileWriterTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.041 sec
    [junit] Running org.apache.qpid.disttest.VisitorTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.012 sec
    [junit] Running org.apache.qpid.disttest.client.ClientCommandVisitorTest
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.084 sec
    [junit] Running org.apache.qpid.disttest.client.ClientTest
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.577 sec
    [junit] Running org.apache.qpid.disttest.client.ConsumerParticipantTest
    [junit] Tests run: 7, Failures: 0, Errors: 0, Time elapsed: 0.166 sec
    [junit] Running org.apache.qpid.disttest.client.MessageProviderTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.118 sec
    [junit] Running org.apache.qpid.disttest.client.ParticipantExecutorTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.025 sec
    [junit] Running org.apache.qpid.disttest.client.ParticipantRegistryTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.003 sec
    [junit] Running org.apache.qpid.disttest.client.ParticipantResultFactoryTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.003 sec
    [junit] Running org.apache.qpid.disttest.client.ProducerParticipantTest
    [junit] Tests run: 9, Failures: 0, Errors: 0, Time elapsed: 1.977 sec
    [junit] Running org.apache.qpid.disttest.client.property.ListPropertyValueTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.003 sec
    [junit] Running org.apache.qpid.disttest.client.property.PropertyValueFactoryTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.005 sec
    [junit] Running org.apache.qpid.disttest.client.property.RandomPropertyValueTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.003 sec
    [junit] Running org.apache.qpid.disttest.client.property.RangePropertyValueTest
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.005 sec
    [junit] Running org.apache.qpid.disttest.client.property.SimplePropertyValueTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.003 sec
    [junit] Running org.apache.qpid.disttest.client.utils.ExecutorWithNoLimitsTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.004 sec
    [junit] Running org.apache.qpid.disttest.client.utils.ExecutorWithTimeLimitTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.506 sec
    [junit] Running org.apache.qpid.disttest.controller.ClientRegistryTest
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 2.558 sec
    [junit] Running org.apache.qpid.disttest.controller.ControllerTest
    [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.053 sec
    [junit] Running org.apache.qpid.disttest.controller.ParticipatingClientsTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.005 sec
    [junit] Running org.apache.qpid.disttest.controller.TestRunnerTest
    [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 1.194 sec
    [junit] Running org.apache.qpid.disttest.controller.config.ClientConfigTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.01 sec
    [junit] Running org.apache.qpid.disttest.controller.config.ConfigReaderTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.616 sec
    [junit] Running org.apache.qpid.disttest.controller.config.ConfigTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.005 sec
    [junit] Running org.apache.qpid.disttest.controller.config.ConnectionConfigTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.008 sec
    [junit] Running org.apache.qpid.disttest.controller.config.ConsumerConfigTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.004 sec
    [junit] Running org.apache.qpid.disttest.controller.config.IterationValueTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.025 sec
    [junit] Running org.apache.qpid.disttest.controller.config.JavaScriptConfigEvaluatorTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.04 sec
    [junit] Running org.apache.qpid.disttest.controller.config.MessageProviderConfigTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.002 sec
    [junit] Running org.apache.qpid.disttest.controller.config.ParticipantConfigTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.006 sec
    [junit] Running org.apache.qpid.disttest.controller.config.ProducerConfigTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.003 sec
    [junit] Running org.apache.qpid.disttest.controller.config.SessionConfigTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.013 sec
    [junit] Running org.apache.qpid.disttest.controller.config.TestConfigTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.007 sec
    [junit] Running org.apache.qpid.disttest.controller.config.TestInstanceTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.006 sec
    [junit] Running org.apache.qpid.disttest.db.ResultsDbWriterTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 5.263 sec
    [junit] Running org.apache.qpid.disttest.jms.JmsMessageAdaptorTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.003 sec
    [junit] Running org.apache.qpid.disttest.message.JsonHandlerTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.015 sec
    [junit] Running org.apache.qpid.disttest.message.ParticipantResultTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.062 sec
    [junit] Running org.apache.qpid.disttest.results.aggregation.AggregatorTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.008 sec
    [junit] Running org.apache.qpid.disttest.results.aggregation.ParticipantResultAggregatorTest
    [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 0.005 sec
    [junit] Running org.apache.qpid.disttest.results.aggregation.SeriesStatisticsTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.023 sec
    [junit] Running org.apache.qpid.disttest.results.aggregation.TestResultAggregatorTest
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.004 sec
    [junit] Running org.apache.qpid.disttest.results.formatting.CSVFormatterTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.004 sec
    [junit] Running org.apache.qpid.disttest.results.formatting.CSVOrderParticipantResultComparatorTest
    [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.012 sec
    [junit] Running org.apache.qpid.systest.disttest.QpidQueueCreatorTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 1.083 sec
    [junit] Running org.apache.qpid.systest.disttest.clientonly.BasicDistributedClientTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.527 sec
    [junit] Running org.apache.qpid.systest.disttest.clientonly.ConsumerParticipantTest
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 6.33 sec
    [junit] Running org.apache.qpid.systest.disttest.clientonly.DistributedClientTest
    [junit] Tests run: 9, Failures: 0, Errors: 0, Time elapsed: 0.851 sec
    [junit] Running org.apache.qpid.systest.disttest.clientonly.MessageProviderTest
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.138 sec
    [junit] Running org.apache.qpid.systest.disttest.clientonly.ProducerParticipantTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.57 sec
    [junit] Running org.apache.qpid.systest.disttest.controllerandclient.ControllerAndClientTest
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 1.971 sec
    [junit] Running org.apache.qpid.systest.disttest.controlleronly.DistributedControllerTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.075 sec
    [junit] Running org.apache.qpid.systest.disttest.endtoend.EndToEndTest
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.658 sec
     [echo] Running ant for module : perftests

touch-failed:

BUILD FAILED
/home/hudson/jenkins-slave/workspace/Qpid-Java-Java-Test-IBMJDK1.6/trunk/qpid/java/build.xml:107: TEST SUITE FAILED

Total time: 19 minutes 5 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results

Jenkins build is back to normal : Qpid-Java-Java-Test-IBMJDK1.6 #692

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Qpid-Java-Java-Test-IBMJDK1.6/692/changes>