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

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] [Hudson] Build failed in Hudson: master #169
From: hudsonbuild@xxxxxxxxxxx
Date: Wed, 22 Mar 2017 09:59:07 -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/master/169/changes>

Changes:

[Achim Kraus] Remove nested synchonize section.

------------------------------------------
[...truncated 2948 lines...]
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res ACK-2.05   MID=  540, Token=5161ba28e425f784, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "89912762" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
<-----   CON [MID=8001, T=d9ec901b3a79e1c2], 2.05, 2:0/1/16, observe(1)
ACK [MID=8001]   ----->
CON [MID=539, T=5161ba28e425f784], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=539, T=5161ba28e425f784], 2.05, 2:1/1/16
CON [MID=540, T=5161ba28e425f784], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=540, T=5161ba28e425f784], 2.05, 2:2/0/16

248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res CON-2.05   MID= 8002, Token=d9ec901b3a79e1c2, OptionSet={"Observe":2, "Block2":"(szx=0/16, m=true, num=0)"}, "4607632680023338" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== emp ACK        MID= 8002 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:584) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== req CON-GET    MID=  541, Token=7636c628c2b8c7e2, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:533) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res ACK-2.05   MID=  541, Token=7636c628c2b8c7e2, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "1720290387338681" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== req CON-GET    MID=  542, Token=7636c628c2b8c7e2, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:533) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res CON-2.05   MID= 8003, Token=d9ec901b3a79e1c2, OptionSet={"Observe":3, "Block2":"(szx=0/16, m=true, num=0)"}, "abcdefghijklmnop" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== emp ACK        MID= 8003 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:584) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 WARNING [BlockwiseLayer]: ongoing blockwise transfer reset at num = 2 by new notification: CON-2.05   MID= 8003, Token=d9ec901b3a79e1c2, OptionSet={"Observe":3, "Block2":"(szx=0/16, m=true, num=0)"}, "abcdefghijklmnop" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:403) handleBlock2Response() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== req CON-GET    MID=  543, Token=0efcbfb79faebd33, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:533) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res ACK-2.05   MID=  542, Token=7636c628c2b8c7e2, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "89912762" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 WARNING [UdpMatcher]: Possible MID reuse before lifetime end for token [7636c628c2b8c7e2], expected MID 543 but received 542 - (org.eclipse.californium.core.network.UdpMatcher.java:735) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 WARNING [BlockwiseLayer]: Wrong block number. Expected 1 but received 2: ACK-2.05   MID=  542, Token=7636c628c2b8c7e2, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "89912762" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:403) handleBlock2Response() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res ACK-2.05   MID=  543, Token=0efcbfb79faebd33, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "qrstuvwxyzabcdef" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== req CON-GET    MID=  544, Token=0efcbfb79faebd33, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:533) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res ACK-2.05   MID=  544, Token=0efcbfb79faebd33, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "ghijklmn" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
<-----   CON [MID=8002, T=d9ec901b3a79e1c2], 2.05, 2:0/1/16, observe(2)
ACK [MID=8002]   ----->
CON [MID=541, T=7636c628c2b8c7e2], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=541, T=7636c628c2b8c7e2], 2.05, 2:1/1/16
CON [MID=542, T=7636c628c2b8c7e2], GET, /test, 2:2/0/16    ----->

//////// Overriding notification ////////
<-----   CON [MID=8003, T=d9ec901b3a79e1c2], 2.05, 2:0/1/16, observe(3)
ACK [MID=8003]   ----->
CON [MID=543, T=0efcbfb79faebd33], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=542, T=7636c628c2b8c7e2], 2.05, 2:2/0/16
<-----   ACK [MID=543, T=0efcbfb79faebd33], 2.05, 2:1/1/16
CON [MID=544, T=0efcbfb79faebd33], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=544, T=0efcbfb79faebd33], 2.05, 2:2/0/16

