Skip to main content

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] [Hudson] Build failed in Hudson: master #98

See <https://hudson.eclipse.org/californium/job/master/98/changes>

Changes:

[Kai Hudalla] Improve shutdown of CoapServer's scheduled executor.

[Kai Hudalla] Fix #63: Keep track of MIDs in use for each peer endpoint individually.

[Kai Hudalla] Use LeastRecentlyUsedCache for maintaining MIDs per peer.

------------------------------------------
[...truncated 1834 lines...]
Establish an observe relation and receive NON notifications
337 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:36933#23 and resource /obs - (org.eclipse.californium.core.CoapResource.java:301) addObserveRelation() in thread CoapServer#1 at (2016-10-06 04:28:14)
Resource changed: First notification -164590159
Resource changed: Second notification 5354869826
Resource changed: Third notification 5271188302
Reject notification
NON [MID=7001], GET, /obs, observe(0)    ----->
<-----   NON [MID=59], 2.05, observe(0)    
Observe relation established
<-----   NON [MID=60], 2.05, observe(1)    
<-----   CON [MID=61], 2.05, observe(2)    
ACK [MID=61], 0                           ----->
<-----   NON [MID=62], 2.05, observe(3)    
RST [MID=62], 0                           ----->


 1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2016-10-06 04:28:14)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:49600 - (org.eclipse.californium.core.network.CoapEndpoint.java:270) destroy() in thread main at (2016-10-06 04:28:14)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:49600 - (org.eclipse.californium.core.network.CoapEndpoint.java:395) stop() in thread main at (2016-10-06 04:28:14)
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:107) destroy() in thread main at (2016-10-06 04:28:14)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:200) start() in thread main at (2016-10-06 04:28:14)
 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:212) start() in thread main at (2016-10-06 04:28:14)
Server binds to port 37202
Establish an observe relation. Cancellation due to a reject from the client
348 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:57953#24 and resource /obs - (org.eclipse.californium.core.CoapResource.java:301) addObserveRelation() in thread CoapServer#1 at (2016-10-06 04:28:14)
Resource changed: First notification -571899193
Reject notification
CON [MID=7001], GET, /obs, observe(0)    ----->
<-----   ACK [MID=7001], 2.05, observe(0)    
Observe relation established
<-----   CON [MID=623], 2.05, observe(1)    // lost 
<-----   CON [MID=623], 2.05, observe(1)    
RST [MID=623], 0                          ----->


 1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2016-10-06 04:28:14)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:37202 - (org.eclipse.californium.core.network.CoapEndpoint.java:270) destroy() in thread main at (2016-10-06 04:28:14)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:37202 - (org.eclipse.californium.core.network.CoapEndpoint.java:395) stop() in thread main at (2016-10-06 04:28:14)
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:107) destroy() in thread main at (2016-10-06 04:28:14)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:200) start() in thread main at (2016-10-06 04:28:14)
 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:212) start() in thread main at (2016-10-06 04:28:14)
Server binds to port 49401
Establish an observe relation. Cancellation after timeout. During the timeouts, the resource still changes.
358 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:38387#25 and resource /obs - (org.eclipse.californium.core.CoapResource.java:301) addObserveRelation() in thread CoapServer#1 at (2016-10-06 04:28:14)
Resource changed: First notification -387421656
Resource changed: Second notification 1681587842
Resource changed: Third notification -932846091
364 INFO [ObserveLayer$NotificationController]: Notification 25 timed out. Cancel all relations with source /127.0.0.1:38387 - (org.eclipse.californium.core.network.stack.ObserveLayer$NotificationController.java:532) onTimeout() in thread CoapServer#5 at (2016-10-06 04:28:15)
CON [MID=7001], GET, /obs, observe(0)    ----->
<-----   ACK [MID=7001], 2.05, observe(0)    
Observe relation established
<-----   CON [MID=41], 2.05, observe(1)    // lost 
<-----   CON [MID=41], 2.05, observe(1)    // lost (1. retransmission)
<-----   CON [MID=41], 2.05, observe(2)    // lost (2. retransmission)
<-----   CON [MID=41], 2.05, observe(3)    // lost (3. retransmission)
<-----   CON [MID=41], 2.05, observe(3)    // lost (4. retransmission)


 1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2016-10-06 04:28:15)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:49401 - (org.eclipse.californium.core.network.CoapEndpoint.java:270) destroy() in thread main at (2016-10-06 04:28:15)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:49401 - (org.eclipse.californium.core.network.CoapEndpoint.java:395) stop() in thread main at (2016-10-06 04:28:15)
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:107) destroy() in thread main at (2016-10-06 04:28:15)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:200) start() in thread main at (2016-10-06 04:28:15)
 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:212) start() in thread main at (2016-10-06 04:28:15)
