[cf-dev] [Hudson] Build failed in Hudson: master #129

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] [Hudson] Build failed in Hudson: master #129
From: hudsonbuild@xxxxxxxxxxx
Date: Mon, 28 Nov 2016 07:37:32 -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/master/129/>

------------------------------------------
[...truncated 2411 lines...]
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:107) destroy() in thread main at (2016-11-28 07:37:21)
End ObserveServerSideTest

Start ObserveClientSideTest
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.317 sec - in org.eclipse.californium.core.test.lockstep.ObserveServerSideTest
Running org.eclipse.californium.core.test.lockstep.ObserveClientSideTest
 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:82) start() in thread main at (2016-11-28 07:37:21)
Client binds to port 39856
Observe with lost ACKs:
342 INFO [MessageTracer]: /127.0.0.1:35,363 <== req CON-GET    MID=  846, Token=ae52cbac1f3e2d0f, OptionSet={"Observe":0, "Uri-Port":35363, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:21)
342 INFO [MessageTracer]: /127.0.0.1:35,363 ==> emp ACK        MID=  846 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:762) receiveEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:21)
342 INFO [MessageTracer]: /127.0.0.1:35,363 ==> res CON-2.05   MID= 8001, Token=ae52cbac1f3e2d0f, OptionSet={"Observe":101}, "-445874840" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:21)
342 INFO [MessageTracer]: /127.0.0.1:35,363 <== emp ACK        MID= 8001 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:21)
342 INFO [MessageTracer]: /127.0.0.1:35,363 ==> res CON-2.05   MID= 8001, Token=ae52cbac1f3e2d0f, OptionSet={"Observe":101}, "-445874840_DUPLICATE" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:21)
342 INFO [MessageTracer]: /127.0.0.1:35,363 <== emp ACK        MID= 8001 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:21)
CON [MID=846, T=ae52cbac1f3e2d0f], GET, /test, (observe=0)    ----->
<-----   ACK [MID=846], 0
<-----   CON [MID=8001, T=ae52cbac1f3e2d0f], 2.05, (observe=101)    
ACK [MID=8001], 0                         -----> // lost
<-----   CON [MID=8001, T=ae52cbac1f3e2d0f], 2.05, (observe=101)    
ACK [MID=8001], 0                         ----->

Relation established
342 INFO [MessageTracer]: /127.0.0.1:35,363 ==> res CON-2.05   MID= 8002, Token=ae52cbac1f3e2d0f, OptionSet={"Observe":102}, "-199487212" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:21)
342 INFO [MessageTracer]: /127.0.0.1:35,363 <== emp ACK        MID= 8002 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:21)
342 INFO [MessageTracer]: /127.0.0.1:35,363 ==> res CON-2.05   MID= 8002, Token=ae52cbac1f3e2d0f, OptionSet={"Observe":102}, "-199487212_DUPLICATE" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:21)
342 INFO [MessageTracer]: /127.0.0.1:35,363 <== emp ACK        MID= 8002 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:21)
<-----   CON [MID=8002, T=ae52cbac1f3e2d0f], 2.05, (observe=102)    
ACK [MID=8002], 0                         -----> // lost
<-----   CON [MID=8002, T=ae52cbac1f3e2d0f], 2.05, (observe=102)    
ACK [MID=8002], 0                         ----->

 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:39856 - (org.eclipse.californium.core.network.CoapEndpoint.java:89) destroy() in thread main at (2016-11-28 07:37:21)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:39856 - (org.eclipse.californium.core.network.CoapEndpoint.java:396) stop() in thread main at (2016-11-28 07:37:21)
 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:82) start() in thread main at (2016-11-28 07:37:21)
