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

[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 #162
From: hudsonbuild@xxxxxxxxxxx
Date: Tue, 22 Aug 2017 02:49:45 -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/2.0.x-nightly/162/>

------------------------------------------
[...truncated 5309 lines...]
02:49:38.277:   1 FINE [DirectDatagramSocketImpl]: closing port 8,231, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.278:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:275) destroy() in thread main
02:49:38.279:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:203) start() in thread main
02:49:38.280:   1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:414) start() in thread main
02:49:38.281:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:49:38.281:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
02:49:38.282:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,233 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
02:49:38.283:   1 INFO [CoapEndpoint]: Started endpoint at coap://localhost:8233 - (org.eclipse.californium.core.network.CoapEndpoint.java:423) start() in thread main
Server binds to port 8233
02:49:38.283:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:49:38.284:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
02:49:38.285:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,234 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Atomic blockwise POST with blockwise response:
02:49:38.287: 172 FINE [DirectDatagramSocketImpl]: (E465,T172) 127.0.0.1:8,234 ==> :8,233 [CON-POST   MID= 7001, Token=1d, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)", "Size1":300}, "869646816316403130911066".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.289: 175 FINE [DirectDatagramSocketImpl]: (E466,T175) 127.0.0.1:8,233 ==> :8,234 [ACK-2.31   MID= 7001, Token=1d, OptionSet={"Block1":"(szx=3/128, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.290: 172 FINE [DirectDatagramSocketImpl]: (E467,T172) 127.0.0.1:8,234 ==> :8,233 [CON-POST   MID= 7002, Token=1d, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "153025861771759908412392".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.292: 175 FINE [DirectDatagramSocketImpl]: (E468,T175) 127.0.0.1:8,233 ==> :8,234 [ACK-2.31   MID= 7002, Token=1d, OptionSet={"Block1":"(szx=3/128, m=true, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.293: 172 FINE [DirectDatagramSocketImpl]: (E469,T172) 127.0.0.1:8,234 ==> :8,233 [CON-POST   MID= 7003, Token=1d, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=false, num=2)"}, "686520349790852538874337".. 44 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.295: 175 FINE [DirectDatagramSocketImpl]: (E470,T175) 127.0.0.1:8,233 ==> :8,234 [ACK-2.04   MID= 7003, Token=1d, OptionSet={"ETag":0x0001, "Block2":"(szx=3/128, m=true, num=0)", "Block1":"(szx=3/128, m=false, num=2)", "Size2":500}, "332281778668827631919540".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.297: 172 FINE [DirectDatagramSocketImpl]: (E471,T172) 127.0.0.1:8,234 ==> :8,233 [CON-POST   MID= 7004, Token=1d, OptionSet={"ETag":0x0001, "Uri-Path":"test", "Block2":"(szx=3/128, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.299: 175 FINE [DirectDatagramSocketImpl]: (E472,T175) 127.0.0.1:8,233 ==> :8,234 [ACK-2.04   MID= 7004, Token=1d, OptionSet={"ETag":0x0001, "Block2":"(szx=3/128, m=true, num=1)"}, "286295365732328456532941".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.300: 172 FINE [DirectDatagramSocketImpl]: (E473,T172) 127.0.0.1:8,234 ==> :8,233 [CON-POST   MID= 7005, Token=1d, OptionSet={"ETag":0x0001, "Uri-Path":"test", "Block2":"(szx=3/128, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.301: 175 FINE [DirectDatagramSocketImpl]: (E474,T175) 127.0.0.1:8,233 ==> :8,234 [ACK-2.04   MID= 7005, Token=1d, OptionSet={"ETag":0x0001, "Block2":"(szx=3/128, m=true, num=2)"}, "125423756887521788842029".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.303: 172 FINE [DirectDatagramSocketImpl]: (E475,T172) 127.0.0.1:8,234 ==> :8,233 [CON-POST   MID= 7006, Token=1d, OptionSet={"ETag":0x0001, "Uri-Path":"test", "Block2":"(szx=3/128, m=false, num=3)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.304: 175 FINE [DirectDatagramSocketImpl]: (E476,T175) 127.0.0.1:8,233 ==> :8,234 [ACK-2.04   MID= 7006, Token=1d, OptionSet={"ETag":0x0001, "Block2":"(szx=3/128, m=false, num=3)"}, "793463750440961650590481".. 116 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
CON [MID=7001, T=1d], POST, /test, 1:0/1/128, size1(300)    ----->
<-----   ACK [MID=7001, T=1d], 2.31, 1:0/1/128
CON [MID=7002, T=1d], POST, /test, 1:1/1/128    ----->
<-----   ACK [MID=7002, T=1d], 2.31, 1:1/1/128
CON [MID=7003, T=1d], POST, /test, 1:2/0/128    ----->
<-----   ACK [MID=7003, T=1d], 2.04, 1:2/0/128, 2:0/1/128, size2(500), ETags(0001)
CON [MID=7004, T=1d], POST, /test, 2:1/0/128, ETags(0001)    ----->
<-----   ACK [MID=7004, T=1d], 2.04, 2:1/1/128, ETags(0001)
CON [MID=7005, T=1d], POST, /test, 2:2/0/128, ETags(0001)    ----->
<-----   ACK [MID=7005, T=1d], 2.04, 2:2/1/128, ETags(0001)
CON [MID=7006, T=1d], POST, /test, 2:3/0/128, ETags(0001)    ----->
<-----   ACK [MID=7006, T=1d], 2.04, 2:3/0/128, ETags(0001)


02:49:38.305:   1 FINE [DirectDatagramSocketImpl]: closing port 8,234, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.306:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:251) destroy() in thread main
02:49:38.395:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:8233 - (org.eclipse.californium.core.network.CoapEndpoint.java:466) destroy() in thread main
02:49:38.395:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8233 - (org.eclipse.californium.core.network.CoapEndpoint.java:453) stop() in thread main
02:49:38.396:   1 FINE [DirectDatagramSocketImpl]: closing port 8,233, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.397:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:275) destroy() in thread main
02:49:38.398:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:203) start() in thread main
02:49:38.399:   1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:414) start() in thread main
02:49:38.400:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:49:38.400:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
02:49:38.401:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,235 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
02:49:38.402:   1 INFO [CoapEndpoint]: Started endpoint at coap://localhost:8235 - (org.eclipse.californium.core.network.CoapEndpoint.java:423) start() in thread main
Server binds to port 8235
02:49:38.402:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:49:38.403:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
02:49:38.404:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,236 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Atomic blockwise POST with blockwise response using early negotiation:
02:49:38.406: 181 FINE [DirectDatagramSocketImpl]: (E477,T181) 127.0.0.1:8,236 ==> :8,235 [CON-POST   MID= 7001, Token=1e, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)"}, "477813682474818039135400".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.408: 184 FINE [DirectDatagramSocketImpl]: (E478,T184) 127.0.0.1:8,235 ==> :8,236 [ACK-2.31   MID= 7001, Token=1e, OptionSet={"Block1":"(szx=3/128, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.409: 181 FINE [DirectDatagramSocketImpl]: (E479,T181) 127.0.0.1:8,236 ==> :8,235 [CON-POST   MID= 7002, Token=1e, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "572643965113809231369329".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.411: 184 FINE [DirectDatagramSocketImpl]: (E480,T184) 127.0.0.1:8,235 ==> :8,236 [ACK-2.31   MID= 7002, Token=1e, OptionSet={"Block1":"(szx=3/128, m=true, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.413: 181 FINE [DirectDatagramSocketImpl]: (E481,T181) 127.0.0.1:8,236 ==> :8,235 [CON-POST   MID= 7003, Token=1e, OptionSet={"Uri-Path":"test", "Block2":"(szx=2/64, m=false, num=0)", "Block1":"(szx=3/128, m=false, num=2)"}, "736364195329970774369207".. 44 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.415: 184 FINE [DirectDatagramSocketImpl]: (E482,T184) 127.0.0.1:8,235 ==> :8,236 [ACK-2.04   MID= 7003, Token=1e, OptionSet={"ETag":0x0001, "Block2":"(szx=2/64, m=true, num=0)", "Block1":"(szx=3/128, m=false, num=2)", "Size2":250}, "160517025669405730793437".. 64 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.416: 181 FINE [DirectDatagramSocketImpl]: (E483,T181) 127.0.0.1:8,236 ==> :8,235 [CON-POST   MID= 7004, Token=1e, OptionSet={"ETag":0x0001, "Uri-Path":"test", "Block2":"(szx=2/64, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.418: 184 FINE [DirectDatagramSocketImpl]: (E484,T184) 127.0.0.1:8,235 ==> :8,236 [ACK-2.04   MID= 7004, Token=1e, OptionSet={"ETag":0x0001, "Block2":"(szx=2/64, m=true, num=1)"}, "481435057369568984376097".. 64 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.420: 181 FINE [DirectDatagramSocketImpl]: (E485,T181) 127.0.0.1:8,236 ==> :8,235 [CON-POST   MID= 7005, Token=1e, OptionSet={"ETag":0x0001, "Uri-Path":"test", "Block2":"(szx=2/64, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.421: 184 FINE [DirectDatagramSocketImpl]: (E486,T184) 127.0.0.1:8,235 ==> :8,236 [ACK-2.04   MID= 7005, Token=1e, OptionSet={"ETag":0x0001, "Block2":"(szx=2/64, m=true, num=2)"}, "343995379404800602145170".. 64 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.423: 181 FINE [DirectDatagramSocketImpl]: (E487,T181) 127.0.0.1:8,236 ==> :8,235 [CON-POST   MID= 7006, Token=1e, OptionSet={"ETag":0x0001, "Uri-Path":"test", "Block2":"(szx=2/64, m=false, num=3)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.424: 184 FINE [DirectDatagramSocketImpl]: (E488,T184) 127.0.0.1:8,235 ==> :8,236 [ACK-2.04   MID= 7006, Token=1e, OptionSet={"ETag":0x0001, "Block2":"(szx=2/64, m=false, num=3)"}, "556096521283255289860273".. 58 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
CON [MID=7001, T=1e], POST, /test, 1:0/1/128    ----->
<-----   ACK [MID=7001, T=1e], 2.31, 1:0/1/128
CON [MID=7002, T=1e], POST, /test, 1:1/1/128    ----->
<-----   ACK [MID=7002, T=1e], 2.31, 1:1/1/128
CON [MID=7003, T=1e], POST, /test, 1:2/0/128, 2:0/0/64    ----->
<-----   ACK [MID=7003, T=1e], 2.04, 1:2/0/128, 2:0/1/64, size2(250), ETags(0001)// early negotiation
CON [MID=7004, T=1e], POST, /test, 2:1/0/64, ETags(0001)    ----->
<-----   ACK [MID=7004, T=1e], 2.04, 2:1/1/64, ETags(0001)
CON [MID=7005, T=1e], POST, /test, 2:2/0/64, ETags(0001)    ----->
<-----   ACK [MID=7005, T=1e], 2.04, 2:2/1/64, ETags(0001)
CON [MID=7006, T=1e], POST, /test, 2:3/0/64, ETags(0001)    ----->
<-----   ACK [MID=7006, T=1e], 2.04, 2:3/0/64, ETags(0001)


02:49:38.425:   1 FINE [DirectDatagramSocketImpl]: closing port 8,236, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.426:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:251) destroy() in thread main
02:49:38.514:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:8235 - (org.eclipse.californium.core.network.CoapEndpoint.java:466) destroy() in thread main
02:49:38.515:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8235 - (org.eclipse.californium.core.network.CoapEndpoint.java:453) stop() in thread main
02:49:38.515:   1 FINE [DirectDatagramSocketImpl]: closing port 8,235, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.516:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:275) destroy() in thread main
02:49:38.519:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:203) start() in thread main
02:49:38.519:   1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:414) start() in thread main
02:49:38.520:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:49:38.521:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
02:49:38.521:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,237 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
02:49:38.523:   1 INFO [CoapEndpoint]: Started endpoint at coap://localhost:8237 - (org.eclipse.californium.core.network.CoapEndpoint.java:423) start() in thread main
Server binds to port 8237
02:49:38.523:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:49:38.523:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
02:49:38.524:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,238 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Random access PUT attempt: (try to put block 2 first is now allowed)
02:49:38.526: 190 FINE [DirectDatagramSocketImpl]: (E489,T190) 127.0.0.1:8,238 ==> :8,237 [CON-PUT    MID= 7001, Token=1f, OptionSet={"Uri-Path":"test", "Block1":"(szx=2/64, m=true, num=2)"}, "805558454102493577960755".. 64 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.527: 192 WARNING [BlockwiseLayer]: peer sent wrong block, expected no. 0 but got 2. Responding with 4.08 (Request Entity Incomplete) - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:344) handleInboundBlockwiseUpload() in thread CoapServer#1
02:49:38.528: 193 FINE [DirectDatagramSocketImpl]: (E490,T193) 127.0.0.1:8,237 ==> :8,238 [ACK-4.08   MID= 7001, Token=1f, OptionSet={"Block1":"(szx=2/64, m=true, num=2)"}, "wrong block number"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
CON [MID=7001, T=1f], PUT, /test, 1:2/1/64    ----->
<-----   ACK [MID=7001, T=1f], 4.08, 1:2/1/64


02:49:38.530:   1 FINE [DirectDatagramSocketImpl]: closing port 8,238, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.530:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:251) destroy() in thread main
02:49:38.628:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:8237 - (org.eclipse.californium.core.network.CoapEndpoint.java:466) destroy() in thread main
02:49:38.628:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8237 - (org.eclipse.californium.core.network.CoapEndpoint.java:453) stop() in thread main
02:49:38.629:   1 FINE [DirectDatagramSocketImpl]: closing port 8,237, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.629:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:275) destroy() in thread main
02:49:38.631:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:203) start() in thread main
02:49:38.631:   1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:414) start() in thread main
02:49:38.632:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:49:38.633:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
02:49:38.633:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,239 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
02:49:38.634:   1 INFO [CoapEndpoint]: Started endpoint at coap://localhost:8239 - (org.eclipse.californium.core.network.CoapEndpoint.java:423) start() in thread main
Server binds to port 8239
02:49:38.635:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:49:38.636:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
02:49:38.636:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,240 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Simple atomic blockwise PUT restart of the blockwise transfer
02:49:38.639: 198 FINE [DirectDatagramSocketImpl]: (E491,T198) 127.0.0.1:8,240 ==> :8,239 [CON-PUT    MID= 7001, Token=20, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)", "Size1":300}, "012514458456567919419337".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.640: 201 FINE [DirectDatagramSocketImpl]: (E492,T201) 127.0.0.1:8,239 ==> :8,240 [ACK-2.31   MID= 7001, Token=20, OptionSet={"Block1":"(szx=3/128, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.642: 198 FINE [DirectDatagramSocketImpl]: (E493,T198) 127.0.0.1:8,240 ==> :8,239 [CON-PUT    MID= 7002, Token=20, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "505559430759913429250870".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.644: 201 FINE [DirectDatagramSocketImpl]: (E494,T201) 127.0.0.1:8,239 ==> :8,240 [ACK-2.31   MID= 7002, Token=20, OptionSet={"Block1":"(szx=3/128, m=true, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.645: 198 FINE [DirectDatagramSocketImpl]: (E495,T198) 127.0.0.1:8,240 ==> :8,239 [CON-PUT    MID= 7003, Token=20, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)", "Size1":300}, "012514458456567919419337".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.646: 200 WARNING [BlockwiseLayer]: inbound block1 transfer reset at [currentNum=2, currentSzx=0, bufferSize=300, complete=false, random access=false] by peer: CON-PUT    MID= 7003, Token=20, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)", "Size1":300}, "012514458456567919419337".. 128 bytes - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:973) resetInboundBlock1Status() in thread CoapServer#1
02:49:38.648: 201 FINE [DirectDatagramSocketImpl]: (E496,T201) 127.0.0.1:8,239 ==> :8,240 [ACK-2.31   MID= 7003, Token=20, OptionSet={"Block1":"(szx=3/128, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.649: 198 FINE [DirectDatagramSocketImpl]: (E497,T198) 127.0.0.1:8,240 ==> :8,239 [CON-PUT    MID= 7004, Token=20, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "505559430759913429250870".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.651: 201 FINE [DirectDatagramSocketImpl]: (E498,T201) 127.0.0.1:8,239 ==> :8,240 [ACK-2.31   MID= 7004, Token=20, OptionSet={"Block1":"(szx=3/128, m=true, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.652: 198 FINE [DirectDatagramSocketImpl]: (E499,T198) 127.0.0.1:8,240 ==> :8,239 [CON-PUT    MID= 7005, Token=20, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=false, num=2)"}, "138623952166685787941041".. 44 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.653: 201 FINE [DirectDatagramSocketImpl]: (E500,T201) 127.0.0.1:8,239 ==> :8,240 [ACK-2.04   MID= 7005, Token=20, OptionSet={"Block1":"(szx=3/128, m=false, num=2)"}, "250743046598686242105845".. 50 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
CON [MID=7001, T=20], PUT, /test, 1:0/1/128, size1(300)    ----->
<-----   ACK [MID=7001, T=20], 2.31, 1:0/1/128
CON [MID=7002, T=20], PUT, /test, 1:1/1/128    ----->
<-----   ACK [MID=7002, T=20], 2.31, 1:1/1/128
... client crashes or whatever and restarts transfer
CON [MID=7003, T=20], PUT, /test, 1:0/1/128, size1(300)    ----->
<-----   ACK [MID=7003, T=20], 2.31, 1:0/1/128
CON [MID=7004, T=20], PUT, /test, 1:1/1/128    ----->
<-----   ACK [MID=7004, T=20], 2.31, 1:1/1/128
CON [MID=7005, T=20], PUT, /test, 1:2/0/128    ----->
<-----   ACK [MID=7005, T=20], 2.04, 1:2/0/128


02:49:38.654:   1 FINE [DirectDatagramSocketImpl]: closing port 8,240, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.654:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:251) destroy() in thread main
02:49:38.753:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:8239 - (org.eclipse.californium.core.network.CoapEndpoint.java:466) destroy() in thread main
02:49:38.754:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8239 - (org.eclipse.californium.core.network.CoapEndpoint.java:453) stop() in thread main
02:49:38.754:   1 FINE [DirectDatagramSocketImpl]: closing port 8,239, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.755:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:275) destroy() in thread main
02:49:38.756:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:203) start() in thread main
02:49:38.756:   1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:414) start() in thread main
02:49:38.757:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:49:38.757:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
02:49:38.758:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,241 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
02:49:38.759:   1 INFO [CoapEndpoint]: Started endpoint at coap://localhost:8241 - (org.eclipse.californium.core.network.CoapEndpoint.java:423) start() in thread main
Server binds to port 8241
02:49:38.759:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:49:38.760:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
02:49:38.760:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,242 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Simple atomic blockwise PUT
02:49:38.762: 207 FINE [DirectDatagramSocketImpl]: (E501,T207) 127.0.0.1:8,242 ==> :8,241 [CON-PUT    MID= 7001, Token=21, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)", "Size1":300}, "478526875519934671904377".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.764: 210 FINE [DirectDatagramSocketImpl]: (E502,T210) 127.0.0.1:8,241 ==> :8,242 [ACK-2.31   MID= 7001, Token=21, OptionSet={"Block1":"(szx=3/128, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.765: 207 FINE [DirectDatagramSocketImpl]: (E503,T207) 127.0.0.1:8,242 ==> :8,241 [CON-PUT    MID= 7002, Token=22, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "792454192755027943977999".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.767: 210 FINE [DirectDatagramSocketImpl]: (E504,T210) 127.0.0.1:8,241 ==> :8,242 [ACK-2.31   MID= 7002, Token=22, OptionSet={"Block1":"(szx=3/128, m=true, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.768: 207 FINE [DirectDatagramSocketImpl]: (E505,T207) 127.0.0.1:8,242 ==> :8,241 [CON-PUT    MID= 7003, Token=23, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=false, num=2)"}, "176799271989074029085282".. 44 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:49:38.770: 210 FINE [DirectDatagramSocketImpl]: (E506,T210) 127.0.0.1:8,241 ==> :8,242 [ACK-2.04   MID= 7003, Token=23, OptionSet={"Block1":"(szx=3/128, m=false, num=2)"}, "974869047821376574950093".. 50 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
CON [MID=7001, T=21], PUT, /test, 1:0/1/128, size1(300)    ----->
<-----   ACK [MID=7001, T=21], 2.31, 1:0/1/128
CON [MID=7002, T=22], PUT, /test, 1:1/1/128    ----->
<-----   ACK [MID=7002, T=22], 2.31, 1:1/1/128
CON [MID=7003, T=23], PUT, /test, 1:2/0/128    ----->
<-----   ACK [MID=7003, T=23], 2.04, 1:2/0/128


02:49:38.771:   1 FINE [DirectDatagramSocketImpl]: closing port 8,242, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.772:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:251) destroy() in thread main
02:49:38.870:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:8241 - (org.eclipse.californium.core.network.CoapEndpoint.java:466) destroy() in thread main
02:49:38.871:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8241 - (org.eclipse.californium.core.network.CoapEndpoint.java:453) stop() in thread main
02:49:38.871:   1 FINE [DirectDatagramSocketImpl]: closing port 8,241, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:49:38.872:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:275) destroy() in thread main
End BlockwiseServerSideTest
02:49:38.872:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2 sec - in org.eclipse.californium.core.test.lockstep.BlockwiseServerSideTest

Results :

Failed tests: 
  ObserveClientSideTest.testGETObserveWithLostACK:178 
Expected: is <1>
     but: was <2>

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  3.104 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  1.449 s]
[INFO] element-connector .................................. SUCCESS [01:42 min]
[INFO] Scandium (Sc) Core ................................. SUCCESS [01:03 min]
[INFO] Californium (Cf) Core .............................. FAILURE [02:04 min]
[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-SimpleFileServer ................................ 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: 05:00 min
[INFO] Finished at: 2017-08-22T02:49:39-04:00
[INFO] Final Memory: 48M/303M
[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 <https://hudson.eclipse.org/californium/job/2.0.x-nightly/ws/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 <https://hudson.eclipse.org/californium/job/2.0.x-nightly/ws/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: 2.0.x-nightly #163
  - From: hudsonbuild
Prev by Date: [cf-dev] [Hudson] Hudson build is back to normal : 2.0.x-nightly #159
Next by Date: [cf-dev] How To Do Multicast
Previous by thread: [cf-dev] [Hudson] Build failed in Hudson: 2.0.x-nightly #158
Next by thread: [cf-dev] [Hudson] Build failed in Hudson: 2.0.x-nightly #163
Index(es):
- Date
- Thread
Breadcrumbs
