[cf-dev] Build failed in Jenkins: 1.1.x-nightly #398

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] Build failed in Jenkins: 1.1.x-nightly #398
From: hudsonbuild@xxxxxxxxxxx
Date: Fri, 20 Apr 2018 02:25:53 -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://ci.eclipse.org/californium/job/1.1.x-nightly/398/display/redirect>

------------------------------------------
[...truncated 1.78 MB...]
CON [MID=7003, T=19], GET, /test, 2:3/0/64    ----->
<-----   ACK [MID=7003, T=19], 2.05, 2:3/1/64, size2(350)
CON [MID=7004, T=19], GET, /test, 2:4/0/64    ----->
<-----   ACK [MID=7004, T=19], 2.05, 2:4/1/64, size2(350)
CON [MID=7005, T=19], GET, /test, 2:5/0/64    ----->
<-----   ACK [MID=7005, T=19], 2.05, 2:5/0/64, size2(350)


02:25:52.376:   1 FINE [DirectDatagramSocketImpl]: closing port 8,218, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.377:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:249) destroy() in thread main
02:25:52.377:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8217 - (org.eclipse.californium.core.network.CoapEndpoint.java:392) destroy() in thread main
02:25:52.378:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8217 - (org.eclipse.californium.core.network.CoapEndpoint.java:379) stop() in thread main
02:25:52.378:   1 FINE [DirectDatagramSocketImpl]: closing port 8,217, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.379:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:273) destroy() in thread main
02:25:52.380:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:201) start() in thread main
02:25:52.380:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:341) start() in thread main
02:25:52.380:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
02:25:52.381:   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:25:52.381:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,219 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Server binds to port 8219
02:25:52.382:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
02:25:52.382:   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:25:52.383:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,220 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Atomic blockwise POST with blockwise response:
02:25:52.385: 124 FINE [DirectDatagramSocketImpl]: (E398,T124) 127.0.0.1:8,220 ==> :8,219 [CON-POST   MID= 7001, Token=1a, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)"}, "263039230703724429206563".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.387: 127 FINE [DirectDatagramSocketImpl]: (E399,T127) 127.0.0.1:8,219 ==> :8,220 [ACK-2.31   MID= 7001, Token=1a, 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:25:52.388: 124 FINE [DirectDatagramSocketImpl]: (E400,T124) 127.0.0.1:8,220 ==> :8,219 [CON-POST   MID= 7002, Token=1a, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "828571068726101043967774".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.389: 127 FINE [DirectDatagramSocketImpl]: (E401,T127) 127.0.0.1:8,219 ==> :8,220 [ACK-2.31   MID= 7002, Token=1a, 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:25:52.391: 124 FINE [DirectDatagramSocketImpl]: (E402,T124) 127.0.0.1:8,220 ==> :8,219 [CON-POST   MID= 7003, Token=1a, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=false, num=2)"}, "540584470469308114061819".. 44 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Server has received request payload: 263039230703724429206563282407371101363265078264714763963234219700140008784612312498544866323661724018181193508999636647596593648285710687261010439677748155975379273660016263475557533251522687114990265818518256525516528893924130095299340448419027748743265854058447046930811406181959415562067487515892
02:25:52.392: 127 FINE [DirectDatagramSocketImpl]: (E403,T127) 127.0.0.1:8,219 ==> :8,220 [ACK-2.04   MID= 7003, Token=1a, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=3/128, m=true, num=0)", "Block1":"(szx=3/128, m=false, num=2)", "Size2":500}, "919654691292883725554987".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.394: 124 FINE [DirectDatagramSocketImpl]: (E404,T124) 127.0.0.1:8,220 ==> :8,219 [CON-POST   MID= 7004, Token=1a, OptionSet={"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:25:52.395: 127 FINE [DirectDatagramSocketImpl]: (E405,T127) 127.0.0.1:8,219 ==> :8,220 [ACK-2.04   MID= 7004, Token=1a, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=3/128, m=true, num=1)", "Size2":500}, "755280468523434509300721".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.396: 124 FINE [DirectDatagramSocketImpl]: (E406,T124) 127.0.0.1:8,220 ==> :8,219 [CON-POST   MID= 7005, Token=1a, OptionSet={"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:25:52.397: 127 FINE [DirectDatagramSocketImpl]: (E407,T127) 127.0.0.1:8,219 ==> :8,220 [ACK-2.04   MID= 7005, Token=1a, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=3/128, m=true, num=2)", "Size2":500}, "439353415989277837675650".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.399: 124 FINE [DirectDatagramSocketImpl]: (E408,T124) 127.0.0.1:8,220 ==> :8,219 [CON-POST   MID= 7006, Token=1a, OptionSet={"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:25:52.400: 127 FINE [DirectDatagramSocketImpl]: (E409,T127) 127.0.0.1:8,219 ==> :8,220 [ACK-2.04   MID= 7006, Token=1a, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=3/128, m=false, num=3)", "Size2":500}, "940665879705132512953541".. 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=1a], POST, /test, 1:0/1/128    ----->
<-----   ACK [MID=7001, T=1a], 2.31, 1:0/1/128
CON [MID=7002, T=1a], POST, /test, 1:1/1/128    ----->
<-----   ACK [MID=7002, T=1a], 2.31, 1:1/1/128
CON [MID=7003, T=1a], POST, /test, 1:2/0/128    ----->
<-----   ACK [MID=7003, T=1a], 2.04, 1:2/0/128, 2:0/1/128, size2(500)
CON [MID=7004, T=1a], POST, /test, 2:1/0/128    ----->
<-----   ACK [MID=7004, T=1a], 2.04, 2:1/1/128, size2(500)
CON [MID=7005, T=1a], POST, /test, 2:2/0/128    ----->
<-----   ACK [MID=7005, T=1a], 2.04, 2:2/1/128, size2(500)
CON [MID=7006, T=1a], POST, /test, 2:3/0/128    ----->
<-----   ACK [MID=7006, T=1a], 2.04, 2:3/0/128, size2(500)


