Skip to main content

[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/


Back to the top