[
Date Prev][
Date Next][
Thread Prev][
Thread Next][
Date Index][
Thread Index]
[
List Home]
|
[cf-dev] [Hudson] Build failed in Hudson: 2.0.x #105
|
See <https://hudson.eclipse.org/californium/job/2.0.x/105/changes>
Changes:
[Kai Hudalla] [#234] Include key and trust stores in artifact.
------------------------------------------
[...truncated 3276 lines...]
<----- CON [MID=8002, T=8ac49d365ec7f612], 2.05, observe(102)
ACK [MID=8002] -----> // lost
<----- CON [MID=8002, T=8ac49d365ec7f612], 2.05, observe(102)
ACK [MID=8002] ----->
1 INFO [CoapEndpoint]: Destroying endpoint at address coap://127.0.0.1:43676 - (org.eclipse.californium.core.network.CoapEndpoint.java:94) destroy() in thread main at (2017-03-09 12:35:14)
1 INFO [CoapEndpoint]: Stopping endpoint at address coap://127.0.0.1:43676 - (org.eclipse.californium.core.network.CoapEndpoint.java:464) stop() in thread main at (2017-03-09 12:35:14)
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 (2017-03-09 12:35:14)
1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:87) start() in thread main at (2017-03-09 12:35:14)
1 INFO [CoapEndpoint]: Started endpoint at coap://127.0.0.1:52401 - (org.eclipse.californium.core.network.CoapEndpoint.java:87) start() in thread main at (2017-03-09 12:35:14)
Client binds to port 52401
Blockwise Observe:
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 827, Token=2f1776e53f1b0a3a, OptionSet={"Observe":0, "Uri-Path":"test"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 827, Token=2f1776e53f1b0a3a, OptionSet={"Observe":0, "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "6642800554497978" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 828, Token=ab2c026ede77aaee, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 828, Token=ab2c026ede77aaee, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "4058477913399066" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 829, Token=ab2c026ede77aaee, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 829, Token=ab2c026ede77aaee, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "88645644" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
CON [MID=827, T=2f1776e53f1b0a3a], GET, /test, observe(0) ----->
<----- ACK [MID=827, T=2f1776e53f1b0a3a], 2.05, 2:0/1/16, observe(0), size2(40)
CON [MID=828, T=ab2c026ede77aaee], GET, /test, 2:1/0/16 ----->
<----- ACK [MID=828, T=ab2c026ede77aaee], 2.05, 2:1/1/16
CON [MID=829, T=ab2c026ede77aaee], GET, /test, 2:2/0/16 ----->
<----- ACK [MID=829, T=ab2c026ede77aaee], 2.05, 2:2/0/16
observe relation has been established, server now sends a notification
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res CON-2.05 MID= 8001, Token=2f1776e53f1b0a3a, OptionSet={"Observe":1, "Block2":"(szx=0/16, m=true, num=0)", "Size2":45}, "9586201595718140" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== emp ACK MID= 8001 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:676) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 830, Token=74add51e88ed8d02, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 830, Token=74add51e88ed8d02, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "0431547557561966" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 831, Token=74add51e88ed8d02, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 831, Token=74add51e88ed8d02, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "5307859883998" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
<----- CON [MID=8001, T=2f1776e53f1b0a3a], 2.05, 2:0/1/16, observe(1), size2(45)
ACK [MID=8001] ----->
CON [MID=830, T=74add51e88ed8d02], GET, /test, 2:1/0/16 ----->
<----- ACK [MID=830, T=74add51e88ed8d02], 2.05, 2:1/1/16
CON [MID=831, T=74add51e88ed8d02], GET, /test, 2:2/0/16 ----->
<----- ACK [MID=831, T=74add51e88ed8d02], 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
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res CON-2.05 MID= 8002, Token=2f1776e53f1b0a3a, OptionSet={"Observe":2, "Block2":"(szx=0/16, m=true, num=0)", "Size2":42}, "0034302044365830" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== emp ACK MID= 8002 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:676) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 832, Token=7457c7c5d82a0fdf, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 832, Token=7457c7c5d82a0fdf, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "2103087704137244" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 833, Token=7457c7c5d82a0fdf, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res CON-2.05 MID= 8003, Token=2f1776e53f1b0a3a, OptionSet={"Observe":3, "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "abcdefghijklmnop" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== emp ACK MID= 8003 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:676) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 WARNING [BlockwiseLayer]: inbound block2 transfer reset at [currentNum=2, currentSzx=0, observe=2, bufferSize=42, complete=false, random access=false] by new notification: CON-2.05 MID= 8003, Token=2f1776e53f1b0a3a, OptionSet={"Observe":3, "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "abcdefghijklmnop" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:721) resetInboundBlock2Status() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 834, Token=da2af057b8dfbc1f, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 833, Token=7457c7c5d82a0fdf, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "2668634469" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 834, Token=da2af057b8dfbc1f, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "qrstuvwxyzabcdef" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 835, Token=da2af057b8dfbc1f, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 835, Token=da2af057b8dfbc1f, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "ghijklmn" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
<----- CON [MID=8002, T=2f1776e53f1b0a3a], 2.05, 2:0/1/16, observe(2), size2(42)
ACK [MID=8002] ----->
CON [MID=832, T=7457c7c5d82a0fdf], GET, /test, 2:1/0/16 ----->
<----- ACK [MID=832, T=7457c7c5d82a0fdf], 2.05, 2:1/1/16
CON [MID=833, T=7457c7c5d82a0fdf], GET, /test, 2:2/0/16 ----->
//////// Overriding notification ////////
<----- CON [MID=8003, T=2f1776e53f1b0a3a], 2.05, 2:0/1/16, observe(3), size2(40)
ACK [MID=8003] ----->
CON [MID=834, T=da2af057b8dfbc1f], GET, /test, 2:1/0/16 ----->
<----- ACK [MID=833, T=7457c7c5d82a0fdf], 2.05, 2:2/0/16
<----- ACK [MID=834, T=da2af057b8dfbc1f], 2.05, 2:1/1/16
CON [MID=835, T=da2af057b8dfbc1f], GET, /test, 2:2/0/16 ----->
<----- ACK [MID=835, T=da2af057b8dfbc1f], 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
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res CON-2.05 MID= 8004, Token=2f1776e53f1b0a3a, OptionSet={"Observe":4, "Block2":"(szx=0/16, m=true, num=0)", "Size2":38}, "3223074980889977" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== emp ACK MID= 8004 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:676) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 836, Token=45663867aad04c9a, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res CON-2.05 MID= 8005, Token=2f1776e53f1b0a3a, OptionSet={"Observe":5, "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "ABCDEFGHIJKLMNOP" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== emp ACK MID= 8005 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:676) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 WARNING [BlockwiseLayer]: inbound block2 transfer reset at [currentNum=1, currentSzx=0, observe=4, bufferSize=38, complete=false, random access=false] by new notification: CON-2.05 MID= 8005, Token=2f1776e53f1b0a3a, OptionSet={"Observe":5, "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "ABCDEFGHIJKLMNOP" - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:721) resetInboundBlock2Status() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 837, Token=37bb1a8cc965c88d, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 836, Token=45663867aad04c9a, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "4190720905831301" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 837, Token=37bb1a8cc965c88d, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "QRSTUVWXYZABCDEF" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 838, Token=37bb1a8cc965c88d, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 838, Token=37bb1a8cc965c88d, OptionSet={"Block2":"(szx=0/16, m=false, num=2)"}, "GHIJKLMN" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
<----- CON [MID=8004, T=2f1776e53f1b0a3a], 2.05, 2:0/1/16, observe(4), size2(38)
ACK [MID=8004] ----->
CON [MID=836, T=45663867aad04c9a], GET, /test, 2:1/0/16 ----->
//////// Overriding notification (4) ////////
<----- CON [MID=8005, T=2f1776e53f1b0a3a], 2.05, 2:0/1/16, observe(5), size2(40)
ACK [MID=8005] ----->
CON [MID=837, T=37bb1a8cc965c88d], GET, /test, 2:1/0/16 ----->
//////// Conflicting notification block ////////
<----- ACK [MID=836, T=45663867aad04c9a], 2.05, 2:1/1/16
<----- ACK [MID=837, T=37bb1a8cc965c88d], 2.05, 2:1/1/16
CON [MID=838, T=37bb1a8cc965c88d], GET, /test, 2:2/0/16 ----->
<----- ACK [MID=838, T=37bb1a8cc965c88d], 2.05, 2:2/0/16
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res CON-2.05 MID= 8006, Token=2f1776e53f1b0a3a, OptionSet={"Observe":6, "Block2":"(szx=0/16, m=true, num=0)", "Size2":34}, "9694665062255685" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== emp ACK MID= 8006 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:676) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== req CON-GET MID= 839, Token=212edd599a6b987d, OptionSet={"Uri-Path":"test", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:621) sendRequest() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res ACK-2.05 MID= 839, Token=212edd599a6b987d, OptionSet={"Block2":"(szx=0/16, m=true, num=1)"}, "2108981198441244" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:14)
//////// Notification after cancellation ////////
<----- CON [MID=8006, T=2f1776e53f1b0a3a], 2.05, 2:0/1/16, observe(6), size2(34)
ACK [MID=8006] ----->
CON [MID=839, T=212edd599a6b987d], GET, /test, 2:1/0/16 ----->
<----- ACK [MID=839, T=212edd599a6b987d], 2.05, 2:1/1/16
232 INFO [MessageTracer]: /127.0.0.1:53,934 ==> res CON-2.05 MID= 8007, Token=2f1776e53f1b0a3a, OptionSet={"Observe":7, "Block2":"(szx=0/16, m=true, num=0)"}, "9694665062255685" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:820) receiveResponse() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:15)
232 INFO [MessageTracer]: /127.0.0.1:53,934 <== emp RST MID= 8007 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:676) sendEmptyMessage() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:15)
<----- CON [MID=8007, T=2f1776e53f1b0a3a], 2.05, 2:0/1/16, observe(7)
RST [MID=8007] ----->
1 INFO [CoapEndpoint]: Destroying endpoint at address coap://127.0.0.1:52401 - (org.eclipse.californium.core.network.CoapEndpoint.java:94) destroy() in thread main at (2017-03-09 12:35:15)
1 INFO [CoapEndpoint]: Stopping endpoint at address coap://127.0.0.1:52401 - (org.eclipse.californium.core.network.CoapEndpoint.java:464) stop() in thread main at (2017-03-09 12:35:15)
End ObserveClientSideTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.036 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-09 12:35:15)
1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:79) start() in thread main at (2017-03-09 12:35:15)
1 INFO [CoapEndpoint]: Started endpoint at coap://127.0.0.1:46703 - (org.eclipse.californium.core.network.CoapEndpoint.java:79) start() in thread main at (2017-03-09 12:35:15)
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-09 12:35:15)
1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:200) start() in thread main at (2017-03-09 12:35:15)
1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:212) start() in thread main at (2017-03-09 12:35:15)
1 INFO [CoapEndpoint]: Started endpoint at coap://127.0.0.1:51478 - (org.eclipse.californium.core.network.CoapEndpoint.java:212) start() in thread main at (2017-03-09 12:35:15)
Starting man in the middle...
client at 127.0.0.1:46703, middle at 127.0.0.1:41992, server at 127.0.0.1:51478
Man in the middle will drop packets [5, 6, 8, 9, 15]
doing a blockwise GET on: coap://localhost:41992/test
Dropping packet 5 (burst 1) from server
Dropping packet 6 (burst 2) from client
246 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-09 12:35:16)
Dropping packet 8 (burst 3) from server
Dropping packet 9 (burst 4) from client
245 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#3 at (2017-03-09 12:35:16)
Dropping packet 15 (burst 1) from server
247 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-09 12:35:17)
Received 250 bytes after 1511ms
Man in the middle will drop packets [0, 4, 8, 8, 10, 11, 12, 12, 13, 14]
doing a blockwise GET on: coap://localhost:41992/test
Dropping packet 0 (burst 1) from client
Dropping packet 4 (burst 1) from server
244 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-09 12:35:17)
Dropping packet 8 (burst 1) from server
242 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-09 12:35:18)
Dropping packet 10 (burst 2) from server
Dropping packet 11 (burst 3) from client
Dropping packet 12 (burst 4) from client
248 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-09 12:35:19)
Received 250 bytes after 1811ms
Man in the middle will drop packets [0, 2, 2, 4, 4, 4, 8, 8, 8, 11]
doing a blockwise GET on: coap://localhost:41992/test
Dropping packet 0 (burst 1) from client
Dropping packet 2 (burst 2) from server
253 FINE [ReliabilityLayer]: Respond with the current response to the duplicate request - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:188) receiveRequest() in thread CoapServer#11 at (2017-03-09 12:35:19)
Dropping packet 4 (burst 3) from server
254 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-09 12:35:19)
Dropping packet 8 (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#20 at (2017-03-09 12:35:20)
Dropping packet 11 (burst 1) from client
Received 250 bytes after 1510ms
Man in the middle will drop packets [0, 0, 2, 2, 5, 6, 9, 10, 12, 15]
doing a blockwise GET on: coap://localhost:41992/test
Dropping packet 0 (burst 1) from client
Dropping packet 2 (burst 2) from server
250 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-09 12:35:21)
Dropping packet 5 (burst 2) from client
Dropping packet 6 (burst 3) from client
261 FINE [ReliabilityLayer]: The server has not yet decided what to do with the request. We ignore the duplicate. - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:203) receiveRequest() in thread CoapServer#19 at (2017-03-09 12:35:22)
Dropping packet 9 (burst 3) from server
Dropping packet 10 (burst 4) from client
238 FINE [ReliabilityLayer$RetransmissionTask]: Timeout: retransmission limit reached, exchange failed, message: CON-GET MID= 153, Token=82a1ddb4421a4251, OptionSet={"Uri-Host":"localhost", "Uri-Path":"test", "Block2":"(szx=1/32, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.stack.ReliabilityLayer$RetransmissionTask.java:471) run() in thread CoapEndpoint-coap://localhost:0#1 at (2017-03-09 12:35:22)
1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:248) destroy() in thread main at (2017-03-09 12:35:30)
1 INFO [CoapEndpoint]: Destroying endpoint at address coap://127.0.0.1:51478 - (org.eclipse.californium.core.network.CoapEndpoint.java:252) destroy() in thread main at (2017-03-09 12:35:30)
1 INFO [CoapEndpoint]: Stopping endpoint at address coap://127.0.0.1:51478 - (org.eclipse.californium.core.network.CoapEndpoint.java:464) stop() in thread main at (2017-03-09 12:35:30)
1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:273) destroy() in thread main at (2017-03-09 12:35:31)
1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:110) destroy() in thread main at (2017-03-09 12:35:31)
1 INFO [CoapEndpoint]: Destroying endpoint at address coap://127.0.0.1:46703 - (org.eclipse.californium.core.network.CoapEndpoint.java:111) destroy() in thread main at (2017-03-09 12:35:31)
1 INFO [CoapEndpoint]: Stopping endpoint at address coap://127.0.0.1:46703 - (org.eclipse.californium.core.network.CoapEndpoint.java:464) stop() in thread main at (2017-03-09 12:35:31)
End LossyBlockwiseTransferTestTests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 15.86 sec <<< FAILURE! - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest
testBlockwiseTransferToleratesLostMessages(org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest) Time elapsed: 15.859 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: 21, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Californium (Cf) Parent ............................ SUCCESS [ 3.703 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [ 1.459 s]
[INFO] element-connector .................................. SUCCESS [01:19 min]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 18.242 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 55.330 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:46 min
[INFO] Finished at: 2017-03-09T12:35:32-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/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/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/
