[
Date Prev][
Date Next][
Thread Prev][
Thread Next][
Date Index][
Thread Index]
[
List Home]
|
[cf-dev] [Hudson] Build failed in Hudson: californium #59
|
See <https://hudson.eclipse.org/californium/job/californium/59/changes>
Changes:
[Achim Kraus] Bugfix of wrong payload length in message tracing for responses.
[Achim Kraus] Correct code formating.
------------------------------------------
[...truncated 1038 lines...]
Resource changed: Forth notification
Resource changed: Fifth notification
297 INFO [ReliabilityLayer$RetransmissionTask]: Timeout: retransmission limit reached, exchange failed, message: CON-2.05 MID=55768, Token=0b, OptionSet={"Observe":5}, "Fifth notification" - (org.eclipse.californium.core.network.stack.ReliabilityLayer$RetransmissionTask.java:345) run() in thread pool-17-thread-8 at (2015-08-06 06:22:44)
297 INFO [ObserveLayer$NotificationController]: Notification 0b timed out. Cancel all relations with source /127.0.0.1:58849 - (org.eclipse.californium.core.network.stack.ObserveLayer$NotificationController.java:225) onTimeout() in thread pool-17-thread-8 at (2015-08-06 06:22:44)
297 INFO [ObserveRelation]: Canceling observe relation /127.0.0.1:58849#0b with /obs - (org.eclipse.californium.core.observe.ObserveRelation.java:115) cancel() in thread pool-17-thread-8 at (2015-08-06 06:22:44)
CON [MID=7001], GET, /obs, observe(0) ----->
<----- ACK [MID=7001], 2.05, observe(0)
Observe relation established
<----- NON [MID=55764], 2.05, observe(1)
<----- NON [MID=55765], 2.05, observe(2)
<----- CON [MID=55766], 2.05, observe(3)
ACK [MID=55766], 0 ----->
<----- NON [MID=55767], 2.05, observe(4)
<----- CON [MID=55768], 2.05, observe(5) // lost
<----- CON [MID=55768], 2.05, observe(5) // lost
<----- CON [MID=55768], 2.05, observe(5) // lost
<----- CON [MID=55768], 2.05, observe(5) // lost
<----- CON [MID=55768], 2.05, observe(5) // lost
Establish an observe relation. Cancellation after timeout. During the timeouts, the resource still changes.
295 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:34977#0c and resource /obs - (org.eclipse.californium.core.CoapResource.java:675) addObserveRelation() in thread pool-17-thread-6 at (2015-08-06 06:22:44)
Resource changed: First notification 858950664-
Resource changed: Second notification -117187212
Resource changed: Third notification -160127059
304 INFO [ReliabilityLayer$RetransmissionTask]: Timeout: retransmission limit reached, exchange failed, message: CON-2.05 MID=55769, Token=0c, OptionSet={"Observe":8}, "Third notification -1601".. 29 bytes - (org.eclipse.californium.core.network.stack.ReliabilityLayer$RetransmissionTask.java:345) run() in thread pool-17-thread-13 at (2015-08-06 06:22:45)
304 INFO [ObserveLayer$NotificationController]: Notification 0c timed out. Cancel all relations with source /127.0.0.1:34977 - (org.eclipse.californium.core.network.stack.ObserveLayer$NotificationController.java:225) onTimeout() in thread pool-17-thread-13 at (2015-08-06 06:22:45)
304 INFO [ObserveRelation]: Canceling observe relation /127.0.0.1:34977#0c with /obs - (org.eclipse.californium.core.observe.ObserveRelation.java:115) cancel() in thread pool-17-thread-13 at (2015-08-06 06:22:45)
CON [MID=7002], GET, /obs, observe(0) ----->
<----- ACK [MID=7002], 2.05, observe(5)
Observe relation established
<----- CON [MID=55769], 2.05, observe(6) // lost
<----- CON [MID=55769], 2.05, observe(6) // lost (1. retransmission)
<----- CON [MID=55769], 2.05, observe(7) // lost (2. retransmission)
<----- CON [MID=55769], 2.05, observe(8) // lost (3. retransmission)
<----- CON [MID=55769], 2.05, observe(8) // lost (4. retransmission)
Establish an observe relation. Cancellation due to a reject from the client
305 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:60865#0d and resource /obs - (org.eclipse.californium.core.CoapResource.java:675) addObserveRelation() in thread pool-17-thread-14 at (2015-08-06 06:22:45)
Resource changed: First notification 1097045187
Reject notification
286 INFO [ObserveRelation]: Canceling observe relation /127.0.0.1:60865#0d with /obs - (org.eclipse.californium.core.observe.ObserveRelation.java:115) cancel() in thread pool-17-thread-1 at (2015-08-06 06:22:45)
CON [MID=7003], GET, /obs, observe(0) ----->
<----- ACK [MID=7003], 2.05, observe(8)
Observe relation established
<----- CON [MID=55770], 2.05, observe(9) // lost
<----- CON [MID=55770], 2.05, observe(9)
RST [MID=55770], 0 ----->
Establish an observe relation and receive NON notifications
294 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:36175#0e and resource /obs - (org.eclipse.californium.core.CoapResource.java:675) addObserveRelation() in thread pool-17-thread-5 at (2015-08-06 06:22:45)
Resource changed: First notification 1628016331
Resource changed: Second notification -175957589
Resource changed: Third notification 1472845596
Reject notification
295 INFO [ObserveRelation]: Canceling observe relation /127.0.0.1:36175#0e with /obs - (org.eclipse.californium.core.observe.ObserveRelation.java:115) cancel() in thread pool-17-thread-6 at (2015-08-06 06:22:45)
NON [MID=7004], GET, /obs, observe(0) ----->
<----- NON [MID=55771], 2.05, observe(9)
Observe relation established
<----- NON [MID=55772], 2.05, observe(10)
<----- CON [MID=55773], 2.05, observe(11)
ACK [MID=55773], 0 ----->
<----- NON [MID=55774], 2.05, observe(12)
RST [MID=55774], 0 ----->
Establish an observe relation and receive NON notifications
310 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:38057#0f and resource /obs - (org.eclipse.californium.core.CoapResource.java:675) addObserveRelation() in thread pool-17-thread-19 at (2015-08-06 06:22:46)
Resource changed: First notification -304498870
Resource changed: Second notification -52299425-
Resource changed: Third notification 142358334-
Resource changed: Fourth notification 1791124319
Reject notification
300 INFO [ObserveRelation]: Canceling observe relation /127.0.0.1:38057#0f with /obs - (org.eclipse.californium.core.observe.ObserveRelation.java:115) cancel() in thread pool-17-thread-11 at (2015-08-06 06:22:46)
NON [MID=7005], GET, /obs, 2:0/0/32, observe(0) ----->
<----- NON [MID=55775], 2.05, 2:0/0/32, observe(12)
Observe relation established
<----- NON [MID=55776], 2.05, 2:0/0/32, observe(13)
<----- CON [MID=55777], 2.05, 2:0/0/32, observe(14)
ACK [MID=55777], 0 ----->
<----- NON [MID=55778], 2.05, 2:0/0/32, observe(15)
<----- NON [MID=55779], 2.05, 2:0/0/32, observe(16)
RST [MID=55779], 0 ----->
Establish an observe relation to a quickly changing resource and do no longer respond
289 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:43116#10 and resource /obs - (org.eclipse.californium.core.CoapResource.java:675) addObserveRelation() in thread pool-17-thread-2 at (2015-08-06 06:22:46)
Resource changed: First notification -143602756
Resource changed: Second notification 1658097340
Resource changed: NON notification 1 1393088642
Resource changed: NON notification 2 -181201917
Resource changed: NON notification 3 -134279296
Resource changed: NON notification 4 1167047245
305 INFO [ReliabilityLayer$RetransmissionTask]: Timeout: retransmission limit reached, exchange failed, message: CON-2.05 MID=55781, Token=10, OptionSet={"Observe":22}, "NON notification 4 11670".. 29 bytes - (org.eclipse.californium.core.network.stack.ReliabilityLayer$RetransmissionTask.java:345) run() in thread pool-17-thread-14 at (2015-08-06 06:22:46)
305 INFO [ObserveLayer$NotificationController]: Notification 10 timed out. Cancel all relations with source /127.0.0.1:43116 - (org.eclipse.californium.core.network.stack.ObserveLayer$NotificationController.java:225) onTimeout() in thread pool-17-thread-14 at (2015-08-06 06:22:46)
305 INFO [ObserveRelation]: Canceling observe relation /127.0.0.1:43116#10 with /obs - (org.eclipse.californium.core.observe.ObserveRelation.java:115) cancel() in thread pool-17-thread-14 at (2015-08-06 06:22:46)
CON [MID=7006], GET, /obs, observe(0) ----->
<----- ACK [MID=7006], 2.05, observe(16)
Observe relation established
<----- NON [MID=55780], 2.05, observe(17)
<----- CON [MID=55781], 2.05, observe(18)
<----- CON [MID=55781], 2.05, observe(19)
<----- CON [MID=55781], 2.05, observe(20)
<----- CON [MID=55781], 2.05, observe(21)
<----- CON [MID=55781], 2.05, observe(22)
server cancels the relation
1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main at (2015-08-06 06:22:46)
1 INFO [CoAPEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:48207 - (org.eclipse.californium.core.network.CoAPEndpoint.java:222) destroy() in thread main at (2015-08-06 06:22:46)
1 INFO [CoAPEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:48207 - (org.eclipse.californium.core.network.CoAPEndpoint.java:314) stop() in thread main at (2015-08-06 06:22:46)
End ObserveServerSideTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.844 sec - in org.eclipse.californium.core.test.lockstep.ObserveServerSideTest
Running org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest
Start LossyBlockwiseTransferTest
1 INFO [CoAPEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoAPEndpoint.java:71) start() in thread main at (2015-08-06 06:22:46)
1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main at (2015-08-06 06:22:46)
1 INFO [CoAPEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoAPEndpoint.java:192) start() in thread main at (2015-08-06 06:22:46)
Client at 39069, middle at 55119, server at 59522
Start man in the middle
uri: coap://localhost:55119/test
Man in the middle will drop packets [5, 6, 8, 9, 15]
Drop packet 5 (burst 1)
Drop packet 6 (burst 2)
333 INFO [Matcher]: Duplicate ongoing request: CON-GET MID=23746, Token=7c04ad7d, OptionSet={"Uri-Port":55119, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:251) receiveRequest() in thread pool-18-thread-4 at (2015-08-06 06:22:47)
Drop packet 8 (burst 3)
Drop packet 9 (burst 4)
332 INFO [Matcher]: Duplicate ongoing request: CON-GET MID=23746, Token=7c04ad7d, OptionSet={"Uri-Port":55119, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:251) receiveRequest() in thread pool-18-thread-3 at (2015-08-06 06:22:47)
Drop packet 15 (burst 1)
334 INFO [Matcher]: Duplicate ongoing request: CON-GET MID=23748, Token=7c04ad7d, OptionSet={"Uri-Port":55119, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=4)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:251) receiveRequest() in thread pool-18-thread-5 at (2015-08-06 06:22:47)
Received 250 bytes
Man in the middle will drop packets [1, 1, 2, 5, 5, 5, 8, 9, 10, 15]
Drop packet 1 (burst 1)
Drop packet 2 (burst 2)
339 INFO [Matcher]: Duplicate request: CON-GET MID=23752, Token=7c04ad7e, OptionSet={"Uri-Port":55119, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.Matcher.java:235) receiveRequest() in thread pool-18-thread-10 at (2015-08-06 06:22:48)
Drop packet 5 (burst 2)
Drop packet 8 (burst 2)
Drop packet 9 (burst 3)
Drop packet 10 (burst 4)
Drop packet 15 (burst 1)
Received 250 bytes
Man in the middle will drop packets [0, 0, 0, 3, 7, 8, 8, 10, 11, 13]
Drop packet 0 (burst 1)
Drop packet 3 (burst 1)
Drop packet 7 (burst 1)
Drop packet 8 (burst 2)
332 INFO [Matcher]: Duplicate ongoing request: CON-GET MID=23762, Token=7c04ad7f, OptionSet={"Uri-Port":55119, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:251) receiveRequest() in thread pool-18-thread-3 at (2015-08-06 06:22:50)
Drop packet 10 (burst 3)
Drop packet 11 (burst 4)
348 INFO [Matcher]: Duplicate ongoing request: CON-GET MID=23762, Token=7c04ad7f, OptionSet={"Uri-Port":55119, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:251) receiveRequest() in thread pool-18-thread-18 at (2015-08-06 06:22:50)
Received 250 bytes
Man in the middle will drop packets [1, 2, 3, 8, 8, 11, 12, 14, 15, 15]
Drop packet 1 (burst 1)
Drop packet 2 (burst 2)
Drop packet 3 (burst 3)
340 INFO [Matcher]: Duplicate request: CON-GET MID=23768, Token=7c04ad80, OptionSet={"Uri-Port":55119, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.Matcher.java:235) receiveRequest() in thread pool-18-thread-11 at (2015-08-06 06:22:51)
Drop packet 8 (burst 1)
Drop packet 11 (burst 1)
Drop packet 12 (burst 2)
Drop packet 14 (burst 3)
Drop packet 15 (burst 4)
337 INFO [Matcher]: Duplicate ongoing request: CON-GET MID=23771, Token=7c04ad80, OptionSet={"Uri-Port":55119, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=3)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:251) receiveRequest() in thread pool-18-thread-8 at (2015-08-06 06:22:52)
Received 250 bytes
Man in the middle will drop packets [0, 0, 1, 6, 8, 9, 10, 11, 12, 15]
Drop packet 0 (burst 1)
Drop packet 1 (burst 2)
Drop packet 6 (burst 1)
Drop packet 8 (burst 2)
Drop packet 9 (burst 3)
Drop packet 10 (burst 4)
353 INFO [Matcher]: Duplicate ongoing request: CON-GET MID=23778, Token=7c04ad81, OptionSet={"Uri-Port":55119, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:251) receiveRequest() in thread pool-18-thread-23 at (2015-08-06 06:22:53)
Drop packet 15 (burst 1)
Received 250 bytes
Man in the middle will drop packets [0, 1, 5, 5, 5, 5, 7, 8, 13, 14]
Drop packet 0 (burst 1)
Drop packet 1 (burst 2)
Drop packet 5 (burst 1)
333 INFO [Matcher]: Duplicate ongoing request: CON-GET MID=23785, Token=7c04ad82, OptionSet={"Uri-Port":55119, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:251) receiveRequest() in thread pool-18-thread-4 at (2015-08-06 06:22:54)
Drop packet 7 (burst 2)
Drop packet 8 (burst 3)
343 INFO [Matcher]: Duplicate ongoing request: CON-GET MID=23785, Token=7c04ad82, OptionSet={"Uri-Port":55119, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:251) receiveRequest() in thread pool-18-thread-14 at (2015-08-06 06:22:54)
Drop packet 13 (burst 1)
Drop packet 14 (burst 2)
Received 250 bytes
1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main at (2015-08-06 06:22:54)
1 INFO [CoAPEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:59522 - (org.eclipse.californium.core.network.CoAPEndpoint.java:222) destroy() in thread main at (2015-08-06 06:22:54)
1 INFO [CoAPEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:59522 - (org.eclipse.californium.core.network.CoAPEndpoint.java:314) stop() in thread main at (2015-08-06 06:22:54)
1 INFO [CoAPEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:39069 - (org.eclipse.californium.core.network.CoAPEndpoint.java:90) destroy() in thread main at (2015-08-06 06:22:54)
1 INFO [CoAPEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:39069 - (org.eclipse.californium.core.network.CoAPEndpoint.java:314) stop() in thread main at (2015-08-06 06:22:54)
End LossyBlockwiseTransferTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 8.08 sec - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest
Running org.eclipse.californium.core.network.CoAPEndpointTest
1 INFO [CoAPEndpoint]: Starting endpoint at null - (org.eclipse.californium.core.network.CoAPEndpoint.java:83) start() in thread main at (2015-08-06 06:22:54)
1 INFO [CoAPEndpoint]: Stopping endpoint at address null - (org.eclipse.californium.core.network.CoAPEndpoint.java:60) stop() in thread main at (2015-08-06 06:22:54)
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.007 sec - in org.eclipse.californium.core.network.CoAPEndpointTest
Results :
Failed tests:
MessageTypeTest.testConfirmable:126
Tests in error:
ResourceTreeTest.testNameChange:68 NullPointer
Tests run: 37, Failures: 1, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Californium (Cf) Root .............................. SUCCESS [ 2.637 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 35.161 s]
[INFO] Californium (Cf) Proxy ............................. SKIPPED
[INFO] Californium (Cf) OSGi Bundle ....................... SKIPPED
[INFO] Cf-PlugtestClient .................................. SKIPPED
[INFO] Cf-PlugtestChecker ................................. SKIPPED
[INFO] Cf-PlugtestServer .................................. SKIPPED
[INFO] Cf-HelloWorldClient ................................ SKIPPED
[INFO] Cf-HelloWorldServer ................................ SKIPPED
[INFO] Cf-CoCoA-Client .................................... SKIPPED
[INFO] Cf-SecureServer .................................... SKIPPED
[INFO] Cf-ExampleCrossProxy ............................... SKIPPED
[INFO] Cf Benchmark Server ................................ SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 39.123 s
[INFO] Finished at: 2015-08-06T06:22:55-04:00
[INFO] Final Memory: 32M/2037M
[INFO] ------------------------------------------------------------------------
[INFO] o.h.m.e.h.MavenExecutionResultHandler - Build failed with exception(s)
[INFO] o.h.m.e.h.MavenExecutionResultHandler - [1] org.apache.maven.lifecycle.LifecycleExecutionException: Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project californium-core: There are test failures.
Please refer to /jobs/genie.californium/californium/workspace/californium-core/target/surefire-reports for the individual test results.
[DEBUG] Closing connection to remote
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project californium-core: There are test failures.
[ERROR]
[ERROR] Please refer to /jobs/genie.californium/californium/workspace/californium-core/target/surefire-reports for the individual test results.
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :californium-core
[DEBUG] Waiting for process to finish
[DEBUG] Result: 1
Archiving artifacts
--
This message is automatically generated by Hudson.
For more information on Hudson, see: http://hudson-ci.org/