02:25:52.401:   1 FINE [DirectDatagramSocketImpl]: closing port 8,220, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.402:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:249) destroy() in thread main
02:25:52.403:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8219 - (org.eclipse.californium.core.network.CoapEndpoint.java:392) destroy() in thread main
02:25:52.404:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8219 - (org.eclipse.californium.core.network.CoapEndpoint.java:379) stop() in thread main
02:25:52.404:   1 FINE [DirectDatagramSocketImpl]: closing port 8,219, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.405:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:273) destroy() in thread main
02:25:52.407:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:201) start() in thread main
02:25:52.408:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:341) start() in thread main
02:25:52.408:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
02:25:52.409:   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:25:52.409:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,221 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Server binds to port 8221
02:25:52.411:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
02:25:52.412:   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:25:52.412:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,222 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Atomic blockwise POST with blockwise response:
02:25:52.415: 133 FINE [DirectDatagramSocketImpl]: (E410,T133) 127.0.0.1:8,222 ==> :8,221 [CON-POST   MID= 7001, Token=1b, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)"}, "045307032500778703931503".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.420: 136 FINE [DirectDatagramSocketImpl]: (E411,T136) 127.0.0.1:8,221 ==> :8,222 [ACK-2.31   MID= 7001, Token=1b, 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:25:52.422: 133 FINE [DirectDatagramSocketImpl]: (E412,T133) 127.0.0.1:8,222 ==> :8,221 [CON-POST   MID= 7002, Token=1b, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "713175424060327765948258".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.424: 136 FINE [DirectDatagramSocketImpl]: (E413,T136) 127.0.0.1:8,221 ==> :8,222 [ACK-2.31   MID= 7002, Token=1b, 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:25:52.426: 133 FINE [DirectDatagramSocketImpl]: (E414,T133) 127.0.0.1:8,222 ==> :8,221 [CON-POST   MID= 7003, Token=1b, OptionSet={"Uri-Path":"test", "Block2":"(szx=2/64, m=false, num=0)", "Block1":"(szx=3/128, m=false, num=2)"}, "458125146197123239706158".. 44 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Server has received request payload: 045307032500778703931503910721220784271344020589848579079399216251760588095891313263942796708734810336023816758361746642168525127131754240603277659482585628189663606138135984301204083881088521239997602309341958875375176293570330316449251546062453076778189045812514619712323970615854193190615512621786
02:25:52.428: 136 FINE [DirectDatagramSocketImpl]: (E415,T136) 127.0.0.1:8,221 ==> :8,222 [ACK-2.04   MID= 7003, Token=1b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=2/64, m=true, num=0)", "Block1":"(szx=3/128, m=false, num=2)", "Size2":250}, "136228217360431970600184".. 64 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.429: 133 FINE [DirectDatagramSocketImpl]: (E416,T133) 127.0.0.1:8,222 ==> :8,221 [CON-POST   MID= 7004, Token=1b, OptionSet={"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:25:52.430: 136 FINE [DirectDatagramSocketImpl]: (E417,T136) 127.0.0.1:8,221 ==> :8,222 [ACK-2.04   MID= 7004, Token=1b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=2/64, m=true, num=1)", "Size2":250}, "581630194388727109695966".. 64 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.431: 133 FINE [DirectDatagramSocketImpl]: (E418,T133) 127.0.0.1:8,222 ==> :8,221 [CON-POST   MID= 7005, Token=1b, OptionSet={"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:25:52.433: 136 FINE [DirectDatagramSocketImpl]: (E419,T136) 127.0.0.1:8,221 ==> :8,222 [ACK-2.04   MID= 7005, Token=1b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=2/64, m=true, num=2)", "Size2":250}, "065463013030917560050112".. 64 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.434: 133 FINE [DirectDatagramSocketImpl]: (E420,T133) 127.0.0.1:8,222 ==> :8,221 [CON-POST   MID= 7006, Token=1b, OptionSet={"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:25:52.436: 136 FINE [DirectDatagramSocketImpl]: (E421,T136) 127.0.0.1:8,221 ==> :8,222 [ACK-2.04   MID= 7006, Token=1b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=2/64, m=false, num=3)", "Size2":250}, "634311594713217819039617".. 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=1b], POST, /test, 1:0/1/128    ----->
<-----   ACK [MID=7001, T=1b], 2.31, 1:0/1/128
CON [MID=7002, T=1b], POST, /test, 1:1/1/128    ----->
<-----   ACK [MID=7002, T=1b], 2.31, 1:1/1/128
CON [MID=7003, T=1b], POST, /test, 1:2/0/128, 2:0/0/64    ----->
<-----   ACK [MID=7003, T=1b], 2.04, 1:2/0/128, 2:0/1/64, size2(250)// early negotiation
CON [MID=7004, T=1b], POST, /test, 2:1/0/64    ----->
<-----   ACK [MID=7004, T=1b], 2.04, 2:1/1/64, size2(250)
CON [MID=7005, T=1b], POST, /test, 2:2/0/64    ----->
<-----   ACK [MID=7005, T=1b], 2.04, 2:2/1/64, size2(250)
CON [MID=7006, T=1b], POST, /test, 2:3/0/64    ----->
<-----   ACK [MID=7006, T=1b], 2.04, 2:3/0/64, size2(250)


