[cf-dev] [Hudson] Build failed in Hudson: 2.0.x-nightly #21

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] [Hudson] Build failed in Hudson: 2.0.x-nightly #21
From: hudsonbuild@xxxxxxxxxxx
Date: Tue, 20 Dec 2016 06:17:43 -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/2.0.x-nightly/21/>

------------------------------------------
[...truncated 2631 lines...]

Relation established
242 INFO [MessageTracer]: /127.0.0.1:46,789 ==> res CON-2.05   MID= 8002, Token=448be49ab380c4f1, OptionSet={"Observe":102}, "2002080738" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:25)
242 INFO [MessageTracer]: /127.0.0.1:46,789 <== emp ACK        MID= 8002 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:637) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:25)
242 INFO [MessageTracer]: /127.0.0.1:46,789 ==> res CON-2.05   MID= 8002, Token=448be49ab380c4f1, OptionSet={"Observe":102}, "2002080738_DUPLICATE" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:25)
242 INFO [MessageTracer]: /127.0.0.1:46,789 <== emp ACK        MID= 8002 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:637) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:25)
242 FINE [ReliabilityLayer]: ignoring duplicate response - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:235) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:25)
<-----   CON [MID=8002, T=448be49ab380c4f1], 2.05, observe(102)
ACK [MID=8002]   -----> // lost
<-----   CON [MID=8002, T=448be49ab380c4f1], 2.05, observe(102)
ACK [MID=8002]   ----->

 1 INFO [CoapEndpoint]: Destroying endpoint at address coap://127.0.0.1:44675 - (org.eclipse.californium.core.network.CoapEndpoint.java:95) destroy() in thread main at (2016-12-20 06:17:25)
 1 INFO [CoapEndpoint]: Stopping endpoint at address coap://127.0.0.1:44675 - (org.eclipse.californium.core.network.CoapEndpoint.java:429) stop() in thread main at (2016-12-20 06:17:25)
 1 CONFIG [ReliabilityLayer]: ReliabilityLayer uses ACK_TIMEOUT=200, ACK_RANDOM_FACTOR=1, and ACK_TIMEOUT_SCALE=1 - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:95) <init>() in thread main at (2016-12-20 06:17:25)
 1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:88) start() in thread main at (2016-12-20 06:17:25)
 1 INFO [CoapEndpoint]: Started endpoint at coap://127.0.0.1:59422 - (org.eclipse.californium.core.network.CoapEndpoint.java:88) start() in thread main at (2016-12-20 06:17:26)
Client binds to port 59422
Blockwise Observe:
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  282, Token=9097261ead60ff7d, OptionSet={"Observe":0, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  282, Token=9097261ead60ff7d, OptionSet={"Observe":0, "Block2":"(szx=0/16, m=true, num=0)"}, "5207364550303590" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  283, Token=abd44ef0e1eca182, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  283, Token=abd44ef0e1eca182, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "7571930041564453" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  284, Token=abd44ef0e1eca182, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  284, Token=abd44ef0e1eca182, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "71572253" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
CON [MID=282, T=9097261ead60ff7d], GET, /test, observe(0)    ----->
<-----   ACK [MID=282, T=9097261ead60ff7d], 2.05, 2:0/1/16, observe(0)
CON [MID=283, T=abd44ef0e1eca182], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=283, T=abd44ef0e1eca182], 2.05, 2:1/1/16
CON [MID=284, T=abd44ef0e1eca182], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=284, T=abd44ef0e1eca182], 2.05, 2:2/0/16

observe relation has been established, server now sends a notification
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res CON-2.05   MID= 8001, Token=9097261ead60ff7d, OptionSet={"Observe":1, "Block2":"(szx=0/16, m=true, num=0)"}, "5207364550303590" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== emp ACK        MID= 8001 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:637) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  285, Token=930c082f84ce68e3, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  285, Token=930c082f84ce68e3, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "7571930041564453" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  286, Token=930c082f84ce68e3, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  286, Token=930c082f84ce68e3, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "71572253" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
<-----   CON [MID=8001, T=9097261ead60ff7d], 2.05, 2:0/1/16, observe(1)
ACK [MID=8001]   ----->
CON [MID=285, T=930c082f84ce68e3], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=285, T=930c082f84ce68e3], 2.05, 2:1/1/16
CON [MID=286, T=930c082f84ce68e3], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=286, T=930c082f84ce68e3], 2.05, 2:2/0/16

