[
Date Prev][
Date Next][
Thread Prev][
Thread Next][
Date Index][
Thread Index]
[
List Home]
|
[cf-dev] [Hudson] Build failed in Hudson: 1.0.x #82
|
See <https://hudson.eclipse.org/californium/job/1.0.x/82/changes>
Changes:
[Achim Kraus] Fix rare race condition for MID on responses.
------------------------------------------
[...truncated 1552 lines...]
08:20:00.991: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:20:00.992: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:20:00.992: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:20:00.992: 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:20:00.993: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,245 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:20:00.993: 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:20:00.995: 302 FINE [DirectDatagramSocketImpl]: (E248,T302) 127.0.0.1:8,244 ==> :8,245 [CON-GET MID=38507, Token=217701323cab0214, 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:20:00.996: 291 FINE [DirectDatagramSocketImpl]: (E249,T291) 127.0.0.1:8,245 ==> :8,244 [RST MID=38507] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
1 Errors!
08:20:00.996: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:20:00.997: 1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8245 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:20:00.997: 1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8245 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:20:00.997: 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
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:20:00.999: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:20:00.999: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:20:01.000: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:20:01.000: 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:20:01.000: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,246 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:20:01.001: 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:20:01.002: 307 FINE [DirectDatagramSocketImpl]: (E250,T307) 127.0.0.1:8,244 ==> :8,246 [CON-GET MID=38508, Token=99b8a364, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8246, "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:20:01.002: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:20:01.003: 1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8246 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:20:01.003: 1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8246 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:20:01.003: 1 FINE [DirectDatagramSocketImpl]: closing port 8,246, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:20:01.103: 306 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#99b8a364 and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-24-thread-1
08:20:01.104: 306 INFO [ReliabilityLayer]: Endpoint is being destroyed: skipping retransmission - (org.eclipse.californium.core.network.stack.ReliabilityLayer.java:181) prepareRetransmission() in thread pool-24-thread-1
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:20:01.105: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:20:01.105: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:20:01.106: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:20:01.106: 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:20:01.106: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,247 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:20:01.107: 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:20:01.108: 312 FINE [DirectDatagramSocketImpl]: (E251,T312) 127.0.0.1:8,244 ==> :8,247 [CON-GET MID=38509, Token=e2227192c6f75e, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8247, "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:20:01.109: 311 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#e2227192c6f75e and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-25-thread-1
08:20:01.110: 291 FINE [DirectDatagramSocketImpl]: (E252,T291) 127.0.0.1:8,247 ==> :8,244 [CON-2.05 MID=10788, Token=e2227192c6f75e, 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 1. Notification: CON-2.05 MID=10788, Token=e2227192c6f75e, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes
08:20:01.111: 312 FINE [DirectDatagramSocketImpl]: (E253,T312) 127.0.0.1:8,244 ==> :8,247 [ACK MID=10788] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:20:01.112: 312 FINE [DirectDatagramSocketImpl]: (E254,T312) 127.0.0.1:8,244 ==> :8,247 [CON-GET MID=38510, Token=e2227192c6f75e, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8247, "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:20:01.213: 311 INFO [CoapResource]: Replacing observe relation between localhost/127.0.0.1:8244#e2227192c6f75e and resource /resX - (org.eclipse.californium.core.CoapResource.java:686) addObserveRelation() in thread pool-25-thread-1
08:20:01.214: 291 FINE [DirectDatagramSocketImpl]: (E255,T291) 127.0.0.1:8,247 ==> :8,244 [CON-2.05 MID=10789, Token=e2227192c6f75e, 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]
Resource resX changed to "resX says client for the 2 time"
Received 2. Notification: CON-2.05 MID=10789, Token=e2227192c6f75e, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes
08:20:01.215: 312 FINE [DirectDatagramSocketImpl]: (E256,T312) 127.0.0.1:8,244 ==> :8,247 [ACK MID=10789] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:20:01.216: 291 FINE [DirectDatagramSocketImpl]: (E257,T291) 127.0.0.1:8,247 ==> :8,244 [NON-2.05 MID=10790, Token=e2227192c6f75e, OptionSet={"Observe":2}, ""resX says client for th".. 33 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 3. Notification: NON-2.05 MID=10790, Token=e2227192c6f75e, OptionSet={"Observe":2}, ""resX says client for th".. 33 bytes
08:20:01.217: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:20:01.217: 1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8247 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:20:01.218: 1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8247 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:20:01.218: 1 FINE [DirectDatagramSocketImpl]: closing port 8,247, 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:20:01.220: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:20:01.221: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:20:01.221: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:20:01.221: 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:20:01.222: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,248 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:20:01.223: 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:20:01.224: 323 FINE [DirectDatagramSocketImpl]: (E258,T323) 127.0.0.1:8,244 ==> :8,248 [CON-GET MID=38511, Token=c75e9cd591, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8248, "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:20:01.225: 322 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#c75e9cd591 and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-26-thread-1
08:20:01.226: 291 FINE [DirectDatagramSocketImpl]: (E259,T291) 127.0.0.1:8,248 ==> :8,244 [CON-2.05 MID=16149, Token=c75e9cd591, 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 1. Notification: CON-2.05 MID=16149, Token=c75e9cd591, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes
08:20:01.227: 323 FINE [DirectDatagramSocketImpl]: (E260,T323) 127.0.0.1:8,244 ==> :8,248 [ACK MID=16149] - (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:20:01.278: 291 FINE [DirectDatagramSocketImpl]: (E261,T291) 127.0.0.1:8,248 ==> :8,244 [NON-2.05 MID=16150, Token=c75e9cd591, 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:20:01.279: 323 FINE [DirectDatagramSocketImpl]: (E262,T323) 127.0.0.1:8,244 ==> :8,248 [RST MID=16150] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Received 1. RST: 16150
Resource resX changed to "resX says hi for the 3 time"
08:20:01.328: 291 FINE [DirectDatagramSocketImpl]: (E263,T291) 127.0.0.1:8,248 ==> :8,244 [NON-2.05 MID=16151, Token=c75e9cd591, 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:20:01.329: 323 FINE [DirectDatagramSocketImpl]: (E264,T323) 127.0.0.1:8,244 ==> :8,248 [RST MID=16151] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Received 2. RST: 16151
Resource resX changed to "resX says hi for the 4 time"
08:20:01.378: 291 FINE [DirectDatagramSocketImpl]: (E265,T291) 127.0.0.1:8,248 ==> :8,244 [NON-2.05 MID=16152, Token=c75e9cd591, 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:20:01.379: 323 FINE [DirectDatagramSocketImpl]: (E266,T323) 127.0.0.1:8,244 ==> :8,248 [RST MID=16152] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Received 3. RST: 16152
08:20:02.427: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:20:02.427: 1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8248 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:20:02.427: 1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8248 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:20:02.428: 1 FINE [DirectDatagramSocketImpl]: closing port 8,248, 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:20:02.430: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:20:02.431: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:20:02.431: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:20:02.432: 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:20:02.432: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,249 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:20:02.433: 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:20:02.434: 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:20:02.438: 333 FINE [DirectDatagramSocketImpl]: (E267,T333) 127.0.0.1:8,244 ==> :8,249 [CON-GET MID=38512, Token=c37b1c1e, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8249, "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:20:02.439: 332 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#c37b1c1e and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-27-thread-1
08:20:02.440: 333 FINE [DirectDatagramSocketImpl]: (E268,T333) 127.0.0.1:8,244 ==> :8,249 [CON-GET MID=38513, Token=e5a44cdb9776, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8249, "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:20:02.441: 336 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#e5a44cdb9776 and resource /resY - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-27-thread-3
08:20:02.441: 291 FINE [DirectDatagramSocketImpl]: (E269,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=38943, Token=c37b1c1e, 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:20:02.442: 333 FINE [DirectDatagramSocketImpl]: (E271,T333) 127.0.0.1:8,244 ==> :8,249 [ACK MID=38943] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:20:02.442: 291 FINE [DirectDatagramSocketImpl]: (E270,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=38944, Token=e5a44cdb9776, 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:20:02.443: 333 FINE [DirectDatagramSocketImpl]: (E272,T333) 127.0.0.1:8,244 ==> :8,249 [ACK MID=38944] - (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:20:02.495: 291 FINE [DirectDatagramSocketImpl]: (E273,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=38945, Token=c37b1c1e, 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 38945, payload = "resX says hi for the 2 time"
08:20:02.695: 291 FINE [DirectDatagramSocketImpl]: (E274,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=38945, Token=c37b1c1e, 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 38945, payload = "resX says hi for the 2 time"
08:20:02.895: 291 FINE [DirectDatagramSocketImpl]: (E275,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=38945, Token=c37b1c1e, 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 38945, payload = "resX says hi for the 2 time"
Resource resX changed to "resX says hi for the 3 time"
08:20:03.096: 291 FINE [DirectDatagramSocketImpl]: (E276,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=38946, Token=c37b1c1e, 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 38946, payload = "resX says hi for the 3 time"
08:20:03.099: 292 SEVERE [DirectDatagramSocketImpl]: destination (port 8,246) not available! (E277,T292) 127.0.0.1:8,244 ==> :8,246 [CON-GET MID=38508, Token=99b8a364, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8246, "Uri-Path":"resX"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:250) send() in thread UDP-Sender-0.0.0.0/0.0.0.0:0[0]
08:20:03.296: 291 FINE [DirectDatagramSocketImpl]: (E278,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=38946, Token=c37b1c1e, 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 38946, payload = "resX says hi for the 3 time"
08:20:03.496: 338 INFO [ObserveLayer$NotificationController]: Notification c37b1c1e 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-27-thread-5
08:20:03.994: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:20:03.995: 1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8249 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:20:03.995: 1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8249 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:20:03.996: 1 FINE [DirectDatagramSocketImpl]: closing port 8,249, 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:20:03.998: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:20:03.999: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:20:03.999: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:20:04.000: 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:20:04.000: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,250 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:20:04.001: 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:20:04.002: 349 FINE [DirectDatagramSocketImpl]: (E279,T349) 127.0.0.1:8,244 ==> :8,250 [CON-GET MID=38514, Token=653fda97, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8250, "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:20:04.103: 348 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#653fda97 and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-28-thread-1
08:20:04.104: 291 FINE [DirectDatagramSocketImpl]: (E280,T291) 127.0.0.1:8,250 ==> :8,244 [CON-2.05 MID=51390, Token=653fda97, 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]
Resource resX changed to "resX says client for the 2 time"
Received 1. Notification: CON-2.05 MID=51390, Token=653fda97, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes
08:20:04.105: 349 FINE [DirectDatagramSocketImpl]: (E281,T349) 127.0.0.1:8,244 ==> :8,250 [ACK MID=51390] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
08:20:04.106: 291 FINE [DirectDatagramSocketImpl]: (E282,T291) 127.0.0.1:8,250 ==> :8,244 [NON-2.05 MID=51391, Token=653fda97, OptionSet={"Observe":2}, ""resX says client for th".. 33 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 2. Notification: NON-2.05 MID=51391, Token=653fda97, OptionSet={"Observe":2}, ""resX says client for th".. 33 bytes
08:20:04.107: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
08:20:04.107: 1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8250 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:20:04.107: 1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8250 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:20:04.108: 1 FINE [DirectDatagramSocketImpl]: closing port 8,250, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
End ObserveTest
08:20:04.109: 1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
08:20:04.109: 1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:8244 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:20:04.109: 1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8244 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:20:04.110: 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:20:04.110: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:277) applyConfig() in thread main
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.139 sec - in org.eclipse.californium.core.test.ObserveTest
Running org.eclipse.californium.core.test.SmallServerClientTest
Start SmallServerClientTest
08:20:04.112: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
08:20:04.112: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:20:04.113: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:20:04.113: 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:20:04.114: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,251 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:20:04.114: 1 INFO [CoapEndpoint]: Starting endpoint at 0.0.0.0/0.0.0.0:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
08:20:04.115: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
08:20:04.115: 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:20:04.116: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,252 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
08:20:04.116: 1 INFO [EndpointManager]: Created implicit default endpoint 0.0.0.0/0.0.0.0:8252 - (org.eclipse.californium.core.network.EndpointManager.java:117) createDefaultEndpoint() in thread main
client sent request
08:20:04.117: 357 FINE [DirectDatagramSocketImpl]: (E283,T357) 127.0.0.1:8,252 ==> :8,251 [NON-POST MID=31330, Token=1713, 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:20:04.618: 361 FINE [DirectDatagramSocketImpl]: (E284,T361) 127.0.0.1:8,251 ==> :8,252 [NON-2.05 MID=23808, Token=1713, 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:20:04.619: 1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
08:20:04.619: 1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:8252 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
08:20:04.620: 1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8252 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
08:20:04.620: 1 FINE [DirectDatagramSocketImpl]: closing port 8,252, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
08:20:04.620: 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.509 sec - in org.eclipse.californium.core.test.SmallServerClientTest
Results :
Failed tests:
ObserveServerSideTest.testEstablishmentAndTimeoutWithUpdateInMiddle:220 did not receive message within expected time frame (2 secs)
Tests run: 38, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Californium (Cf) Parent ............................ SUCCESS [ 1.313 s]
[INFO] element-connector .................................. SUCCESS [ 7.318 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [ 1.638 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 13.078 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 22.931 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: 50.075 s
[INFO] Finished at: 2017-06-21T08:20:04-04:00
[INFO] Final Memory: 38M/1931M
[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/