Server binds to port 50802
Establish an observe relation. Cancellation after timeout
372 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:53723#26 and resource /obs - (org.eclipse.californium.core.CoapResource.java:301) addObserveRelation() in thread CoapServer#1 at (2016-10-06 04:28:15)
Resource changed: First notification
Resource changed: Second notification
Resource changed: Third notification
Resource changed: Forth notification
Resource changed: Fifth notification
379 INFO [ObserveLayer$NotificationController]: Notification 26 timed out. Cancel all relations with source /127.0.0.1:53723 - (org.eclipse.californium.core.network.stack.ObserveLayer$NotificationController.java:532) onTimeout() in thread CoapServer#6 at (2016-10-06 04:28:15)
CON [MID=7001], GET, /obs, observe(0)    ----->
<-----   ACK [MID=7001], 2.05, observe(0)    
Observe relation established
<-----   NON [MID=671], 2.05, observe(1)    
<-----   NON [MID=672], 2.05, observe(2)    
<-----   CON [MID=673], 2.05, observe(3)    
ACK [MID=673], 0                          ----->
<-----   NON [MID=674], 2.05, observe(4)    
<-----   CON [MID=675], 2.05, observe(5)    // lost
<-----   CON [MID=675], 2.05, observe(5)    // lost
<-----   CON [MID=675], 2.05, observe(5)    // lost
<-----   CON [MID=675], 2.05, observe(5)    // lost
<-----   CON [MID=675], 2.05, observe(5)    // lost


 1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2016-10-06 04:28:15)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:50802 - (org.eclipse.californium.core.network.CoapEndpoint.java:270) destroy() in thread main at (2016-10-06 04:28:15)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:50802 - (org.eclipse.californium.core.network.CoapEndpoint.java:395) stop() in thread main at (2016-10-06 04:28:15)
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:107) destroy() in thread main at (2016-10-06 04:28:15)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:200) start() in thread main at (2016-10-06 04:28:15)
 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:212) start() in thread main at (2016-10-06 04:28:15)
Server binds to port 58294
Establish an observe relation to a quickly changing resource and do no longer respond
387 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:45439#27 and resource /obs - (org.eclipse.californium.core.CoapResource.java:301) addObserveRelation() in thread CoapServer#1 at (2016-10-06 04:28:15)
Resource changed: First notification 1099224046
Resource changed: Second notification 1682605962
Resource changed: NON notification 1 1827848861
Resource changed: NON notification 2 -924186186
Resource changed: NON notification 3 9190149071
Resource changed: NON notification 4 -897224657
394 INFO [ObserveLayer$NotificationController]: Notification 27 timed out. Cancel all relations with source /127.0.0.1:45439 - (org.eclipse.californium.core.network.stack.ObserveLayer$NotificationController.java:532) onTimeout() in thread CoapServer#6 at (2016-10-06 04:28:16)
CON [MID=7001], GET, /obs, observe(0)    ----->
<-----   ACK [MID=7001], 2.05, observe(0)    
Observe relation established
<-----   NON [MID=354], 2.05, observe(1)    
<-----   CON [MID=355], 2.05, observe(2)    
<-----   CON [MID=355], 2.05, observe(3)    
<-----   CON [MID=355], 2.05, observe(4)    
<-----   CON [MID=355], 2.05, observe(5)    
<-----   CON [MID=355], 2.05, observe(6)    
   server cancels the relation


 1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2016-10-06 04:28:16)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:58294 - (org.eclipse.californium.core.network.CoapEndpoint.java:270) destroy() in thread main at (2016-10-06 04:28:16)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:58294 - (org.eclipse.californium.core.network.CoapEndpoint.java:395) stop() in thread main at (2016-10-06 04:28:16)
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:107) destroy() in thread main at (2016-10-06 04:28:16)
End ObserveServerSideTest
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.458 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 localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:75) start() in thread main at (2016-10-06 04:28:16)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:200) start() in thread main at (2016-10-06 04:28:16)
 1 INFO [CoapEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:212) start() in thread main at (2016-10-06 04:28:16)