client has successfully retrieved content for notification using blockwise transfer
server now sends notifications interfering with ongoing blockwise transfer
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res CON-2.05   MID= 8002, Token=9097261ead60ff7d, OptionSet={"Observe":2, "Block2":"(szx=0/16, m=true, num=0)"}, "5207364550303590" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== emp ACK        MID= 8002 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:637) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  287, Token=12b0765533b711a9, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  287, Token=12b0765533b711a9, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "7571930041564453" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  288, Token=12b0765533b711a9, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res CON-2.05   MID= 8003, Token=9097261ead60ff7d, OptionSet={"Observe":3, "Block2":"(szx=0/16, m=true, num=0)"}, "abcdefghijklmnop" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== emp ACK        MID= 8003 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:637) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  289, Token=4632416e406c5fb6, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  288, Token=12b0765533b711a9, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "71572253" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  289, Token=4632416e406c5fb6, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "qrstuvwxyzabcdef" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  290, Token=4632416e406c5fb6, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  290, Token=4632416e406c5fb6, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "ghijklmn" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
<-----   CON [MID=8002, T=9097261ead60ff7d], 2.05, 2:0/1/16, observe(2)
ACK [MID=8002]   ----->
CON [MID=287, T=12b0765533b711a9], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=287, T=12b0765533b711a9], 2.05, 2:1/1/16
CON [MID=288, T=12b0765533b711a9], GET, /test, 2:2/0/16    ----->

//////// Overriding notification ////////
<-----   CON [MID=8003, T=9097261ead60ff7d], 2.05, 2:0/1/16, observe(3)
ACK [MID=8003]   ----->
CON [MID=289, T=4632416e406c5fb6], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=288, T=12b0765533b711a9], 2.05, 2:2/0/16
<-----   ACK [MID=289, T=4632416e406c5fb6], 2.05, 2:1/1/16
CON [MID=290, T=4632416e406c5fb6], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=290, T=4632416e406c5fb6], 2.05, 2:2/0/16

client has detected newly arriving notification while doing blockwise transfer of previous notification
server now sends notifications interfering with ongoing blockwise transfer using conflicting block numbers
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res CON-2.05   MID= 8004, Token=9097261ead60ff7d, OptionSet={"Observe":4, "Block2":"(szx=0/16, m=true, num=0)"}, "5207364550303590" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== emp ACK        MID= 8004 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:637) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  291, Token=9cf9c2aee91f2bc9, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res CON-2.05   MID= 8005, Token=9097261ead60ff7d, OptionSet={"Observe":5, "Block2":"(szx=0/16, m=true, num=0)"}, "ABCDEFGHIJKLMNOP" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== emp ACK        MID= 8005 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:637) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  292, Token=d1399c0ed911f1d1, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  291, Token=9cf9c2aee91f2bc9, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "7571930041564453" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  293, Token=9cf9c2aee91f2bc9, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  293, Token=9cf9c2aee91f2bc9, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "71572253" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  292, Token=d1399c0ed911f1d1, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "QRSTUVWXYZABCDEF" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  294, Token=d1399c0ed911f1d1, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  294, Token=d1399c0ed911f1d1, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "GHIJKLMN" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
<-----   CON [MID=8004, T=9097261ead60ff7d], 2.05, 2:0/1/16, observe(4)
ACK [MID=8004]   ----->
CON [MID=291, T=9cf9c2aee91f2bc9], GET, /test, 2:1/0/16    ----->

//////// Overriding notification 2 ////////
<-----   CON [MID=8005, T=9097261ead60ff7d], 2.05, 2:0/1/16, observe(5)
ACK [MID=8005]   ----->
CON [MID=292, T=d1399c0ed911f1d1], GET, /test, 2:1/0/16    ----->

//////// Conflicting notification block ////////
<-----   ACK [MID=291, T=9cf9c2aee91f2bc9], 2.05, 2:1/1/16
CON [MID=293, T=9cf9c2aee91f2bc9], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=293, T=9cf9c2aee91f2bc9], 2.05, 2:2/0/16
<-----   ACK [MID=292, T=d1399c0ed911f1d1], 2.05, 2:1/1/16
CON [MID=294, T=d1399c0ed911f1d1], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=294, T=d1399c0ed911f1d1], 2.05, 2:2/0/16

