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 #26

See <https://hudson.eclipse.org/californium/job/1.0.x/26/changes>

Changes:

[Achim Kraus] Adjust logging configuration according the configuration of the other

------------------------------------------
[...truncated 3056 lines...]
 1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8242 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) stop() in thread main at (2017-03-31 06:55:58)
 1 FINE [DirectDatagramSocketImpl]: closing port 8,242, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:1232) close() in thread main at (2017-03-31 06:55:58)
End LossyBlockwiseTransferTest
 1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:80) closeConfig() in thread main at (2017-03-31 06:55:58)
 1 INFO [NetworkRule]: Test org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest did not close all DatagramSockets! - (org.eclipse.californium.elements.rule.NetworkRule.java:172) closeNetwork() in thread main at (2017-03-31 06:55:58)
 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:80) applyConfig() in thread main at (2017-03-31 06:55:58)
 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:282) start() in thread main at (2017-03-31 06:55:58)
 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:124) isActive() in thread main at (2017-03-31 06:55:58)
 1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:397) bind() in thread main at (2017-03-31 06:55:58)
 1 FINE [DirectDatagramSocketImpl]: assigned port 8,245 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:150) bind() in thread main at (2017-03-31 06:55:58)
 1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:180) start() in thread main at (2017-03-31 06:55:58)
 1 INFO [CoapEndpoint]: Starting endpoint at localhost/127.0.0.1:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:192) start() in thread main at (2017-03-31 06:55:58)
 1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:124) isActive() in thread main at (2017-03-31 06:55:58)
 1 FINE [DirectDatagramSocketImpl]: binding to port 0, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:397) bind() in thread main at (2017-03-31 06:55:58)
 1 FINE [DirectDatagramSocketImpl]: assigned port 8,246 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:150) bind() in thread main at (2017-03-31 06:55:58)
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 8.415 sec - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest
Running org.eclipse.californium.core.test.MemoryLeakingHashMapTest
410 FINE [DirectDatagramSocketImpl]: (E589,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30954, Token=6b48dc6322, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8246, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:58)
409 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30954, Token=6b48dc6322, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8246, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-1 at (2017-03-31 06:55:58)
409 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8245#6b48dc6322 and resource /piggy - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-26-thread-1 at (2017-03-31 06:55:59)
409 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30954, Token=6b48dc6322, OptionSet={"Observe":0, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)"}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-1 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E590,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30954, Token=6b48dc6322, OptionSet={"Observe":0, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)"}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E591,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30955, Token=ffaa6b, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
412 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30955, Token=ffaa6b, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-2 at (2017-03-31 06:55:59)
412 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30955, Token=ffaa6b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-2 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E592,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30955, Token=ffaa6b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E593,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30956, Token=ffaa6b, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
414 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30956, Token=ffaa6b, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-4 at (2017-03-31 06:55:59)
414 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30956, Token=ffaa6b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-4 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E594,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30956, Token=ffaa6b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
408 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.05   MID= 6755, Token=6b48dc6322, OptionSet={"Observe":1, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)"}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-25-thread-1 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E595,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.05   MID= 6755, Token=6b48dc6322, OptionSet={"Observe":1, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)"}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E596,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30957, Token=2589, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
413 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30957, Token=2589, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-3 at (2017-03-31 06:55:59)
413 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30957, Token=2589, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-3 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E597,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30957, Token=2589, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E598,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30958, Token=2589, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
416 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30958, Token=2589, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-6 at (2017-03-31 06:55:59)
416 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30958, Token=2589, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-6 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E599,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30958, Token=2589, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
408 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.05   MID= 6756, Token=6b48dc6322, OptionSet={"Observe":2, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)"}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-25-thread-1 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E600,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.05   MID= 6756, Token=6b48dc6322, OptionSet={"Observe":2, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)"}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E601,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30959, Token=fbd6f77ea3, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
414 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30959, Token=fbd6f77ea3, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-4 at (2017-03-31 06:55:59)
414 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30959, Token=fbd6f77ea3, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-4 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E602,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30959, Token=fbd6f77ea3, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E603,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30960, Token=fbd6f77ea3, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
421 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30960, Token=fbd6f77ea3, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-10 at (2017-03-31 06:55:59)
421 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30960, Token=fbd6f77ea3, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-10 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E604,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30960, Token=fbd6f77ea3, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E605,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30961, Token=6b48dc6322, OptionSet={"Uri-Host":"localhost", "Observe":1, "Uri-Port":8246, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
413 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30961, Token=6b48dc6322, OptionSet={"Uri-Host":"localhost", "Observe":1, "Uri-Port":8246, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-3 at (2017-03-31 06:55:59)
413 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30961, Token=6b48dc6322, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)"}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-3 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E606,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30961, Token=6b48dc6322, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)"}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E607,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30962, Token=6b48dc6322, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
424 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30962, Token=6b48dc6322, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-13 at (2017-03-31 06:55:59)
424 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30962, Token=6b48dc6322, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-13 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E608,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30962, Token=6b48dc6322, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E609,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30963, Token=6b48dc6322, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
409 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30963, Token=6b48dc6322, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-1 at (2017-03-31 06:55:59)
409 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30963, Token=6b48dc6322, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-1 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E610,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30963, Token=6b48dc6322, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
Wait until deduplicator should be empty (0.447 seconds)
Assertion passed: all HashMaps of server are empty
Assertion passed: all HashMaps of client are empty
410 FINE [DirectDatagramSocketImpl]: (E611,T410) 127.0.0.1:8,245 ==> :8,246 [NON-POST   MID=30964, Token=32918432, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)"}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
429 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req NON-POST   MID=30964, Token=32918432, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)"}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-18 at (2017-03-31 06:55:59)
429 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.31   MID= 6757, Token=32918432, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-18 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E612,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.31   MID= 6757, Token=32918432, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E613,T410) 127.0.0.1:8,245 ==> :8,246 [NON-POST   MID=30965, Token=32918432, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
421 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req NON-POST   MID=30965, Token=32918432, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-10 at (2017-03-31 06:55:59)
421 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.01   MID= 6758, Token=32918432, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)"}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-10 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E614,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.01   MID= 6758, Token=32918432, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)"}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E615,T410) 127.0.0.1:8,245 ==> :8,246 [NON-POST   MID=30966, Token=32918432, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
432 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req NON-POST   MID=30966, Token=32918432, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-21 at (2017-03-31 06:55:59)
432 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.01   MID= 6759, Token=32918432, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-21 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E616,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.01   MID= 6759, Token=32918432, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
410 FINE [DirectDatagramSocketImpl]: (E617,T410) 127.0.0.1:8,245 ==> :8,246 [NON-POST   MID=30967, Token=32918432, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
433 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req NON-POST   MID=30967, Token=32918432, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-22 at (2017-03-31 06:55:59)
433 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.01   MID= 6760, Token=32918432, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-22 at (2017-03-31 06:55:59)
406 FINE [DirectDatagramSocketImpl]: (E618,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.01   MID= 6760, Token=32918432, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:55:59)
Wait until deduplicator should be empty (0.447 seconds)
Assertion passed: all HashMaps of server are empty
Assertion passed: all HashMaps of client are empty
Test observe relation with a reactive cancelation to coap://localhost:8246/piggy
410 FINE [DirectDatagramSocketImpl]: (E619,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30968, Token=20, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8246, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
412 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30968, Token=20, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8246, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-2 at (2017-03-31 06:56:00)
412 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8245#20 and resource /piggy - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-26-thread-2 at (2017-03-31 06:56:00)
412 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30968, Token=20, OptionSet={"Observe":20, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-2 at (2017-03-31 06:56:00)
406 FINE [DirectDatagramSocketImpl]: (E620,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30968, Token=20, OptionSet={"Observe":20, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
408 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.05   MID= 6761, Token=20, OptionSet={"Observe":21, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-25-thread-1 at (2017-03-31 06:56:00)
406 FINE [DirectDatagramSocketImpl]: (E621,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.05   MID= 6761, Token=20, OptionSet={"Observe":21, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
408 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.05   MID= 6762, Token=20, OptionSet={"Observe":22, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-25-thread-1 at (2017-03-31 06:56:00)
406 FINE [DirectDatagramSocketImpl]: (E622,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.05   MID= 6762, Token=20, OptionSet={"Observe":22, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
Wait until deduplicator should be empty (0.447 seconds)
408 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.05   MID= 6763, Token=20, OptionSet={"Observe":23, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-25-thread-1 at (2017-03-31 06:56:00)
406 FINE [DirectDatagramSocketImpl]: (E623,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.05   MID= 6763, Token=20, OptionSet={"Observe":23, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
410 FINE [DirectDatagramSocketImpl]: (E624,T410) 127.0.0.1:8,245 ==> :8,246 [RST        MID= 6763] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
427 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> emp RST        MID= 6763 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:64) receiveEmptyMessage() in thread pool-26-thread-16 at (2017-03-31 06:56:00)
Assertion passed: all HashMaps of server are empty
Assertion passed: all HashMaps of client are empty
410 FINE [DirectDatagramSocketImpl]: (E625,T410) 127.0.0.1:8,245 ==> :8,246 [CON-POST   MID=30969, Token=baaa11d1, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)"}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
420 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-POST   MID=30969, Token=baaa11d1, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)"}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-9 at (2017-03-31 06:56:00)
420 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.31   MID=30969, Token=baaa11d1, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-9 at (2017-03-31 06:56:00)
406 FINE [DirectDatagramSocketImpl]: (E626,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.31   MID=30969, Token=baaa11d1, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
410 FINE [DirectDatagramSocketImpl]: (E627,T410) 127.0.0.1:8,245 ==> :8,246 [CON-POST   MID=30970, Token=baaa11d1, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
424 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-POST   MID=30970, Token=baaa11d1, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-13 at (2017-03-31 06:56:00)
424 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.01   MID=30970, Token=baaa11d1, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)"}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-13 at (2017-03-31 06:56:00)
406 FINE [DirectDatagramSocketImpl]: (E628,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.01   MID=30970, Token=baaa11d1, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)"}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
410 FINE [DirectDatagramSocketImpl]: (E629,T410) 127.0.0.1:8,245 ==> :8,246 [CON-POST   MID=30971, Token=baaa11d1, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
409 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-POST   MID=30971, Token=baaa11d1, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-1 at (2017-03-31 06:56:00)
409 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.01   MID=30971, Token=baaa11d1, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-1 at (2017-03-31 06:56:00)
406 FINE [DirectDatagramSocketImpl]: (E630,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.01   MID=30971, Token=baaa11d1, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
410 FINE [DirectDatagramSocketImpl]: (E631,T410) 127.0.0.1:8,245 ==> :8,246 [CON-POST   MID=30972, Token=baaa11d1, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
417 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-POST   MID=30972, Token=baaa11d1, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-7 at (2017-03-31 06:56:00)
417 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.01   MID=30972, Token=baaa11d1, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-7 at (2017-03-31 06:56:00)
406 FINE [DirectDatagramSocketImpl]: (E632,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.01   MID=30972, Token=baaa11d1, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:00)
Wait until deduplicator should be empty (0.447 seconds)
Assertion passed: all HashMaps of server are empty
Assertion passed: all HashMaps of client are empty
410 FINE [DirectDatagramSocketImpl]: (E633,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30973, Token=c5fbc2bcdc5eccf0, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"separate"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:01)
429 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30973, Token=c5fbc2bcdc5eccf0, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"separate"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-18 at (2017-03-31 06:56:01)
429 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== emp ACK        MID=30973 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:49) sendEmptyMessage() in thread pool-26-thread-18 at (2017-03-31 06:56:01)
406 FINE [DirectDatagramSocketImpl]: (E634,T406) 127.0.0.1:8,246 ==> :8,245 [ACK        MID=30973] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:01)
429 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res CON-2.05   MID= 6764, Token=c5fbc2bcdc5eccf0, OptionSet={"Content-Format":"text/plain"}, "simple GET" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-18 at (2017-03-31 06:56:01)
406 FINE [DirectDatagramSocketImpl]: (E635,T406) 127.0.0.1:8,246 ==> :8,245 [CON-2.05   MID= 6764, Token=c5fbc2bcdc5eccf0, OptionSet={"Content-Format":"text/plain"}, "simple GET"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:01)
Wait until deduplicator should be empty (0.447 seconds)
410 FINE [DirectDatagramSocketImpl]: (E636,T410) 127.0.0.1:8,245 ==> :8,246 [ACK        MID= 6764] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:01)
432 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> emp ACK        MID= 6764 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:64) receiveEmptyMessage() in thread pool-26-thread-21 at (2017-03-31 06:56:01)
Assertion passed: all HashMaps of server are empty
Assertion passed: all HashMaps of client are empty
410 FINE [DirectDatagramSocketImpl]: (E637,T410) 127.0.0.1:8,245 ==> :8,246 [NON-GET    MID=30974, Token=d1cd720fa13768, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:01)
413 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req NON-GET    MID=30974, Token=d1cd720fa13768, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-3 at (2017-03-31 06:56:01)
413 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.05   MID= 6765, Token=d1cd720fa13768, OptionSet={"Content-Format":"text/plain"}, "simple NON GET" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-3 at (2017-03-31 06:56:01)
406 FINE [DirectDatagramSocketImpl]: (E638,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.05   MID= 6765, Token=d1cd720fa13768, OptionSet={"Content-Format":"text/plain"}, "simple NON GET"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:01)
Wait until deduplicator should be empty (0.447 seconds)
Assertion passed: all HashMaps of server are empty
Assertion passed: all HashMaps of client are empty
410 FINE [DirectDatagramSocketImpl]: (E639,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30975, Token=1ef5a3, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8246, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:01)
414 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30975, Token=1ef5a3, OptionSet={"Uri-Host":"localhost", "Observe":0, "Uri-Port":8246, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-4 at (2017-03-31 06:56:01)
414 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8245#1ef5a3 and resource /piggy - (org.eclipse.californium.core.CoapResource.java:688) addObserveRelation() in thread pool-26-thread-4 at (2017-03-31 06:56:01)
414 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30975, Token=1ef5a3, OptionSet={"Observe":58, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-4 at (2017-03-31 06:56:01)
406 FINE [DirectDatagramSocketImpl]: (E640,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30975, Token=1ef5a3, OptionSet={"Observe":58, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:01)
408 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.05   MID= 6766, Token=1ef5a3, OptionSet={"Observe":59, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-25-thread-1 at (2017-03-31 06:56:01)
406 FINE [DirectDatagramSocketImpl]: (E641,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.05   MID= 6766, Token=1ef5a3, OptionSet={"Observe":59, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:01)
408 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res NON-2.05   MID= 6767, Token=1ef5a3, OptionSet={"Observe":60, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-25-thread-1 at (2017-03-31 06:56:02)
406 FINE [DirectDatagramSocketImpl]: (E642,T406) 127.0.0.1:8,246 ==> :8,245 [NON-2.05   MID= 6767, Token=1ef5a3, OptionSet={"Observe":60, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
410 FINE [DirectDatagramSocketImpl]: (E643,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30976, Token=1ef5a3, OptionSet={"Uri-Host":"localhost", "Observe":1, "Uri-Port":8246, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
423 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30976, Token=1ef5a3, OptionSet={"Uri-Host":"localhost", "Observe":1, "Uri-Port":8246, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-12 at (2017-03-31 06:56:02)
423 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30976, Token=1ef5a3, OptionSet={"Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-12 at (2017-03-31 06:56:02)
406 FINE [DirectDatagramSocketImpl]: (E644,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30976, Token=1ef5a3, OptionSet={"Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
Wait until deduplicator should be empty (0.447 seconds)
Assertion passed: all HashMaps of server are empty
Assertion passed: all HashMaps of client are empty
410 FINE [DirectDatagramSocketImpl]: (E645,T410) 127.0.0.1:8,245 ==> :8,246 [CON-GET    MID=30977, Token=70, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
417 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-GET    MID=30977, Token=70, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-7 at (2017-03-31 06:56:02)
417 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.05   MID=30977, Token=70, OptionSet={"Content-Format":"text/plain"}, "simple GET" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-7 at (2017-03-31 06:56:02)
406 FINE [DirectDatagramSocketImpl]: (E646,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.05   MID=30977, Token=70, OptionSet={"Content-Format":"text/plain"}, "simple GET"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
Wait until deduplicator should be empty (0.447 seconds)
Assertion passed: all HashMaps of server are empty
Assertion passed: all HashMaps of client are empty
410 FINE [DirectDatagramSocketImpl]: (E647,T410) 127.0.0.1:8,245 ==> :8,246 [CON-POST   MID=30978, Token=02ed42d3b5498d, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"separate", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)"}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
429 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-POST   MID=30978, Token=02ed42d3b5498d, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"separate", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)"}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-18 at (2017-03-31 06:56:02)
429 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.31   MID=30978, Token=02ed42d3b5498d, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-18 at (2017-03-31 06:56:02)
406 FINE [DirectDatagramSocketImpl]: (E648,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.31   MID=30978, Token=02ed42d3b5498d, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
410 FINE [DirectDatagramSocketImpl]: (E649,T410) 127.0.0.1:8,245 ==> :8,246 [CON-POST   MID=30979, Token=02ed42d3b5498d, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"separate", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
423 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-POST   MID=30979, Token=02ed42d3b5498d, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"separate", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-12 at (2017-03-31 06:56:02)
423 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== emp ACK        MID=30978 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:49) sendEmptyMessage() in thread pool-26-thread-12 at (2017-03-31 06:56:02)
406 FINE [DirectDatagramSocketImpl]: (E650,T406) 127.0.0.1:8,246 ==> :8,245 [ACK        MID=30978] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
423 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.01   MID=30979, Token=02ed42d3b5498d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)"}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-12 at (2017-03-31 06:56:02)
405 INFO [Matcher]: Ignoring unmatchable empty message from localhost/127.0.0.1:8246: ACK        MID=30978 - (org.eclipse.californium.core.network.Matcher.java:382) receiveEmptyMessage() in thread Thread-6 at (2017-03-31 06:56:02)
406 FINE [DirectDatagramSocketImpl]: (E651,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.01   MID=30979, Token=02ed42d3b5498d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)"}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
410 FINE [DirectDatagramSocketImpl]: (E652,T410) 127.0.0.1:8,245 ==> :8,246 [CON-POST   MID=30980, Token=02ed42d3b5498d, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"separate", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
420 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-POST   MID=30980, Token=02ed42d3b5498d, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"separate", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-9 at (2017-03-31 06:56:02)
420 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.01   MID=30980, Token=02ed42d3b5498d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-9 at (2017-03-31 06:56:02)
406 FINE [DirectDatagramSocketImpl]: (E653,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.01   MID=30980, Token=02ed42d3b5498d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
410 FINE [DirectDatagramSocketImpl]: (E654,T410) 127.0.0.1:8,245 ==> :8,246 [CON-POST   MID=30981, Token=02ed42d3b5498d, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"separate", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
434 INFO [MessageTracer]: localhost/127.0.0.1:8245 ==> req CON-POST   MID=30981, Token=02ed42d3b5498d, OptionSet={"Uri-Host":"localhost", "Uri-Port":8246, "Uri-Path":"separate", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:54) receiveRequest() in thread pool-26-thread-23 at (2017-03-31 06:56:02)
434 INFO [MessageTracer]: localhost/127.0.0.1:8245 <== res ACK-2.01   MID=30981, Token=02ed42d3b5498d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:44) sendResponse() in thread pool-26-thread-23 at (2017-03-31 06:56:02)
406 FINE [DirectDatagramSocketImpl]: (E655,T406) 127.0.0.1:8,246 ==> :8,245 [ACK-2.01   MID=30981, Token=02ed42d3b5498d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:817) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0] at (2017-03-31 06:56:02)
Wait until deduplicator should be empty (0.447 seconds)
Assertion passed: all HashMaps of server are empty
Assertion passed: all HashMaps of client are empty
 1 INFO [CoapServer]: Destroy server - (org.eclipse.californium.core.CoapServer.java:220) destroy() in thread main at (2017-03-31 06:56:03)
 1 INFO [CoapEndpoint]: Destroying endpoint at address localhost/127.0.0.1:8246 - (org.eclipse.californium.core.network.CoapEndpoint.java:222) destroy() in thread main at (2017-03-31 06:56:03)
 1 INFO [CoapEndpoint]: Stopping endpoint at address localhost/127.0.0.1:8246 - (org.eclipse.californium.core.network.CoapEndpoint.java:311) stop() in thread main at (2017-03-31 06:56:03)
 1 FINE [DirectDatagramSocketImpl]: closing port 8,246, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:1232) close() in thread main at (2017-03-31 06:56:03)
 1 WARNING [CoapServer]: Server executor did not shutdown in time - (org.eclipse.californium.core.CoapServer.java:227) destroy() in thread main at (2017-03-31 06:56:08)
 1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:80) closeConfig() in thread main at (2017-03-31 06:56:08)
 1 INFO [NetworkRule]: Test org.eclipse.californium.core.test.MemoryLeakingHashMapTest did not close all DatagramSockets! - (org.eclipse.californium.elements.rule.NetworkRule.java:172) closeNetwork() in thread main at (2017-03-31 06:56:08)
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 9.379 sec - in org.eclipse.californium.core.test.MemoryLeakingHashMapTest

Results :

Failed tests: 
  ObserveServerSideTest.testQuickChangeAndTimeout:404 Wrong payload: expected:<NON notification [2 2322376214]> but was:<NON notification [1 1338867053]>

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  1.335 s]
[INFO] element-connector .................................. SUCCESS [  8.487 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  1.907 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 10.707 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 58.581 s]
[INFO] Californium (Cf) Proxy ............................. SKIPPED
[INFO] Californium (Cf) OSGi Bundle ....................... SKIPPED
[INFO] Californium (Cf) Demo Applications ................. SKIPPED
[INFO] Sc-DTLS-Example .................................... SKIPPED
[INFO] Cf-PlugtestClient .................................. SKIPPED
[INFO] Cf-PlugtestChecker ................................. SKIPPED
[INFO] Cf-PlugtestServer .................................. SKIPPED
[INFO] Cf-HelloWorldClient ................................ SKIPPED
[INFO] Cf-HelloWorldServer ................................ SKIPPED
[INFO] Cf-CoCoAClient ..................................... SKIPPED
[INFO] Cf-SecureServer .................................... SKIPPED
[INFO] Cf-ExampleCrossProxy ............................... SKIPPED
[INFO] Cf-BenchmarkServer ................................. SKIPPED
[INFO] Cf-ObserveBenchmarkClient .......................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 01:24 min
[INFO] Finished at: 2017-03-31T06:56:08-04:00
[INFO] Final Memory: 39M/1934M
[INFO] ------------------------------------------------------------------------
[INFO] o.h.m.e.h.MavenExecutionResultHandler - Build failed with exception(s)
[INFO] o.h.m.e.h.MavenExecutionResultHandler - [1] org.apache.maven.lifecycle.LifecycleExecutionException: Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test (large-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 (large-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/


Back to the top