248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res CON-2.05   MID= 8004, Token=d9ec901b3a79e1c2, OptionSet={"Observe":4, "Block2":"(szx=0/16, m=true, num=0)"}, "4607632680023338" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== emp ACK        MID= 8004 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:584) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== req CON-GET    MID=  545, Token=b6b24a1c3285d124, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:533) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res CON-2.05   MID= 8005, Token=d9ec901b3a79e1c2, OptionSet={"Observe":5, "Block2":"(szx=0/16, m=true, num=0)"}, "ABCDEFGHIJKLMNOP" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== emp ACK        MID= 8005 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:584) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 WARNING [BlockwiseLayer]: ongoing blockwise transfer reset at num = 1 by new notification: CON-2.05   MID= 8005, Token=d9ec901b3a79e1c2, OptionSet={"Observe":5, "Block2":"(szx=0/16, m=true, num=0)"}, "ABCDEFGHIJKLMNOP" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:403) handleBlock2Response() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== req CON-GET    MID=  546, Token=f7fbb98c4e7f735e, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:533) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res ACK-2.05   MID=  545, Token=b6b24a1c3285d124, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "1720290387338681" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 WARNING [UdpMatcher]: Possible MID reuse before lifetime end for token [b6b24a1c3285d124], expected MID 546 but received 545 - (org.eclipse.californium.core.network.UdpMatcher.java:735) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 WARNING [BlockwiseLayer]: Wrong block number. Expected 1 but received 1: ACK-2.05   MID=  545, Token=b6b24a1c3285d124, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "1720290387338681" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:403) handleBlock2Response() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res ACK-2.05   MID=  546, Token=f7fbb98c4e7f735e, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "QRSTUVWXYZABCDEF" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== req CON-GET    MID=  547, Token=f7fbb98c4e7f735e, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:533) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res ACK-2.05   MID=  547, Token=f7fbb98c4e7f735e, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "GHIJKLMN" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
<-----   CON [MID=8004, T=d9ec901b3a79e1c2], 2.05, 2:0/1/16, observe(4)
ACK [MID=8004]   ----->
CON [MID=545, T=b6b24a1c3285d124], GET, /test, 2:1/0/16    ----->

//////// Overriding notification 2 ////////
<-----   CON [MID=8005, T=d9ec901b3a79e1c2], 2.05, 2:0/1/16, observe(5)
ACK [MID=8005]   ----->
CON [MID=546, T=f7fbb98c4e7f735e], GET, /test, 2:1/0/16    ----->

//////// Conflicting notification block ////////
<-----   ACK [MID=545, T=b6b24a1c3285d124], 2.05, 2:1/1/16
<-----   ACK [MID=546, T=f7fbb98c4e7f735e], 2.05, 2:1/1/16
CON [MID=547, T=f7fbb98c4e7f735e], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=547, T=f7fbb98c4e7f735e], 2.05, 2:2/0/16

248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res CON-2.05   MID= 8006, Token=d9ec901b3a79e1c2, OptionSet={"Observe":6, "Block2":"(szx=0/16, m=true, num=0)"}, "4607632680023338" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== emp ACK        MID= 8006 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:584) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== req CON-GET    MID=  548, Token=7b78509ed16ad2d4, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:533) sendRequest() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)

//////// Notification after cancellation ////////
<-----   CON [MID=8006, T=d9ec901b3a79e1c2], 2.05, 2:0/1/16, observe(6)
ACK [MID=8006]   ----->
CON [MID=548, T=7b78509ed16ad2d4], GET, /test, 2:1/0/16    ----->

248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res ACK-2.05   MID=  548, Token=7b78509ed16ad2d4, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "1720290387338681" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 ==> res CON-2.05   MID= 8007, Token=d9ec901b3a79e1c2, OptionSet={"Observe":7, "Block2":"(szx=0/16, m=true, num=0)"}, "4607632680023338" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:730) receiveResponse() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
248 INFO [MessageTracer]: /127.0.0.1:42,065 <== emp RST        MID= 8007 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:584) sendEmptyMessage() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:44)
<-----   ACK [MID=548, T=7b78509ed16ad2d4], 2.05, 2:1/1/16
<-----   CON [MID=8007, T=d9ec901b3a79e1c2], 2.05, 2:0/1/16, observe(7)
RST [MID=8007]   ----->

 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:59564 - (org.eclipse.californium.core.network.CoapEndpoint.java:90) destroy() in thread main at (2017-03-22 09:58:44)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:59564 - (org.eclipse.californium.core.network.CoapEndpoint.java:397) stop() in thread main at (2017-03-22 09:58:44)
End ObserveClientSideTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.137 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 (2017-03-22 09:58:44)
 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 (2017-03-22 09:58:44)
 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 (2017-03-22 09:58:44)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:200) start() in thread main at (2017-03-22 09:58:44)
 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 (2017-03-22 09:58:44)