02:25:52.436:   1 FINE [DirectDatagramSocketImpl]: closing port 8,222, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.437:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:249) destroy() in thread main
02:25:52.438:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8221 - (org.eclipse.californium.core.network.CoapEndpoint.java:392) destroy() in thread main
02:25:52.439:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8221 - (org.eclipse.californium.core.network.CoapEndpoint.java:379) stop() in thread main
02:25:52.440:   1 FINE [DirectDatagramSocketImpl]: closing port 8,221, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.441:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:273) destroy() in thread main
02:25:52.442:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:201) start() in thread main
02:25:52.442:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:341) start() in thread main
02:25:52.443:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
02:25:52.452:   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:25:52.453:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,223 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Server binds to port 8223
02:25:52.457:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
02:25:52.457:   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:25:52.458:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,224 - (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:25:52.460: 142 FINE [DirectDatagramSocketImpl]: (E422,T142) 127.0.0.1:8,224 ==> :8,223 [CON-PUT    MID= 7001, Token=1c, OptionSet={"Uri-Path":"test", "Block1":"(szx=2/64, m=true, num=2)"}, "850079715348369927048561".. 64 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.461: 144 WARNING [BlockwiseLayer]: Wrong block number. Expected 0 but received 2. Respond with 4.08 (Request Entity Incomplete) - (org.eclipse.californium.core.network.stack.BlockwiseLayer.java:311) handleInboundBlockwiseUpload() in thread CoapServer#1
02:25:52.462: 145 FINE [DirectDatagramSocketImpl]: (E423,T145) 127.0.0.1:8,223 ==> :8,224 [ACK-4.08   MID= 7001, Token=1c, 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=1c], PUT, /test, 1:2/1/64    ----->
<-----   ACK [MID=7001, T=1c], 4.08, 1:2/1/64


02:25:52.470:   1 FINE [DirectDatagramSocketImpl]: closing port 8,224, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.471:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:249) destroy() in thread main
02:25:52.472:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8223 - (org.eclipse.californium.core.network.CoapEndpoint.java:392) destroy() in thread main
02:25:52.473:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8223 - (org.eclipse.californium.core.network.CoapEndpoint.java:379) stop() in thread main
02:25:52.473:   1 FINE [DirectDatagramSocketImpl]: closing port 8,223, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.474:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:273) destroy() in thread main
02:25:52.474:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:201) start() in thread main
02:25:52.475:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:341) start() in thread main
02:25:52.475:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
02:25:52.475:   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:25:52.476:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,225 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Server binds to port 8225
02:25:52.477:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
02:25:52.478:   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:25:52.478:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,226 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Simple atomic blockwise PUT restart of the blockwise transfer
02:25:52.480: 150 FINE [DirectDatagramSocketImpl]: (E424,T150) 127.0.0.1:8,226 ==> :8,225 [CON-PUT    MID= 7001, Token=1d, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)"}, "169224063574326740960683".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.481: 153 FINE [DirectDatagramSocketImpl]: (E425,T153) 127.0.0.1:8,225 ==> :8,226 [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:25:52.483: 150 FINE [DirectDatagramSocketImpl]: (E426,T150) 127.0.0.1:8,226 ==> :8,225 [CON-PUT    MID= 7002, Token=1d, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "886593687428291539944018".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.484: 153 FINE [DirectDatagramSocketImpl]: (E427,T153) 127.0.0.1:8,225 ==> :8,226 [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:25:52.485: 150 FINE [DirectDatagramSocketImpl]: (E428,T150) 127.0.0.1:8,226 ==> :8,225 [CON-PUT    MID= 7003, Token=1d, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)"}, "169224063574326740960683".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.487: 153 FINE [DirectDatagramSocketImpl]: (E429,T153) 127.0.0.1:8,225 ==> :8,226 [ACK-2.31   MID= 7003, 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:25:52.488: 150 FINE [DirectDatagramSocketImpl]: (E430,T150) 127.0.0.1:8,226 ==> :8,225 [CON-PUT    MID= 7004, Token=1d, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "886593687428291539944018".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.489: 153 FINE [DirectDatagramSocketImpl]: (E431,T153) 127.0.0.1:8,225 ==> :8,226 [ACK-2.31   MID= 7004, 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:25:52.490: 150 FINE [DirectDatagramSocketImpl]: (E432,T150) 127.0.0.1:8,226 ==> :8,225 [CON-PUT    MID= 7005, Token=1d, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=false, num=2)"}, "231636601657329769248835".. 44 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Server has received request payload: 169224063574326740960683544892051504765563752592945897122614830872549195214511367440443829345496887623937941567114583854125261908865936874282915399440184369134491167180533042416971949839477889894757886000296330874888053410420313666506727550071893081126824723163660165732976924883586997899809626882163
02:25:52.492: 153 FINE [DirectDatagramSocketImpl]: (E433,T153) 127.0.0.1:8,225 ==> :8,226 [ACK-2.04   MID= 7005, Token=1d, OptionSet={"Content-Format":"text/plain", "Block1":"(szx=3/128, m=false, num=2)"}, "200976062348567486865805".. 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=1d], PUT, /test, 1:0/1/128    ----->
<-----   ACK [MID=7001, T=1d], 2.31, 1:0/1/128
CON [MID=7002, T=1d], PUT, /test, 1:1/1/128    ----->
<-----   ACK [MID=7002, T=1d], 2.31, 1:1/1/128
... client crashes or whatever and restarts transfer
CON [MID=7003, T=1d], PUT, /test, 1:0/1/128    ----->
<-----   ACK [MID=7003, T=1d], 2.31, 1:0/1/128
CON [MID=7004, T=1d], PUT, /test, 1:1/1/128    ----->
<-----   ACK [MID=7004, T=1d], 2.31, 1:1/1/128
CON [MID=7005, T=1d], PUT, /test, 1:2/0/128    ----->
<-----   ACK [MID=7005, T=1d], 2.04, 1:2/0/128


02:25:52.493:   1 FINE [DirectDatagramSocketImpl]: closing port 8,226, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.494:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:249) destroy() in thread main
02:25:52.495:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8225 - (org.eclipse.californium.core.network.CoapEndpoint.java:392) destroy() in thread main
02:25:52.495:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8225 - (org.eclipse.californium.core.network.CoapEndpoint.java:379) stop() in thread main
02:25:52.495:   1 FINE [DirectDatagramSocketImpl]: closing port 8,225, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.496:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:273) destroy() in thread main
02:25:52.497:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:201) start() in thread main
02:25:52.498:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:341) start() in thread main
02:25:52.498:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
02:25:52.499:   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:25:52.499:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,227 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Server binds to port 8227
02:25:52.501:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
02:25:52.501:   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:25:52.502:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,228 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Simple atomic blockwise PUT
02:25:52.504: 159 FINE [DirectDatagramSocketImpl]: (E434,T159) 127.0.0.1:8,228 ==> :8,227 [CON-PUT    MID= 7001, Token=1e, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)"}, "389054883547146015818927".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.506: 162 FINE [DirectDatagramSocketImpl]: (E435,T162) 127.0.0.1:8,227 ==> :8,228 [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:25:52.507: 159 FINE [DirectDatagramSocketImpl]: (E436,T159) 127.0.0.1:8,228 ==> :8,227 [CON-PUT    MID= 7002, Token=1e, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=1)"}, "790758530585553385678975".. 128 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:25:52.508: 162 FINE [DirectDatagramSocketImpl]: (E437,T162) 127.0.0.1:8,227 ==> :8,228 [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:25:52.510: 159 FINE [DirectDatagramSocketImpl]: (E438,T159) 127.0.0.1:8,228 ==> :8,227 [CON-PUT    MID= 7003, Token=1e, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=false, num=2)"}, "040602164504658117829392".. 44 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Server has received request payload: 389054883547146015818927289618273168937219887024928251026923018049105828332685301513710234899071273764737876523337878411581438467907585305855533856789758347092530835827812788222172242593071602775437418257156190764849581734463694390149586056686743014711384804060216450465811782939274374835139720960636
02:25:52.511: 162 FINE [DirectDatagramSocketImpl]: (E439,T162) 127.0.0.1:8,227 ==> :8,228 [ACK-2.04   MID= 7003, Token=1e, OptionSet={"Content-Format":"text/plain", "Block1":"(szx=3/128, m=false, num=2)"}, "062378253579598250616766".. 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=1e], PUT, /test, 1:0/1/128    ----->
<-----   ACK [MID=7001, T=1e], 2.31, 1:0/1/128
CON [MID=7002, T=1e], PUT, /test, 1:1/1/128    ----->
<-----   ACK [MID=7002, T=1e], 2.31, 1:1/1/128
CON [MID=7003, T=1e], PUT, /test, 1:2/0/128    ----->
<-----   ACK [MID=7003, T=1e], 2.04, 1:2/0/128


02:25:52.512:   1 FINE [DirectDatagramSocketImpl]: closing port 8,228, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.513:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:249) destroy() in thread main
02:25:52.514:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8227 - (org.eclipse.californium.core.network.CoapEndpoint.java:392) destroy() in thread main
02:25:52.514:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8227 - (org.eclipse.californium.core.network.CoapEndpoint.java:379) stop() in thread main
02:25:52.514:   1 FINE [DirectDatagramSocketImpl]: closing port 8,227, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:25:52.515:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:273) destroy() in thread main
End BlockwiseServerSideTest
02:25:52.516:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
Tests run: 15, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 1.192 sec <<< FAILURE! - in org.eclipse.californium.core.test.lockstep.BlockwiseServerSideTest
testSimpleAtomicBlockwisePUTWithLostAck(org.eclipse.californium.core.test.lockstep.BlockwiseServerSideTest)  Time elapsed: 0.254 sec  <<< FAILURE!
java.lang.AssertionError: Wrong payload length:  expected:<0> but was:<18>
	at org.junit.Assert.fail(Assert.java:88)
	at org.junit.Assert.failNotEquals(Assert.java:834)
	at org.junit.Assert.assertEquals(Assert.java:645)
	at org.eclipse.californium.core.test.lockstep.LockstepEndpoint$MessageExpectation$9.check(LockstepEndpoint.java:472)
	at org.eclipse.californium.core.test.lockstep.LockstepEndpoint$MessageExpectation$9.check(LockstepEndpoint.java:467)
	at org.eclipse.californium.core.test.lockstep.LockstepEndpoint$MessageExpectation.check(LockstepEndpoint.java:666)
	at org.eclipse.californium.core.test.lockstep.LockstepEndpoint$ResponseExpectation.check(LockstepEndpoint.java:1072)
	at org.eclipse.californium.core.test.lockstep.LockstepEndpoint$ResponseExpectation.go(LockstepEndpoint.java:1081)
	at org.eclipse.californium.core.test.lockstep.LockstepEndpoint$MessageExpectation.go(LockstepEndpoint.java:701)
	at org.eclipse.californium.core.test.lockstep.BlockwiseServerSideTest.testSimpleAtomicBlockwisePUTWithLostAck(BlockwiseServerSideTest.java:403)


