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

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

Changes:

[Achim Kraus] Fix NullPointerException in unit test logs.

[Achim Kraus] Restart executor on start() after stop().

[Achim Kraus] Fix getURI() with empty uri query.

------------------------------------------
[...truncated 3430 lines...]
02:07:50.779: 435 FINE [DirectDatagramSocketImpl]: (E432,T435) 127.0.0.1:8,277 ==> :8,276 [CON-GET    MID=54396, Token=e83e37c76c00e70c, 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:07:50.779: 442 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:07:50.780: 439 FINE [DirectDatagramSocketImpl]: (E433,T439) 127.0.0.1:8,276 ==> :8,277 [ACK-4.04   MID=54396, Token=e83e37c76c00e70c, 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:07:50.781: 435 FINE [DirectDatagramSocketImpl]: (E434,T435) 127.0.0.1:8,277 ==> :8,276 [CON-GET    MID=54397, Token=117637d9c70d970c, 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:07:50.782: 444 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:07:50.782: 439 FINE [DirectDatagramSocketImpl]: (E435,T439) 127.0.0.1:8,276 ==> :8,277 [ACK-4.04   MID=54397, Token=117637d9c70d970c, 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:07:50.783:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:249) destroy() in thread main
02:07:50.783:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:8276 - (org.eclipse.californium.core.network.CoapEndpoint.java:458) destroy() in thread main
02:07:50.784:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8276 - (org.eclipse.californium.core.network.CoapEndpoint.java:445) stop() in thread main
02:07:50.784:   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:07:50.784:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:458) destroy() in thread main
02:07:50.784:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:276) destroy() in thread main
End ResourceTreeTest
02:07:50.785:   1 INFO [NetworkRule]: 0 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:299) closeConfig() in thread main
02:07:50.785:   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:07:50.785:   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:07:50.785:   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:07:50.786:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:277) applyConfig() in thread main
02:07:50.787:   1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:406) start() in thread main
02:07:50.788:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:07:50.788:   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:07:50.788:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,278 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
02:07:50.789:   1 INFO [CoapEndpoint]: Started endpoint at coap://localhost:8278 - (org.eclipse.californium.core.network.CoapEndpoint.java:415) start() in thread main
02:07:50.791:   1 INFO [CoapServer]: Starting server - (org.eclipse.californium.core.CoapServer.java:201) start() in thread main
02:07:50.791:   1 INFO [CoapEndpoint]: Starting endpoint at coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:406) start() in thread main
02:07:50.791:   1 INFO [NetworkRule]: 1 rules active. - (org.eclipse.californium.elements.rule.NetworkRule.java:425) isActive() in thread main
02:07:50.792:   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:07:50.792:   1 FINE [DirectDatagramSocketImpl]: assigned port 8,279 - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:366) bind() in thread main
02:07:50.793:   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.028 sec - in org.eclipse.californium.core.test.ResourceTreeTest
Running org.eclipse.californium.core.test.MemoryLeakingHashMapTest
02:07:50.795: 454 FINE [DirectDatagramSocketImpl]: (E436,T454) 127.0.0.1:8,278 ==> :8,279 [NON-POST   MID=30879, Token=2d1dad842a7f7837, 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:07:50.795: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req NON-POST   MID=30879, Token=2d1dad842a7f7837, 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:07:50.796: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.31   MID= 5650, Token=2d1dad842a7f7837, 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:07:50.797: 449 FINE [DirectDatagramSocketImpl]: (E437,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.31   MID= 5650, Token=2d1dad842a7f7837, 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:07:50.797: 454 FINE [DirectDatagramSocketImpl]: (E438,T454) 127.0.0.1:8,278 ==> :8,279 [NON-POST   MID=30880, Token=2d1dad842a7f7837, 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:07:50.798: 457 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req NON-POST   MID=30880, Token=2d1dad842a7f7837, 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:07:50.799: 457 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.01   MID= 5651, Token=2d1dad842a7f7837, 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:07:50.800: 449 FINE [DirectDatagramSocketImpl]: (E439,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.01   MID= 5651, Token=2d1dad842a7f7837, 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:07:50.801: 454 FINE [DirectDatagramSocketImpl]: (E440,T454) 127.0.0.1:8,278 ==> :8,279 [NON-POST   MID=30881, Token=2d1dad842a7f7837, 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:07:50.801: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req NON-POST   MID=30881, Token=2d1dad842a7f7837, 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:07:50.802: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.01   MID= 5652, Token=2d1dad842a7f7837, 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:07:50.803: 449 FINE [DirectDatagramSocketImpl]: (E441,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.01   MID= 5652, Token=2d1dad842a7f7837, 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:07:50.804: 454 FINE [DirectDatagramSocketImpl]: (E442,T454) 127.0.0.1:8,278 ==> :8,279 [NON-POST   MID=30882, Token=2d1dad842a7f7837, 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:07:50.804: 459 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req NON-POST   MID=30882, Token=2d1dad842a7f7837, 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:07:50.805: 459 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.01   MID= 5653, Token=2d1dad842a7f7837, 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:07:50.806: 449 FINE [DirectDatagramSocketImpl]: (E443,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.01   MID= 5653, Token=2d1dad842a7f7837, 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:07:51.130: 454 FINE [DirectDatagramSocketImpl]: (E444,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30883, Token=123d6bf0b90e2a0b, 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:07:51.130: 461 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30883, Token=123d6bf0b90e2a0b, OptionSet={"Observe":0, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#6
02:07:51.131: 461 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8278#123d6bf0b90e2a0b and resource /piggy - (org.eclipse.californium.core.CoapResource.java:691) addObserveRelation() in thread CoapServer#6
02:07:51.131: 461 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30883, Token=123d6bf0b90e2a0b, OptionSet={"Observe":1, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#6
02:07:51.132: 449 FINE [DirectDatagramSocketImpl]: (E445,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30883, Token=123d6bf0b90e2a0b, 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:07:51.191: 452 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID= 5654, Token=123d6bf0b90e2a0b, 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:07:51.192: 449 FINE [DirectDatagramSocketImpl]: (E446,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID= 5654, Token=123d6bf0b90e2a0b, 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:07:51.392: 452 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID= 5655, Token=123d6bf0b90e2a0b, 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:07:51.393: 449 FINE [DirectDatagramSocketImpl]: (E447,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID= 5655, Token=123d6bf0b90e2a0b, 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:07:51.593: 452 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID= 5656, Token=123d6bf0b90e2a0b, 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:07:51.593: 449 FINE [DirectDatagramSocketImpl]: (E448,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID= 5656, Token=123d6bf0b90e2a0b, 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:07:51.594: 454 FINE [DirectDatagramSocketImpl]: (E449,T454) 127.0.0.1:8,278 ==> :8,279 [RST        MID= 5656] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:07:51.594: 458 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> emp RST        MID= 5656 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:67) receiveEmptyMessage() in thread CoapServer#3
02:07:51.780: 454 FINE [DirectDatagramSocketImpl]: (E450,T454) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=30884, Token=fb4ac0e9c8eeb128, 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:07:51.780: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=30884, Token=fb4ac0e9c8eeb128, 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:07:51.781: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.31   MID=30884, Token=fb4ac0e9c8eeb128, 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:07:51.782: 449 FINE [DirectDatagramSocketImpl]: (E451,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.31   MID=30884, Token=fb4ac0e9c8eeb128, 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:07:51.783: 454 FINE [DirectDatagramSocketImpl]: (E452,T454) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=30885, Token=fb4ac0e9c8eeb128, 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:07:51.783: 463 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=30885, Token=fb4ac0e9c8eeb128, 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:07:51.784: 463 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=30885, Token=fb4ac0e9c8eeb128, 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:07:51.785: 449 FINE [DirectDatagramSocketImpl]: (E453,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=30885, Token=fb4ac0e9c8eeb128, 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:07:51.786: 454 FINE [DirectDatagramSocketImpl]: (E454,T454) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=30886, Token=fb4ac0e9c8eeb128, 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:07:51.787: 457 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=30886, Token=fb4ac0e9c8eeb128, 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:07:51.787: 457 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=30886, Token=fb4ac0e9c8eeb128, 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:07:51.788: 449 FINE [DirectDatagramSocketImpl]: (E455,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=30886, Token=fb4ac0e9c8eeb128, 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:07:51.789: 454 FINE [DirectDatagramSocketImpl]: (E456,T454) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=30887, Token=fb4ac0e9c8eeb128, 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:07:51.789: 461 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=30887, Token=fb4ac0e9c8eeb128, 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:07:51.790: 461 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=30887, Token=fb4ac0e9c8eeb128, 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:07:51.791: 449 FINE [DirectDatagramSocketImpl]: (E457,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=30887, Token=fb4ac0e9c8eeb128, 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:07:52.113: 454 FINE [DirectDatagramSocketImpl]: (E458,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30888, Token=85415d0120de30ff, 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:07:52.114: 467 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30888, Token=85415d0120de30ff, OptionSet={"Uri-Path":"separate"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#10
02:07:52.114: 467 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== emp ACK        MID=30888 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:52) sendEmptyMessage() in thread CoapServer#10
02:07:52.115: 449 FINE [DirectDatagramSocketImpl]: (E459,T449) 127.0.0.1:8,279 ==> :8,278 [ACK        MID=30888] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:07:52.115: 467 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res CON-2.05   MID= 5657, Token=85415d0120de30ff, OptionSet={"Content-Format":"text/plain"}, "simple GET" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#10
02:07:52.115: 449 FINE [DirectDatagramSocketImpl]: (E460,T449) 127.0.0.1:8,279 ==> :8,278 [CON-2.05   MID= 5657, Token=85415d0120de30ff, 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:07:52.116: 454 FINE [DirectDatagramSocketImpl]: (E461,T454) 127.0.0.1:8,278 ==> :8,279 [ACK        MID= 5657] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:07:52.116: 470 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> emp ACK        MID= 5657 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:67) receiveEmptyMessage() in thread CoapServer#11
02:07:52.438: 454 FINE [DirectDatagramSocketImpl]: (E462,T454) 127.0.0.1:8,278 ==> :8,279 [NON-GET    MID=30889, Token=643a8729e6beecb9, 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:07:52.438: 459 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req NON-GET    MID=30889, Token=643a8729e6beecb9, OptionSet={"Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#4
02:07:52.439: 459 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID= 5658, Token=643a8729e6beecb9, OptionSet={"Content-Format":"text/plain"}, "simple NON GET" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#4
02:07:52.439: 449 FINE [DirectDatagramSocketImpl]: (E463,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID= 5658, Token=643a8729e6beecb9, 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:07:52.762: 454 FINE [DirectDatagramSocketImpl]: (E464,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30890, Token=8c078166c150cc5f, 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:07:52.763: 473 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30890, Token=8c078166c150cc5f, OptionSet={"Observe":0, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#14
02:07:52.763: 473 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8278#8c078166c150cc5f and resource /piggy - (org.eclipse.californium.core.CoapResource.java:691) addObserveRelation() in thread CoapServer#14
02:07:52.764: 473 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30890, Token=8c078166c150cc5f, 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#14
02:07:52.764: 449 FINE [DirectDatagramSocketImpl]: (E465,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30890, Token=8c078166c150cc5f, 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:07:52.765: 454 FINE [DirectDatagramSocketImpl]: (E466,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30891, Token=01d470cc5032090a, 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:07:52.766: 463 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30891, Token=01d470cc5032090a, 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#8
02:07:52.766: 463 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30891, Token=01d470cc5032090a, 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#8
02:07:52.767: 449 FINE [DirectDatagramSocketImpl]: (E467,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30891, Token=01d470cc5032090a, 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:07:52.768: 454 FINE [DirectDatagramSocketImpl]: (E468,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30892, Token=01d470cc5032090a, 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:07:52.768: 475 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30892, Token=01d470cc5032090a, 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#16
02:07:52.769: 475 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30892, Token=01d470cc5032090a, 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#16
02:07:52.770: 449 FINE [DirectDatagramSocketImpl]: (E469,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30892, Token=01d470cc5032090a, 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:07:52.794: 452 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID= 5659, Token=8c078166c150cc5f, 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:07:52.795: 449 FINE [DirectDatagramSocketImpl]: (E470,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID= 5659, Token=8c078166c150cc5f, 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:07:52.796: 454 FINE [DirectDatagramSocketImpl]: (E471,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30893, Token=33ff8fd5f77cd9ed, 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:07:52.796: 462 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30893, Token=33ff8fd5f77cd9ed, 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#7
02:07:52.797: 462 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30893, Token=33ff8fd5f77cd9ed, 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:07:52.798: 449 FINE [DirectDatagramSocketImpl]: (E472,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30893, Token=33ff8fd5f77cd9ed, 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:07:52.799: 454 FINE [DirectDatagramSocketImpl]: (E473,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30894, Token=33ff8fd5f77cd9ed, 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:07:52.799: 461 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30894, Token=33ff8fd5f77cd9ed, 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#6
02:07:52.800: 461 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30894, Token=33ff8fd5f77cd9ed, 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:07:52.801: 449 FINE [DirectDatagramSocketImpl]: (E474,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30894, Token=33ff8fd5f77cd9ed, 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:07:52.995: 452 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID= 5660, Token=8c078166c150cc5f, 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:07:52.996: 449 FINE [DirectDatagramSocketImpl]: (E475,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID= 5660, Token=8c078166c150cc5f, 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:07:52.997: 454 FINE [DirectDatagramSocketImpl]: (E476,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30895, Token=95ba49c276ab3b7d, 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:07:52.997: 479 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30895, Token=95ba49c276ab3b7d, 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#18
02:07:52.998: 479 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30895, Token=95ba49c276ab3b7d, 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#18
02:07:52.999: 449 FINE [DirectDatagramSocketImpl]: (E477,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30895, Token=95ba49c276ab3b7d, 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:07:53.000: 454 FINE [DirectDatagramSocketImpl]: (E478,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30896, Token=95ba49c276ab3b7d, 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:07:53.000: 467 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30896, Token=95ba49c276ab3b7d, 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#10
02:07:53.000: 467 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30896, Token=95ba49c276ab3b7d, 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#10
02:07:53.001: 449 FINE [DirectDatagramSocketImpl]: (E479,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30896, Token=95ba49c276ab3b7d, 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:07:53.002: 454 FINE [DirectDatagramSocketImpl]: (E480,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30897, Token=8c078166c150cc5f, 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:07:53.003: 481 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30897, Token=8c078166c150cc5f, OptionSet={"Observe":1, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#20
02:07:53.003: 481 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30897, Token=8c078166c150cc5f, 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#20
02:07:53.004: 449 FINE [DirectDatagramSocketImpl]: (E481,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30897, Token=8c078166c150cc5f, 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:07:53.005: 454 FINE [DirectDatagramSocketImpl]: (E482,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30898, Token=8c078166c150cc5f, 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:07:53.005: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30898, Token=8c078166c150cc5f, 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#1
02:07:53.006: 456 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30898, Token=8c078166c150cc5f, 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:07:53.007: 449 FINE [DirectDatagramSocketImpl]: (E483,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30898, Token=8c078166c150cc5f, 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:07:53.008: 454 FINE [DirectDatagramSocketImpl]: (E484,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30899, Token=8c078166c150cc5f, 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:07:53.008: 472 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30899, Token=8c078166c150cc5f, 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#13
02:07:53.008: 472 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30899, Token=8c078166c150cc5f, 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#13
02:07:53.009: 449 FINE [DirectDatagramSocketImpl]: (E485,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30899, Token=8c078166c150cc5f, 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:07:53.268: 454 FINE [DirectDatagramSocketImpl]: (E486,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30900, Token=73d3f8d525f8c0aa, 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:07:53.268: 459 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30900, Token=73d3f8d525f8c0aa, OptionSet={"Observe":0, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#4
02:07:53.269: 459 INFO [CoapResource]: Successfully established observe relation between localhost/127.0.0.1:8278#73d3f8d525f8c0aa and resource /piggy - (org.eclipse.californium.core.CoapResource.java:691) addObserveRelation() in thread CoapServer#4
02:07:53.269: 459 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30900, Token=73d3f8d525f8c0aa, OptionSet={"Observe":12, "Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#4
02:07:53.270: 449 FINE [DirectDatagramSocketImpl]: (E487,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30900, Token=73d3f8d525f8c0aa, 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:07:53.396: 452 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID= 5661, Token=73d3f8d525f8c0aa, 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:07:53.397: 449 FINE [DirectDatagramSocketImpl]: (E488,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID= 5661, Token=73d3f8d525f8c0aa, 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:07:53.597: 452 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res NON-2.05   MID= 5662, Token=73d3f8d525f8c0aa, 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:07:53.598: 449 FINE [DirectDatagramSocketImpl]: (E489,T449) 127.0.0.1:8,279 ==> :8,278 [NON-2.05   MID= 5662, Token=73d3f8d525f8c0aa, 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:07:53.599: 454 FINE [DirectDatagramSocketImpl]: (E490,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30901, Token=73d3f8d525f8c0aa, 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:07:53.599: 487 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30901, Token=73d3f8d525f8c0aa, OptionSet={"Observe":1, "Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#22
02:07:53.599: 487 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30901, Token=73d3f8d525f8c0aa, OptionSet={"Content-Format":"text/plain"}, "Hello observer" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#22
02:07:53.600: 449 FINE [DirectDatagramSocketImpl]: (E491,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30901, Token=73d3f8d525f8c0aa, 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:07:53.923: 454 FINE [DirectDatagramSocketImpl]: (E492,T454) 127.0.0.1:8,278 ==> :8,279 [CON-GET    MID=30902, Token=86385bbf9a54a9aa, 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:07:53.923: 473 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-GET    MID=30902, Token=86385bbf9a54a9aa, OptionSet={"Uri-Path":"piggy"}, no payload - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:57) receiveRequest() in thread CoapServer#14
02:07:53.924: 473 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.05   MID=30902, Token=86385bbf9a54a9aa, OptionSet={"Content-Format":"text/plain"}, "simple GET" - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:47) sendResponse() in thread CoapServer#14
02:07:53.924: 449 FINE [DirectDatagramSocketImpl]: (E493,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.05   MID=30902, Token=86385bbf9a54a9aa, 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:07:54.247: 454 FINE [DirectDatagramSocketImpl]: (E494,T454) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=30903, Token=89bf8a0b6511da06, 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:07:54.248: 489 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=30903, Token=89bf8a0b6511da06, 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:07:54.248: 489 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.31   MID=30903, Token=89bf8a0b6511da06, 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:07:54.249: 449 FINE [DirectDatagramSocketImpl]: (E495,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.31   MID=30903, Token=89bf8a0b6511da06, 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:07:54.250: 454 FINE [DirectDatagramSocketImpl]: (E496,T454) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=30904, Token=89bf8a0b6511da06, 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:07:54.250: 457 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=30904, Token=89bf8a0b6511da06, 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#2
02:07:54.251: 457 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== emp ACK        MID=30904 - (org.eclipse.californium.core.network.interceptors.MessageTracer.java:52) sendEmptyMessage() in thread CoapServer#2
02:07:54.252: 449 FINE [DirectDatagramSocketImpl]: (E497,T449) 127.0.0.1:8,279 ==> :8,278 [ACK        MID=30904] - (org.eclipse.californium.elements.util.DirectDatagramSocketImpl.java:208) receive() in thread UDP-Receiver-localhost/127.0.0.1:0[0]
02:07:54.252: 457 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=30904, Token=89bf8a0b6511da06, 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:07:54.253: 449 FINE [DirectDatagramSocketImpl]: (E498,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=30904, Token=89bf8a0b6511da06, 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:07:54.254: 454 FINE [DirectDatagramSocketImpl]: (E499,T454) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=30905, Token=89bf8a0b6511da06, 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:07:54.254: 476 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=30905, Token=89bf8a0b6511da06, 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#17
02:07:54.255: 476 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=30905, Token=89bf8a0b6511da06, 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#17
02:07:54.256: 449 FINE [DirectDatagramSocketImpl]: (E500,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=30905, Token=89bf8a0b6511da06, 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:07:54.257: 454 FINE [DirectDatagramSocketImpl]: (E501,T454) 127.0.0.1:8,278 ==> :8,279 [CON-POST   MID=30906, Token=89bf8a0b6511da06, 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:07:54.257: 458 INFO [MessageTracer]: localhost/127.0.0.1:8,278 ==> req CON-POST   MID=30906, Token=89bf8a0b6511da06, 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#3
02:07:54.258: 458 INFO [MessageTracer]: localhost/127.0.0.1:8,278 <== res ACK-2.01   MID=30906, Token=89bf8a0b6511da06, 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#3
02:07:54.259: 449 FINE [DirectDatagramSocketImpl]: (E502,T449) 127.0.0.1:8,279 ==> :8,278 [ACK-2.01   MID=30906, Token=89bf8a0b6511da06, 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:07:54.580:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8278 - (org.eclipse.californium.core.network.CoapEndpoint.java:445) stop() in thread main
02:07:54.581:   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:07:54.581:   1 INFO [CoapServer]: Destroying server - (org.eclipse.californium.core.CoapServer.java:249) destroy() in thread main
02:07:54.582:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:8279 - (org.eclipse.californium.core.network.CoapEndpoint.java:458) destroy() in thread main
02:07:54.583:   1 INFO [CoapEndpoint]: Stopping endpoint at address coap://localhost:8279 - (org.eclipse.californium.core.network.CoapEndpoint.java:445) stop() in thread main
02:07:54.583:   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:07:55.585:   1 INFO [CoapEndpoint]: Destroying endpoint at address coap://localhost:0 - (org.eclipse.californium.core.network.CoapEndpoint.java:458) destroy() in thread main
02:07:55.585:   1 INFO [CoapServer]: CoAP server has been destroyed - (org.eclipse.californium.core.CoapServer.java:276) destroy() in thread main
02:07:55.585:   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.792 sec - in org.eclipse.californium.core.test.MemoryLeakingHashMapTest

Results :

Tests in error: 
  BlockwiseClientSideTest.testPUTFailsWith408OnIncompleteTransfer:391 NullPointer

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

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  2.163 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  2.361 s]
[INFO] element-connector .................................. SUCCESS [01:31 min]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 22.280 s]
[INFO] Californium (Cf) Core .............................. FAILURE [ 29.964 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:32 min
[INFO] Finished at: 2017-04-29T02:07:55-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