Client at 48381, middle at 46976, server at 38127
Start man in the middle
uri: coap://localhost:46976/test
Man in the middle will drop packets [5, 6, 8, 9, 15]
Drop packet 5 (burst 1)
Drop packet 6 (burst 2)
Drop packet 8 (burst 3)
Drop packet 9 (burst 4)
Drop packet 15 (burst 1)
Received 250 bytes
Man in the middle will drop packets [1, 3, 3, 4, 4, 5, 7, 9, 13, 13]
Drop packet 1 (burst 1)
Drop packet 3 (burst 2)
Drop packet 4 (burst 3)
Drop packet 5 (burst 4)
Drop packet 9 (burst 1)
Drop packet 13 (burst 1)
Received 250 bytes
Man in the middle will drop packets [2, 2, 6, 6, 7, 7, 9, 13, 14, 15]
Drop packet 2 (burst 1)
Drop packet 6 (burst 1)
Drop packet 7 (burst 2)
Drop packet 9 (burst 3)
Drop packet 13 (burst 1)
Drop packet 14 (burst 2)
Drop packet 15 (burst 3)
Received 250 bytes
Man in the middle will drop packets [0, 3, 4, 4, 7, 8, 9, 9, 12, 15]
Drop packet 0 (burst 1)
Drop packet 3 (burst 1)
Drop packet 4 (burst 2)
Drop packet 7 (burst 2)
Drop packet 8 (burst 3)
Drop packet 9 (burst 4)
Drop packet 15 (burst 1)
Received 250 bytes
Man in the middle will drop packets [0, 0, 5, 8, 9, 10, 11, 12, 13, 15]
Drop packet 0 (burst 1)
Drop packet 5 (burst 1)
Drop packet 8 (burst 1)
Drop packet 9 (burst 2)
Drop packet 10 (burst 3)
Drop packet 11 (burst 4)
Drop packet 15 (burst 1)
Received 250 bytes
Man in the middle will drop packets [0, 1, 3, 6, 6, 6, 7, 8, 9, 10]
Drop packet 0 (burst 1)
Drop packet 1 (burst 2)
Drop packet 3 (burst 3)
Drop packet 6 (burst 3)
Drop packet 7 (burst 4)
Received 250 bytes

 1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2016-10-06 04:28:24)
 1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:38127 - (org.eclipse.californium.core.network.CoapEndpoint.java:270) destroy() in thread main at (2016-10-06 04:28:24)
 1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:38127 - (org.eclipse.californium.core.network.CoapEndpoint.java:395) stop() in thread main at (2016-10-06 04:28:24)
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:93) destroy() in thread main at (2016-10-06 04:28:24)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:48381 - (org.eclipse.californium.core.network.CoapEndpoint.java:94) destroy() in thread main at (2016-10-06 04:28:24)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:48381 - (org.eclipse.californium.core.network.CoapEndpoint.java:395) stop() in thread main at (2016-10-06 04:28:24)
End LossyBlockwiseTransferTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.497 sec - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest

Results :

Failed tests: 
  MemoryLeakingHashMapTest.assertAllExchangesAreCompleted:105 null

Tests run: 34, Failures: 1, Errors: 0, Skipped: 0

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  1.601 s]
[INFO] element-connector .................................. SUCCESS [ 58.017 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  2.322 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 14.207 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 40.001 s]
[INFO] Californium (Cf) Proxy ............................. SKIPPED
[INFO] Californium (Cf) OSGi Bundle ....................... SKIPPED
[INFO] Californium (Cf) Demo Applications ................. SKIPPED
[INFO] Sc-DTLS-Example-Server ............................. SKIPPED
[INFO] Sc-DTLS-Example-Client ............................. SKIPPED
[INFO] Cf-PlugtestClient .................................. SKIPPED
[INFO] Cf-PlugtestChecker ................................. SKIPPED
[INFO] Cf-PlugtestServer .................................. SKIPPED
[INFO] Cf-HelloWorldClient ................................ SKIPPED
[INFO] Cf-HelloWorldServer ................................ SKIPPED
[INFO] Cf-CoCoAClient ..................................... SKIPPED
[INFO] Cf-SecureServer .................................... SKIPPED
[INFO] Cf-ExampleCrossProxy ............................... SKIPPED
[INFO] Cf-BenchmarkServer ................................. SKIPPED
[INFO] Cf-ObserveBenchmarkClient .......................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 02:00 min
[INFO] Finished at: 2016-10-06T04:28:24-04:00
[INFO] Final Memory: 49M/2437M
[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.18.1:test (large-tests) on project californium-core: There are test failures.

Please refer to /jobs/genie.californium/master/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.18.1:test (large-tests) on project californium-core: There are test failures.
[ERROR] 
[ERROR] Please refer to /jobs/genie.californium/master/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
Recording test results
Archiving artifacts

--
This message is automatically generated by Hudson. 
For more information on Hudson, see: http://hudson-ci.org/


Back to the top