[cf-dev] [Hudson] Build failed in Hudson: californium.core #149

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] [Hudson] Build failed in Hudson: californium.core #149
From: hudsonbuild@xxxxxxxxxxx
Date: Thu, 26 Nov 2015 10:55:30 -0500 (EST)
Delivered-to: cf-dev@xxxxxxxxxxx
List-archive: <https://dev.eclipse.org/mailman/private/cf-dev>
List-help: <mailto:cf-dev-request@eclipse.org?subject=help>
List-subscribe: <https://dev.eclipse.org/mailman/listinfo/cf-dev>, <mailto:cf-dev-request@eclipse.org?subject=subscribe>
List-unsubscribe: <https://dev.eclipse.org/mailman/options/cf-dev>, <mailto:cf-dev-request@eclipse.org?subject=unsubscribe>
See <https://hudson.eclipse.org/californium/job/californium.core/149/changes>

Changes:

[hudson] Release 1.0.0

[hudson] Release 1.0.0

------------------------------------------
[...truncated 1027 lines...]
<-----   CON [MID=39358], 2.05, observe(5)    // lost
<-----   CON [MID=39358], 2.05, observe(5)    // lost

Establish an observe relation. Cancellation after timeout. During the timeouts, the resource still changes.
293 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:43101#0c and resource /obs - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-17-thread-2 at (2015-11-26 10:55:13)
Resource changed: First notification -192956937
Resource changed: Second notification -220895436
Resource changed: Third notification 1092032175
308 INFO [ObserveLayer$NotificationController]: Notification 0c timed out. Cancel all relations with source /127.0.0.1:43101 - (org.eclipse.californium.core.network.stack.ObserveLayer$NotificationController.java:225) onTimeout() in thread pool-17-thread-13 at (2015-11-26 10:55:14)
CON [MID=7002], GET, /obs, observe(0)    ----->
<-----   ACK [MID=7002], 2.05, observe(5)    
Observe relation established
<-----   CON [MID=39359], 2.05, observe(6)    // lost 
<-----   CON [MID=39359], 2.05, observe(6)    // lost (1. retransmission)
<-----   CON [MID=39359], 2.05, observe(7)    // lost (2. retransmission)
<-----   CON [MID=39359], 2.05, observe(8)    // lost (3. retransmission)
<-----   CON [MID=39359], 2.05, observe(8)    // lost (4. retransmission)

Establish an observe relation. Cancellation due to a reject from the client
309 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:58384#0d and resource /obs - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-17-thread-14 at (2015-11-26 10:55:14)
Resource changed: First notification -121822729
Reject notification
CON [MID=7003], GET, /obs, observe(0)    ----->
<-----   ACK [MID=7003], 2.05, observe(8)    
Observe relation established
<-----   CON [MID=39360], 2.05, observe(9)    // lost 
<-----   CON [MID=39360], 2.05, observe(9)    
RST [MID=39360], 0                        ----->

Observe with blockwise
298 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:58331#0e and resource /obs - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-17-thread-5 at (2015-11-26 10:55:14)
Resource changed: -18449472011641120231-566798320-254649421091486825189512081911040710-380023270-7
Resource changed: -190561115120259758511946070559-1283928848-4426787615772466374890303381997638383
CON [MID=7004], GET, /obs, observe(0)    ----->
<-----   ACK [MID=7004], 2.05, 2:0/1/32, observe(9)    
Observe relation established
CON [MID=7005], GET, /obs, 2:1/0/32    ----->
<-----   ACK [MID=7005], 2.05, 2:1/1/32    
CON [MID=7006], GET, /obs, 2:2/0/32    ----->
<-----   ACK [MID=7006], 2.05, 2:2/0/32    
<-----   CON [MID=39361], 2.05, 2:0/1/32, observe(10)    
   === changed ===
ACK [MID=39361], 0                        ----->
CON [MID=7007], GET, /obs, 2:1/0/32    ----->
<-----   ACK [MID=7007], 2.05, 2:1/1/32    
CON [MID=7008], GET, /obs, 2:2/0/32    ----->
<-----   ACK [MID=7008], 2.05, 2:2/0/32    
<-----   CON [MID=39362], 2.05, 2:0/1/32, observe(11)    
   === changed ===
RST [MID=39362], 0                        ----->

Establish an observe relation and receive NON notifications
318 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:50601#11 and resource /obs - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-17-thread-21 at (2015-11-26 10:55:15)
Resource changed: First notification -124911561
Resource changed: Second notification 484139371-
Resource changed: Third notification -163643630
Reject notification
NON [MID=7009], GET, /obs, observe(0)    ----->
<-----   NON [MID=39363], 2.05, observe(11)    
Observe relation established
<-----   NON [MID=39364], 2.05, observe(12)    
<-----   CON [MID=39365], 2.05, observe(13)    
ACK [MID=39365], 0                        ----->
<-----   NON [MID=39366], 2.05, observe(14)    
RST [MID=39366], 0                        ----->