Results :

Failed tests: 
  BlockwiseServerSideTest.testSimpleAtomicBlockwisePUTWithLostAck:403 Wrong payload length:  expected:<0> but was:<18>

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  1.902 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  3.180 s]
[INFO] element-connector .................................. SUCCESS [ 58.002 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 29.810 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 48.718 s]
[INFO] Californium (Cf) Proxy ............................. SKIPPED
[INFO] Californium (Cf) OSGi Bundle ....................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 02:26 min
[INFO] Finished at: 2018-04-20T02:25:52-04:00
[INFO] Final Memory: 44M/283M
[INFO] ------------------------------------------------------------------------
[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://ci.eclipse.org/californium/job/1.1.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
Build step 'Invoke top-level Maven targets' marked build as failure
Follow-Ups:
- [cf-dev] Jenkins build is back to normal : 1.1.x-nightly #399
  - From: hudsonbuild
Prev by Date: [cf-dev] Jenkins build is back to normal : 2.0.x #430
Next by Date: [cf-dev] Jenkins build is back to normal : 1.1.x-nightly #399
Previous by thread: [cf-dev] Build failed in Jenkins: 2.0.x #429
Next by thread: [cf-dev] Jenkins build is back to normal : 1.1.x-nightly #399
Index(es):
- Date
- Thread
Breadcrumbs