248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res CON-2.05   MID= 8006, Token=9097261ead60ff7d, OptionSet={"Observe":6, "Block2":"(szx=0/16, m=true, num=0)"}, "5207364550303590" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== emp ACK        MID= 8006 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:637) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== req CON-GET    MID=  295, Token=616762f9e1cf0c5c, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:586) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)
248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res ACK-2.05   MID=  295, Token=616762f9e1cf0c5c, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "7571930041564453" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:26)

//////// Notification after cancellation ////////
<-----   CON [MID=8006, T=9097261ead60ff7d], 2.05, 2:0/1/16, observe(6)
ACK [MID=8006]   ----->
CON [MID=295, T=616762f9e1cf0c5c], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=295, T=616762f9e1cf0c5c], 2.05, 2:1/1/16

248 INFO [MessageTracer]: /127.0.0.1:52,189 ==> res CON-2.05   MID= 8007, Token=9097261ead60ff7d, OptionSet={"Observe":7, "Block2":"(szx=0/16, m=true, num=0)"}, "5207364550303590" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:791) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:27)
248 INFO [MessageTracer]: /127.0.0.1:52,189 <== emp RST        MID= 8007 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:749) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:27)
<-----   CON [MID=8007, T=9097261ead60ff7d], 2.05, 2:0/1/16, observe(7)
RST [MID=8007]   ----->

 1 INFO [CoapEndpoint]: Destroying endpoint at address coap://127.0.0.1:59422 - (org.eclipse.californium.core.network.CoapEndpoint.java:95) destroy() in thread main at (2016-12-20 06:17:28)
 1 INFO [CoapEndpoint]: Stopping endpoint at address coap://127.0.0.1:59422 - (org.eclipse.californium.core.network.CoapEndpoint.java:429) stop() in thread main at (2016-12-20 06:17:28)
End ObserveClientSideTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.242 sec - in org.eclipse.californium.core.test.lockstep.ObserveClientSideTest
Running org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest

StartLossyBlockwiseTransferTest
 1 CONFIG [ReliabilityLayer]: ReliabilityLayer uses ACK_TIMEOUT=300, ACK_RANDOM_FACTOR=1, and ACK_TIMEOUT_SCALE=1 - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:95) <init>() in thread main at (2016-12-20 06:17:28)
 1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:79) start() in thread main at (2016-12-20 06:17:28)
 1 INFO [CoapEndpoint]: Started endpoint at coap://127.0.0.1:49688 - (org.eclipse.californium.core.network.CoapEndpoint.java:79) start() in thread main at (2016-12-20 06:17:28)
 1 CONFIG [ReliabilityLayer]: ReliabilityLayer uses ACK_TIMEOUT=300, ACK_RANDOM_FACTOR=1, and ACK_TIMEOUT_SCALE=1 - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:95) <init>() in thread main at (2016-12-20 06:17:28)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:200) start() in thread main at (2016-12-20 06:17:28)
 1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:212) start() in thread main at (2016-12-20 06:17:28)
 1 INFO [CoapEndpoint]: Started endpoint at coap://127.0.0.1:39168 - (org.eclipse.californium.core.network.CoapEndpoint.java:212) start() in thread main at (2016-12-20 06:17:28)