Client binds to port 45753
Blockwise Observe:
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  120, Token=58927ba54c21c3e0, OptionSet={"Observe":0, "Uri-Port":51252, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  120, Token=58927ba54c21c3e0, OptionSet={"Observe":0, "Block2":"(szx=0/16, m=true, num=0)"}, "-1442899216-5198" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  121, Token=8ff5231c1b885728, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  121, Token=8ff5231c1b885728, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "36805791290635-1" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  122, Token=8ff5231c1b885728, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  122, Token=8ff5231c1b885728, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "22789120" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
CON [MID=120, T=58927ba54c21c3e0], GET, /test, (observe=0)    ----->
<-----   ACK [MID=120, T=58927ba54c21c3e0], 2.05, 2:0/1/16, (observe=0)    
CON [MID=121, T=8ff5231c1b885728], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=121, T=8ff5231c1b885728], 2.05, 2:1/1/16    
CON [MID=122, T=8ff5231c1b885728], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=122, T=8ff5231c1b885728], 2.05, 2:2/0/16    

348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res CON-2.05   MID= 8001, Token=58927ba54c21c3e0, OptionSet={"Observe":1, "Block2":"(szx=0/16, m=true, num=0)"}, "-1442899216-5198" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== emp ACK        MID= 8001 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  123, Token=cc29e22d9857b254, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  123, Token=cc29e22d9857b254, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "36805791290635-1" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  124, Token=cc29e22d9857b254, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  124, Token=cc29e22d9857b254, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "22789120" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
<-----   CON [MID=8001, T=58927ba54c21c3e0], 2.05, 2:0/1/16, (observe=1)    
ACK [MID=8001], 0                         ----->
CON [MID=123, T=cc29e22d9857b254], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=123, T=cc29e22d9857b254], 2.05, 2:1/1/16    
CON [MID=124, T=cc29e22d9857b254], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=124, T=cc29e22d9857b254], 2.05, 2:2/0/16    

348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res CON-2.05   MID= 8002, Token=58927ba54c21c3e0, OptionSet={"Observe":2, "Block2":"(szx=0/16, m=true, num=0)"}, "-1442899216-5198" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== emp ACK        MID= 8002 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  125, Token=71153f3f88087b49, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  125, Token=71153f3f88087b49, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "36805791290635-1" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  126, Token=71153f3f88087b49, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res CON-2.05   MID= 8003, Token=58927ba54c21c3e0, OptionSet={"Observe":3, "Block2":"(szx=0/16, m=true, num=0)"}, "abcdefghijklmnop" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== emp ACK        MID= 8003 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 WARNING [BlockwiseLayer]: Ongoing blockwise transfer reseted at num=2 by new notification: CON-2.05   MID= 8003, Token=58927ba54c21c3e0, OptionSet={"Observe":3, "Block2":"(szx=0/16, m=true, num=0)"}, "abcdefghijklmnop" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:103) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  127, Token=d3547b2bf3f8c312, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  126, Token=71153f3f88087b49, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "22789120" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 WARNING [UdpMatcher]: Possible MID reuse before lifetime end for token [71153f3f88087b49], expected MID 127 but received 126 - (org.eclipse.californium.core.network.UdpMatcher.java:742) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 WARNING [BlockwiseLayer]: Wrong block number. Expected 1 but received 2: ACK-2.05   MID=  126, Token=71153f3f88087b49, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "22789120" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:103) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  127, Token=d3547b2bf3f8c312, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "qrstuvwxyzabcdef" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  128, Token=d3547b2bf3f8c312, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  128, Token=d3547b2bf3f8c312, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "ghijklmn" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
<-----   CON [MID=8002, T=58927ba54c21c3e0], 2.05, 2:0/1/16, (observe=2)    
ACK [MID=8002], 0                         ----->
CON [MID=125, T=71153f3f88087b49], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=125, T=71153f3f88087b49], 2.05, 2:1/1/16    
CON [MID=126, T=71153f3f88087b49], GET, /test, 2:2/0/16    ----->

//////// Overriding notification ////////
<-----   CON [MID=8003, T=58927ba54c21c3e0], 2.05, 2:0/1/16, (observe=3)    
ACK [MID=8003], 0                         ----->
CON [MID=127, T=d3547b2bf3f8c312], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=126, T=71153f3f88087b49], 2.05, 2:2/0/16    
<-----   ACK [MID=127, T=d3547b2bf3f8c312], 2.05, 2:1/1/16    
CON [MID=128, T=d3547b2bf3f8c312], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=128, T=d3547b2bf3f8c312], 2.05, 2:2/0/16    

