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

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] [Hudson] Build failed in Hudson: master #142
From: hudsonbuild@xxxxxxxxxxx
Date: Wed, 7 Dec 2016 03:16:22 -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/142/changes>

Changes:

[Kai Hudalla] Print out timing info during test execution.

------------------------------------------
[...truncated 2452 lines...]

Relation established
342 INFO [MessageTracer]: /127.0.0.1:47,830 ==> res CON-2.05   MID= 8002, Token=e21062938ab6c6ac, OptionSet={"Observe":102}, "4639777102" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
342 INFO [MessageTracer]: /127.0.0.1:47,830 <== 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-12-07 03:16:10)
342 INFO [MessageTracer]: /127.0.0.1:47,830 ==> res CON-2.05   MID= 8002, Token=e21062938ab6c6ac, OptionSet={"Observe":102}, "4639777102_DUPLICATE" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
342 INFO [MessageTracer]: /127.0.0.1:47,830 <== 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-12-07 03:16:10)
<-----   CON [MID=8002, T=e21062938ab6c6ac], 2.05, observe(102)
ACK [MID=8002]   -----> // lost
<-----   CON [MID=8002, T=e21062938ab6c6ac], 2.05, observe(102)
ACK [MID=8002]   ----->

 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:60867 - (org.eclipse.californium.core.network.CoapEndpoint.java:90) destroy() in thread main at (2016-12-07 03:16:10)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:60867 - (org.eclipse.californium.core.network.CoapEndpoint.java:396) stop() in thread main at (2016-12-07 03:16:10)
 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:83) start() in thread main at (2016-12-07 03:16:10)
Client binds to port 49503
Blockwise Observe:
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  807, Token=08ca3faf46b0cd4a, OptionSet={"Observe":0, "Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  807, Token=08ca3faf46b0cd4a, OptionSet={"Observe":0, "Block2":"(szx=0/16, m=true, num=0)"}, "7469918409496188" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  808, Token=7548af0804004187, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  808, Token=7548af0804004187, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "2022000463246730" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  809, Token=7548af0804004187, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  809, Token=7548af0804004187, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "22886509" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
CON [MID=807, T=08ca3faf46b0cd4a], GET, /test, observe(0)    ----->
<-----   ACK [MID=807, T=08ca3faf46b0cd4a], 2.05, 2:0/1/16, observe(0)
CON [MID=808, T=7548af0804004187], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=808, T=7548af0804004187], 2.05, 2:1/1/16
CON [MID=809, T=7548af0804004187], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=809, T=7548af0804004187], 2.05, 2:2/0/16

348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res CON-2.05   MID= 8001, Token=08ca3faf46b0cd4a, OptionSet={"Observe":1, "Block2":"(szx=0/16, m=true, num=0)"}, "7469918409496188" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== 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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  810, Token=096d3a2d5329d8dc, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  810, Token=096d3a2d5329d8dc, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "2022000463246730" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  811, Token=096d3a2d5329d8dc, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  811, Token=096d3a2d5329d8dc, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "22886509" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
<-----   CON [MID=8001, T=08ca3faf46b0cd4a], 2.05, 2:0/1/16, observe(1)
ACK [MID=8001]   ----->
CON [MID=810, T=096d3a2d5329d8dc], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=810, T=096d3a2d5329d8dc], 2.05, 2:1/1/16
CON [MID=811, T=096d3a2d5329d8dc], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=811, T=096d3a2d5329d8dc], 2.05, 2:2/0/16

348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res CON-2.05   MID= 8002, Token=08ca3faf46b0cd4a, OptionSet={"Observe":2, "Block2":"(szx=0/16, m=true, num=0)"}, "7469918409496188" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== 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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  812, Token=a795b3f535d68061, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  812, Token=a795b3f535d68061, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "2022000463246730" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  813, Token=a795b3f535d68061, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res CON-2.05   MID= 8003, Token=08ca3faf46b0cd4a, 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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== 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-12-07 03:16:10)
348 WARNING [BlockwiseLayer]: ongoing blockwise transfer reset at num = 2 by new notification: CON-2.05   MID= 8003, Token=08ca3faf46b0cd4a, OptionSet={"Observe":3, "Block2":"(szx=0/16, m=true, num=0)"}, "abcdefghijklmnop" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:398) handleBlock2Response() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  814, Token=5652e5939ca62fe4, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  813, Token=a795b3f535d68061, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "22886509" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 WARNING [UdpMatcher]: Possible MID reuse before lifetime end for token [a795b3f535d68061], expected MID 814 but received 813 - (org.eclipse.californium.core.network.UdpMatcher.java:742) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 WARNING [BlockwiseLayer]: Wrong block number. Expected 1 but received 2: ACK-2.05   MID=  813, Token=a795b3f535d68061, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "22886509" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:398) handleBlock2Response() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  814, Token=5652e5939ca62fe4, 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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  815, Token=5652e5939ca62fe4, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  815, Token=5652e5939ca62fe4, 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-12-07 03:16:10)
<-----   CON [MID=8002, T=08ca3faf46b0cd4a], 2.05, 2:0/1/16, observe(2)
ACK [MID=8002]   ----->
CON [MID=812, T=a795b3f535d68061], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=812, T=a795b3f535d68061], 2.05, 2:1/1/16
CON [MID=813, T=a795b3f535d68061], GET, /test, 2:2/0/16    ----->

