[cf-dev] [Hudson] Build failed in Hudson: 1.0.x-nightly #68

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] [Hudson] Build failed in Hudson: 1.0.x-nightly #68
From: hudsonbuild@xxxxxxxxxxx
Date: Fri, 30 Jun 2017 08:49:25 -0400 (EDT)
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/1.0.x-nightly/68/changes>

Changes:

[Achim Kraus] Adjust thread safe cancel of flight.

[Achim Kraus] Mask lower 16 bit when generate MID.

[Achim Kraus] Fix error stopping an connector, when socket failed to open.

------------------------------------------
[...truncated 1836 lines...]
08:49:09.223:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8240 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:49:09.223:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8240 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:49:09.224:   1 FINE [DirectDatagramSocketImpl]: closing port 8,240, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:49:09.225:   1 FINE [DirectDatagramSocketImpl]: closing port 8,241, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:49:09.226:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:49:09.226:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:09.227:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:09.227:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,242 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Client binds to port 8242
08:49:09.228:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:09.229:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:09.229:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,243 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Atomic blockwise POST with blockwise response:
08:49:09.333: 223 FINE [DirectDatagramSocketImpl]: (E239,T223) 127.0.0.1:8,242 ==> :8,243 [CON-POST   MID=63699, Token=9945302968982545, OptionSet={"Uri-Port":8243, "Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)"}, "-7193045101101635236-198".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:09.532: 223 FINE [DirectDatagramSocketImpl]: (E240,T223) 127.0.0.1:8,242 ==> :8,243 [CON-POST   MID=63699, Token=9945302968982545, OptionSet={"Uri-Port":8243, "Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)"}, "-7193045101101635236-198".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:09.933: 223 FINE [DirectDatagramSocketImpl]: (E241,T223) 127.0.0.1:8,242 ==> :8,243 [CON-POST   MID=63699, Token=9945302968982545, OptionSet={"Uri-Port":8243, "Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)"}, "-7193045101101635236-198".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:22.234: 221 FINE [DirectDatagramSocketImpl]: (E242,T221) 127.0.0.1:8,243 ==> :8,242 [ACK-2.31   MID=63699, Token=9945302968982545, OptionSet={"Block1":"(szx=3/128, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:22.236: 220 INFO [Matcher]: Ignoring unmatchable piggy-backed response from localhost/127.0.0.1:8243: ACK-2.31   MID=63699, Token=9945302968982545, OptionSet={"Block1":"(szx=3/128, m=true, num=0)"}, no payload - (org.eclipse.californium.core.network.Matcher.java:381) receiveResponse() in thread Thread-17
08:49:24.235:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8242 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:49:24.235:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8242 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:49:24.236:   1 FINE [DirectDatagramSocketImpl]: closing port 8,242, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:49:24.236:   1 FINE [DirectDatagramSocketImpl]: closing port 8,243, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:49:24.247:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:49:24.247:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:24.248:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:24.248:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,244 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Client binds to port 8244
08:49:24.249:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:24.250:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:24.250:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,245 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Simple atomic blockwise PUT
08:49:24.354: 228 FINE [DirectDatagramSocketImpl]: (E243,T228) 127.0.0.1:8,244 ==> :8,245 [CON-PUT    MID= 6849, Token=51861ab63a640cdc, OptionSet={"Uri-Port":8245, "Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)"}, "187070841382807784512955".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:24.355: 226 FINE [DirectDatagramSocketImpl]: (E244,T226) 127.0.0.1:8,245 ==> :8,244 [ACK-2.31   MID= 6849, Token=51861ab63a640cdc, OptionSet={"Block1":"(szx=3/128, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:24.358: 228 FINE [DirectDatagramSocketImpl]: (E245,T228) 127.0.0.1:8,244 ==> :8,245 [CON-PUT    MID= 6850, Token=51861ab63a640cdc, OptionSet={"Uri-Port":8245, "Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "6-335244512-1045215395-7".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:24.359: 226 FINE [DirectDatagramSocketImpl]: (E246,T226) 127.0.0.1:8,245 ==> :8,244 [ACK-2.31   MID= 6850, Token=51861ab63a640cdc, OptionSet={"Block1":"(szx=3/128, m=true, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:24.362: 228 FINE [DirectDatagramSocketImpl]: (E247,T228) 127.0.0.1:8,244 ==> :8,245 [CON-PUT    MID= 6851, Token=51861ab63a640cdc, OptionSet={"Uri-Port":8245, "Uri-Path":"test", "Block1":"(szx=3/128, m=false, num=2)"}, "79-159089297426794872313".. 44 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:24.363: 226 FINE [DirectDatagramSocketImpl]: (E248,T226) 127.0.0.1:8,245 ==> :8,244 [ACK-2.04   MID= 6851, Token=51861ab63a640cdc, OptionSet={"Block1":"(szx=3/128, m=false, num=2)"}, "-176336769390653372-1863".. 50 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
CON [MID=6849, T=51861ab63a640cdc], PUT, /test, 1:0/1/128    ----->
<-----   ACK [MID=6849, T=51861ab63a640cdc], 2.31, 1:0/1/128    
CON [MID=6850, T=51861ab63a640cdc], PUT, /test, 1:1/1/128    ----->
<-----   ACK [MID=6850, T=51861ab63a640cdc], 2.31, 1:1/1/128    
CON [MID=6851, T=51861ab63a640cdc], PUT, /test, 1:2/0/128    ----->
<-----   ACK [MID=6851, T=51861ab63a640cdc], 2.04, 1:2/0/128    

08:49:24.365:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8244 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:49:24.365:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8244 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:49:24.365:   1 FINE [DirectDatagramSocketImpl]: closing port 8,244, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:49:24.366:   1 FINE [DirectDatagramSocketImpl]: closing port 8,245, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
End BlockwiseClientSideTest
08:49:24.367:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
08:49:24.367:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:277) applyConfig() in thread main
Tests run: 11, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 18.339 sec <<< FAILURE! - in org.eclipse.californium.core.test.lockstep.BlockwiseClientSideTest
testAtomicBlockwisePOSTWithBlockwiseResponse(org.eclipse.californium.core.test.lockstep.BlockwiseClientSideTest)  Time elapsed: 15.026 sec  <<< FAILURE!
java.lang.AssertionError: did not receive message within expected time frame (2 secs)
	at org.junit.Assert.fail(Assert.java:88)
	at org.junit.Assert.assertTrue(Assert.java:41)
	at org.junit.Assert.assertNotNull(Assert.java:712)
	at org.eclipse.californium.core.test.lockstep.LockstepEndpoint.receiveNextMessage(LockstepEndpoint.java:270)
	at org.eclipse.californium.core.test.lockstep.LockstepEndpoint$RequestExpectation.go(LockstepEndpoint.java:672)
	at org.eclipse.californium.core.test.lockstep.BlockwiseClientSideTest.testAtomicBlockwisePOSTWithBlockwiseResponse(BlockwiseClientSideTest.java:449)

Running org.eclipse.californium.core.test.lockstep.DeduplicationTest

Start DeduplicationTest
08:49:24.377:   1 INFO [CoapEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:49:24.378:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:24.379:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:24.379:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,246 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Client binds to port 8246
Simple blockwise GET:
08:49:24.380:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:24.381:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:24.381:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,247 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:49:24.484: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 <== req CON-GET    MID= 1234, Token=6bafe26424, OptionSet={"Uri-Host":"localhost", "Uri-Port":8247, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:39) sendRequest() in thread Thread-19
08:49:24.485: 233 FINE [DirectDatagramSocketImpl]: (E249,T233) 127.0.0.1:8,246 ==> :8,247 [CON-GET    MID= 1234, Token=6bafe26424, OptionSet={"Uri-Host":"localhost", "Uri-Port":8247, "Uri-Path":"test"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:24.486: 231 FINE [DirectDatagramSocketImpl]: (E250,T231) 127.0.0.1:8,247 ==> :8,246 [ACK        MID= 1234] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:24.487: 231 FINE [DirectDatagramSocketImpl]: (E251,T231) 127.0.0.1:8,247 ==> :8,246 [ACK        MID= 1234] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:24.487: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 ==> emp ACK        MID= 1234 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:64) receiveEmptyMessage() in thread Thread-19
08:49:24.488: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 ==> emp ACK        MID= 1234 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:64) receiveEmptyMessage() in thread Thread-19
08:49:24.488: 231 FINE [DirectDatagramSocketImpl]: (E252,T231) 127.0.0.1:8,247 ==> :8,246 [CON-2.05   MID= 4711, Token=6bafe26424, OptionSet={}, "separate"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:24.488: 230 INFO [Matcher]: Ignoring unmatchable empty message from localhost/127.0.0.1:8247: ACK        MID= 1234 - (org.eclipse.californium.core.network.Matcher.java:400) receiveEmptyMessage() in thread Thread-19
08:49:24.489: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 ==> res CON-2.05   MID= 4711, Token=6bafe26424, OptionSet={}, "separate" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:59) receiveResponse() in thread Thread-19
08:49:24.489: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 <== emp ACK        MID= 4711 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:49) sendEmptyMessage() in thread Thread-19
08:49:24.490: 233 FINE [DirectDatagramSocketImpl]: (E253,T233) 127.0.0.1:8,246 ==> :8,247 [ACK        MID= 4711] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:24.491: 231 FINE [DirectDatagramSocketImpl]: (E254,T231) 127.0.0.1:8,247 ==> :8,246 [CON-2.05   MID= 4711, Token=6bafe26424, OptionSet={}, "separate"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:24.492: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 ==> res CON-2.05   MID= 4711, Token=6bafe26424, OptionSet={}, "separate" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:59) receiveResponse() in thread Thread-19
08:49:24.493: 230 INFO [Matcher]: Duplicate response for completed exchange: CON-2.05   MID= 4711, Token=6bafe26424, OptionSet={}, "separate" - (org.eclipse.californium.core.network.Matcher.java:376) receiveResponse() in thread Thread-19
08:49:24.493: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 <== emp ACK        MID= 4711 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:49) sendEmptyMessage() in thread Thread-19
08:49:24.494: 233 FINE [DirectDatagramSocketImpl]: (E255,T233) 127.0.0.1:8,246 ==> :8,247 [ACK        MID= 4711] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:24.495: 231 FINE [DirectDatagramSocketImpl]: (E256,T231) 127.0.0.1:8,247 ==> :8,246 [CON-2.05   MID=   42, Token=6bafe26424, OptionSet={}, "separate"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:24.496: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 ==> res CON-2.05   MID=   42, Token=6bafe26424, OptionSet={}, "separate" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:59) receiveResponse() in thread Thread-19
08:49:24.497: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 <== emp RST        MID=   42 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:49) sendEmptyMessage() in thread Thread-19
08:49:24.497: 233 FINE [DirectDatagramSocketImpl]: (E257,T233) 127.0.0.1:8,246 ==> :8,247 [RST        MID=   42] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:24.499: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 <== req CON-GET    MID= 4711, Token=65ae8edf05369a, OptionSet={"Uri-Host":"localhost", "Uri-Port":8247, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:39) sendRequest() in thread Thread-19
08:49:24.500: 233 FINE [DirectDatagramSocketImpl]: (E258,T233) 127.0.0.1:8,246 ==> :8,247 [CON-GET    MID= 4711, Token=65ae8edf05369a, OptionSet={"Uri-Host":"localhost", "Uri-Port":8247, "Uri-Path":"test"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:24.505: 231 FINE [DirectDatagramSocketImpl]: (E259,T231) 127.0.0.1:8,247 ==> :8,246 [ACK-2.05   MID= 4711, Token=65ae8edf05369a, OptionSet={}, "possible conflict"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:24.506: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 ==> res ACK-2.05   MID= 4711, Token=65ae8edf05369a, OptionSet={}, "possible conflict" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:59) receiveResponse() in thread Thread-19
08:49:24.506: 231 FINE [DirectDatagramSocketImpl]: (E260,T231) 127.0.0.1:8,247 ==> :8,246 [ACK-2.05   MID= 4711, Token=65ae8edf05369a, OptionSet={}, "possible conflict"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:24.507: 230 INFO [MessageTracer]: localhost/127.0.0.1:8247 ==> res ACK-2.05   MID= 4711, Token=65ae8edf05369a, OptionSet={}, "possible conflict" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:59) receiveResponse() in thread Thread-19
08:49:24.507: 230 INFO [Matcher]: Ignoring unmatchable piggy-backed response from localhost/127.0.0.1:8247: ACK-2.05   MID= 4711, Token=65ae8edf05369a, OptionSet={}, "possible conflict" - (org.eclipse.californium.core.network.Matcher.java:381) receiveResponse() in thread Thread-19

08:49:25.507:   1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:8246 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:49:25.507:   1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8246 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:49:25.507:   1 FINE [DirectDatagramSocketImpl]: closing port 8,246, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
End DeduplicationTest
08:49:25.508:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
08:49:25.509:   1 INFO [NetworkRule]: Test org.eclipse.californium.core.test.lockstep.DeduplicationTest did not close all DatagramSockets! - (org.eclipse.californium.elements.rule.NetworkRule.java:374) closeNetwork() in thread main
08:49:25.509:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:277) applyConfig() in thread main
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.108 sec - in org.eclipse.californium.core.test.lockstep.DeduplicationTest
Running org.eclipse.californium.core.test.MessageTypeTest

Start MessageTypeTest
08:49:25.517:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:49:25.517:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:49:25.518:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:25.519:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:25.519:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,248 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:49:25.520:   1 WARNING [Request]: update your code to supply an RFC 7252 compliant URI including a valid scheme - (org.eclipse.californium.core.coap.Request.java:222) setURI() in thread main
08:49:25.521:   1 INFO [CoapEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:49:25.522:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:25.522:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:25.523:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,249 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:49:25.524:   1 INFO [EndpointManager]: Created implicit default endpoint 0.0.0.0/0.0.0.0:8249 - (org.eclipse.californium.core.network.EndpointManager.java:117) createDefaultEndpoint() in thread main
08:49:25.525: 236 FINE [DirectDatagramSocketImpl]: (E261,T236) 127.0.0.1:8,249 ==> :8,248 [CON-POST   MID=19754, Token=6ce3, OptionSet={"Uri-Host":"localhost", "Uri-Port":8248, "Uri-Path":"acc-res"}, "client says hi"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
gotit
08:49:25.526: 240 FINE [DirectDatagramSocketImpl]: (E262,T240) 127.0.0.1:8,248 ==> :8,249 [ACK        MID=19754] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:25.527: 240 FINE [DirectDatagramSocketImpl]: (E263,T240) 127.0.0.1:8,248 ==> :8,249 [CON-2.05   MID=34862, Token=6ce3, OptionSet={"Content-Format":"text/plain"}, "server responds hi"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:25.529: 236 FINE [DirectDatagramSocketImpl]: (E264,T236) 127.0.0.1:8,249 ==> :8,248 [ACK        MID=34862] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:25.530: 236 FINE [DirectDatagramSocketImpl]: (E265,T236) 127.0.0.1:8,249 ==> :8,248 [CON-POST   MID=19755, Token=f8ef68b172, OptionSet={"Uri-Host":"localhost", "Uri-Port":8248, "Uri-Path":"no-acc-res"}, "client says hi"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:25.531: 240 FINE [DirectDatagramSocketImpl]: (E266,T240) 127.0.0.1:8,248 ==> :8,249 [ACK-2.05   MID=19755, Token=f8ef68b172, OptionSet={"Content-Format":"text/plain"}, "server responds hi"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:25.532:   1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:49:25.532:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8248 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:49:25.533:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8248 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:49:25.533:   1 FINE [DirectDatagramSocketImpl]: closing port 8,248, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
End MessageTypeTest

Start MessageTypeTest
08:49:25.537:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:49:25.537:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:49:25.538:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:25.538:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:25.539:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,250 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:49:25.540:   1 WARNING [Request]: update your code to supply an RFC 7252 compliant URI including a valid scheme - (org.eclipse.californium.core.coap.Request.java:222) setURI() in thread main
08:49:25.542: 245 FINE [DirectDatagramSocketImpl]: (E267,T245) 127.0.0.1:8,249 ==> :8,250 [NON-POST   MID=19756, Token=926f06, OptionSet={"Uri-Host":"localhost", "Uri-Port":8250, "Uri-Path":"acc-res"}, "client says hi"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
gotit
08:49:25.543: 240 FINE [DirectDatagramSocketImpl]: (E268,T240) 127.0.0.1:8,250 ==> :8,249 [NON-2.05   MID=36824, Token=926f06, OptionSet={"Content-Format":"text/plain"}, "server responds hi"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:25.545: 245 FINE [DirectDatagramSocketImpl]: (E269,T245) 127.0.0.1:8,249 ==> :8,250 [NON-POST   MID=19757, Token=62301519, OptionSet={"Uri-Host":"localhost", "Uri-Port":8250, "Uri-Path":"no-acc-res"}, "client says hi"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:25.546: 240 FINE [DirectDatagramSocketImpl]: (E270,T240) 127.0.0.1:8,250 ==> :8,249 [NON-2.05   MID=36825, Token=62301519, OptionSet={"Content-Format":"text/plain"}, "server responds hi"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:25.547:   1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:49:25.547:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8250 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:49:25.548:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8250 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:49:25.548:   1 FINE [DirectDatagramSocketImpl]: closing port 8,250, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
End MessageTypeTest
08:49:25.550:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
08:49:25.550:   1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:8249 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:49:25.550:   1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8249 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:49:25.551:   1 FINE [DirectDatagramSocketImpl]: closing port 8,249, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:49:25.552:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:277) applyConfig() in thread main
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.034 sec - in org.eclipse.californium.core.test.MessageTypeTest
Running org.eclipse.californium.core.test.RandomAccessBlockTest

Start RandomAccessBlockTest
08:49:25.556:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:49:25.557:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:49:25.557:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:25.558:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:25.558:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,251 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Request block number 2
08:49:25.561:   1 INFO [CoapEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:49:25.562:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:49:25.562:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:49:25.563:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,252 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:49:25.564:   1 INFO [EndpointManager]: Created implicit default endpoint 0.0.0.0/0.0.0.0:8252 - (org.eclipse.californium.core.network.EndpointManager.java:117) createDefaultEndpoint() in thread main
08:49:25.565: 251 FINE [DirectDatagramSocketImpl]: (E271,T251) 127.0.0.1:8,252 ==> :8,251 [CON-GET    MID=19310, Token=62eb864f, OptionSet={"Uri-Port":8251, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:25.568: 255 FINE [DirectDatagramSocketImpl]: (E272,T255) 127.0.0.1:8,251 ==> :8,252 [ACK-2.05   MID=19310, Token=62eb864f, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "34567890"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
Request block number 1
08:49:25.570: 251 FINE [DirectDatagramSocketImpl]: (E273,T251) 127.0.0.1:8,252 ==> :8,251 [CON-GET    MID=19311, Token=d3bda1c6f4e7, OptionSet={"Uri-Port":8251, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:25.573: 255 FINE [DirectDatagramSocketImpl]: (E274,T255) 127.0.0.1:8,251 ==> :8,252 [ACK-2.05   MID=19311, Token=d3bda1c6f4e7, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789_123456789_12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
Request block number 3
08:49:25.575: 251 FINE [DirectDatagramSocketImpl]: (E275,T251) 127.0.0.1:8,252 ==> :8,251 [CON-GET    MID=19312, Token=4ff7, OptionSet={"Uri-Port":8251, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=3)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:49:25.577: 255 FINE [DirectDatagramSocketImpl]: (E276,T255) 127.0.0.1:8,251 ==> :8,252 [ACK-2.05   MID=19312, Token=4ff7, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=3)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:49:25.578:   1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:49:25.578:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8251 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:49:25.578:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8251 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:49:25.579:   1 FINE [DirectDatagramSocketImpl]: closing port 8,251, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
End RandomAccessBlockTest
08:49:25.581:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
08:49:25.581:   1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:8252 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:49:25.582:   1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8252 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:49:25.582:   1 FINE [DirectDatagramSocketImpl]: closing port 8,252, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.023 sec - in org.eclipse.californium.core.test.RandomAccessBlockTest

Results :

Failed tests: 
  BlockwiseClientSideTest.testAtomicBlockwisePOSTWithBlockwiseResponse:449 did not receive message within expected time frame (2 secs)

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  3.263 s]
[INFO] element-connector .................................. SUCCESS [ 22.263 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  1.311 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [01:00 min]
[INFO] Californium (Cf) Core .............................. FAILURE [ 55.100 s]
[INFO] Californium (Cf) Proxy ............................. SKIPPED
[INFO] Californium (Cf) OSGi Bundle ....................... SKIPPED
[INFO] Californium (Cf) Demo Applications ................. SKIPPED
[INFO] Sc-DTLS-Example .................................... 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:28 min
[INFO] Finished at: 2017-06-30T08:49:25-04:00
[INFO] Final Memory: 44M/292M
[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 (medium-tests) on project californium-core: There are test failures.

Please refer to <https://hudson.eclipse.org/californium/job/1.0.x-nightly/ws/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 (medium-tests) on project californium-core: There are test failures.
[ERROR] 
[ERROR] Please refer to <https://hudson.eclipse.org/californium/job/1.0.x-nightly/ws/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

--
This message is automatically generated by Hudson. 
For more information on Hudson, see: http://hudson-ci.org/
Follow-Ups:
- [cf-dev] [Hudson] Build failed in Hudson: 1.0.x-nightly #69
  - From: hudsonbuild
References:
- [cf-dev] [Hudson] Build failed in Hudson: 1.0.x-nightly #67
  - From: hudsonbuild
Prev by Date: Re: [cf-dev] CoAP Proxy unable to resolve localhost address
Next by Date: [cf-dev] [Hudson] Build failed in Hudson: 1.0.x-nightly #69
Previous by thread: [cf-dev] [Hudson] Build failed in Hudson: 1.0.x-nightly #67
Next by thread: [cf-dev] [Hudson] Build failed in Hudson: 1.0.x-nightly #69
Index(es):
- Date
- Thread
Breadcrumbs
