[
Date Prev][
Date Next][
Thread Prev][
Thread Next][
Date Index][
Thread Index]
[
List Home]
[cf-dev] [Hudson] Build failed in Hudson: 1.0.x #91
|
See <https://hudson.eclipse.org/californium/job/1.0.x/91/changes>
Changes:
[Achim Kraus] Relax timing waiting for empty collections.
------------------------------------------
[...truncated 1511 lines...]
09:57:02.569: 269 FINE [DirectDatagramSocketImpl]: (E229,T269) 127.0.0.1:8,242 ==> :8,241 [CON-GET MID= 4239, Token=c8e34f68, 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]
09:57:02.569: 276 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8242#c8e34f68 and resource /storage - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-16-thread-3
09:57:02.570: 274 FINE [DirectDatagramSocketImpl]: (E230,T274) 127.0.0.1:8,241 ==> :8,242 [ACK-2.05 MID= 4239, Token=c8e34f68, 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]
09:57:02.572: 274 FINE [DirectDatagramSocketImpl]: (E231,T274) 127.0.0.1:8,241 ==> :8,242 [NON-2.05 MID=38537, Token=c8e34f68, 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]
09:57:02.573: 274 FINE [DirectDatagramSocketImpl]: (E232,T274) 127.0.0.1:8,241 ==> :8,242 [NON-2.05 MID=38538, Token=c8e34f68, 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]
09:57:02.573: 274 FINE [DirectDatagramSocketImpl]: (E233,T274) 127.0.0.1:8,241 ==> :8,242 [NON-2.05 MID=38539, Token=c8e34f68, 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]
09:57:02.574: 274 FINE [DirectDatagramSocketImpl]: (E234,T274) 127.0.0.1:8,241 ==> :8,242 [NON-2.05 MID=38540, Token=c8e34f68, 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]
09:57:02.575: 269 FINE [DirectDatagramSocketImpl]: (E235,T269) 127.0.0.1:8,242 ==> :8,241 [RST MID=38540] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
09:57:02.627: 269 FINE [DirectDatagramSocketImpl]: (E236,T269) 127.0.0.1:8,242 ==> :8,241 [CON-GET MID= 4240, Token=9d6d47, 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]
09:57:02.628: 274 FINE [DirectDatagramSocketImpl]: (E237,T274) 127.0.0.1:8,241 ==> :8,242 [ACK-2.05 MID= 4240, Token=9d6d47, 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]
09:57:02.631: 269 FINE [DirectDatagramSocketImpl]: (E238,T269) 127.0.0.1:8,242 ==> :8,241 [CON-POST MID= 4241, Token=276528191a, 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]
09:57:02.632: 274 FINE [DirectDatagramSocketImpl]: (E239,T274) 127.0.0.1:8,241 ==> :8,242 [ACK-2.04 MID= 4241, Token=276528191a, 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]
09:57:02.633: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
09:57:02.633: 1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8241 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
09:57:02.633: 1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8241 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
09:57:02.634: 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
09:57:02.636: 1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
09:57:02.636: 1 INFO [CoapEndpoint]: Destroying endpoint at address 0.0.0.0/0.0.0.0:8242 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
09:57:02.636: 1 INFO [CoapEndpoint]: Stopping endpoint at address 0.0.0.0/0.0.0.0:8242 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
09:57:02.636: 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
09:57:02.637: 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.08 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"
09:57:02.639: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
09:57:02.639: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
09:57:02.640: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
09:57:02.642: 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
09:57:02.643: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,243 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
09:57:02.645: 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
09:57:02.646: 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
09:57:02.647: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
09:57:02.647: 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
09:57:02.648: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,244 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
09:57:02.648: 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
09:57:02.650: 287 FINE [DirectDatagramSocketImpl]: (E240,T287) 127.0.0.1:8,244 ==> :8,243 [CON-GET MID=47654, Token=326d370fc9d958, 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]
09:57:02.651: 286 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#326d370fc9d958 and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-22-thread-1
09:57:02.652: 291 FINE [DirectDatagramSocketImpl]: (E241,T291) 127.0.0.1:8,243 ==> :8,244 [CON-2.05 MID=32035, Token=326d370fc9d958, 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=32035, Token=326d370fc9d958, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes
09:57:02.653: 287 FINE [DirectDatagramSocketImpl]: (E242,T287) 127.0.0.1:8,244 ==> :8,243 [ACK MID=32035] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
09:57:02.654: 291 FINE [DirectDatagramSocketImpl]: (E243,T291) 127.0.0.1:8,243 ==> :8,244 [NON-2.05 MID=32036, Token=326d370fc9d958, 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=32036, Token=326d370fc9d958, OptionSet={"Observe":2}, ""resX says client for th".. 33 bytes
09:57:02.655: 287 FINE [DirectDatagramSocketImpl]: (E244,T287) 127.0.0.1:8,244 ==> :8,243 [CON-GET MID=47655, Token=326d370fc9d958, 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]
09:57:02.656: 289 INFO [CoapResource]: Replacing observe relation between localhost/127.0.0.1:8244#326d370fc9d958 and resource /resX - (org.eclipse.californium.core.CoapResource.java:686) addObserveRelation() in thread pool-22-thread-2
09:57:02.657: 291 FINE [DirectDatagramSocketImpl]: (E245,T291) 127.0.0.1:8,243 ==> :8,244 [CON-2.05 MID=32037, Token=326d370fc9d958, 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]
localhost:8243/resX reregistered
Resource resX changed to "resX says new client for the 3 time"
Received 3. Notification: CON-2.05 MID=32037, Token=326d370fc9d958, OptionSet={"Observe":2}, ""resX says client for th".. 33 bytes
09:57:02.658: 287 FINE [DirectDatagramSocketImpl]: (E246,T287) 127.0.0.1:8,244 ==> :8,243 [ACK MID=32037] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
09:57:02.660: 291 FINE [DirectDatagramSocketImpl]: (E247,T291) 127.0.0.1:8,243 ==> :8,244 [NON-2.05 MID=32038, Token=326d370fc9d958, OptionSet={"Observe":3}, ""resX says new client fo".. 37 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 4. Notification: NON-2.05 MID=32038, Token=326d370fc9d958, OptionSet={"Observe":3}, ""resX says new client fo".. 37 bytes
09:57:02.660: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
09:57:02.661: 1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8243 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) destroy() in thread main
09:57:02.661: 1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8243 - (org.eclipse.californium.core.network.CoapEndpoint.java:296) stop() in thread main
09:57:02.662: 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"
09:57:02.666: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
09:57:02.666: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
09:57:02.667: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
09:57:02.667: 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
09:57:02.668: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,245 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
09:57:02.669: 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
09:57:02.670: 302 FINE [DirectDatagramSocketImpl]: (E248,T302) 127.0.0.1:8,244 ==> :8,245 [CON-GET MID=47656, Token=f02c6aa3c829d915, 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]
09:57:02.672: 291 FINE [DirectDatagramSocketImpl]: (E249,T291) 127.0.0.1:8,245 ==> :8,244 [RST MID=47656] - (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!
09:57:02.673: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
09:57:02.673: 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
09:57:02.673: 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
09:57:02.674: 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"
09:57:02.675: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
09:57:02.675: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
09:57:02.676: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
09:57:02.676: 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
09:57:02.676: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,246 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
09:57:02.677: 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
09:57:02.678: 307 FINE [DirectDatagramSocketImpl]: (E250,T307) 127.0.0.1:8,244 ==> :8,246 [CON-GET MID=47657, Token=1825, 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]
09:57:02.679: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
09:57:02.679: 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
09:57:02.679: 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
09:57:02.679: 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
09:57:02.779: 306 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#1825 and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-24-thread-1
09:57:02.780: 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"
09:57:02.781: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
09:57:02.782: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
09:57:02.782: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
09:57:02.782: 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
09:57:02.783: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,247 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
09:57:02.783: 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
09:57:02.785: 312 FINE [DirectDatagramSocketImpl]: (E251,T312) 127.0.0.1:8,244 ==> :8,247 [CON-GET MID=47658, Token=36d2ab2d31, 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]
09:57:02.786: 311 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#36d2ab2d31 and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-25-thread-1
09:57:02.787: 291 FINE [DirectDatagramSocketImpl]: (E252,T291) 127.0.0.1:8,247 ==> :8,244 [CON-2.05 MID=40702, Token=36d2ab2d31, 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=40702, Token=36d2ab2d31, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes
09:57:02.788: 312 FINE [DirectDatagramSocketImpl]: (E253,T312) 127.0.0.1:8,244 ==> :8,247 [ACK MID=40702] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
09:57:02.789: 312 FINE [DirectDatagramSocketImpl]: (E254,T312) 127.0.0.1:8,244 ==> :8,247 [CON-GET MID=47659, Token=36d2ab2d31, 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]
09:57:02.890: 316 INFO [CoapResource]: Replacing observe relation between localhost/127.0.0.1:8244#36d2ab2d31 and resource /resX - (org.eclipse.californium.core.CoapResource.java:686) addObserveRelation() in thread pool-25-thread-4
09:57:02.892: 291 FINE [DirectDatagramSocketImpl]: (E255,T291) 127.0.0.1:8,247 ==> :8,244 [CON-2.05 MID=40703, Token=36d2ab2d31, 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=40703, Token=36d2ab2d31, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes
09:57:02.894: 312 FINE [DirectDatagramSocketImpl]: (E256,T312) 127.0.0.1:8,244 ==> :8,247 [ACK MID=40703] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
09:57:02.895: 291 FINE [DirectDatagramSocketImpl]: (E257,T291) 127.0.0.1:8,247 ==> :8,244 [NON-2.05 MID=40704, Token=36d2ab2d31, 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=40704, Token=36d2ab2d31, OptionSet={"Observe":2}, ""resX says client for th".. 33 bytes
09:57:02.896: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
09:57:02.896: 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
09:57:02.896: 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
09:57:02.897: 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"
09:57:02.900: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
09:57:02.901: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
09:57:02.901: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
09:57:02.902: 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
09:57:02.903: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,248 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
09:57:02.905: 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
09:57:02.906: 323 FINE [DirectDatagramSocketImpl]: (E258,T323) 127.0.0.1:8,244 ==> :8,248 [CON-GET MID=47660, Token=62, 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]
09:57:02.907: 322 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#62 and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-26-thread-1
09:57:02.908: 291 FINE [DirectDatagramSocketImpl]: (E259,T291) 127.0.0.1:8,248 ==> :8,244 [CON-2.05 MID=21596, Token=62, 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=21596, Token=62, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes
09:57:02.909: 323 FINE [DirectDatagramSocketImpl]: (E260,T323) 127.0.0.1:8,244 ==> :8,248 [ACK MID=21596] - (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"
09:57:02.960: 291 FINE [DirectDatagramSocketImpl]: (E261,T291) 127.0.0.1:8,248 ==> :8,244 [NON-2.05 MID=21597, Token=62, 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]
09:57:02.961: 323 FINE [DirectDatagramSocketImpl]: (E262,T323) 127.0.0.1:8,244 ==> :8,248 [RST MID=21597] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Received 1. RST: 21597
Resource resX changed to "resX says hi for the 3 time"
09:57:03.010: 291 FINE [DirectDatagramSocketImpl]: (E263,T291) 127.0.0.1:8,248 ==> :8,244 [NON-2.05 MID=21598, Token=62, 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]
09:57:03.011: 323 FINE [DirectDatagramSocketImpl]: (E264,T323) 127.0.0.1:8,244 ==> :8,248 [RST MID=21598] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Received 2. RST: 21598
Resource resX changed to "resX says hi for the 4 time"
09:57:03.061: 291 FINE [DirectDatagramSocketImpl]: (E265,T291) 127.0.0.1:8,248 ==> :8,244 [NON-2.05 MID=21599, Token=62, 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]
09:57:03.062: 323 FINE [DirectDatagramSocketImpl]: (E266,T323) 127.0.0.1:8,244 ==> :8,248 [RST MID=21599] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Received 3. RST: 21599
09:57:04.110: 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main
09:57:04.110: 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
09:57:04.111: 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
09:57:04.111: 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"
09:57:04.114: 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main
09:57:04.114: 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:259) start() in thread main
09:57:04.114: 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:429) isActive() in thread main
09:57:04.115: 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
09:57:04.115: 1 FINE [DirectDatagramSocketImpl]: assigned port 8,249 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
09:57:04.116: 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
09:57:04.117: 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
09:57:04.118: 333 FINE [DirectDatagramSocketImpl]: (E267,T333) 127.0.0.1:8,244 ==> :8,249 [CON-GET MID=47661, Token=fb30, 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]
09:57:04.118: 332 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#fb30 and resource /resX - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-27-thread-1
09:57:04.483: 333 FINE [DirectDatagramSocketImpl]: (E268,T333) 127.0.0.1:8,244 ==> :8,249 [CON-GET MID=47662, Token=5029, 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]
[DEBUG] Closing connection to remote
09:57:04.483: 291 FINE [DirectDatagramSocketImpl]: (E269,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=43653, Token=fb30, 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]
09:57:04.484: 337 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8244#5029 and resource /resY - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-27-thread-4
09:57:04.484: 291 FINE [DirectDatagramSocketImpl]: (E270,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=43653, Token=fb30, 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]
09:57:04.485: 333 FINE [DirectDatagramSocketImpl]: (E271,T333) 127.0.0.1:8,244 ==> :8,249 [ACK MID=43653] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
09:57:04.485: 290 INFO [Matcher]: Duplicate response for open exchange: CON-2.05 MID=43653, Token=fb30, OptionSet={"Observe":1}, ""resX says hi for the 1 ".. 29 bytes - (org.eclipse.californium.core.network.Matcher.java:360) receiveResponse() in thread Thread-20
09:57:04.485: 291 FINE [DirectDatagramSocketImpl]: (E272,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=43654, Token=5029, 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]
Lose response 3 with MID 43654, payload = "resY says hi for the 1 time"
09:57:04.486: 333 FINE [DirectDatagramSocketImpl]: (E273,T333) 127.0.0.1:8,244 ==> :8,249 [ACK MID=43653] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
09:57:04.486: 338 INFO [Matcher]: Ignoring unmatchable empty message from localhost/127.0.0.1:8244: ACK MID=43653 - (org.eclipse.californium.core.network.Matcher.java:400) receiveEmptyMessage() in thread pool-27-thread-5
09:57:04.686: 291 FINE [DirectDatagramSocketImpl]: (E274,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=43654, Token=5029, 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]
Lose response 4 with MID 43654, payload = "resY says hi for the 1 time"
09:57:04.708: 292 SEVERE [DirectDatagramSocketImpl]: destination (port 8,246) not available! (E275,T292) 127.0.0.1:8,244 ==> :8,246 [CON-GET MID=47657, Token=1825, 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]
09:57:04.886: 291 FINE [DirectDatagramSocketImpl]: (E276,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=43654, Token=5029, 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]
Lose response 5 with MID 43654, payload = "resY says hi for the 1 time"
Resource resX changed to "resX says hi for the 2 time"
09:57:04.888: 291 FINE [DirectDatagramSocketImpl]: (E277,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=43655, Token=fb30, 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 6 with MID 43655, payload = "resX says hi for the 2 time"
09:57:05.087: 291 FINE [DirectDatagramSocketImpl]: (E278,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=43654, Token=5029, 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]
Lose response 7 with MID 43654, payload = "resY says hi for the 1 time"
09:57:05.088: 291 FINE [DirectDatagramSocketImpl]: (E279,T291) 127.0.0.1:8,249 ==> :8,244 [CON-2.05 MID=43655, Token=fb30, 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]
Results :
Tests run: 30, Failures: 0, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Californium (Cf) Parent ............................ SUCCESS [ 3.066 s]
[INFO] element-connector .................................. SUCCESS [ 10.713 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [ 2.009 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 14.835 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 19.710 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: 57.359 s
[INFO] Finished at: 2017-07-19T09:57:05-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: Execution medium-tests of goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test failed: The forked VM terminated without properly saying goodbye. VM crash or System.exit called?
Command was /bin/sh -c cd /jobs/genie.californium/1.0.x/workspace/californium-core && /opt/public/common/jdk1.7.0_80/jre/bin/java -jar /jobs/genie.californium/1.0.x/workspace/californium-core/target/surefire/surefirebooter7562914750133497470.jar /jobs/genie.californium/1.0.x/workspace/californium-core/target/surefire/surefire4927884638192299761tmp /jobs/genie.californium/1.0.x/workspace/californium-core/target/surefire/surefire_47051971540304239803tmp
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test (medium-tests) on project californium-core: Execution medium-tests of goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test failed: The forked VM terminated without properly saying goodbye. VM crash or System.exit called?
[ERROR] Command was /bin/sh -c cd /jobs/genie.californium/1.0.x/workspace/californium-core && /opt/public/common/jdk1.7.0_80/jre/bin/java -jar /jobs/genie.californium/1.0.x/workspace/californium-core/target/surefire/surefirebooter7562914750133497470.jar /jobs/genie.californium/1.0.x/workspace/californium-core/target/surefire/surefire4927884638192299761tmp /jobs/genie.californium/1.0.x/workspace/californium-core/target/surefire/surefire_47051971540304239803tmp
[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/PluginExecutionException
[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/