Establish an observe relation and receive NON notifications
298 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:52090#12 and resource /obs - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-17-thread-5 at (2015-11-26 10:55:15)
Resource changed: First notification -334875705
Resource changed: Second notification 1712051036
Resource changed: Third notification 561663457-
Resource changed: Fourth notification -112772406
Reject notification
NON [MID=7010], GET, /obs, 2:0/0/32, observe(0)    ----->
<-----   NON [MID=39367], 2.05, 2:0/0/32, observe(14)    
Observe relation established
<-----   NON [MID=39368], 2.05, observe(15)    
<-----   CON [MID=39369], 2.05, observe(16)    
ACK [MID=39369], 0                        ----->
<-----   NON [MID=39370], 2.05, observe(17)    
<-----   NON [MID=39371], 2.05, observe(18)    
RST [MID=39371], 0                        ----->

Establish an observe relation to a quickly changing resource and do no longer respond
308 INFO [CoapResource]: Successfully established observe relation between /127.0.0.1:53748#13 and resource /obs - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-17-thread-13 at (2015-11-26 10:55:15)
Resource changed: First notification 2099292113
Resource changed: Second notification 7910146546
Resource changed: NON notification 1 1531160942
Resource changed: NON notification 2 -145878017
Resource changed: NON notification 3 2802755336
Resource changed: NON notification 4 7319551314
313 INFO [ObserveLayer$NotificationController]: Notification 13 timed out. Cancel all relations with source /127.0.0.1:53748 - (org.eclipse.californium.core.network.stack.ObserveLayer$NotificationController.java:225) onTimeout() in thread pool-17-thread-18 at (2015-11-26 10:55:16)
CON [MID=7011], GET, /obs, observe(0)    ----->
<-----   ACK [MID=7011], 2.05, observe(18)    
Observe relation established
<-----   NON [MID=39372], 2.05, observe(19)    
<-----   CON [MID=39373], 2.05, observe(20)    
<-----   CON [MID=39373], 2.05, observe(21)    
<-----   CON [MID=39373], 2.05, observe(22)    
<-----   CON [MID=39373], 2.05, observe(23)    
<-----   CON [MID=39373], 2.05, observe(24)    
   server cancels the relation


 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main at (2015-11-26 10:55:16)
 1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:59179 - (org.eclipse.californium.core.network.CoapEndpoint.java:222) destroy() in thread main at (2015-11-26 10:55:16)
 1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:59179 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) stop() in thread main at (2015-11-26 10:55:16)
 1 WARNING [CoapServer]: Server executor did not shutdown in time - (org.eclipse.californium.core.CoapServer.java:227) destroy() in thread main at (2015-11-26 10:55:21)
End ObserveServerSideTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 8.284 sec - in org.eclipse.californium.core.test.lockstep.ObserveServerSideTest
Running org.eclipse.californium.core.test.CoapTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.eclipse.californium.core.test.CoapTest
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-11-26 10:55:21)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main at (2015-11-26 10:55:21)
 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-11-26 10:55:21)