Starting man in the middle...
client at 127.0.0.1:50633, middle at 127.0.0.1:38856, server at 127.0.0.1:34555
Man in the middle will drop packets [5, 6, 8, 9, 15]
doing a blockwise GET on: coap://localhost:38856/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 (2017-03-22 09:58:45)
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 (2017-03-22 09:58:46)
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 (2017-03-22 09:58:46)
Received 250 bytes after 1511ms
Man in the middle will drop packets [1, 2, 5, 8, 9, 10, 12, 13, 14, 15]
doing a blockwise GET on: coap://localhost:38856/test
Dropping packet 1 (burst 1) from server
Dropping packet 2 (burst 2) from client
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 (2017-03-22 09:58:47)
Dropping packet 5 (burst 2) from client
Dropping packet 8 (burst 2) from client
Dropping packet 9 (burst 3) from client
Dropping packet 10 (burst 4) from client
Dropping packet 14 (burst 1) from server
Dropping packet 15 (burst 2) from client
274 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#16 at (2017-03-22 09:58:48)
Received 250 bytes after 2415ms
Man in the middle will drop packets [0, 4, 5, 6, 7, 7, 8, 9, 11, 11]
doing a blockwise GET on: coap://localhost:38856/test
Dropping packet 0 (burst 1) from client
Dropping packet 4 (burst 1) from server
Dropping packet 5 (burst 2) from client
Dropping packet 6 (burst 3) from client
Dropping packet 7 (burst 4) 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 (2017-03-22 09:58:50)
Dropping packet 11 (burst 1) from server
282 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#24 at (2017-03-22 09:58:50)
Received 250 bytes after 1812ms
Man in the middle will drop packets [1, 5, 6, 7, 8, 9, 11, 12, 14, 15]
doing a blockwise GET on: coap://localhost:38856/test
Dropping packet 1 (burst 1) from server
268 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#10 at (2017-03-22 09:58:50)
Dropping packet 5 (burst 1) from server
Dropping packet 6 (burst 2) from client
Dropping packet 7 (burst 3) from client
Dropping packet 8 (burst 4) from client
264 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#6 at (2017-03-22 09:58:52)
Dropping packet 12 (burst 1) from server
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 (2017-03-22 09:58:52)
Dropping packet 14 (burst 2) from server
Dropping packet 15 (burst 3) from client
258 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#1 at (2017-03-22 09:58:53)
Received 250 bytes after 2412ms
Man in the middle will drop packets [0, 1, 2, 3, 6, 6, 7, 7, 12, 12]
doing a blockwise GET on: coap://localhost:38856/test
Dropping packet 0 (burst 1) from client
Dropping packet 1 (burst 2) from client
Dropping packet 2 (burst 3) from client
Dropping packet 3 (burst 4) from client
Dropping packet 7 (burst 1) from server
263 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#5 at (2017-03-22 09:58:54)
Dropping packet 12 (burst 1) from client
Received 250 bytes after 1811ms
Man in the middle will drop packets [1, 1, 4, 4, 6, 8, 8, 13, 13, 15]
doing a blockwise GET on: coap://localhost:38856/test
Dropping packet 1 (burst 1) from server
275 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#17 at (2017-03-22 09:58:55)
Dropping packet 4 (burst 1) from client
Dropping packet 6 (burst 2) from server
277 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#19 at (2017-03-22 09:58:55)
Dropping packet 8 (burst 3) from server
268 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#10 at (2017-03-22 09:58:56)
Dropping packet 13 (burst 1) from client
Dropping packet 15 (burst 2) from server
281 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#23 at (2017-03-22 09:58:56)
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 (2017-03-22 09:58:56)
258 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#1 at (2017-03-22 09:58:57)
254 FINE [ReliabilityLayer$RetransmissionTask]: Timeout: retransmission limit reached, exchange failed, message: CON-GET    MID=  129, Token=e9b8326b2d8c1a11, OptionSet={"Uri-Host":"localhost", "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=3)"}, no payload - (org.eclipse.californium.core.network.stack.ReliabilityLayer$RetransmissionTask.java:471) run() in thread CoapEndpoint-localhost/127.0.0.1:0#1 at (2017-03-22 09:58:57)

 1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2017-03-22 09:59:04)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:34555 - (org.eclipse.californium.core.network.CoapEndpoint.java:270) destroy() in thread main at (2017-03-22 09:59:05)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:34555 - (org.eclipse.californium.core.network.CoapEndpoint.java:397) stop() in thread main at (2017-03-22 09:59:05)
 1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:110) destroy() in thread main at (2017-03-22 09:59:05)
 1 INFO [CoapEndpoint]: Destroying endpoint at address /127.0.0.1:50633 - (org.eclipse.californium.core.network.CoapEndpoint.java:111) destroy() in thread main at (2017-03-22 09:59:05)
 1 INFO [CoapEndpoint]: Stopping endpoint at address /127.0.0.1:50633 - (org.eclipse.californium.core.network.CoapEndpoint.java:397) stop() in thread main at (2017-03-22 09:59:05)
End LossyBlockwiseTransferTestTests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 20.989 sec <<< FAILURE! - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest
testBlockwiseTransferToleratesLostMessages(org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest)  Time elapsed: 20.989 sec  <<< FAILURE!
java.lang.AssertionError: Blockwise GET timed out after 10000ms
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 10000ms
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.105 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  1.596 s]
[INFO] element-connector .................................. SUCCESS [01:00 min]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 16.012 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 56.070 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:20 min
[INFO] Finished at: 2017-03-22T09:59:06-04: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] Hudson build is back to normal : master #170
  - From: hudsonbuild
Prev by Date: [cf-dev] backporting changes
Next by Date: [cf-dev] [Hudson] Hudson build is back to normal : master #170
Previous by thread: [cf-dev] backporting changes
Next by thread: [cf-dev] [Hudson] Hudson build is back to normal : master #170
Index(es):
- Date
- Thread
Breadcrumbs