348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res CON-2.05   MID= 8004, Token=58927ba54c21c3e0, OptionSet={"Observe":4, "Block2":"(szx=0/16, m=true, num=0)"}, "-1442899216-5198" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== emp ACK        MID= 8004 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  129, Token=bbbacc144833585b, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res CON-2.05   MID= 8005, Token=58927ba54c21c3e0, OptionSet={"Observe":5, "Block2":"(szx=0/16, m=true, num=0)"}, "ABCDEFGHIJKLMNOP" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== emp ACK        MID= 8005 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 WARNING [BlockwiseLayer]: Ongoing blockwise transfer reseted at num=1 by new notification: CON-2.05   MID= 8005, Token=58927ba54c21c3e0, OptionSet={"Observe":5, "Block2":"(szx=0/16, m=true, num=0)"}, "ABCDEFGHIJKLMNOP" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:103) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  130, Token=ebf5c2e91a588212, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  129, Token=bbbacc144833585b, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "36805791290635-1" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 WARNING [UdpMatcher]: Possible MID reuse before lifetime end for token [bbbacc144833585b], expected MID 130 but received 129 - (org.eclipse.californium.core.network.UdpMatcher.java:742) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 WARNING [BlockwiseLayer]: Wrong block number. Expected 1 but received 1: ACK-2.05   MID=  129, Token=bbbacc144833585b, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "36805791290635-1" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:103) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  130, Token=ebf5c2e91a588212, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "QRSTUVWXYZABCDEF" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  131, Token=ebf5c2e91a588212, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  131, Token=ebf5c2e91a588212, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "GHIJKLMN" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
<-----   CON [MID=8004, T=58927ba54c21c3e0], 2.05, 2:0/1/16, (observe=4)    
ACK [MID=8004], 0                         ----->
CON [MID=129, T=bbbacc144833585b], GET, /test, 2:1/0/16    ----->

//////// Overriding notification 2 ////////
<-----   CON [MID=8005, T=58927ba54c21c3e0], 2.05, 2:0/1/16, (observe=5)    
ACK [MID=8005], 0                         ----->
CON [MID=130, T=ebf5c2e91a588212], GET, /test, 2:1/0/16    ----->

//////// Conflicting notification block ////////
<-----   ACK [MID=129, T=bbbacc144833585b], 2.05, 2:1/1/16    
<-----   ACK [MID=130, T=ebf5c2e91a588212], 2.05, 2:1/1/16    
CON [MID=131, T=ebf5c2e91a588212], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=131, T=ebf5c2e91a588212], 2.05, 2:2/0/16    

348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res CON-2.05   MID= 8006, Token=58927ba54c21c3e0, OptionSet={"Observe":6, "Block2":"(szx=0/16, m=true, num=0)"}, "-1442899216-5198" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== emp ACK        MID= 8006 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== req CON-GET    MID=  132, Token=5bf4e720548d63e9, OptionSet={"Uri-Port":51252, "Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:532) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)

//////// Notification after cancellation ////////
<-----   CON [MID=8006, T=58927ba54c21c3e0], 2.05, 2:0/1/16, (observe=6)    
ACK [MID=8006], 0                         ----->
CON [MID=132, T=5bf4e720548d63e9], GET, /test, 2:1/0/16    ----->

348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res ACK-2.05   MID=  132, Token=5bf4e720548d63e9, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "36805791290635-1" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 ==> res CON-2.05   MID= 8007, Token=58927ba54c21c3e0, OptionSet={"Observe":7, "Block2":"(szx=0/16, m=true, num=0)"}, "-1442899216-5198" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
348 INFO [MessageTracer]: /127.0.0.1:51,252 <== emp RST        MID= 8007 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:583) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-11-28 07:37:22)
<-----   ACK [MID=132, T=5bf4e720548d63e9], 2.05, 2:1/1/16    
<-----   CON [MID=8007, T=58927ba54c21c3e0], 2.05, 2:0/1/16, (observe=7)    
RST [MID=8007], 0                         ----->

 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:45753 - (org.eclipse.californium.core.network.CoapEndpoint.java:89) destroy() in thread main at (2016-11-28 07:37:22)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:45753 - (org.eclipse.californium.core.network.CoapEndpoint.java:396) stop() in thread main at (2016-11-28 07:37:22)
End ObserveClientSideTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.352 sec - in org.eclipse.californium.core.test.lockstep.ObserveClientSideTest
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:78) start() in thread main at (2016-11-28 07:37:22)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:200) start() in thread main at (2016-11-28 07:37:22)
 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-11-28 07:37:22)
Starting man in the middle...
Client at 53028, middle at 127.0.0.1:38543, server at 34362
uri: coap://localhost:38543/test
Man in the middle will drop packets [5, 6, 8, 9, 15]
Dropping packet 5 (burst 1)
Dropping packet 6 (burst 2)
Dropping packet 8 (burst 3)
Dropping packet 9 (burst 4)
Dropping packet 15 (burst 1)
Received 250 bytes
Man in the middle will drop packets [0, 1, 3, 7, 8, 8, 9, 9, 11, 12]
Dropping packet 0 (burst 1)
Dropping packet 1 (burst 2)
Dropping packet 3 (burst 3)
Dropping packet 7 (burst 1)
Dropping packet 8 (burst 2)
Dropping packet 9 (burst 3)
Dropping packet 11 (burst 4)
Received 250 bytes
Man in the middle will drop packets [0, 3, 5, 6, 10, 11, 12, 12, 12, 13]
Dropping packet 0 (burst 1)
Dropping packet 3 (burst 1)
Dropping packet 5 (burst 2)
Dropping packet 6 (burst 3)

 1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2016-11-28 07:37:29)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:34362 - (org.eclipse.californium.core.network.CoapEndpoint.java:270) destroy() in thread main at (2016-11-28 07:37:30)
[DEBUG] Closing connection to remote
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:34362 - (org.eclipse.californium.core.network.CoapEndpoint.java:396) stop() in thread main at (2016-11-28 07:37:30)
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:104) destroy() in thread main at (2016-11-28 07:37:30)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:53028 - (org.eclipse.californium.core.network.CoapEndpoint.java:105) destroy() in thread main at (2016-11-28 07:37:30)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:53028 - (org.eclipse.californium.core.network.CoapEndpoint.java:396) stop() in thread main at (2016-11-28 07:37:30)
End LossyBlockwiseTransferTestTests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 8.46 sec <<< FAILURE! - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest
testBlockwiseTransferToleratesLostMessages(org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest)  Time elapsed: 8.46 sec  <<< FAILURE!
java.lang.AssertionError: 
Expected: is not null
     but: was null
	at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
	at org.junit.Assert.assertThat(Assert.java:956)
	at org.junit.Assert.assertThat(Assert.java:923)
	at org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest.getResourceAndAssertPayload(LossyBlockwiseTransferTest.java:139)
	at org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest.testBlockwiseTransferToleratesLostMessages(LossyBlockwiseTransferTest.java:132)


Results :

Failed tests: 
  LossyBlockwiseTransferTest.testBlockwiseTransferToleratesLostMessages:132->getResourceAndAssertPayload:139 
Expected: is not null
     but: was null

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  5.397 s]
[INFO] element-connector .................................. SUCCESS [01:07 min]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  2.050 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 38.501 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 58.202 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:57 min
[INFO] Finished at: 2016-11-28T07:37:30-05:00
[INFO] Final Memory: 43M/1934M
[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.
[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

--
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 : master #130
  - From: hudsonbuild
Prev by Date: [cf-dev] [Hudson] Build failed in Hudson: actinium #26
Next by Date: [cf-dev] [Hudson] Hudson build is back to normal : master #130
Previous by thread: [cf-dev] [Hudson] Build failed in Hudson: 1.1.x-nightly #9
Next by thread: [cf-dev] [Hudson] Hudson build is back to normal : master #130
Index(es):
- Date
- Thread
Breadcrumbs