Starting man in the middle...
client at 127.0.0.1:49688, middle at 127.0.0.1:55089, server at 127.0.0.1:39168
Man in the middle will drop packets [5, 6, 8, 9, 15]
doing a blockwise GET on: coap://localhost:55089/test
Dropping packet 5 (burst 1) from server
Dropping packet 6 (burst 2) from client
262 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#4 at (2016-12-20 06:17:28)
Dropping packet 8 (burst 3) from server
Dropping packet 9 (burst 4) from client
260 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#2 at (2016-12-20 06:17:29)
Dropping packet 15 (burst 1) from server
266 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#8 at (2016-12-20 06:17:29)
Received 250 bytes after 1515ms
Man in the middle will drop packets [1, 4, 5, 6, 9, 10, 10, 11, 13, 15]
doing a blockwise GET on: coap://localhost:55089/test
Dropping packet 1 (burst 1) from server
270 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#12 at (2016-12-20 06:17:29)
Dropping packet 4 (burst 1) from client
Dropping packet 5 (burst 2) from client
Dropping packet 6 (burst 3) from client
Dropping packet 9 (burst 3) from client
Dropping packet 10 (burst 4) from client
Dropping packet 15 (burst 1) from client
Received 250 bytes after 2112ms
Man in the middle will drop packets [0, 0, 3, 6, 6, 8, 9, 11, 14, 15]
doing a blockwise GET on: coap://localhost:55089/test
Dropping packet 0 (burst 1) from client
Dropping packet 3 (burst 1) from client
Dropping packet 6 (burst 1) from client
Dropping packet 8 (burst 2) from server
Dropping packet 9 (burst 3) from client
271 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#13 at (2016-12-20 06:17:33)
Dropping packet 11 (burst 4) from client
254 FINE [ReliabilityLayer$RetransmissionTask]: Timeout: retransmission limit reached, exchange failed, message: CON-GET    MID=  592, Token=c5fe850fec72453a, OptionSet={"Uri-Host":"localhost", "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.stack.ReliabilityLayer$RetransmissionTask.java:471) run() in thread CoapEndpoint-coap://localhost:0#1 at (2016-12-20 06:17:33)

 1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2016-12-20 06:17:41)
 1 INFO [CoapEndpoint]: Destroying endpoint at address coap://127.0.0.1:39168 - (org.eclipse.californium.core.network.CoapEndpoint.java:252) destroy() in thread main at (2016-12-20 06:17:41)
 1 INFO [CoapEndpoint]: Stopping endpoint at address coap://127.0.0.1:39168 - (org.eclipse.californium.core.network.CoapEndpoint.java:429) stop() in thread main at (2016-12-20 06:17:41)
 1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:273) destroy() in thread main at (2016-12-20 06:17:42)
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:110) destroy() in thread main at (2016-12-20 06:17:42)
 1 INFO [CoapEndpoint]: Destroying endpoint at address coap://127.0.0.1:49688 - (org.eclipse.californium.core.network.CoapEndpoint.java:111) destroy() in thread main at (2016-12-20 06:17:42)
 1 INFO [CoapEndpoint]: Stopping endpoint at address coap://127.0.0.1:49688 - (org.eclipse.californium.core.network.CoapEndpoint.java:429) stop() in thread main at (2016-12-20 06:17:42)
End LossyBlockwiseTransferTestTests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 14.655 sec <<< FAILURE! - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest
testBlockwiseTransferToleratesLostMessages(org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest)  Time elapsed: 14.655 sec  <<< FAILURE!
java.lang.AssertionError: Blockwise GET timed out after 10001ms
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.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest.getResourceAndAssertPayload(LossyBlockwiseTransferTest.java:148)
	at org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest.testBlockwiseTransferToleratesLostMessages(LossyBlockwiseTransferTest.java:137)


Results :

Failed tests: 
  LossyBlockwiseTransferTest.testBlockwiseTransferToleratesLostMessages:137->getResourceAndAssertPayload:148 Blockwise GET timed out after 10001ms
Expected: is not null
     but: was null

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  2.367 s]
[INFO] element-connector .................................. SUCCESS [ 54.511 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  0.972 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 22.604 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 52.422 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:17 min
[INFO] Finished at: 2016-12-20T06:17:43-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/2.0.x-nightly/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/2.0.x-nightly/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 : 2.0.x-nightly #22
  - From: hudsonbuild
References:
- [cf-dev] [Hudson] Build failed in Hudson: 2.0.x-nightly #20
  - From: hudsonbuild
Prev by Date: [cf-dev] [Hudson] Build failed in Hudson: 2.0.x-nightly #20
Next by Date: [cf-dev] [Hudson] Hudson build is back to normal : 2.0.x-nightly #22
Previous by thread: [cf-dev] [Hudson] Build failed in Hudson: 2.0.x-nightly #20
Next by thread: [cf-dev] [Hudson] Hudson build is back to normal : 2.0.x-nightly #22
Index(es):
- Date
- Thread
Breadcrumbs