//////// Overriding notification ////////
<-----   CON [MID=8003, T=08ca3faf46b0cd4a], 2.05, 2:0/1/16, observe(3)
ACK [MID=8003]   ----->
CON [MID=814, T=5652e5939ca62fe4], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=813, T=a795b3f535d68061], 2.05, 2:2/0/16
<-----   ACK [MID=814, T=5652e5939ca62fe4], 2.05, 2:1/1/16
CON [MID=815, T=5652e5939ca62fe4], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=815, T=5652e5939ca62fe4], 2.05, 2:2/0/16

348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res CON-2.05   MID= 8004, Token=08ca3faf46b0cd4a, OptionSet={"Observe":4, "Block2":"(szx=0/16, m=true, num=0)"}, "7469918409496188" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== 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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  816, Token=cc84a48215a95ffe, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res CON-2.05   MID= 8005, Token=08ca3faf46b0cd4a, 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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== 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-12-07 03:16:10)
348 WARNING [BlockwiseLayer]: ongoing blockwise transfer reset at num = 1 by new notification: CON-2.05   MID= 8005, Token=08ca3faf46b0cd4a, OptionSet={"Observe":5, "Block2":"(szx=0/16, m=true, num=0)"}, "ABCDEFGHIJKLMNOP" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:398) handleBlock2Response() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  817, Token=2556c01883312f33, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  816, Token=cc84a48215a95ffe, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "2022000463246730" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 WARNING [UdpMatcher]: Possible MID reuse before lifetime end for token [cc84a48215a95ffe], expected MID 817 but received 816 - (org.eclipse.californium.core.network.UdpMatcher.java:742) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 WARNING [BlockwiseLayer]: Wrong block number. Expected 1 but received 1: ACK-2.05   MID=  816, Token=cc84a48215a95ffe, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "2022000463246730" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:398) handleBlock2Response() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  817, Token=2556c01883312f33, 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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  818, Token=2556c01883312f33, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  818, Token=2556c01883312f33, 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-12-07 03:16:10)
<-----   CON [MID=8004, T=08ca3faf46b0cd4a], 2.05, 2:0/1/16, observe(4)
ACK [MID=8004]   ----->
CON [MID=816, T=cc84a48215a95ffe], GET, /test, 2:1/0/16    ----->

//////// Overriding notification 2 ////////
<-----   CON [MID=8005, T=08ca3faf46b0cd4a], 2.05, 2:0/1/16, observe(5)
ACK [MID=8005]   ----->
CON [MID=817, T=2556c01883312f33], GET, /test, 2:1/0/16    ----->

//////// Conflicting notification block ////////
<-----   ACK [MID=816, T=cc84a48215a95ffe], 2.05, 2:1/1/16
<-----   ACK [MID=817, T=2556c01883312f33], 2.05, 2:1/1/16
CON [MID=818, T=2556c01883312f33], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=818, T=2556c01883312f33], 2.05, 2:2/0/16

348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res CON-2.05   MID= 8006, Token=08ca3faf46b0cd4a, OptionSet={"Observe":6, "Block2":"(szx=0/16, m=true, num=0)"}, "7469918409496188" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== 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-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== req CON-GET    MID=  819, Token=c1d3a94f37fd14a1, OptionSet={"Uri-Port":42110, "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-12-07 03:16:10)

//////// Notification after cancellation ////////
<-----   CON [MID=8006, T=08ca3faf46b0cd4a], 2.05, 2:0/1/16, observe(6)
ACK [MID=8006]   ----->
CON [MID=819, T=c1d3a94f37fd14a1], GET, /test, 2:1/0/16    ----->

348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res ACK-2.05   MID=  819, Token=c1d3a94f37fd14a1, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "2022000463246730" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 ==> res CON-2.05   MID= 8007, Token=08ca3faf46b0cd4a, OptionSet={"Observe":7, "Block2":"(szx=0/16, m=true, num=0)"}, "7469918409496188" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:737) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2016-12-07 03:16:10)
348 INFO [MessageTracer]: /127.0.0.1:42,110 <== 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-12-07 03:16:10)
<-----   ACK [MID=819, T=c1d3a94f37fd14a1], 2.05, 2:1/1/16
<-----   CON [MID=8007, T=08ca3faf46b0cd4a], 2.05, 2:0/1/16, observe(7)
RST [MID=8007]   ----->

 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:49503 - (org.eclipse.californium.core.network.CoapEndpoint.java:90) destroy() in thread main at (2016-12-07 03:16:10)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:49503 - (org.eclipse.californium.core.network.CoapEndpoint.java:396) stop() in thread main at (2016-12-07 03:16:10)
End ObserveClientSideTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.134 sec - in org.eclipse.californium.core.test.lockstep.ObserveClientSideTest
Running org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest

StartLossyBlockwiseTransferTest
 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:79) start() in thread main at (2016-12-07 03:16:10)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:200) start() in thread main at (2016-12-07 03:16:10)
 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-12-07 03:16:10)
Starting man in the middle...
client at 127.0.0.1:41484, middle at 127.0.0.1:57660, server at 127.0.0.1:43994
Man in the middle will drop packets [5, 6, 8, 9, 15]
doing a blockwise GET on: coap://localhost:57660/test
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 after 1514ms
Man in the middle will drop packets [3, 6, 7, 9, 10, 10, 11, 11, 13, 14]
doing a blockwise GET on: coap://localhost:57660/test
Dropping packet 3 (burst 1)
Dropping packet 6 (burst 1)
Dropping packet 7 (burst 2)
Dropping packet 9 (burst 3)
Dropping packet 10 (burst 4)
Dropping packet 14 (burst 1)
Received 250 bytes after 1811ms
Man in the middle will drop packets [3, 4, 5, 5, 6, 6, 8, 10, 13, 15]
doing a blockwise GET on: coap://localhost:57660/test
Dropping packet 3 (burst 1)
Dropping packet 4 (burst 2)
Dropping packet 5 (burst 3)
Dropping packet 6 (burst 4)
Dropping packet 10 (burst 1)
Dropping packet 13 (burst 1)
Dropping packet 15 (burst 2)
Received 250 bytes after 2111ms
Man in the middle will drop packets [0, 4, 4, 4, 8, 9, 11, 12, 14, 15]
doing a blockwise GET on: coap://localhost:57660/test
Dropping packet 0 (burst 1)
Dropping packet 4 (burst 1)
Dropping packet 8 (burst 1)
Dropping packet 9 (burst 2)
Dropping packet 11 (burst 3)
Dropping packet 12 (burst 4)
Received 250 bytes after 1810ms
Man in the middle will drop packets [0, 0, 4, 5, 5, 7, 10, 12, 14, 15]
doing a blockwise GET on: coap://localhost:57660/test
Dropping packet 0 (burst 1)
Dropping packet 4 (burst 1)
Dropping packet 5 (burst 2)
Dropping packet 7 (burst 3)
Dropping packet 10 (burst 3)
Dropping packet 12 (burst 4)
Received 250 bytes after 1810ms
Man in the middle will drop packets [0, 0, 2, 2, 9, 10, 10, 11, 11, 15]
doing a blockwise GET on: coap://localhost:57660/test
Dropping packet 0 (burst 1)
Dropping packet 2 (burst 2)
Dropping packet 9 (burst 1)
Dropping packet 10 (burst 2)
Dropping packet 11 (burst 3)
Dropping packet 15 (burst 1)
Received 250 bytes after 1811ms

 1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2016-12-07 03:16:21)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:43994 - (org.eclipse.californium.core.network.CoapEndpoint.java:270) destroy() in thread main at (2016-12-07 03:16:21)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:43994 - (org.eclipse.californium.core.network.CoapEndpoint.java:396) stop() in thread main at (2016-12-07 03:16:21)
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:110) destroy() in thread main at (2016-12-07 03:16:21)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:41484 - (org.eclipse.californium.core.network.CoapEndpoint.java:111) destroy() in thread main at (2016-12-07 03:16:21)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:41484 - (org.eclipse.californium.core.network.CoapEndpoint.java:396) stop() in thread main at (2016-12-07 03:16:21)
End LossyBlockwiseTransferTestTests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 10.883 sec - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest

Results :

Failed tests: 
  ObserveServerSideTest.testEstablishmentAndTimeout:158 Did not receive a message (but nothing)

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  2.011 s]
[INFO] element-connector .................................. SUCCESS [ 54.933 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  0.818 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 22.063 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 47.008 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:10 min
[INFO] Finished at: 2016-12-07T03:16:22-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.
[DEBUG] Closing connection to remote
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test (large-tests) on project californium-core: There are test failures.
[ERROR] 
[ERROR] Please refer to /jobs/genie.californium/master/workspace/californium-core/target/surefire-reports for the individual test results.
[ERROR] -> [Help 1]
[ERROR] 
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR] 
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR] 
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR]   mvn <goals> -rf :californium-core
[DEBUG] Waiting for process to finish
[DEBUG] Result: 1
Recording test results

--
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: master #143
  - From: hudsonbuild
Prev by Date: [cf-dev] [Hudson] Build failed in Hudson: 2.0.x-nightly #14
Next by Date: [cf-dev] [Hudson] Build failed in Hudson: master #143
Previous by thread: [cf-dev] [Hudson] Build failed in Hudson: 1.1.x-nightly #13
Next by thread: [cf-dev] [Hudson] Build failed in Hudson: master #143
Index(es):
- Date
- Thread
Breadcrumbs
