[cf-dev] [Hudson] Build failed in Hudson: 1.0.x #62

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] [Hudson] Build failed in Hudson: 1.0.x #62
From: hudsonbuild@xxxxxxxxxxx
Date: Thu, 11 May 2017 08:37:04 -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/1.0.x/62/changes>

Changes:

[Achim Kraus] Fix wrongly reported blockwise timeout.

------------------------------------------
[...truncated 1421 lines...]
08:36:50.601:   1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8237 - (org.eclipse.californium.core.network.CoapEndpoint.java:294) stop() in thread main
08:36:50.601:   1 FINE [DirectDatagramSocketImpl]: closing port 8,237, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:36:50.602:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:277) applyConfig() in thread main
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.eclipse.californium.core.test.MessageTypeTest
Running org.eclipse.californium.core.test.RandomAccessBlockTest

Start RandomAccessBlockTest
08:36:50.604:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:36:50.604:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:257) start() in thread main
08:36:50.604:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:36:50.605:   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
08:36:50.605:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,239 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Request block number 2
08:36:50.606:   1 INFO [CoapEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:257) start() in thread main
08:36:50.607:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:36:50.607:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:36:50.607:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,240 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:36:50.608:   1 INFO [EndpointManager]: Created implicit default endpoint 0.0.0.0/0.0.0.0:8240 - (org.eclipse.californium.core.network.EndpointManager.java:117) createDefaultEndpoint() in thread main
08:36:50.609: 256 FINE [DirectDatagramSocketImpl]: (E218,T256) 127.0.0.1:8,240 ==> :8,239 [CON-GET    MID=58482, Token=5fdf578c3a42, OptionSet={"Uri-Port":8239, "Uri-Path":"test", "Block2":"(szx=0/16, 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]
08:36:50.611: 260 FINE [DirectDatagramSocketImpl]: (E219,T260) 127.0.0.1:8,239 ==> :8,240 [ACK-2.05   MID=58482, Token=5fdf578c3a42, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "34567890"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
Request block number 1
08:36:50.612: 256 FINE [DirectDatagramSocketImpl]: (E220,T256) 127.0.0.1:8,240 ==> :8,239 [CON-GET    MID=58483, Token=63ae1282, OptionSet={"Uri-Port":8239, "Uri-Path":"test", "Block2":"(szx=0/16, 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]
08:36:50.614: 260 FINE [DirectDatagramSocketImpl]: (E221,T260) 127.0.0.1:8,239 ==> :8,240 [ACK-2.05   MID=58483, Token=63ae1282, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789_123456789_12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
Request block number 3
08:36:50.615: 256 FINE [DirectDatagramSocketImpl]: (E222,T256) 127.0.0.1:8,240 ==> :8,239 [CON-GET    MID=58484, Token=de2a86, OptionSet={"Uri-Port":8239, "Uri-Path":"test", "Block2":"(szx=0/16, 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]
08:36:50.617: 260 FINE [DirectDatagramSocketImpl]: (E223,T260) 127.0.0.1:8,239 ==> :8,240 [ACK-2.05   MID=58484, Token=de2a86, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=3)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.618:   1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:36:50.618:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8239 - (org.eclipse.californium.core.network.CoapEndpoint.java:309) destroy() in thread main
08:36:50.618:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8239 - (org.eclipse.californium.core.network.CoapEndpoint.java:294) stop() in thread main
08:36:50.618:   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
End RandomAccessBlockTest
08:36:50.620:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
08:36:50.620:   1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:8240 - (org.eclipse.californium.core.network.CoapEndpoint.java:309) destroy() in thread main
08:36:50.620:   1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8240 - (org.eclipse.californium.core.network.CoapEndpoint.java:294) stop() in thread main
08:36:50.620:   1 FINE [DirectDatagramSocketImpl]: closing port 8,240, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:36:50.621:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:277) applyConfig() in thread main

Start ClientAsynchronousTest
08:36:50.622:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:36:50.622:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:257) start() in thread main
08:36:50.623:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:36:50.623:   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
08:36:50.623:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,241 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.eclipse.californium.core.test.RandomAccessBlockTest
Running org.eclipse.californium.core.test.ClientAsynchronousTest
08:36:50.626:   1 INFO [CoapEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:257) start() in thread main
08:36:50.626:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:36:50.626:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:36:50.627:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,242 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:36:50.627:   1 INFO [EndpointManager]: Created implicit default endpoint 0.0.0.0/0.0.0.0:8242 - (org.eclipse.californium.core.network.EndpointManager.java:117) createDefaultEndpoint() in thread main
08:36:50.628: 269 FINE [DirectDatagramSocketImpl]: (E224,T269) 127.0.0.1:8,242 ==> :8,241 [CON-PUT    MID=21472, Token=f6009ea0, OptionSet={"Uri-Host":"localhost", "Uri-Port":8241, "Uri-Path":"storage", "Content-Format":"text/plain"}, "two"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:36:50.629: 274 FINE [DirectDatagramSocketImpl]: (E225,T274) 127.0.0.1:8,241 ==> :8,242 [ACK-4.05   MID=21472, Token=f6009ea0, OptionSet={}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.630:   1 WARNING [Request]: update your code to supply an RFC 7252 compliant URI including a valid scheme - (org.eclipse.californium.core.coap.Request.java:222) setURI() in thread main
08:36:50.631: 269 FINE [DirectDatagramSocketImpl]: (E226,T269) 127.0.0.1:8,242 ==> :8,241 [CON-GET    MID=21473, Token=4b, OptionSet={"Uri-Host":"localhost", "Uri-Port":8241, "Uri-Path":"storage", "Uri-Query":"uppercase"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:36:50.632: 274 FINE [DirectDatagramSocketImpl]: (E227,T274) 127.0.0.1:8,241 ==> :8,242 [ACK-2.05   MID=21473, Token=4b, OptionSet={"Content-Format":"text/plain"}, "ONE"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.634: 269 FINE [DirectDatagramSocketImpl]: (E228,T269) 127.0.0.1:8,242 ==> :8,241 [CON-GET    MID=21474, Token=b269, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8241, "Uri-Path":"storage"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:36:50.635: 268 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8242#b269 and resource /storage - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-16-thread-1
08:36:50.636: 274 FINE [DirectDatagramSocketImpl]: (E229,T274) 127.0.0.1:8,241 ==> :8,242 [ACK-2.05   MID=21474, Token=b269, OptionSet={"Observe":0, "Content-Format":"text/plain"}, "one"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.637: 274 FINE [DirectDatagramSocketImpl]: (E230,T274) 127.0.0.1:8,241 ==> :8,242 [NON-2.05   MID=33622, Token=b269, OptionSet={"Observe":1, "Content-Format":"text/plain"}, "one"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.638: 274 FINE [DirectDatagramSocketImpl]: (E231,T274) 127.0.0.1:8,241 ==> :8,242 [NON-2.05   MID=33623, Token=b269, OptionSet={"Observe":2, "Content-Format":"text/plain"}, "one"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.638: 274 FINE [DirectDatagramSocketImpl]: (E232,T274) 127.0.0.1:8,241 ==> :8,242 [NON-2.05   MID=33624, Token=b269, OptionSet={"Observe":3, "Content-Format":"text/plain"}, "one"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.639: 274 FINE [DirectDatagramSocketImpl]: (E233,T274) 127.0.0.1:8,241 ==> :8,242 [NON-2.05   MID=33625, Token=b269, OptionSet={"Observe":4, "Content-Format":"text/plain"}, "one"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.640: 269 FINE [DirectDatagramSocketImpl]: (E234,T269) 127.0.0.1:8,242 ==> :8,241 [RST        MID=33625] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:36:50.691: 269 FINE [DirectDatagramSocketImpl]: (E235,T269) 127.0.0.1:8,242 ==> :8,241 [CON-GET    MID=21475, Token=3485106791a7, OptionSet={"Uri-Host":"localhost", "Uri-Port":8241, "Uri-Path":"storage"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:36:50.692: 274 FINE [DirectDatagramSocketImpl]: (E236,T274) 127.0.0.1:8,241 ==> :8,242 [ACK-2.05   MID=21475, Token=3485106791a7, OptionSet={"Content-Format":"text/plain"}, "one"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.695: 269 FINE [DirectDatagramSocketImpl]: (E237,T269) 127.0.0.1:8,242 ==> :8,241 [CON-POST   MID=21476, Token=17, OptionSet={"Uri-Host":"localhost", "Uri-Port":8241, "Uri-Path":"storage", "Content-Format":"text/plain"}, "two"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:36:50.695: 274 FINE [DirectDatagramSocketImpl]: (E238,T274) 127.0.0.1:8,241 ==> :8,242 [ACK-2.04   MID=21476, Token=17, OptionSet={"Content-Format":"text/plain"}, "one"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.696:   1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:36:50.696:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8241 - (org.eclipse.californium.core.network.CoapEndpoint.java:309) destroy() in thread main
08:36:50.697:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8241 - (org.eclipse.californium.core.network.CoapEndpoint.java:294) stop() in thread main
08:36:50.697:   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
End ClientAsynchronousTest
08:36:50.698:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
08:36:50.698:   1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:8242 - (org.eclipse.californium.core.network.CoapEndpoint.java:309) destroy() in thread main
08:36:50.698:   1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8242 - (org.eclipse.californium.core.network.CoapEndpoint.java:294) stop() in thread main
08:36:50.699:   1 FINE [DirectDatagramSocketImpl]: closing port 8,242, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:36:50.700:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:277) applyConfig() in thread main
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.eclipse.californium.core.test.ClientAsynchronousTest
Running org.eclipse.californium.core.test.ObserveTest

Start ObserveTest
Resource resX changed to "resX says hi for the 1 time"
Resource resY changed to "resY says hi for the 1 time"
08:36:50.701:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:36:50.701:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:257) start() in thread main
08:36:50.702:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:36:50.702:   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
08:36:50.702:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,243 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:36:50.704:   1 WARNING [Request]: update your code to supply an RFC 7252 compliant URI including a valid scheme - (org.eclipse.californium.core.coap.Request.java:222) setURI() in thread main
08:36:50.704:   1 INFO [CoapEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:257) start() in thread main
08:36:50.705:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:36:50.705:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:36:50.705:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,244 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:36:50.706:   1 INFO [EndpointManager]: Created implicit default endpoint 0.0.0.0/0.0.0.0:8244 - (org.eclipse.californium.core.network.EndpointManager.java:117) createDefaultEndpoint() in thread main
08:36:50.707: 287 FINE [DirectDatagramSocketImpl]: (E239,T287) 127.0.0.1:8,244 ==> :8,243 [CON-GET    MID=38392, Token=8dfc863f407a, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8243, "Uri-Path":"resX"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:36:50.707: 286 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#8dfc863f407a and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-22-thread-1
08:36:50.708: 291 FINE [DirectDatagramSocketImpl]: (E240,T291) 127.0.0.1:8,243 ==> :8,244 [CON-2.05   MID=22876, Token=8dfc863f407a, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
Received Notification: 22876
08:36:50.709: 287 FINE [DirectDatagramSocketImpl]: (E241,T287) 127.0.0.1:8,244 ==> :8,243 [ACK        MID=22876] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Resource resX changed to "resX says hi for the 2 time"
08:36:50.760: 291 FINE [DirectDatagramSocketImpl]: (E242,T291) 127.0.0.1:8,243 ==> :8,244 [NON-2.05   MID=22877, Token=8dfc863f407a, OptionSet={"Observe":2}, ""resX says hi for the 2 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.761: 287 FINE [DirectDatagramSocketImpl]: (E243,T287) 127.0.0.1:8,244 ==> :8,243 [RST        MID=22877] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Received RST: 22877
Resource resX changed to "resX says hi for the 3 time"
08:36:50.810: 291 FINE [DirectDatagramSocketImpl]: (E244,T291) 127.0.0.1:8,243 ==> :8,244 [NON-2.05   MID=22878, Token=8dfc863f407a, OptionSet={"Observe":3}, ""resX says hi for the 3 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.811: 287 FINE [DirectDatagramSocketImpl]: (E245,T287) 127.0.0.1:8,244 ==> :8,243 [RST        MID=22878] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Received RST: 22878
Resource resX changed to "resX says hi for the 4 time"
08:36:50.861: 291 FINE [DirectDatagramSocketImpl]: (E246,T291) 127.0.0.1:8,243 ==> :8,244 [NON-2.05   MID=22879, Token=8dfc863f407a, OptionSet={"Observe":4}, ""resX says hi for the 4 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.861: 287 FINE [DirectDatagramSocketImpl]: (E247,T287) 127.0.0.1:8,244 ==> :8,243 [RST        MID=22879] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Received RST: 22879
08:36:50.910:   1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:36:50.911:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8243 - (org.eclipse.californium.core.network.CoapEndpoint.java:309) destroy() in thread main
08:36:50.911:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8243 - (org.eclipse.californium.core.network.CoapEndpoint.java:294) stop() in thread main
08:36:50.911:   1 FINE [DirectDatagramSocketImpl]: closing port 8,243, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
End ObserveTest

Start ObserveTest
Resource resX changed to "resX says hi for the 1 time"
Resource resY changed to "resY says hi for the 1 time"
08:36:50.913:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:36:50.914:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:257) start() in thread main
08:36:50.914:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:36:50.914:   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
08:36:50.914:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,245 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:36:50.915:   1 WARNING [Request]: update your code to supply an RFC 7252 compliant URI including a valid scheme - (org.eclipse.californium.core.coap.Request.java:222) setURI() in thread main
08:36:50.916:   1 WARNING [Request]: update your code to supply an RFC 7252 compliant URI including a valid scheme - (org.eclipse.californium.core.coap.Request.java:222) setURI() in thread main
08:36:50.917: 300 FINE [DirectDatagramSocketImpl]: (E248,T300) 127.0.0.1:8,244 ==> :8,245 [CON-GET    MID=38393, Token=b87d7e, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8245, "Uri-Path":"resX"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:36:50.917: 299 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#b87d7e and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-23-thread-1
08:36:50.917: 300 FINE [DirectDatagramSocketImpl]: (E249,T300) 127.0.0.1:8,244 ==> :8,245 [CON-GET    MID=38394, Token=7a, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8245, "Uri-Path":"resY"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:36:50.918: 302 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#7a and resource /resY - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-23-thread-2
08:36:50.918: 291 FINE [DirectDatagramSocketImpl]: (E250,T291) 127.0.0.1:8,245 ==> :8,244 [CON-2.05   MID=61210, Token=b87d7e, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
08:36:50.919: 300 FINE [DirectDatagramSocketImpl]: (E252,T300) 127.0.0.1:8,244 ==> :8,245 [ACK        MID=61210] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:36:50.919: 291 FINE [DirectDatagramSocketImpl]: (E251,T291) 127.0.0.1:8,245 ==> :8,244 [CON-2.05   MID=61211, Token=7a, OptionSet={"Observe":1}, ""resY says hi for the 1 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]

Observe relation established, resource changes
08:36:50.920: 300 FINE [DirectDatagramSocketImpl]: (E253,T300) 127.0.0.1:8,244 ==> :8,245 [ACK        MID=61211] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Resource resX changed to "resX says hi for the 2 time"
08:36:50.971: 291 FINE [DirectDatagramSocketImpl]: (E254,T291) 127.0.0.1:8,245 ==> :8,244 [CON-2.05   MID=61212, Token=b87d7e, OptionSet={"Observe":2}, ""resX says hi for the 2 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]

Lose response 3 with MID 61212, payload = "resX says hi for the 2 time"
08:36:51.171: 291 FINE [DirectDatagramSocketImpl]: (E255,T291) 127.0.0.1:8,245 ==> :8,244 [CON-2.05   MID=61212, Token=b87d7e, OptionSet={"Observe":2}, ""resX says hi for the 2 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]

Lose response 4 with MID 61212, payload = "resX says hi for the 2 time"
08:36:51.371: 291 FINE [DirectDatagramSocketImpl]: (E256,T291) 127.0.0.1:8,245 ==> :8,244 [CON-2.05   MID=61212, Token=b87d7e, OptionSet={"Observe":2}, ""resX says hi for the 2 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]

Lose response 5 with MID 61212, payload = "resX says hi for the 2 time"
Resource resX changed to "resX says hi for the 3 time"
08:36:51.572: 291 FINE [DirectDatagramSocketImpl]: (E257,T291) 127.0.0.1:8,245 ==> :8,244 [CON-2.05   MID=61213, Token=b87d7e, OptionSet={"Observe":3}, ""resX says hi for the 3 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]

Lose response 6 with MID 61213, payload = "resX says hi for the 3 time"
08:36:51.773: 291 FINE [DirectDatagramSocketImpl]: (E258,T291) 127.0.0.1:8,245 ==> :8,244 [CON-2.05   MID=61213, Token=b87d7e, OptionSet={"Observe":3}, ""resX says hi for the 3 ".. 29 bytes] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]

Lose response 7 with MID 61213, payload = "resX says hi for the 3 time"
08:36:51.972: 299 INFO [ObserveLayer$NotificationController]: Notification b87d7e timed out. Cancel all relations with source localhost/127.0.0.1:8244 - (org.eclipse.californium.core.network.stack.ObserveLayer$NotificationController.java:227) onTimeout() in thread pool-23-thread-1
08:36:52.471:   1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:36:52.471:   1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8245 - (org.eclipse.californium.core.network.CoapEndpoint.java:309) destroy() in thread main
08:36:52.472:   1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8245 - (org.eclipse.californium.core.network.CoapEndpoint.java:294) stop() in thread main
08:36:52.472:   1 FINE [DirectDatagramSocketImpl]: closing port 8,245, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
End ObserveTest
08:36:52.474:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
08:36:52.474:   1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:8244 - (org.eclipse.californium.core.network.CoapEndpoint.java:309) destroy() in thread main
08:36:52.474:   1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8244 - (org.eclipse.californium.core.network.CoapEndpoint.java:294) stop() in thread main
08:36:52.474:   1 FINE [DirectDatagramSocketImpl]: closing port 8,244, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:36:52.475:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:277) applyConfig() in thread main
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 sec - in org.eclipse.californium.core.test.ObserveTest
Running org.eclipse.californium.core.test.SmallServerClientTest

Start SmallServerClientTest
08:36:53.106:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:36:53.106:   1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:257) start() in thread main
08:36:53.106:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:36:53.107:   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
08:36:53.107:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,246 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:36:53.108:   1 INFO [CoapEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:257) start() in thread main
08:36:53.109:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:36:53.109:   1 FINE [DirectDatagramSocketImpl]: binding to port 0, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:138) bind() in thread main
08:36:53.110:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,247 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:36:53.110:   1 INFO [EndpointManager]: Created implicit default endpoint 0.0.0.0/0.0.0.0:8247 - (org.eclipse.californium.core.network.EndpointManager.java:117) createDefaultEndpoint() in thread main
client sent request
08:36:53.111: 316 FINE [DirectDatagramSocketImpl]: (E259,T316) 127.0.0.1:8,247 ==> :8,246 [NON-POST   MID=46016, Token=bcfc, OptionSet={}, "client says hi"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
server received request
08:36:53.612: 320 FINE [DirectDatagramSocketImpl]: (E260,T320) 127.0.0.1:8,246 ==> :8,247 [NON-2.05   MID=11863, Token=bcfc, OptionSet={}, "server responds hi"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
client received response
End SmallServerClientTest
08:36:53.613:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
08:36:53.613:   1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:8247 - (org.eclipse.californium.core.network.CoapEndpoint.java:309) destroy() in thread main
08:36:53.614:   1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8247 - (org.eclipse.californium.core.network.CoapEndpoint.java:294) stop() in thread main
08:36:53.614:   1 FINE [DirectDatagramSocketImpl]: closing port 8,247, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:36:53.615:   1 INFO [NetworkRule]: Test org.eclipse.californium.core.test.SmallServerClientTest did not close all DatagramSockets! - (org.eclipse.californium.elements.rule.NetworkRule.java:374) closeNetwork() in thread main
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.498 sec - in org.eclipse.californium.core.test.SmallServerClientTest

Results :

Failed tests: 
  ObserveServerSideTest.testEstablishmentAndTimeout:173 did not receive message within expected time frame (2 secs)

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  1.232 s]
[INFO] element-connector .................................. SUCCESS [ 15.713 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  1.616 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 11.697 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 53.220 s]
[INFO] Californium (Cf) Proxy ............................. SKIPPED
[INFO] Californium (Cf) OSGi Bundle ....................... SKIPPED
[INFO] Californium (Cf) Demo Applications ................. SKIPPED
[INFO] Sc-DTLS-Example .................................... 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: 01:26 min
[INFO] Finished at: 2017-05-11T08:36:53-04:00
[INFO] Final Memory: 38M/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 (medium-tests) on project californium-core: There are test failures.

Please refer to /jobs/genie.californium/1.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 (medium-tests) on project californium-core: There are test failures.
[ERROR] 
[ERROR] Please refer to /jobs/genie.californium/1.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/
Follow-Ups:
- [cf-dev] [Hudson] Hudson build is back to normal : 1.0.x #63
  - From: hudsonbuild
Prev by Date: [cf-dev] [Hudson] Hudson build is back to normal : 1.1.x-nightly #61
Next by Date: [cf-dev] [Hudson] Hudson build is back to normal : 1.0.x #63
Previous by thread: [cf-dev] [Hudson] Build failed in Hudson: 1.1.x-nightly #59
Next by thread: [cf-dev] [Hudson] Hudson build is back to normal : 1.0.x #63
Index(es):
- Date
- Thread
Breadcrumbs
