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: 2.0.x-nightly #67

See <https://hudson.eclipse.org/californium/job/2.0.x-nightly/67/changes>

Changes:

[Achim Kraus] Use synchronized to access exchange retry fields.

[Achim Kraus] Rename loadMID into sameMID.

[Achim Kraus] Introduce smart deduplication for lockstep test.

------------------------------------------
[...truncated 3446 lines...]
02:10:27.676: 434 FINE [DirectDatagramSocketImpl]: (E431,T434) 127.0.0.1:8,277 ==> :8,276 [CON-GET    MID=47363, Token=0df68d131210eaa8, OptionSet={"Uri-Host":"localhost", "Uri-Path":["A","AA","first"]}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:27.677: 441 INFO [ServerMessageDeliverer]: Did not find resource [A, AA, first] requested by localhost/127.0.0.1:8,277 - (org.eclipse.californium.core.server.ServerMessageDeliverer.java:88) deliverRequest() in thread CoapServer#2
02:10:27.678: 438 FINE [DirectDatagramSocketImpl]: (E432,T438) 127.0.0.1:8,276 ==> :8,277 [ACK-4.04   MID=47363, Token=0df68d131210eaa8, OptionSet={}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
Check that the child of (now) 'second' is not reachable under 'first'
02:10:27.679: 434 FINE [DirectDatagramSocketImpl]: (E433,T434) 127.0.0.1:8,277 ==> :8,276 [CON-GET    MID=47364, Token=d56f56e46ecbed1e, OptionSet={"Uri-Host":"localhost", "Uri-Path":["A","AA","first","child"]}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:27.679: 443 INFO [ServerMessageDeliverer]: Did not find resource [A, AA, first, child] requested by localhost/127.0.0.1:8,277 - (org.eclipse.californium.core.server.ServerMessageDeliverer.java:88) deliverRequest() in thread CoapServer#4
02:10:27.680: 438 FINE [DirectDatagramSocketImpl]: (E434,T438) 127.0.0.1:8,276 ==> :8,277 [ACK-4.04   MID=47364, Token=d56f56e46ecbed1e, OptionSet={}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-0.0.0.0/0.0.0.0:0[0]
02:10:27.680:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:249) destroy() in thread main
02:10:27.681:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:8276 - (org.eclipse.californium.core.network.CoapEndpoint.java:458) destroy() in thread main
02:10:27.681:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8276 - (org.eclipse.californium.core.network.CoapEndpoint.java:445) stop() in thread main
02:10:27.681:   1 FINE [DirectDatagramSocketImpl]: closing port 8,276, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:10:27.682:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:458) destroy() in thread main
02:10:27.682:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:276) destroy() in thread main
End ResourceTreeTest
02:10:27.682:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
02:10:27.682:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://0.0.0.0:8277 - (org.eclipse.californium.core.network.CoapEndpoint.java:458) destroy() in thread main
02:10:27.683:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://0.0.0.0:8277 - (org.eclipse.californium.core.network.CoapEndpoint.java:445) stop() in thread main
02:10:27.683:   1 FINE [DirectDatagramSocketImpl]: closing port 8,277, address 0.0.0.0/0.0.0.0 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:10:27.683:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:277) applyConfig() in thread main
02:10:27.685:   1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:406) start() in thread main
02:10:27.685:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:10:27.686:   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
02:10:27.686:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,278 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
02:10:27.686:   1 INFO [CoapEndpoint]: Started endpoint at coap://localhost:8278 - (org.eclipse.californium.core.network.CoapEndpoint.java:415) start() in thread main
02:10:27.688:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:201) start() in thread main
02:10:27.688:   1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:406) start() in thread main
02:10:27.689:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:10:27.689:   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
02:10:27.689:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,279 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
02:10:27.690:   1 INFO [CoapEndpoint]: Started endpoint at coap://localhost:8279 - (org.eclipse.californium.core.network.CoapEndpoint.java:415) start() in thread main
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.027 sec - in org.eclipse.californium.core.test.ResourceTreeTest
Running org.eclipse.californium.core.test.MemoryLeakingHashMapTest
02:10:27.692: 453 FINE [DirectDatagramSocketImpl]: (E435,T453) 127.0.0.1:8,278 ==> :8,279 [NON-POST   MID=11790, Token=7d50cf38d702701d, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)", "Size1":20}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:27.692: 455 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req NON-POST   MID=11790, Token=7d50cf38d702701d, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)", "Size1":20}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#1
02:10:27.693: 455 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.31   MID=16656, Token=7d50cf38d702701d, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#1
02:10:27.693: 448 FINE [DirectDatagramSocketImpl]: (E436,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.31   MID=16656, Token=7d50cf38d702701d, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:27.694: 453 FINE [DirectDatagramSocketImpl]: (E437,T453) 127.0.0.1:8,278 ==> :8,279 [NON-POST   MID=11791, Token=7d50cf38d702701d, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:27.695: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req NON-POST   MID=11791, Token=7d50cf38d702701d, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#2
02:10:27.696: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.01   MID=16657, Token=7d50cf38d702701d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)", "Size2":40}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#2
02:10:27.697: 448 FINE [DirectDatagramSocketImpl]: (E438,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.01   MID=16657, Token=7d50cf38d702701d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)", "Size2":40}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:27.698: 453 FINE [DirectDatagramSocketImpl]: (E439,T453) 127.0.0.1:8,278 ==> :8,279 [NON-POST   MID=11792, Token=7d50cf38d702701d, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:27.698: 455 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req NON-POST   MID=11792, Token=7d50cf38d702701d, OptionSet={"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:57) receiveRequest() in thread CoapServer#1
02:10:27.699: 455 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.01   MID=16658, Token=7d50cf38d702701d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#1
02:10:27.700: 448 FINE [DirectDatagramSocketImpl]: (E440,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.01   MID=16658, Token=7d50cf38d702701d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:27.701: 453 FINE [DirectDatagramSocketImpl]: (E441,T453) 127.0.0.1:8,278 ==> :8,279 [NON-POST   MID=11793, Token=7d50cf38d702701d, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:27.701: 458 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req NON-POST   MID=11793, Token=7d50cf38d702701d, OptionSet={"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:57) receiveRequest() in thread CoapServer#4
02:10:27.702: 458 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.01   MID=16659, Token=7d50cf38d702701d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#4
02:10:27.702: 448 FINE [DirectDatagramSocketImpl]: (E442,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.01   MID=16659, Token=7d50cf38d702701d, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Wait until deduplicator should be empty (0.647 seconds)
Test observe relation with a reactive cancelation to coap://127.0.0.1:8279/piggy
02:10:28.028: 453 FINE [DirectDatagramSocketImpl]: (E443,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11794, Token=662377dff97109e6, OptionSet={"Observe":0, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.028: 460 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11794, Token=662377dff97109e6, OptionSet={"Observe":0, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#6
02:10:28.029: 460 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8278#662377dff97109e6 and resource /piggy - (org.eclipse.californium.core.CoapResource.java:691) addObserveRelation() in thread CoapServer#6
02:10:28.029: 460 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11794, Token=662377dff97109e6, OptionSet={"Observe":1, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#6
02:10:28.030: 448 FINE [DirectDatagramSocketImpl]: (E444,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11794, Token=662377dff97109e6, OptionSet={"Observe":1, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.089: 451 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID=16660, Token=662377dff97109e6, OptionSet={"Observe":2, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread pool-1-thread-1
02:10:28.090: 448 FINE [DirectDatagramSocketImpl]: (E445,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID=16660, Token=662377dff97109e6, OptionSet={"Observe":2, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.290: 451 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID=16661, Token=662377dff97109e6, OptionSet={"Observe":3, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread pool-1-thread-1
02:10:28.291: 448 FINE [DirectDatagramSocketImpl]: (E446,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID=16661, Token=662377dff97109e6, OptionSet={"Observe":3, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Wait until deduplicator should be empty (0.647 seconds)
02:10:28.491: 451 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID=16662, Token=662377dff97109e6, OptionSet={"Observe":4, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread pool-1-thread-1
02:10:28.492: 448 FINE [DirectDatagramSocketImpl]: (E447,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID=16662, Token=662377dff97109e6, OptionSet={"Observe":4, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.493: 453 FINE [DirectDatagramSocketImpl]: (E448,T453) 127.0.0.1:8,278 ==> :8,279 [RST        MID=16662] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.493: 457 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> emp RST        MID=16662 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:67) receiveEmptyMessage() in thread CoapServer#3
02:10:28.679: 453 FINE [DirectDatagramSocketImpl]: (E449,T453) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=11795, Token=bae40d780b5772a6, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)", "Size1":20}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.679: 455 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=11795, Token=bae40d780b5772a6, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)", "Size1":20}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#1
02:10:28.680: 455 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.31   MID=11795, Token=bae40d780b5772a6, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#1
02:10:28.681: 448 FINE [DirectDatagramSocketImpl]: (E450,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.31   MID=11795, Token=bae40d780b5772a6, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.682: 453 FINE [DirectDatagramSocketImpl]: (E451,T453) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=11796, Token=bae40d780b5772a6, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.682: 462 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=11796, Token=bae40d780b5772a6, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#8
02:10:28.683: 462 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=11796, Token=bae40d780b5772a6, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)", "Size2":40}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#8
02:10:28.685: 448 FINE [DirectDatagramSocketImpl]: (E452,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=11796, Token=bae40d780b5772a6, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)", "Size2":40}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.686: 453 FINE [DirectDatagramSocketImpl]: (E453,T453) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=11797, Token=bae40d780b5772a6, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.686: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=11797, Token=bae40d780b5772a6, OptionSet={"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:57) receiveRequest() in thread CoapServer#2
02:10:28.687: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=11797, Token=bae40d780b5772a6, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#2
02:10:28.688: 448 FINE [DirectDatagramSocketImpl]: (E454,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=11797, Token=bae40d780b5772a6, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.689: 453 FINE [DirectDatagramSocketImpl]: (E455,T453) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=11798, Token=bae40d780b5772a6, OptionSet={"Uri-Path":"piggy", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:28.689: 460 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=11798, Token=bae40d780b5772a6, OptionSet={"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:57) receiveRequest() in thread CoapServer#6
02:10:28.690: 460 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=11798, Token=bae40d780b5772a6, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#6
02:10:28.691: 448 FINE [DirectDatagramSocketImpl]: (E456,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=11798, Token=bae40d780b5772a6, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Wait until deduplicator should be empty (0.647 seconds)
02:10:29.017: 453 FINE [DirectDatagramSocketImpl]: (E457,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11799, Token=a4a1be04152ad3f1, OptionSet={"Uri-Path":"separate"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.018: 466 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11799, Token=a4a1be04152ad3f1, OptionSet={"Uri-Path":"separate"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#10
02:10:29.019: 466 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== emp ACK        MID=11799 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:52) sendEmptyMessage() in thread CoapServer#10
02:10:29.019: 448 FINE [DirectDatagramSocketImpl]: (E458,T448) 127.0.0.1:8,279 ==> :8,278 [ACK        MID=11799] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.019: 466 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res CON-2.05   MID=16663, Token=a4a1be04152ad3f1, OptionSet={"Content-Format":"text/plain"}, "simple GET" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#10
02:10:29.020: 448 FINE [DirectDatagramSocketImpl]: (E459,T448) 127.0.0.1:8,279 ==> :8,278 [CON-2.05   MID=16663, Token=a4a1be04152ad3f1, OptionSet={"Content-Format":"text/plain"}, "simple GET"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Wait until deduplicator should be empty (0.647 seconds)
02:10:29.021: 453 FINE [DirectDatagramSocketImpl]: (E460,T453) 127.0.0.1:8,278 ==> :8,279 [ACK        MID=16663] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.021: 469 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> emp ACK        MID=16663 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:67) receiveEmptyMessage() in thread CoapServer#11
02:10:29.343: 453 FINE [DirectDatagramSocketImpl]: (E461,T453) 127.0.0.1:8,278 ==> :8,279 [NON-GET    MID=11800, Token=e569618ca654c5bd, OptionSet={"Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.344: 455 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req NON-GET    MID=11800, Token=e569618ca654c5bd, OptionSet={"Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#1
02:10:29.344: 455 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID=16664, Token=e569618ca654c5bd, OptionSet={"Content-Format":"text/plain"}, "simple NON GET" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#1
02:10:29.345: 448 FINE [DirectDatagramSocketImpl]: (E462,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID=16664, Token=e569618ca654c5bd, OptionSet={"Content-Format":"text/plain"}, "simple NON GET"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Wait until deduplicator should be empty (0.647 seconds)
02:10:29.669: 453 FINE [DirectDatagramSocketImpl]: (E463,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11801, Token=bbb4f0a44553b482, OptionSet={"Observe":0, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.669: 471 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11801, Token=bbb4f0a44553b482, OptionSet={"Observe":0, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#13
02:10:29.670: 471 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8278#bbb4f0a44553b482 and resource /piggy - (org.eclipse.californium.core.CoapResource.java:691) addObserveRelation() in thread CoapServer#13
02:10:29.671: 471 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11801, Token=bbb4f0a44553b482, OptionSet={"Observe":9, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#13
02:10:29.672: 448 FINE [DirectDatagramSocketImpl]: (E464,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11801, Token=bbb4f0a44553b482, OptionSet={"Observe":9, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.673: 453 FINE [DirectDatagramSocketImpl]: (E465,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11802, Token=e8058accfb4c8aaf, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.674: 473 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11802, Token=e8058accfb4c8aaf, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#15
02:10:29.675: 473 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11802, Token=e8058accfb4c8aaf, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#15
02:10:29.676: 448 FINE [DirectDatagramSocketImpl]: (E466,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11802, Token=e8058accfb4c8aaf, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.677: 453 FINE [DirectDatagramSocketImpl]: (E467,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11803, Token=e8058accfb4c8aaf, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.677: 465 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11803, Token=e8058accfb4c8aaf, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#9
02:10:29.678: 465 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11803, Token=e8058accfb4c8aaf, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#9
02:10:29.679: 448 FINE [DirectDatagramSocketImpl]: (E468,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11803, Token=e8058accfb4c8aaf, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.693: 451 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID=16665, Token=bbb4f0a44553b482, OptionSet={"Observe":10, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread pool-1-thread-1
02:10:29.695: 448 FINE [DirectDatagramSocketImpl]: (E469,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID=16665, Token=bbb4f0a44553b482, OptionSet={"Observe":10, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.697: 453 FINE [DirectDatagramSocketImpl]: (E470,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11804, Token=69c327e172870f5e, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.697: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11804, Token=69c327e172870f5e, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#2
02:10:29.698: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11804, Token=69c327e172870f5e, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#2
02:10:29.699: 448 FINE [DirectDatagramSocketImpl]: (E471,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11804, Token=69c327e172870f5e, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.700: 453 FINE [DirectDatagramSocketImpl]: (E472,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11805, Token=69c327e172870f5e, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.700: 475 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11805, Token=69c327e172870f5e, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#17
02:10:29.701: 475 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11805, Token=69c327e172870f5e, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#17
02:10:29.702: 448 FINE [DirectDatagramSocketImpl]: (E473,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11805, Token=69c327e172870f5e, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.895: 451 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID=16666, Token=bbb4f0a44553b482, OptionSet={"Observe":11, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread pool-1-thread-1
02:10:29.896: 448 FINE [DirectDatagramSocketImpl]: (E474,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID=16666, Token=bbb4f0a44553b482, OptionSet={"Observe":11, "Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.898: 453 FINE [DirectDatagramSocketImpl]: (E475,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11806, Token=e7402f08e6d5051b, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.898: 457 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11806, Token=e7402f08e6d5051b, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#3
02:10:29.899: 457 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11806, Token=e7402f08e6d5051b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#3
02:10:29.900: 448 FINE [DirectDatagramSocketImpl]: (E476,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11806, Token=e7402f08e6d5051b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.901: 453 FINE [DirectDatagramSocketImpl]: (E477,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11807, Token=e7402f08e6d5051b, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.901: 479 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11807, Token=e7402f08e6d5051b, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#19
02:10:29.902: 479 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11807, Token=e7402f08e6d5051b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#19
02:10:29.903: 448 FINE [DirectDatagramSocketImpl]: (E478,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11807, Token=e7402f08e6d5051b, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.904: 453 FINE [DirectDatagramSocketImpl]: (E479,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11808, Token=bbb4f0a44553b482, OptionSet={"Observe":1, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.904: 469 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11808, Token=bbb4f0a44553b482, OptionSet={"Observe":1, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#11
02:10:29.905: 469 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11808, Token=bbb4f0a44553b482, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#11
02:10:29.907: 448 FINE [DirectDatagramSocketImpl]: (E480,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11808, Token=bbb4f0a44553b482, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Size2":40}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.908: 453 FINE [DirectDatagramSocketImpl]: (E481,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11809, Token=bbb4f0a44553b482, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.908: 480 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11809, Token=bbb4f0a44553b482, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=1)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#20
02:10:29.909: 480 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11809, Token=bbb4f0a44553b482, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#20
02:10:29.910: 448 FINE [DirectDatagramSocketImpl]: (E482,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11809, Token=bbb4f0a44553b482, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.911: 453 FINE [DirectDatagramSocketImpl]: (E483,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11810, Token=bbb4f0a44553b482, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:29.911: 483 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11810, Token=bbb4f0a44553b482, OptionSet={"Uri-Path":"piggy", "Block2":"(szx=0/16, m=false, num=2)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#21
02:10:29.912: 483 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11810, Token=bbb4f0a44553b482, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#21
02:10:29.913: 448 FINE [DirectDatagramSocketImpl]: (E484,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11810, Token=bbb4f0a44553b482, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Wait until deduplicator should be empty (0.647 seconds)
02:10:30.172: 453 FINE [DirectDatagramSocketImpl]: (E485,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11811, Token=9c607a0b84f173cd, OptionSet={"Observe":0, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:30.173: 458 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11811, Token=9c607a0b84f173cd, OptionSet={"Observe":0, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#4
02:10:30.174: 458 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8278#9c607a0b84f173cd and resource /piggy - (org.eclipse.californium.core.CoapResource.java:691) addObserveRelation() in thread CoapServer#4
02:10:30.174: 458 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11811, Token=9c607a0b84f173cd, OptionSet={"Observe":12, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#4
02:10:30.175: 448 FINE [DirectDatagramSocketImpl]: (E486,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11811, Token=9c607a0b84f173cd, OptionSet={"Observe":12, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:30.297: 451 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID=16667, Token=9c607a0b84f173cd, OptionSet={"Observe":13, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread pool-1-thread-1
02:10:30.298: 448 FINE [DirectDatagramSocketImpl]: (E487,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID=16667, Token=9c607a0b84f173cd, OptionSet={"Observe":13, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:30.498: 451 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID=16668, Token=9c607a0b84f173cd, OptionSet={"Observe":14, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread pool-1-thread-1
02:10:30.499: 448 FINE [DirectDatagramSocketImpl]: (E488,T448) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID=16668, Token=9c607a0b84f173cd, OptionSet={"Observe":14, "Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:30.500: 453 FINE [DirectDatagramSocketImpl]: (E489,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11812, Token=9c607a0b84f173cd, OptionSet={"Observe":1, "Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:30.500: 472 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11812, Token=9c607a0b84f173cd, OptionSet={"Observe":1, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#14
02:10:30.501: 472 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11812, Token=9c607a0b84f173cd, OptionSet={"Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#14
02:10:30.502: 448 FINE [DirectDatagramSocketImpl]: (E490,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11812, Token=9c607a0b84f173cd, OptionSet={"Content-Format":"text/plain"}, "Hello observer"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Wait until deduplicator should be empty (0.647 seconds)
02:10:30.825: 453 FINE [DirectDatagramSocketImpl]: (E491,T453) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=11813, Token=88efc03df8c61fbf, OptionSet={"Uri-Path":"piggy"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:30.825: 471 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=11813, Token=88efc03df8c61fbf, OptionSet={"Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#13
02:10:30.826: 471 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=11813, Token=88efc03df8c61fbf, OptionSet={"Content-Format":"text/plain"}, "simple GET" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#13
02:10:30.826: 448 FINE [DirectDatagramSocketImpl]: (E492,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=11813, Token=88efc03df8c61fbf, OptionSet={"Content-Format":"text/plain"}, "simple GET"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Wait until deduplicator should be empty (0.647 seconds)
02:10:31.150: 453 FINE [DirectDatagramSocketImpl]: (E493,T453) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=11814, Token=9ee64bbc69a8e2f8, OptionSet={"Uri-Path":"separate", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)", "Size1":20}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:31.151: 488 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=11814, Token=9ee64bbc69a8e2f8, OptionSet={"Uri-Path":"separate", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=true, num=0)", "Size1":20}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#24
02:10:31.151: 488 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.31   MID=11814, Token=9ee64bbc69a8e2f8, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#24
02:10:31.152: 448 FINE [DirectDatagramSocketImpl]: (E494,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.31   MID=11814, Token=9ee64bbc69a8e2f8, OptionSet={"Block1":"(szx=0/16, m=true, num=0)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:31.153: 453 FINE [DirectDatagramSocketImpl]: (E495,T453) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=11815, Token=9ee64bbc69a8e2f8, OptionSet={"Uri-Path":"separate", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:31.153: 474 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=11815, Token=9ee64bbc69a8e2f8, OptionSet={"Uri-Path":"separate", "Content-Format":"text/plain", "Block1":"(szx=0/16, m=false, num=1)"}, "789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#16
02:10:31.154: 474 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== emp ACK        MID=11815 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:52) sendEmptyMessage() in thread CoapServer#16
02:10:31.154: 474 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=11815, Token=9ee64bbc69a8e2f8, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)", "Size2":40}, "123456789.123456" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#16
02:10:31.155: 448 FINE [DirectDatagramSocketImpl]: (E496,T448) 127.0.0.1:8,279 ==> :8,278 [ACK        MID=11815] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:31.155: 448 FINE [DirectDatagramSocketImpl]: (E497,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=11815, Token=9ee64bbc69a8e2f8, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=0)", "Block1":"(szx=0/16, m=false, num=1)", "Size2":40}, "123456789.123456"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:31.156: 453 FINE [DirectDatagramSocketImpl]: (E498,T453) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=11816, Token=9ee64bbc69a8e2f8, OptionSet={"Uri-Path":"separate", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=1)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:31.157: 461 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=11816, Token=9ee64bbc69a8e2f8, OptionSet={"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:57) receiveRequest() in thread CoapServer#7
02:10:31.157: 461 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=11816, Token=9ee64bbc69a8e2f8, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#7
02:10:31.158: 448 FINE [DirectDatagramSocketImpl]: (E499,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=11816, Token=9ee64bbc69a8e2f8, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=true, num=1)"}, "789.123456789.12"] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:31.159: 453 FINE [DirectDatagramSocketImpl]: (E500,T453) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=11817, Token=9ee64bbc69a8e2f8, OptionSet={"Uri-Path":"separate", "Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, no payload] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:10:31.160: 460 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=11817, Token=9ee64bbc69a8e2f8, OptionSet={"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:57) receiveRequest() in thread CoapServer#6
02:10:31.160: 460 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=11817, Token=9ee64bbc69a8e2f8, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789." - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#6
02:10:31.161: 448 FINE [DirectDatagramSocketImpl]: (E501,T448) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=11817, Token=9ee64bbc69a8e2f8, OptionSet={"Content-Format":"text/plain", "Block2":"(szx=0/16, m=false, num=2)"}, "3456789."] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
Wait until deduplicator should be empty (0.647 seconds)
02:10:31.484:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8278 - (org.eclipse.californium.core.network.CoapEndpoint.java:445) stop() in thread main
02:10:31.484:   1 FINE [DirectDatagramSocketImpl]: closing port 8,278, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:10:31.484:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:249) destroy() in thread main
02:10:31.485:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:8279 - (org.eclipse.californium.core.network.CoapEndpoint.java:458) destroy() in thread main
02:10:31.486:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8279 - (org.eclipse.californium.core.network.CoapEndpoint.java:445) stop() in thread main
02:10:31.486:   1 FINE [DirectDatagramSocketImpl]: closing port 8,279, address localhost/127.0.0.1 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:158) close() in thread main
02:10:32.487:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:458) destroy() in thread main
02:10:32.488:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:276) destroy() in thread main
02:10:32.488:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.797 sec - in org.eclipse.californium.core.test.MemoryLeakingHashMapTest

Results :

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

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  2.284 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  1.514 s]
[INFO] element-connector .................................. SUCCESS [01:41 min]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 20.314 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 29.828 s]
[INFO] Californium (Cf) Proxy ............................. SKIPPED
[INFO] Californium (Cf) OSGi Bundle ....................... SKIPPED
[INFO] Californium (Cf) Demo Applications ................. SKIPPED
[INFO] Sc-DTLS-Example-Server ............................. SKIPPED
[INFO] Sc-DTLS-Example-Client ............................. SKIPPED
[INFO] Cf-PlugtestClient .................................. SKIPPED
[INFO] Cf-PlugtestChecker ................................. SKIPPED
[INFO] Cf-PlugtestServer .................................. SKIPPED
[INFO] Cf-HelloWorldClient ................................ SKIPPED
[INFO] Cf-HelloWorldServer ................................ SKIPPED
[INFO] Cf-SimpleFileServer ................................ 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: 02:39 min
[INFO] Finished at: 2017-04-27T02:10:32-04:00
[INFO] Final Memory: 44M/1934M
[INFO] ------------------------------------------------------------------------
[INFO] o.h.m.e.h.MavenExecutionResultHandler - Build failed with exception(s)
[INFO] o.h.m.e.h.MavenExecutionResultHandler - [1] org.apache.maven.lifecycle.LifecycleExecutionException: Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test (medium-tests) on project californium-core: There are test failures.

Please refer to /jobs/genie.californium/2.0.x-nightly/workspace/californium-core/target/surefire-reports for the individual test results.
[DEBUG] Closing connection to remote
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test (medium-tests) on project californium-core: There are test failures.
[ERROR] 
[ERROR] Please refer to /jobs/genie.californium/2.0.x-nightly/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