Client at 48264, middle at 36954, server at 40181
Start man in the middle
uri: coap://localhost:36954/test
Man in the middle will drop packets [5, 6, 8, 9, 15]
Drop packet 5 (burst 1)
Drop packet 6 (burst 2)
339 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48148, Token=f3fa19df079e33, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-3 at (2015-11-26 10:55:21)
Drop packet 8 (burst 3)
Drop packet 9 (burst 4)
342 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48148, Token=f3fa19df079e33, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-6 at (2015-11-26 10:55:22)
Drop packet 15 (burst 1)
341 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48150, Token=f3fa19df079e33, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=4)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-5 at (2015-11-26 10:55:22)
Received 250 bytes
Man in the middle will drop packets [2, 2, 6, 6, 7, 8, 8, 9, 9, 14]
Drop packet 2 (burst 1)
Drop packet 6 (burst 1)
Drop packet 7 (burst 2)
Drop packet 8 (burst 3)
Drop packet 9 (burst 4)
345 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48156, Token=5c23c74745, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-9 at (2015-11-26 10:55:23)
Drop packet 14 (burst 1)
Received 250 bytes
Man in the middle will drop packets [1, 1, 3, 10, 11, 12, 13, 13, 14, 15]
Drop packet 1 (burst 1)
343 INFO [Matcher]: Duplicate request: CON-GET    MID=48162, Token=fd48c058bcbbe6, OptionSet={"Uri-Port":36954, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.Matcher.java:256) receiveRequest() in thread pool-18-thread-7 at (2015-11-26 10:55:23)
Drop packet 3 (burst 2)
358 INFO [Matcher]: Duplicate request: CON-GET    MID=48162, Token=fd48c058bcbbe6, OptionSet={"Uri-Port":36954, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.Matcher.java:256) receiveRequest() in thread pool-18-thread-22 at (2015-11-26 10:55:24)
Drop packet 10 (burst 1)
Drop packet 11 (burst 2)
Drop packet 12 (burst 3)
Drop packet 13 (burst 4)
Received 250 bytes
Man in the middle will drop packets [3, 5, 7, 8, 8, 9, 12, 12, 13, 15]
Drop packet 3 (burst 1)
357 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48171, Token=e50a6d30600765, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-21 at (2015-11-26 10:55:25)
Drop packet 5 (burst 2)
334 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48171, Token=e50a6d30600765, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-1 at (2015-11-26 10:55:25)
Drop packet 7 (burst 3)
Drop packet 8 (burst 4)
359 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48171, Token=e50a6d30600765, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-23 at (2015-11-26 10:55:25)
Drop packet 12 (burst 1)
Drop packet 13 (burst 2)
340 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48172, Token=e50a6d30600765, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-4 at (2015-11-26 10:55:26)
Drop packet 15 (burst 3)
350 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48172, Token=e50a6d30600765, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-14 at (2015-11-26 10:55:26)
Received 250 bytes
Man in the middle will drop packets [0, 2, 2, 3, 6, 9, 10, 13, 14, 15]
Drop packet 0 (burst 1)
Drop packet 2 (burst 2)
Drop packet 3 (burst 3)
356 INFO [Matcher]: Duplicate request: CON-GET    MID=48178, Token=a3c1768b450228, OptionSet={"Uri-Port":36954, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.Matcher.java:256) receiveRequest() in thread pool-18-thread-20 at (2015-11-26 10:55:26)
Drop packet 6 (burst 3)
Drop packet 9 (burst 3)
Drop packet 10 (burst 4)
Drop packet 14 (burst 1)
Drop packet 15 (burst 2)
360 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48181, Token=a3c1768b450228, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=3)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-24 at (2015-11-26 10:55:27)
Received 250 bytes
Man in the middle will drop packets [1, 3, 4, 5, 5, 6, 9, 10, 11, 14]
Drop packet 1 (burst 1)
355 INFO [Matcher]: Duplicate request: CON-GET    MID=48186, Token=5d47e94ab6, OptionSet={"Uri-Port":36954, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.Matcher.java:256) receiveRequest() in thread pool-18-thread-19 at (2015-11-26 10:55:28)
Drop packet 3 (burst 2)
Drop packet 4 (burst 3)
Drop packet 5 (burst 4)
347 INFO [Matcher]: Duplicate request: CON-GET    MID=48186, Token=5d47e94ab6, OptionSet={"Uri-Port":36954, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.Matcher.java:256) receiveRequest() in thread pool-18-thread-11 at (2015-11-26 10:55:28)
Drop packet 9 (burst 1)
Drop packet 10 (burst 2)
Drop packet 11 (burst 3)
334 INFO [Matcher]: Duplicate ongoing request: CON-GET    MID=48187, Token=5d47e94ab6, OptionSet={"Uri-Port":36954, "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:272) receiveRequest() in thread pool-18-thread-1 at (2015-11-26 10:55:29)
Drop packet 14 (burst 3)
Received 250 bytes

 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main at (2015-11-26 10:55:29)
 1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:40181 - (org.eclipse.californium.core.network.CoapEndpoint.java:222) destroy() in thread main at (2015-11-26 10:55:29)
 1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:40181 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) stop() in thread main at (2015-11-26 10:55:29)
 1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:48264 - (org.eclipse.californium.core.network.CoapEndpoint.java:90) destroy() in thread main at (2015-11-26 10:55:29)
 1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:48264 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) stop() in thread main at (2015-11-26 10:55:29)
End LossyBlockwiseTransferTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 8.136 sec - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest

Results :

Failed tests: 
  MemoryLeakingHashMapTest.testServer:85->testObserveBlockwise:312 Client has not received all expected responses

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Core .............................. FAILURE [ 57.912 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] Cf Observe Benchmark Client ........................ SKIPPED
[INFO] Cf Demo Applications ............................... SKIPPED
[INFO] Californium (Cf) ................................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 01:02 min
[INFO] Finished at: 2015-11-26T10:55:30-05:00
[INFO] Final Memory: 34M/1932M
[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 (default-test) on project californium-core: There are test failures.

Please refer to /jobs/genie.californium/californium.core/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 (default-test) on project californium-core: There are test failures.
[ERROR] 
[ERROR] Please refer to /jobs/genie.californium/californium.core/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
[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/
Follow-Ups:
- [cf-dev] [Hudson] Hudson build is back to normal : californium.core #150
  - From: hudsonbuild
Prev by Date: Re: [cf-dev] Scandium Certificate generation problem
Next by Date: [cf-dev] [Hudson] Build failed in Hudson: tools #39
Previous by thread: [cf-dev] Scandium Certificate generation problem
Next by thread: [cf-dev] [Hudson] Hudson build is back to normal : californium.core #150
Index(es):
- Date
- Thread
Breadcrumbs
