Skipping 13,188 KB..
Full Log08:19:51.653 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 49 bytes to 127.0.0.1:8292
08:19:51.653 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,586,T427) 127.0.0.1:8,291 ==> :8,292 [ACK-2.04 MID=12456, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=true, num=1)"}, 18 98 1F C3 3E 58 28 8D 9E 11 B9 DA DC 54 84 EC 21 F4 72 3E 9A 86 B7 AC 2E CA C0 62 50 E1 C6 47]
08:19:51.653 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8292) received 49 bytes from 127.0.0.1:8291
08:19:51.653 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:51.653 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap requesting next Block2 [num=2]: CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=2)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.653 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1517] replace CON-GET MID=12456, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=1)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, acked <empty data> by CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=2)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.654 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1517] send request
08:19:51.654 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1517] prepare retransmission for CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=2)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.654 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1517] added with generated mid KeyMID[127.0.0.1:8291-12457], CON-GET MID=12457, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=2)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.654 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1517] for MID KeyMID[127.0.0.1:8291-12456]
08:19:51.654 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1517] keep for KeyToken[127.0.0.1:8291-40BBC513332A0E35], CON-GET MID=12457, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=2)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.654 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8291-12457], KeyToken[127.0.0.1:8291-40BBC513332A0E35]]
08:19:51.654 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 48 bytes to 127.0.0.1:8291
08:19:51.654 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,587,T424) 127.0.0.1:8,292 ==> :8,291 [CON-GET MID=12457, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=2)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>]
08:19:51.654 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8291) received 48 bytes from 127.0.0.1:8292
08:19:51.654 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8292-12457]
08:19:51.654 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap peer has requested intermediary block of blockwise transfer: [KeyUri[coap:/, 127.0.0.1:8292]: currentNum=2, currentSzx=1, bufferSize=138, complete=false]
08:19:51.654 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R1522] send response null-2.04 MID= -1, Token=null, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=true, num=2)"}, F0 37 57 AD 8C 76 B8 7E F4 39 60 FF D6 4D 07 53 FD E6 BA EE 5B CB 54 A1 AF 1D C4 70 83 A0 7F FD
08:19:51.654 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R1522, complete]!
08:19:51.654 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R1522, complete] completed ACK-2.04 MID=12457, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=true, num=2)"}, F0 37 57 AD 8C 76 B8 7E F4 39 60 FF D6 4D 07 53 FD E6 BA EE 5B CB 54 A1 AF 1D C4 70 83 A0 7F FD!
08:19:51.655 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 49 bytes to 127.0.0.1:8292
08:19:51.655 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,588,T427) 127.0.0.1:8,291 ==> :8,292 [ACK-2.04 MID=12457, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=true, num=2)"}, F0 37 57 AD 8C 76 B8 7E F4 39 60 FF D6 4D 07 53 FD E6 BA EE 5B CB 54 A1 AF 1D C4 70 83 A0 7F FD]
08:19:51.655 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8292) received 49 bytes from 127.0.0.1:8291
08:19:51.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:51.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap requesting next Block2 [num=3]: CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=3)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1517] replace CON-GET MID=12457, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=2)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, acked <empty data> by CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=3)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1517] send request
08:19:51.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1517] prepare retransmission for CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=3)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1517] added with generated mid KeyMID[127.0.0.1:8291-12458], CON-GET MID=12458, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=3)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1517] for MID KeyMID[127.0.0.1:8291-12457]
08:19:51.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1517] keep for KeyToken[127.0.0.1:8291-40BBC513332A0E35], CON-GET MID=12458, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=3)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8291-12458], KeyToken[127.0.0.1:8291-40BBC513332A0E35]]
08:19:51.655 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 48 bytes to 127.0.0.1:8291
08:19:51.655 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,589,T424) 127.0.0.1:8,292 ==> :8,291 [CON-GET MID=12458, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=3)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>]
08:19:51.655 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8291) received 48 bytes from 127.0.0.1:8292
08:19:51.655 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8292-12458]
08:19:51.656 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap peer has requested intermediary block of blockwise transfer: [KeyUri[coap:/, 127.0.0.1:8292]: currentNum=3, currentSzx=1, bufferSize=138, complete=false]
08:19:51.656 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R1523] send response null-2.04 MID= -1, Token=null, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=true, num=3)"}, AB 5F 67 6E A8 DE A9 27 F9 3F E8 B3 94 CA 4C CB 0F 75 B1 41 4F 39 6C 27 88 28 70 8D E4 9B F1 06
08:19:51.656 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R1523, complete]!
08:19:51.656 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R1523, complete] completed ACK-2.04 MID=12458, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=true, num=3)"}, AB 5F 67 6E A8 DE A9 27 F9 3F E8 B3 94 CA 4C CB 0F 75 B1 41 4F 39 6C 27 88 28 70 8D E4 9B F1 06!
08:19:51.656 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 49 bytes to 127.0.0.1:8292
08:19:51.656 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,590,T427) 127.0.0.1:8,291 ==> :8,292 [ACK-2.04 MID=12458, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=true, num=3)"}, AB 5F 67 6E A8 DE A9 27 F9 3F E8 B3 94 CA 4C CB 0F 75 B1 41 4F 39 6C 27 88 28 70 8D E4 9B F1 06]
08:19:51.656 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8292) received 49 bytes from 127.0.0.1:8291
08:19:51.656 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:51.656 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap requesting next Block2 [num=4]: CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=4)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.656 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1517] replace CON-GET MID=12458, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=3)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, acked <empty data> by CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=4)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.656 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1517] send request
08:19:51.656 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1517] prepare retransmission for CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=4)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.657 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1517] added with generated mid KeyMID[127.0.0.1:8291-12459], CON-GET MID=12459, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=4)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.657 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1517] for MID KeyMID[127.0.0.1:8291-12458]
08:19:51.657 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1517] keep for KeyToken[127.0.0.1:8291-40BBC513332A0E35], CON-GET MID=12459, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=4)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.657 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8291-12459], KeyToken[127.0.0.1:8291-40BBC513332A0E35]]
08:19:51.657 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 48 bytes to 127.0.0.1:8291
08:19:51.657 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,591,T424) 127.0.0.1:8,292 ==> :8,291 [CON-GET MID=12459, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=4)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>]
08:19:51.657 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8291) received 48 bytes from 127.0.0.1:8292
08:19:51.657 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8292-12459]
08:19:51.657 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap peer has requested last block of blockwise transfer: [KeyUri[coap:/, 127.0.0.1:8292]: currentNum=4, currentSzx=1, bufferSize=138, complete=true]
08:19:51.657 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R1524] send response null-2.04 MID= -1, Token=null, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=4)"}, 3A C0 9D 98 E0 F8 FF 6D DC 1C
08:19:51.658 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R1524, complete]!
08:19:51.658 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R1524, complete] completed ACK-2.04 MID=12459, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=4)"}, 3A C0 9D 98 E0 F8 FF 6D DC 1C!
08:19:51.658 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 27 bytes to 127.0.0.1:8292
08:19:51.658 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,592,T427) 127.0.0.1:8,291 ==> :8,292 [ACK-2.04 MID=12459, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=4)"}, 3A C0 9D 98 E0 F8 FF 6D DC 1C]
08:19:51.658 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8292) received 27 bytes from 127.0.0.1:8291
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap all blocks have been retrieved, assembling response and delivering to application layer
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap removing block2 tracker [KeyUri[coap:/, 127.0.0.1:8291]], block2 transfers still in progress: 0
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap assembled response: ACK-2.04 MID=12455, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Size2":138}, 18 C2 85 0B 9B 68 13 59 59 B3 74 C9 AB D1 CF 53 0D 88 B9 0C C3 B0 DA B4 92 C5 1D 79 ED 9E B0 02..138 bytes
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1517] replace CON-GET MID=12459, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Block2":"(szx=1/32, m=false, num=4)", "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, acked <empty data> by CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1517, complete]!
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1517, complete] for token KeyToken[127.0.0.1:8291-40BBC513332A0E35]
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1517, complete] for MID KeyMID[127.0.0.1:8291-12459]
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - local Exchange[L1517, complete] completed CON-GET MID= -1, Token=40BBC513332A0E35, OptionSet={"Object-Security":0x, "Proxy-Uri":"coap://127.0.0.1:8290/resource"}, <empty data>!
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.oscore.ObjectSecurityContextLayer - Incoming OSCORE response uses outer block-wise
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ResponseDecryptor - Removes E options from outer options which are not allowed there
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:51.658 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not considered due to it being disabled for this context
==[ CoAP Response ]============================================
MID : 12455
Token : 40BBC513332A0E35
Type : ACK
Status : 2.05 - CONTENT
Options: {"Object-Security":0x}
RTT : 68 ms
Payload: 128 Bytes
===============================================================
08:19:51.659 [main] DEBUG org.eclipse.californium.rule.CoapThreadsRule - shutdown
08:19:51.659 [main] INFO org.eclipse.californium.core.CoapServer - Destroying server
08:19:51.659 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://127.0.0.1:8290
08:19:51.659 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://127.0.0.1:8290
08:19:51.659 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8290] stopping ...
08:19:51.659 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8290, address /127.0.0.1
08:19:51.659 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8290] has stopped.
08:19:51.659 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://127.0.0.1:8290
08:19:51.659 [main] INFO org.eclipse.californium.core.CoapServer - CoAP server has been destroyed
08:19:51.659 [main] DEBUG org.eclipse.californium.rule.CoapThreadsRule - shutdown
08:19:51.659 [main] INFO org.eclipse.californium.core.CoapServer - Destroying server
08:19:51.659 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://127.0.0.1:8291
08:19:51.659 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://127.0.0.1:8291
08:19:51.659 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8291] stopping ...
08:19:51.659 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8291, address /127.0.0.1
08:19:51.660 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8291] has stopped.
08:19:51.660 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://127.0.0.1:8291
08:19:51.660 [main] INFO org.eclipse.californium.core.CoapServer - CoAP server has been destroyed
08:19:51.660 [main] DEBUG org.eclipse.californium.rule.CoapThreadsRule - shutdown
08:19:51.660 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://0.0.0.0:8292
08:19:51.660 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://0.0.0.0:8292
08:19:51.660 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8292] stopping ...
08:19:51.660 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8292, address /0.0.0.0
08:19:51.660 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8292] has stopped.
08:19:51.660 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://0.0.0.0:8292
08:19:51.660 [main] DEBUG org.eclipse.californium.rule.CoapThreadsRule - shutdown
08:19:51.660 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://0.0.0.0:8293
08:19:51.660 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://0.0.0.0:8293
08:19:51.660 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8293] stopping ...
08:19:51.660 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8293, address /0.0.0.0
08:19:51.660 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8293] has stopped.
08:19:51.660 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://0.0.0.0:8293
08:19:51.661 [main] INFO org.eclipse.californium.elements.rule.TestNameLoggerRule - Test testOuterBlockwiseGetProxyClientBW
08:19:51.661 [main] INFO org.eclipse.californium.elements.rule.TestNameLoggerRule - Test testOuterBlockwisePostProxyServerBW
08:19:51.662 [main] INFO org.eclipse.californium.core.network.RandomTokenGenerator - using tokens of 8 bytes in length
08:19:51.662 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap using TokenProvider org.eclipse.californium.core.network.RandomTokenGenerator
08:19:51.662 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap CoapEndpoint uses udp context
08:19:51.662 [main] INFO org.eclipse.californium.core.network.stack.BlockwiseLayer - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
08:19:51.662 [main] INFO org.eclipse.californium.core.CoapServer - Starting server
08:19:51.662 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Starting endpoint at coap://127.0.0.1:0
08:19:51.662 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap no MessageIdProvider set, using default org.eclipse.californium.core.network.InMemoryMessageIdProvider
08:19:51.663 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 1 rules active.
08:19:51.663 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - binding to port 0, address localhost/127.0.0.1
08:19:51.663 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - assigned port 8294
08:19:51.663 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector starts up 1 sender threads and 1 receiver threads
08:19:51.663 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector listening on /127.0.0.1:8294, recv buf = 0, send buf = 0, recv packet size = 2048
08:19:51.663 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Receiver-localhost/127.0.0.1:0[0]]
08:19:51.663 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Started endpoint at coap://127.0.0.1:8294
08:19:51.663 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Sender-localhost/127.0.0.1:0[0]]
08:19:51.663 [main] INFO org.eclipse.californium.core.network.RandomTokenGenerator - using tokens of 8 bytes in length
08:19:51.663 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap using TokenProvider org.eclipse.californium.core.network.RandomTokenGenerator
08:19:51.663 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap CoapEndpoint uses udp context
08:19:51.663 [main] INFO org.eclipse.californium.core.network.stack.BlockwiseLayer - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
08:19:51.663 [main] INFO org.eclipse.californium.core.CoapServer - Starting server
08:19:51.664 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Starting endpoint at coap://127.0.0.1:0
08:19:51.664 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap no MessageIdProvider set, using default org.eclipse.californium.core.network.InMemoryMessageIdProvider
08:19:51.664 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 1 rules active.
08:19:51.664 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - binding to port 0, address localhost/127.0.0.1
08:19:51.664 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - assigned port 8295
08:19:51.664 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector starts up 1 sender threads and 1 receiver threads
08:19:51.664 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector listening on /127.0.0.1:8295, recv buf = 0, send buf = 0, recv packet size = 2048
08:19:51.664 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Receiver-localhost/127.0.0.1:0[0]]
08:19:51.664 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Started endpoint at coap://127.0.0.1:8295
08:19:51.664 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Sender-localhost/127.0.0.1:0[0]]
08:19:51.664 [main] INFO org.eclipse.californium.core.network.RandomTokenGenerator - using tokens of 8 bytes in length
08:19:51.664 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap using TokenProvider org.eclipse.californium.core.network.RandomTokenGenerator
08:19:51.665 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap CoapEndpoint uses udp context
08:19:51.665 [main] INFO org.eclipse.californium.core.network.stack.BlockwiseLayer - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
08:19:51.665 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Endpoint [coap://0.0.0.0:0] requires an executor to start, using default single-threaded daemon executor
08:19:51.665 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Starting endpoint at coap://0.0.0.0:0
08:19:51.665 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap no MessageIdProvider set, using default org.eclipse.californium.core.network.InMemoryMessageIdProvider
08:19:51.665 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 1 rules active.
08:19:51.665 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - binding to port 0, address 0.0.0.0/0.0.0.0
08:19:51.665 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - assigned port 8296
08:19:51.665 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector starts up 1 sender threads and 1 receiver threads
08:19:51.665 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector listening on 0.0.0.0/0.0.0.0:8296, recv buf = 0, send buf = 0, recv packet size = 2048
08:19:51.665 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]]
08:19:51.665 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Started endpoint at coap://0.0.0.0:8296
08:19:51.665 [main] INFO org.eclipse.californium.core.CoapClient - started set client endpoint 0.0.0.0/0.0.0.0:8296
08:19:51.665 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Sender-0.0.0.0/0.0.0.0:0[0]]
08:19:51.666 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityContextLayer - Request: CON-POST MID= -1, Token=null, OptionSet={"Object-Security":0x, "Content-Format":"text/plain", "Accept":"text/plain", "Proxy-Uri":"coap://127.0.0.1:8294/resource"}, "98257387005764743526667350535128".. 128 bytes
08:19:51.666 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1525] send request
08:19:51.666 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1525] prepare retransmission for CON-POST MID= -1, Token=null, OptionSet={"Object-Security":0x, "Content-Format":"text/plain", "Accept":"text/plain", "Proxy-Uri":"coap://127.0.0.1:8294/resource"}, "98257387005764743526667350535128".. 128 bytes
08:19:51.666 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Request: CON-POST MID= -1, Token=null, OptionSet={"Object-Security":0x, "Content-Format":"text/plain", "Accept":"text/plain", "Proxy-Uri":"coap://127.0.0.1:8294/resource"}, "98257387005764743526667350535128".. 128 bytes
08:19:51.666 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1525] replace CON-POST MID= -1, Token=null, OptionSet={"Object-Security":0x, "Content-Format":"text/plain", "Accept":"text/plain", "Proxy-Uri":"coap://127.0.0.1:8294/resource"}, "98257387005764743526667350535128".. 128 bytes by CON-POST MID= -1, Token=null, OptionSet={"Object-Security":0x0900, "Proxy-Uri":"coap://127.0.0.1:8294"}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9..149 bytes
08:19:51.666 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1525] added with generated mid KeyMID[127.0.0.1:8295-19351], CON-POST MID=19351, Token=null, OptionSet={"Object-Security":0x0900, "Proxy-Uri":"coap://127.0.0.1:8294"}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9..149 bytes
08:19:51.667 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1525] added with generated token KeyToken[127.0.0.1:8295-804C05F4F184563B], CON-POST MID=19351, Token=804C05F4F184563B, OptionSet={"Object-Security":0x0900, "Proxy-Uri":"coap://127.0.0.1:8294"}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9..149 bytes
08:19:51.667 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8295-19351], KeyToken[127.0.0.1:8295-804C05F4F184563B]]
08:19:51.667 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 189 bytes to 127.0.0.1:8295
08:19:51.667 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,593,T440) 127.0.0.1:8,296 ==> :8,295 [CON-POST MID=19351, Token=804C05F4F184563B, OptionSet={"Object-Security":0x0900, "Proxy-Uri":"coap://127.0.0.1:8294"}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9..149 bytes]
08:19:51.667 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8295) received 189 bytes from 127.0.0.1:8296
08:19:51.667 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8296-19351]
08:19:51.667 [CoapServer(main)#1] DEBUG org.eclipse.californium.proxy2.Coap2CoapTranslator - Incoming request translated correctly
08:19:51.667 [CoapServer(main)#1] INFO org.eclipse.californium.core.network.RandomTokenGenerator - using tokens of 8 bytes in length
08:19:51.667 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap using TokenProvider org.eclipse.californium.core.network.RandomTokenGenerator
08:19:51.667 [CoapServer(main)#1] INFO org.eclipse.californium.core.network.CoapEndpoint - coap CoapEndpoint uses udp context
08:19:51.667 [CoapServer(main)#1] INFO org.eclipse.californium.core.network.stack.BlockwiseLayer - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=32, PREFERRED_BLOCK_SIZE=32, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
08:19:51.668 [CoapServer(main)#1] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Endpoint [coap://0.0.0.0:0] requires an executor to start, using default single-threaded daemon executor
08:19:51.668 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Starting endpoint at coap://0.0.0.0:0
08:19:51.668 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap no MessageIdProvider set, using default org.eclipse.californium.core.network.InMemoryMessageIdProvider
08:19:51.668 [CoapServer(main)#1] INFO org.eclipse.californium.elements.rule.NetworkRule - 1 rules active.
08:19:51.668 [CoapServer(main)#1] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - binding to port 0, address 0.0.0.0/0.0.0.0
08:19:51.668 [CoapServer(main)#1] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - assigned port 8297
08:19:51.668 [CoapServer(main)#1] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector starts up 1 sender threads and 1 receiver threads
08:19:51.668 [CoapServer(main)#1] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector listening on 0.0.0.0/0.0.0.0:8297, recv buf = 0, send buf = 0, recv packet size = 2048
08:19:51.668 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]]
08:19:51.668 [CoapServer(main)#1] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Started endpoint at coap://0.0.0.0:8297
08:19:51.668 [CoapServer(main)#1] INFO org.eclipse.californium.core.CoapClient - started set client endpoint 0.0.0.0/0.0.0.0:8297
08:19:51.668 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Sender-0.0.0.0/0.0.0.0:0[0]]
08:19:51.668 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap request body [149/32] requires blockwise transfer
08:19:51.668 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap created tracker for outbound block1 transfer [KeyUri[coap:/, 127.0.0.1:8294]: currentNum=0, currentSzx=0, bufferSize=149, complete=false], transfers in progress: 1
08:19:51.668 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1527] replace CON-POST MID= -1, Token=null, OptionSet={"Object-Security":0x0900}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9..149 bytes by CON-POST MID= -1, Token=null, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=0)", "Size1":149}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9
08:19:51.668 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1527] send request
08:19:51.668 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1527] prepare retransmission for CON-POST MID= -1, Token=null, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=0)", "Size1":149}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9
08:19:51.669 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1527] added with generated mid KeyMID[127.0.0.1:8294-31942], CON-POST MID=31942, Token=null, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=0)", "Size1":149}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9
08:19:51.669 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1527] added with generated token KeyToken[127.0.0.1:8294-C4B8034146E2BE03], CON-POST MID=31942, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=0)", "Size1":149}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9
08:19:51.669 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8294-31942], KeyToken[127.0.0.1:8294-C4B8034146E2BE03]]
08:19:51.669 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 54 bytes to 127.0.0.1:8294
08:19:51.669 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,594,T435) 127.0.0.1:8,297 ==> :8,294 [CON-POST MID=31942, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=0)", "Size1":149}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9]
08:19:51.669 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8294) received 54 bytes from 127.0.0.1:8297
08:19:51.669 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8297-31942]
08:19:51.669 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap inbound request contains block1 option (szx=1/32, m=true, num=0)
08:19:51.669 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap created tracker for inbound block1 transfer [KeyUri[coap:/, 127.0.0.1:8297]: currentNum=0, currentSzx=0, bufferSize=149, complete=false], transfers in progress: 1
08:19:51.669 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap acknowledging incoming block1 [num=0], expecting more blocks to come
08:19:51.669 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R1528] send response null-2.31 MID= -1, Token=null, OptionSet={"Block1":"(szx=1/32, m=true, num=0)"}, <empty data>
08:19:51.669 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R1528, complete]!
08:19:51.669 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R1528, complete] completed ACK-2.31 MID=31942, Token=C4B8034146E2BE03, OptionSet={"Block1":"(szx=1/32, m=true, num=0)"}, <empty data>!
08:19:51.669 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 15 bytes to 127.0.0.1:8297
08:19:51.670 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,595,T446) 127.0.0.1:8,294 ==> :8,297 [ACK-2.31 MID=31942, Token=C4B8034146E2BE03, OptionSet={"Block1":"(szx=1/32, m=true, num=0)"}, <empty data>]
08:19:51.670 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8297) received 15 bytes from 127.0.0.1:8294
08:19:51.670 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap received response acknowledging block1 (szx=1/32, m=true, num=0)
08:19:51.670 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap sending (next) Block1 [num=1]: CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=1)"}, 19 1A 41 82 85 24 58 93 23 A0 C3 06 1E 6C 72 AD 20 C4 2C 56 9C 4E 6C 83 3F 2B BF 21 12 21 FA 37
08:19:51.670 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1527] replace CON-POST MID=31942, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=0)", "Size1":149}, acked AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9 by CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=1)"}, 19 1A 41 82 85 24 58 93 23 A0 C3 06 1E 6C 72 AD 20 C4 2C 56 9C 4E 6C 83 3F 2B BF 21 12 21 FA 37
08:19:51.670 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1527] send request
08:19:51.670 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1527] prepare retransmission for CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=1)"}, 19 1A 41 82 85 24 58 93 23 A0 C3 06 1E 6C 72 AD 20 C4 2C 56 9C 4E 6C 83 3F 2B BF 21 12 21 FA 37
08:19:51.670 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1527] added with generated mid KeyMID[127.0.0.1:8294-31943], CON-POST MID=31943, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=1)"}, 19 1A 41 82 85 24 58 93 23 A0 C3 06 1E 6C 72 AD 20 C4 2C 56 9C 4E 6C 83 3F 2B BF 21 12 21 FA 37
08:19:51.670 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1527] for MID KeyMID[127.0.0.1:8294-31942]
08:19:51.670 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1527] keep for KeyToken[127.0.0.1:8294-C4B8034146E2BE03], CON-POST MID=31943, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=1)"}, 19 1A 41 82 85 24 58 93 23 A0 C3 06 1E 6C 72 AD 20 C4 2C 56 9C 4E 6C 83 3F 2B BF 21 12 21 FA 37
08:19:51.670 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8294-31943], KeyToken[127.0.0.1:8294-C4B8034146E2BE03]]
08:19:51.670 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 51 bytes to 127.0.0.1:8294
08:19:51.670 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,596,T435) 127.0.0.1:8,297 ==> :8,294 [CON-POST MID=31943, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=1)"}, 19 1A 41 82 85 24 58 93 23 A0 C3 06 1E 6C 72 AD 20 C4 2C 56 9C 4E 6C 83 3F 2B BF 21 12 21 FA 37]
08:19:51.671 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8294) received 51 bytes from 127.0.0.1:8297
08:19:51.671 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8297-31943]
08:19:51.671 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap inbound request contains block1 option (szx=1/32, m=true, num=1)
08:19:51.671 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap block1 transfer KeyUri[coap:/, 127.0.0.1:8297] for CON-POST MID=31943, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=1)"}, 19 1A 41 82 85 24 58 93 23 A0 C3 06 1E 6C 72 AD 20 C4 2C 56 9C 4E 6C 83 3F 2B BF 21 12 21 FA 37
08:19:51.671 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap acknowledging incoming block1 [num=1], expecting more blocks to come
08:19:51.671 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R1529] send response null-2.31 MID= -1, Token=null, OptionSet={"Block1":"(szx=1/32, m=true, num=1)"}, <empty data>
08:19:51.671 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R1529, complete]!
08:19:51.671 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R1529, complete] completed ACK-2.31 MID=31943, Token=C4B8034146E2BE03, OptionSet={"Block1":"(szx=1/32, m=true, num=1)"}, <empty data>!
08:19:51.671 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 15 bytes to 127.0.0.1:8297
08:19:51.671 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,597,T446) 127.0.0.1:8,294 ==> :8,297 [ACK-2.31 MID=31943, Token=C4B8034146E2BE03, OptionSet={"Block1":"(szx=1/32, m=true, num=1)"}, <empty data>]
08:19:51.671 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8297) received 15 bytes from 127.0.0.1:8294
08:19:51.671 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap received response acknowledging block1 (szx=1/32, m=true, num=1)
08:19:51.671 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap sending (next) Block1 [num=2]: CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=2)"}, E5 F0 F3 29 70 8D C4 02 E8 81 44 BB 3F EC EF 1B 98 AB 7C 5D EE 41 E5 50 60 82 45 A5 BE CA D2 4B
08:19:51.671 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1527] replace CON-POST MID=31943, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=1)"}, acked 19 1A 41 82 85 24 58 93 23 A0 C3 06 1E 6C 72 AD 20 C4 2C 56 9C 4E 6C 83 3F 2B BF 21 12 21 FA 37 by CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=2)"}, E5 F0 F3 29 70 8D C4 02 E8 81 44 BB 3F EC EF 1B 98 AB 7C 5D EE 41 E5 50 60 82 45 A5 BE CA D2 4B
08:19:51.671 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1527] send request
08:19:51.671 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1527] prepare retransmission for CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=2)"}, E5 F0 F3 29 70 8D C4 02 E8 81 44 BB 3F EC EF 1B 98 AB 7C 5D EE 41 E5 50 60 82 45 A5 BE CA D2 4B
08:19:51.672 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1527] added with generated mid KeyMID[127.0.0.1:8294-31944], CON-POST MID=31944, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=2)"}, E5 F0 F3 29 70 8D C4 02 E8 81 44 BB 3F EC EF 1B 98 AB 7C 5D EE 41 E5 50 60 82 45 A5 BE CA D2 4B
08:19:51.672 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1527] for MID KeyMID[127.0.0.1:8294-31943]
08:19:51.672 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1527] keep for KeyToken[127.0.0.1:8294-C4B8034146E2BE03], CON-POST MID=31944, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=2)"}, E5 F0 F3 29 70 8D C4 02 E8 81 44 BB 3F EC EF 1B 98 AB 7C 5D EE 41 E5 50 60 82 45 A5 BE CA D2 4B
08:19:51.672 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8294-31944], KeyToken[127.0.0.1:8294-C4B8034146E2BE03]]
08:19:51.672 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 51 bytes to 127.0.0.1:8294
08:19:51.672 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,598,T435) 127.0.0.1:8,297 ==> :8,294 [CON-POST MID=31944, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=2)"}, E5 F0 F3 29 70 8D C4 02 E8 81 44 BB 3F EC EF 1B 98 AB 7C 5D EE 41 E5 50 60 82 45 A5 BE CA D2 4B]
08:19:51.672 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8294) received 51 bytes from 127.0.0.1:8297
08:19:51.672 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8297-31944]
08:19:51.672 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap inbound request contains block1 option (szx=1/32, m=true, num=2)
08:19:51.672 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap block1 transfer KeyUri[coap:/, 127.0.0.1:8297] for CON-POST MID=31944, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=2)"}, E5 F0 F3 29 70 8D C4 02 E8 81 44 BB 3F EC EF 1B 98 AB 7C 5D EE 41 E5 50 60 82 45 A5 BE CA D2 4B
08:19:51.672 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap acknowledging incoming block1 [num=2], expecting more blocks to come
08:19:51.672 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R1530] send response null-2.31 MID= -1, Token=null, OptionSet={"Block1":"(szx=1/32, m=true, num=2)"}, <empty data>
08:19:51.672 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R1530, complete]!
08:19:51.672 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R1530, complete] completed ACK-2.31 MID=31944, Token=C4B8034146E2BE03, OptionSet={"Block1":"(szx=1/32, m=true, num=2)"}, <empty data>!
08:19:51.672 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 15 bytes to 127.0.0.1:8297
08:19:51.672 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,599,T446) 127.0.0.1:8,294 ==> :8,297 [ACK-2.31 MID=31944, Token=C4B8034146E2BE03, OptionSet={"Block1":"(szx=1/32, m=true, num=2)"}, <empty data>]
08:19:51.672 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8297) received 15 bytes from 127.0.0.1:8294
08:19:51.673 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap received response acknowledging block1 (szx=1/32, m=true, num=2)
08:19:51.673 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap sending (next) Block1 [num=3]: CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=3)"}, 78 2A E2 C6 8D 15 52 35 76 80 0B 70 79 BE CA 2D A8 66 5B 05 0A 25 B6 A3 37 99 39 6E 66 9E 24 AB
08:19:51.673 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1527] replace CON-POST MID=31944, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=2)"}, acked E5 F0 F3 29 70 8D C4 02 E8 81 44 BB 3F EC EF 1B 98 AB 7C 5D EE 41 E5 50 60 82 45 A5 BE CA D2 4B by CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=3)"}, 78 2A E2 C6 8D 15 52 35 76 80 0B 70 79 BE CA 2D A8 66 5B 05 0A 25 B6 A3 37 99 39 6E 66 9E 24 AB
08:19:51.673 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1527] send request
08:19:51.673 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1527] prepare retransmission for CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=3)"}, 78 2A E2 C6 8D 15 52 35 76 80 0B 70 79 BE CA 2D A8 66 5B 05 0A 25 B6 A3 37 99 39 6E 66 9E 24 AB
08:19:51.673 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1527] added with generated mid KeyMID[127.0.0.1:8294-31945], CON-POST MID=31945, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=3)"}, 78 2A E2 C6 8D 15 52 35 76 80 0B 70 79 BE CA 2D A8 66 5B 05 0A 25 B6 A3 37 99 39 6E 66 9E 24 AB
08:19:51.673 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1527] for MID KeyMID[127.0.0.1:8294-31944]
08:19:51.673 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1527] keep for KeyToken[127.0.0.1:8294-C4B8034146E2BE03], CON-POST MID=31945, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=3)"}, 78 2A E2 C6 8D 15 52 35 76 80 0B 70 79 BE CA 2D A8 66 5B 05 0A 25 B6 A3 37 99 39 6E 66 9E 24 AB
08:19:51.673 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8294-31945], KeyToken[127.0.0.1:8294-C4B8034146E2BE03]]
08:19:51.673 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 51 bytes to 127.0.0.1:8294
08:19:51.673 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,600,T435) 127.0.0.1:8,297 ==> :8,294 [CON-POST MID=31945, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=3)"}, 78 2A E2 C6 8D 15 52 35 76 80 0B 70 79 BE CA 2D A8 66 5B 05 0A 25 B6 A3 37 99 39 6E 66 9E 24 AB]
08:19:51.673 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8294) received 51 bytes from 127.0.0.1:8297
08:19:51.673 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8297-31945]
08:19:51.673 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap inbound request contains block1 option (szx=1/32, m=true, num=3)
08:19:51.673 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap block1 transfer KeyUri[coap:/, 127.0.0.1:8297] for CON-POST MID=31945, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=3)"}, 78 2A E2 C6 8D 15 52 35 76 80 0B 70 79 BE CA 2D A8 66 5B 05 0A 25 B6 A3 37 99 39 6E 66 9E 24 AB
08:19:51.674 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap acknowledging incoming block1 [num=3], expecting more blocks to come
08:19:51.674 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R1531] send response null-2.31 MID= -1, Token=null, OptionSet={"Block1":"(szx=1/32, m=true, num=3)"}, <empty data>
08:19:51.674 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R1531, complete]!
08:19:51.674 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R1531, complete] completed ACK-2.31 MID=31945, Token=C4B8034146E2BE03, OptionSet={"Block1":"(szx=1/32, m=true, num=3)"}, <empty data>!
08:19:51.674 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 15 bytes to 127.0.0.1:8297
08:19:51.674 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,601,T446) 127.0.0.1:8,294 ==> :8,297 [ACK-2.31 MID=31945, Token=C4B8034146E2BE03, OptionSet={"Block1":"(szx=1/32, m=true, num=3)"}, <empty data>]
08:19:51.674 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8297) received 15 bytes from 127.0.0.1:8294
08:19:51.674 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap received response acknowledging block1 (szx=1/32, m=true, num=3)
08:19:51.674 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap sending (next) Block1 [num=4]: CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=false, num=4)"}, EF 9C 91 FD 5E 02 91 9D 23 FF 96 04 85 00 45 D5 01 FE A2 D7 00
08:19:51.674 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1527] replace CON-POST MID=31945, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=true, num=3)"}, acked 78 2A E2 C6 8D 15 52 35 76 80 0B 70 79 BE CA 2D A8 66 5B 05 0A 25 B6 A3 37 99 39 6E 66 9E 24 AB by CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=false, num=4)"}, EF 9C 91 FD 5E 02 91 9D 23 FF 96 04 85 00 45 D5 01 FE A2 D7 00
08:19:51.674 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1527] send request
08:19:51.674 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1527] prepare retransmission for CON-POST MID= -1, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=false, num=4)"}, EF 9C 91 FD 5E 02 91 9D 23 FF 96 04 85 00 45 D5 01 FE A2 D7 00
08:19:51.674 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1527] added with generated mid KeyMID[127.0.0.1:8294-31946], CON-POST MID=31946, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=false, num=4)"}, EF 9C 91 FD 5E 02 91 9D 23 FF 96 04 85 00 45 D5 01 FE A2 D7 00
08:19:51.674 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1527] for MID KeyMID[127.0.0.1:8294-31945]
08:19:51.674 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1527] keep for KeyToken[127.0.0.1:8294-C4B8034146E2BE03], CON-POST MID=31946, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=false, num=4)"}, EF 9C 91 FD 5E 02 91 9D 23 FF 96 04 85 00 45 D5 01 FE A2 D7 00
08:19:51.674 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8294-31946], KeyToken[127.0.0.1:8294-C4B8034146E2BE03]]
08:19:51.675 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 40 bytes to 127.0.0.1:8294
08:19:51.675 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,602,T435) 127.0.0.1:8,297 ==> :8,294 [CON-POST MID=31946, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=false, num=4)"}, EF 9C 91 FD 5E 02 91 9D 23 FF 96 04 85 00 45 D5 01 FE A2 D7 00]
08:19:51.675 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8294) received 40 bytes from 127.0.0.1:8297
08:19:51.675 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8297-31946]
08:19:51.675 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap inbound request contains block1 option (szx=1/32, m=false, num=4)
08:19:51.675 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap block1 transfer KeyUri[coap:/, 127.0.0.1:8297] for CON-POST MID=31946, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=false, num=4)"}, EF 9C 91 FD 5E 02 91 9D 23 FF 96 04 85 00 45 D5 01 FE A2 D7 00
08:19:51.675 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap peer has sent last block1 [num=4], delivering request to application layer
08:19:51.675 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap removing block1 tracker [KeyUri[coap:/, 127.0.0.1:8297]], block1 transfers still in progress: 0
08:19:51.675 [CoapServer(main)#1] DEBUG org.eclipse.californium.oscore.ObjectSecurityContextLayer - Incoming OSCORE request uses outer block-wise
08:19:51.675 [CoapServer(main)#1] INFO org.eclipse.californium.oscore.RequestDecryptor - Removes E options from outer options which are not allowed there
08:19:51.675 [CoapServer(main)#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:51.675 [CoapServer(main)#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not initiated due to it being disabled for this context
08:19:51.676 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R1532] send response null-2.05 MID= -1, Token=null, OptionSet={"Block1":"(szx=1/32, m=false, num=4)"}, "98257387005764743526667350535128".. 256 bytes
08:19:51.676 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R1532, complete]!
08:19:51.676 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R1532, complete] completed ACK-2.04 MID=31946, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x, "Block1":"(szx=1/32, m=false, num=4)"}, 18 C2 85 0B 9B 68 13 59 59 B3 74 C9 AB D1 CF 53 0D 88 B9 0C C3 B0 DA B4 92 C5 1D 79 ED 9E B0 02..266 bytes!
08:19:51.676 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 283 bytes to 127.0.0.1:8297
08:19:51.676 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,603,T446) 127.0.0.1:8,294 ==> :8,297 [ACK-2.04 MID=31946, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x, "Block1":"(szx=1/32, m=false, num=4)"}, 18 C2 85 0B 9B 68 13 59 59 B3 74 C9 AB D1 CF 53 0D 88 B9 0C C3 B0 DA B4 92 C5 1D 79 ED 9E B0 02..266 bytes]
08:19:51.676 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8297) received 283 bytes from 127.0.0.1:8294
08:19:51.676 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.BlockwiseLayer - coap received response acknowledging block1 (szx=1/32, m=false, num=4)
08:19:51.676 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1527, complete]!
08:19:51.676 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1527, complete] for token KeyToken[127.0.0.1:8294-C4B8034146E2BE03]
08:19:51.676 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1527, complete] for MID KeyMID[127.0.0.1:8294-31946]
08:19:51.676 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - local Exchange[L1527, complete] completed CON-POST MID=31942, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900}, AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9..149 bytes -/- CON-POST MID=31946, Token=C4B8034146E2BE03, OptionSet={"Object-Security":0x0900, "Block1":"(szx=1/32, m=false, num=4)"}, acked EF 9C 91 FD 5E 02 91 9D 23 FF 96 04 85 00 45 D5 01 FE A2 D7 00!
08:19:51.676 [CoapServer(main)#1] DEBUG org.eclipse.californium.proxy2.Coap2CoapTranslator - Incoming response translated correctly
08:19:51.676 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R1526] send response null-2.04 MID= -1, Token=null, OptionSet={"Object-Security":0x, "Block1":"(szx=1/32, m=false, num=4)"}, 18 C2 85 0B 9B 68 13 59 59 B3 74 C9 AB D1 CF 53 0D 88 B9 0C C3 B0 DA B4 92 C5 1D 79 ED 9E B0 02..266 bytes
08:19:51.677 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R1526, complete]!
08:19:51.677 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R1526, complete] completed ACK-2.04 MID=19351, Token=804C05F4F184563B, OptionSet={"Object-Security":0x, "Block1":"(szx=1/32, m=false, num=4)"}, 18 C2 85 0B 9B 68 13 59 59 B3 74 C9 AB D1 CF 53 0D 88 B9 0C C3 B0 DA B4 92 C5 1D 79 ED 9E B0 02..266 bytes!
08:19:51.677 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 283 bytes to 127.0.0.1:8296
08:19:51.677 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,604,T443) 127.0.0.1:8,295 ==> :8,296 [ACK-2.04 MID=19351, Token=804C05F4F184563B, OptionSet={"Object-Security":0x, "Block1":"(szx=1/32, m=false, num=4)"}, 18 C2 85 0B 9B 68 13 59 59 B3 74 C9 AB D1 CF 53 0D 88 B9 0C C3 B0 DA B4 92 C5 1D 79 ED 9E B0 02..266 bytes]
08:19:51.677 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8296) received 283 bytes from 127.0.0.1:8295
08:19:51.677 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:51.677 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ResponseDecryptor - Removes E options from outer options which are not allowed there
08:19:51.677 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:51.677 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not considered due to it being disabled for this context
08:19:51.677 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1525, complete]!
08:19:51.677 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1525, complete] for token KeyToken[127.0.0.1:8295-804C05F4F184563B]
08:19:51.677 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1525, complete] for MID KeyMID[127.0.0.1:8295-19351]
08:19:51.677 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - local Exchange[L1525, complete] completed CON-POST MID= -1, Token=804C05F4F184563B, OptionSet={"Object-Security":0x, "Content-Format":"text/plain", "Accept":"text/plain", "Proxy-Uri":"coap://127.0.0.1:8294/resource"}, "98257387005764743526667350535128".. 128 bytes -/- CON-POST MID=19351, Token=804C05F4F184563B, OptionSet={"Object-Security":0x0900, "Proxy-Uri":"coap://127.0.0.1:8294"}, acked AD 81 2C 10 62 83 58 E4 DD E3 C6 2C 3D D8 6A 71 04 62 0C AE 80 E1 65 13 43 05 A1 0A 0C BC 8F A9..149 bytes!
==[ CoAP Response ]============================================
MID : 19351
Token : 804C05F4F184563B
Type : ACK
Status : 2.05 - CONTENT
Options: {"Object-Security":0x}
RTT : 11 ms
Payload: 256 Bytes
===============================================================
08:19:51.677 [main] DEBUG org.eclipse.californium.rule.CoapThreadsRule - shutdown
08:19:51.677 [main] INFO org.eclipse.californium.core.CoapServer - Destroying server
08:19:51.678 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://127.0.0.1:8294
08:19:51.678 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://127.0.0.1:8294
08:19:51.678 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8294] stopping ...
08:19:51.678 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8294, address /127.0.0.1
08:19:51.678 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8294] has stopped.
08:19:51.678 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://127.0.0.1:8294
08:19:51.678 [main] INFO org.eclipse.californium.core.CoapServer - CoAP server has been destroyed
08:19:51.678 [main] DEBUG org.eclipse.californium.rule.CoapThreadsRule - shutdown
08:19:51.678 [main] INFO org.eclipse.californium.core.CoapServer - Destroying server
08:19:51.678 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://127.0.0.1:8295
08:19:51.678 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://127.0.0.1:8295
08:19:51.678 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8295] stopping ...
08:19:51.678 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8295, address /127.0.0.1
08:19:51.678 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8295] has stopped.
08:19:51.678 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://127.0.0.1:8295
08:19:51.678 [main] INFO org.eclipse.californium.core.CoapServer - CoAP server has been destroyed
08:19:51.678 [main] DEBUG org.eclipse.californium.rule.CoapThreadsRule - shutdown
08:19:51.678 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://0.0.0.0:8296
08:19:51.678 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://0.0.0.0:8296
08:19:51.678 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8296] stopping ...
08:19:51.678 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8296, address /0.0.0.0
08:19:51.679 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8296] has stopped.
08:19:51.679 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://0.0.0.0:8296
08:19:51.679 [main] DEBUG org.eclipse.californium.rule.CoapThreadsRule - shutdown
08:19:51.679 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://0.0.0.0:8297
08:19:51.679 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://0.0.0.0:8297
08:19:51.679 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8297] stopping ...
08:19:51.679 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8297, address /0.0.0.0
08:19:51.679 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8297] has stopped.
08:19:51.679 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://0.0.0.0:8297
08:19:51.679 [main] INFO org.eclipse.californium.elements.rule.TestNameLoggerRule - Test testOuterBlockwisePostProxyServerBW
08:19:51.680 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 0 rules active.
[INFO] Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.305 s - in org.eclipse.californium.oscore.OSCoreOuterBlockwiseTest
[INFO]
[INFO] Results:
[INFO]
[INFO] Tests run: 44, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO]
[INFO] --- maven-jar-plugin:3.2.2:jar (default-jar) @ cf-oscore ---
[INFO]
[INFO] --- maven-javadoc-plugin:3.3.0:jar (attach-javadocs) @ cf-oscore ---
[INFO] Toolchain in maven-javadoc-plugin: JDK[/opt/tools/java/oracle/jdk-7/latest]
[ERROR] Error fetching link: /home/jenkins/agent/workspace/californium-3.3.x-nightly/legal/target/apidocs. Ignored it.
[INFO] Configuration changed, re-generating javadoc.
[INFO]
Loading source files for package org.eclipse.californium.cose...
Loading source files for package org.eclipse.californium.oscore...
Constructing Javadoc information...
Standard Doclet version 1.7.0_80
Building tree for all the packages and classes...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/AlgorithmID.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/Attribute.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/CoseException.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/Encrypt0Message.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/EncryptCommon.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/HeaderKeys.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/Message.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/MessageTag.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/ByteId.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/CoapOSException.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/CoapOSExceptionHandler.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/ContextRederivation.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/ContextRederivation.PHASE.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/Decryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/Encryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/ErrorDescriptions.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/HashMapCtxDB.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/ObjectSecurityContextLayer.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/ObjectSecurityLayer.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OptionJuggle.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OSCoreCoapStackFactory.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OSCoreCtx.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OSCoreCtxDB.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OSCoreEndpointContextInfo.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OscoreOptionDecoder.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OscoreOptionEncoder.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OSCoreResource.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OSCoreStack.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OSException.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/OSSerializer.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/RequestDecryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/RequestEncryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/ResponseDecryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/ResponseEncryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/overview-frame.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/package-frame.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/package-summary.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/package-tree.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/package-frame.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/package-summary.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/package-tree.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/constant-values.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/serialized-form.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/class-use/MessageTag.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/class-use/Message.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/class-use/HeaderKeys.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/class-use/EncryptCommon.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/class-use/Encrypt0Message.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/class-use/CoseException.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/class-use/Attribute.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/class-use/AlgorithmID.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/ResponseEncryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/ResponseDecryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/RequestEncryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/RequestDecryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OscoreOptionEncoder.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OscoreOptionDecoder.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OptionJuggle.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/ObjectSecurityLayer.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/ObjectSecurityContextLayer.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OSSerializer.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OSException.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OSCoreStack.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OSCoreResource.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OSCoreEndpointContextInfo.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OSCoreCtxDB.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OSCoreCtx.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/OSCoreCoapStackFactory.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/HashMapCtxDB.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/ErrorDescriptions.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/Encryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/Decryptor.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/ContextRederivation.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/ContextRederivation.PHASE.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/CoapOSExceptionHandler.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/CoapOSException.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/class-use/ByteId.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/cose/package-use.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/org/eclipse/californium/oscore/package-use.html...
Building index for all the packages and classes...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/overview-tree.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/index-all.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/deprecated-list.html...
Building index for all classes...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/allclasses-frame.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/allclasses-noframe.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/index.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/overview-summary.html...
Generating /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/apidocs/help-doc.html...
[INFO] Building jar: /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT-javadoc.jar
[INFO]
[INFO] --- maven-assembly-plugin:3.3.0:single (make-assembly) @ cf-oscore ---
[INFO] Building jar: /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT.jar
[INFO]
[INFO] --- maven-surefire-plugin:2.22.2:test (large-tests) @ cf-oscore ---
[INFO] Toolchain in maven-surefire-plugin: JDK[/opt/tools/java/oracle/jdk-7/latest]
[INFO]
[INFO] -------------------------------------------------------
[INFO] T E S T S
[INFO] -------------------------------------------------------
08:19:54.855 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 1 rules active.
08:19:54.869 [main] INFO org.eclipse.californium.elements.config.Configuration - defaults added COAP.
08:19:54.870 [main] INFO org.eclipse.californium.elements.config.Configuration - defaults added SYS.
08:19:54.871 [main] INFO org.eclipse.californium.elements.config.Configuration - defaults added UDP.
[INFO] Running org.eclipse.californium.oscore.OSCoreObserveTest
08:19:54.885 [main] INFO org.eclipse.californium.elements.rule.TestNameLoggerRule - Test testObserve
08:19:54.968 [main] INFO org.eclipse.californium.elements.util.JceProviderUtil - JCE default setup
08:19:54.969 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - Loading net.i2p.crypto.eddsa.EdDSASecurityProvider failed!
08:19:55.001 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - AES: restricted to 128 bits key length
08:19:55.001 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - RSA: true
08:19:55.002 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - EC: true
08:19:55.002 [main] INFO org.eclipse.californium.elements.util.JceProviderUtil - EdDSA not supported!
08:19:55.002 [main] INFO org.eclipse.californium.elements.util.JceProviderUtil - JCE setup: null, ready.
08:19:55.002 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - Security Provider [0]: SUN version 1.7.
08:19:55.002 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - Security Provider [1]: SunRsaSign version 1.7.
08:19:55.002 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - Security Provider [2]: SunEC version 1.7.
08:19:55.002 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - Security Provider [3]: SunJSSE version 1.7.
08:19:55.002 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - Security Provider [4]: SunJCE version 1.7.
08:19:55.002 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - Security Provider [5]: SunJGSS version 1.7.
08:19:55.002 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - Security Provider [6]: SunSASL version 1.7.
08:19:55.002 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - Security Provider [7]: XMLDSig version 1.0.
08:19:55.002 [main] DEBUG org.eclipse.californium.elements.util.JceProviderUtil - Security Provider [8]: SunPCSC version 1.7.
08:19:55.087 [main] INFO org.eclipse.californium.core.network.RandomTokenGenerator - using tokens of 8 bytes in length
08:19:55.095 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap using TokenProvider org.eclipse.californium.core.network.RandomTokenGenerator
08:19:55.096 [main] INFO org.eclipse.californium.ban - Started.
08:19:55.097 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap CoapEndpoint uses udp context
08:19:55.110 [main] INFO org.eclipse.californium.core.network.stack.BlockwiseLayer - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
08:19:55.126 [main] INFO org.eclipse.californium.core.CoapServer - Starting server
08:19:55.128 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Starting endpoint at coap://127.0.0.1:0
08:19:55.131 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap no MessageIdProvider set, using default org.eclipse.californium.core.network.InMemoryMessageIdProvider
08:19:55.133 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 1 rules active.
08:19:55.134 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - binding to port 0, address localhost/127.0.0.1
08:19:55.134 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - assigned port 8192
08:19:55.134 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector starts up 1 sender threads and 1 receiver threads
08:19:55.136 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector listening on /127.0.0.1:8192, recv buf = 0, send buf = 0, recv packet size = 2048
08:19:55.136 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Receiver-localhost/127.0.0.1:0[0]]
08:19:55.136 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Sender-localhost/127.0.0.1:0[0]]
08:19:55.136 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Started endpoint at coap://127.0.0.1:8192
08:19:55.145 [main] DEBUG org.eclipse.californium.elements.util.NetworkInterfacesUtil - Found broadcast address /10.40.41.255 - eth0.
08:19:55.145 [main] INFO org.eclipse.californium.core.network.RandomTokenGenerator - using tokens of 8 bytes in length
08:19:55.145 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap using TokenProvider org.eclipse.californium.core.network.RandomTokenGenerator
08:19:55.145 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap CoapEndpoint uses udp context
08:19:55.145 [main] INFO org.eclipse.californium.core.network.stack.BlockwiseLayer - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
08:19:55.146 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Endpoint [coap://0.0.0.0:0] requires an executor to start, using default single-threaded daemon executor
08:19:55.147 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Starting endpoint at coap://0.0.0.0:0
08:19:55.147 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap no MessageIdProvider set, using default org.eclipse.californium.core.network.InMemoryMessageIdProvider
08:19:55.147 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 1 rules active.
08:19:55.147 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - binding to port 0, address 0.0.0.0/0.0.0.0
08:19:55.147 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - assigned port 8193
08:19:55.147 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector starts up 1 sender threads and 1 receiver threads
08:19:55.148 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector listening on 0.0.0.0/0.0.0.0:8193, recv buf = 0, send buf = 0, recv packet size = 2048
08:19:55.148 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]]
08:19:55.148 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Sender-0.0.0.0/0.0.0.0:0[0]]
08:19:55.148 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Started endpoint at coap://0.0.0.0:8193
08:19:55.148 [main] INFO org.eclipse.californium.core.network.EndpointManager - created implicit endpoint coap://0.0.0.0:8193 for coap
08:19:55.156 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityContextLayer - Request: CON-GET MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.157 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1] send request
08:19:55.157 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L1] prepare retransmission for CON-GET MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.164 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Request: CON-GET MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.164 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1] replace CON-GET MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> by CON-FETCH MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:55.164 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.BaseMatcher - registering observe request CON-FETCH MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:55.165 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.observe.InMemoryObservationStore - added observation for Token=D889B08F75F9BB3F
08:19:55.166 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1] added with KeyMID[127.0.0.1:8192-38290], CON-FETCH MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:55.167 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L1] added with token KeyToken[127.0.0.1:8192-D889B08F75F9BB3F], CON-FETCH MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:55.168 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8192-38290], KeyToken[127.0.0.1:8192-D889B08F75F9BB3F]]
08:19:55.172 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 43 bytes to 127.0.0.1:8192
08:19:55.174 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E1,T14) 127.0.0.1:8,193 ==> :8,192 [CON-FETCH MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8]
08:19:55.174 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8192) received 43 bytes from 127.0.0.1:8193
08:19:55.176 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8193-38290]
08:19:55.177 [CoapServer(main)#1] INFO org.eclipse.californium.oscore.RequestDecryptor - Removes E options from outer options which are not allowed there
08:19:55.177 [CoapServer(main)#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:55.178 [CoapServer(main)#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not initiated due to it being disabled for this context
08:19:55.178 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R2] replace CON-FETCH MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> by CON-GET MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.179 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.server.ServerMessageDeliverer - initiating an observe relation between 127.0.0.1:8193 and resource /oscore/observe2, Exchange[R2]
08:19:55.180 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.observe.ObserveRelation - Observe-relation, checks every 120000000000ns or 100 notifications.
08:19:55.184 [CoapServer(main)#1] INFO org.eclipse.californium.core.CoapResource - successfully established observe relation between 127.0.0.1:8193#D889B08F75F9BB3F and resource /oscore/observe2 (Exchange[R2], size 1)
08:19:55.184 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R2] send response null-2.05 MID= -1, Token=null, OptionSet={"Observe":0, "Content-Format":"text/plain"}, "one"
08:19:55.187 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R2, complete]!
08:19:55.187 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R2, complete] completed ACK-2.04 MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0100}, 4D D3 3B FC 90 86 BC 88 2F C2 A2 05 C2 2E 96!
08:19:55.189 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 32 bytes to 127.0.0.1:8193
08:19:55.189 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E2,T17) 127.0.0.1:8,192 ==> :8,193 [ACK-2.04 MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0100}, 4D D3 3B FC 90 86 BC 88 2F C2 A2 05 C2 2E 96]
08:19:55.189 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8193) received 32 bytes from 127.0.0.1:8192
08:19:55.190 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:55.191 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ResponseDecryptor - Removes E options from outer options which are not allowed there
08:19:55.191 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:55.191 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not considered due to it being disabled for this context
08:19:55.191 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L1, complete]!
08:19:55.191 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1, complete] for token KeyToken[127.0.0.1:8192-D889B08F75F9BB3F]
08:19:55.191 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L1, complete] for MID KeyMID[127.0.0.1:8192-38290]
08:19:55.191 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - local Exchange[L1, complete] completed CON-GET MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> -/- CON-FETCH MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0900}, acked AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8!
08:19:55.193 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.coap.ClientObserveRelation - Updated with ACK-2.05 MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0100, "Content-Format":"text/plain"}, "one"
08:19:55.194 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.coap.ClientObserveRelation - Wait for 62000ms fresh notifies.
NOTIFICATION: one
08:19:55.194 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.core.test.CountingCoapHandler - Received 1. Notification: ACK-2.05 MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0100, "Content-Format":"text/plain"}, "one"
08:19:55.877 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R2] send response NON-2.05 MID= -1, Token=null, OptionSet={"Observe":1, "Content-Format":"text/plain"}, "two"
08:19:55.878 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[R2] added with generated mid KeyMID[127.0.0.1:8193-43539], NON-2.04 MID=43539, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x0101}, 52 82 A2 6B 2D 57 56 04 A8 18 12 C6 9A 79 95 9E
08:19:55.879 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 34 bytes to 127.0.0.1:8193
08:19:55.879 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E3,T17) 127.0.0.1:8,192 ==> :8,193 [NON-2.04 MID=43539, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x0101}, 52 82 A2 6B 2D 57 56 04 A8 18 12 C6 9A 79 95 9E]
08:19:55.879 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8193) received 34 bytes from 127.0.0.1:8192
08:19:55.880 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.observe.InMemoryObservationStore - looking up observation for token Token=D889B08F75F9BB3F: CON-FETCH MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0900}, acked AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:55.881 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.BaseMatcher - re-created exchange from original observe request: CON-FETCH MID=38290, Token=D889B08F75F9BB3F, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:55.882 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8192-43539]
08:19:55.882 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:55.882 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ResponseDecryptor - Removes E options from outer options which are not allowed there
08:19:55.882 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:55.882 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not considered due to it being disabled for this context
08:19:55.882 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L3, complete]!
08:19:55.883 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.coap.ClientObserveRelation - Updated with NON-2.05 MID=43539, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x0101, "Content-Format":"text/plain"}, "two"
08:19:55.883 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.coap.ClientObserveRelation - Wait for 62000ms fresh notifies.
NOTIFICATION: two
08:19:55.883 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.core.test.CountingCoapHandler - Received 2. Notification: NON-2.05 MID=43539, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x0101, "Content-Format":"text/plain"}, "two"
08:19:55.884 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityContextLayer - Request: CON-GET MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.884 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L4] send request
08:19:55.884 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L4] prepare retransmission for CON-GET MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.885 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Request: CON-GET MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.885 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L4] replace CON-GET MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> by CON-FETCH MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x0901}, 19 95 45 75 DB C3 A5 25 33 D0 8D 43 5B FD 48 B9 FB 14 0C C6 C9 B9 4B 14 B1 A5 0A
08:19:55.886 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L4] added with generated mid KeyMID[127.0.0.1:8192-38291], CON-FETCH MID=38291, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x0901}, 19 95 45 75 DB C3 A5 25 33 D0 8D 43 5B FD 48 B9 FB 14 0C C6 C9 B9 4B 14 B1 A5 0A
08:19:55.886 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L4] added with token KeyToken[127.0.0.1:8192-D889B08F75F9BB3F], CON-FETCH MID=38291, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x0901}, 19 95 45 75 DB C3 A5 25 33 D0 8D 43 5B FD 48 B9 FB 14 0C C6 C9 B9 4B 14 B1 A5 0A
08:19:55.886 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8192-38291], KeyToken[127.0.0.1:8192-D889B08F75F9BB3F]]
08:19:55.887 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 45 bytes to 127.0.0.1:8192
08:19:55.887 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E4,T14) 127.0.0.1:8,193 ==> :8,192 [CON-FETCH MID=38291, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x0901}, 19 95 45 75 DB C3 A5 25 33 D0 8D 43 5B FD 48 B9 FB 14 0C C6 C9 B9 4B 14 B1 A5 0A]
08:19:55.887 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8192) received 45 bytes from 127.0.0.1:8193
08:19:55.887 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8193-38291]
08:19:55.888 [CoapServer(main)#1] INFO org.eclipse.californium.oscore.RequestDecryptor - Removes E options from outer options which are not allowed there
08:19:55.888 [CoapServer(main)#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:55.888 [CoapServer(main)#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not initiated due to it being disabled for this context
08:19:55.888 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R5] replace CON-FETCH MID=38291, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> by CON-GET MID=38291, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.889 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.observe.ObserveRelation - Canceling observe relation 127.0.0.1:8193#D889B08F75F9BB3F with /oscore/observe2 (Exchange[R2])
08:19:55.889 [CoapServer(main)#1] INFO org.eclipse.californium.core.CoapResource - remove observe relation between 127.0.0.1:8193#D889B08F75F9BB3F and resource /oscore/observe2 (Exchange[R2], size 0)
08:19:55.890 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R5] send response null-2.05 MID= -1, Token=null, OptionSet={"Content-Format":"text/plain"}, "two"
08:19:55.890 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R5, complete]!
08:19:55.890 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R5, complete] completed ACK-2.04 MID=38291, Token=D889B08F75F9BB3F, OptionSet={"Object-Security":0x0102}, 37 10 24 E0 C9 5C 0F C8 AD EE 14 BD B2 0E!
08:19:55.891 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R2, complete]!
08:19:55.891 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 30 bytes to 127.0.0.1:8193
08:19:55.891 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[R2, complete] for MID KeyMID[127.0.0.1:8193-43539]
08:19:55.891 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R2, complete] completed NON-2.04 MID=43539, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x0101}, 52 82 A2 6B 2D 57 56 04 A8 18 12 C6 9A 79 95 9E!
08:19:55.891 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E5,T17) 127.0.0.1:8,192 ==> :8,193 [ACK-2.04 MID=38291, Token=D889B08F75F9BB3F, OptionSet={"Object-Security":0x0102}, 37 10 24 E0 C9 5C 0F C8 AD EE 14 BD B2 0E]
08:19:55.891 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8193) received 30 bytes from 127.0.0.1:8192
08:19:55.892 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:55.892 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ResponseDecryptor - Removes E options from outer options which are not allowed there
08:19:55.892 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:55.892 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not considered due to it being disabled for this context
08:19:55.892 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L4, complete]!
08:19:55.892 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L4, complete] for token KeyToken[127.0.0.1:8192-D889B08F75F9BB3F]
08:19:55.892 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L4, complete] for MID KeyMID[127.0.0.1:8192-38291]
08:19:55.892 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - local Exchange[L4, complete] completed CON-GET MID= -1, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> -/- CON-FETCH MID=38291, Token=D889B08F75F9BB3F, OptionSet={"Observe":1, "Object-Security":0x0901}, acked 19 95 45 75 DB C3 A5 25 33 D0 8D 43 5B FD 48 B9 FB 14 0C C6 C9 B9 4B 14 B1 A5 0A!
08:19:55.895 [main] DEBUG org.eclipse.californium.rule.CoapThreadsRule - shutdown
08:19:55.895 [main] INFO org.eclipse.californium.core.CoapServer - Destroying server
08:19:55.895 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://127.0.0.1:8192
08:19:55.895 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://127.0.0.1:8192
08:19:55.896 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8192] stopping ...
08:19:55.896 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8192, address /127.0.0.1
08:19:55.896 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8192] has stopped.
08:19:55.896 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://127.0.0.1:8192
08:19:55.896 [main] INFO org.eclipse.californium.core.CoapServer - CoAP server has been destroyed
08:19:55.896 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://0.0.0.0:8193
08:19:55.897 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://0.0.0.0:8193
08:19:55.897 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8193] stopping ...
08:19:55.897 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8193, address /0.0.0.0
08:19:55.897 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8193] has stopped.
08:19:55.897 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://0.0.0.0:8193
08:19:55.898 [main] INFO org.eclipse.californium.elements.rule.TestNameLoggerRule - Test testObserve
08:19:55.898 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 0 rules active.
08:19:55.899 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 1 rules active.
08:19:55.900 [main] INFO org.eclipse.californium.elements.rule.TestNameLoggerRule - Test testObserve
08:19:55.900 [main] INFO org.eclipse.californium.core.network.RandomTokenGenerator - using tokens of 8 bytes in length
08:19:55.901 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap using TokenProvider org.eclipse.californium.core.network.RandomTokenGenerator
08:19:55.901 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap CoapEndpoint uses udp context
08:19:55.901 [main] INFO org.eclipse.californium.core.network.stack.BlockwiseLayer - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
08:19:55.901 [main] INFO org.eclipse.californium.core.CoapServer - Starting server
08:19:55.902 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Starting endpoint at coap://127.0.0.1:0
08:19:55.902 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap no MessageIdProvider set, using default org.eclipse.californium.core.network.InMemoryMessageIdProvider
08:19:55.902 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 1 rules active.
08:19:55.902 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - binding to port 0, address localhost/127.0.0.1
08:19:55.902 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - assigned port 8194
08:19:55.902 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector starts up 1 sender threads and 1 receiver threads
08:19:55.903 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Receiver-localhost/127.0.0.1:0[0]]
08:19:55.903 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Sender-localhost/127.0.0.1:0[0]]
08:19:55.903 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector listening on /127.0.0.1:8194, recv buf = 0, send buf = 0, recv packet size = 2048
08:19:55.903 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Started endpoint at coap://127.0.0.1:8194
08:19:55.904 [main] INFO org.eclipse.californium.core.network.RandomTokenGenerator - using tokens of 8 bytes in length
08:19:55.904 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap using TokenProvider org.eclipse.californium.core.network.RandomTokenGenerator
08:19:55.904 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap CoapEndpoint uses udp context
08:19:55.904 [main] INFO org.eclipse.californium.core.network.stack.BlockwiseLayer - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
08:19:55.904 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Endpoint [coap://0.0.0.0:0] requires an executor to start, using default single-threaded daemon executor
08:19:55.905 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Starting endpoint at coap://0.0.0.0:0
08:19:55.905 [main] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap no MessageIdProvider set, using default org.eclipse.californium.core.network.InMemoryMessageIdProvider
08:19:55.905 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 1 rules active.
08:19:55.905 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - binding to port 0, address 0.0.0.0/0.0.0.0
08:19:55.905 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - assigned port 8195
08:19:55.905 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector starts up 1 sender threads and 1 receiver threads
08:19:55.905 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]]
08:19:55.905 [main] INFO org.eclipse.californium.elements.UDPConnector - UDPConnector listening on 0.0.0.0/0.0.0.0:8195, recv buf = 0, send buf = 0, recv packet size = 2048
08:19:55.905 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - Starting network stage thread [UDP-Sender-0.0.0.0/0.0.0.0:0[0]]
08:19:55.906 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Started endpoint at coap://0.0.0.0:8195
08:19:55.906 [main] INFO org.eclipse.californium.core.network.EndpointManager - created implicit endpoint coap://0.0.0.0:8195 for coap
08:19:55.906 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityContextLayer - Request: CON-GET MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.906 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L6] send request
08:19:55.906 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L6] prepare retransmission for CON-GET MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.908 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Request: CON-GET MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.908 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L6] replace CON-GET MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> by CON-FETCH MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:55.908 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.BaseMatcher - registering observe request CON-FETCH MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:55.909 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.observe.InMemoryObservationStore - added observation for Token=86A68EFA232AA125
08:19:55.909 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L6] added with KeyMID[127.0.0.1:8194-28991], CON-FETCH MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:55.909 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L6] added with token KeyToken[86A68EFA232AA125], CON-FETCH MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:55.909 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8194-28991], KeyToken[86A68EFA232AA125]]
08:19:55.910 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 43 bytes to 127.0.0.1:8194
08:19:55.910 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E6,T24) 127.0.0.1:8,195 ==> :8,194 [CON-FETCH MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8]
08:19:55.910 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8194) received 43 bytes from 127.0.0.1:8195
08:19:55.910 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8195-28991]
08:19:55.911 [CoapServer(main)#1] INFO org.eclipse.californium.oscore.RequestDecryptor - Removes E options from outer options which are not allowed there
08:19:55.911 [CoapServer(main)#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:55.911 [CoapServer(main)#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not initiated due to it being disabled for this context
08:19:55.911 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R7] replace CON-FETCH MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> by CON-GET MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:55.912 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.server.ServerMessageDeliverer - initiating an observe relation between 127.0.0.1:8195 and resource /oscore/observe2, Exchange[R7]
08:19:55.912 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.observe.ObserveRelation - Observe-relation, checks every 120000000000ns or 100 notifications.
08:19:55.912 [CoapServer(main)#1] INFO org.eclipse.californium.core.CoapResource - successfully established observe relation between 127.0.0.1:8195#86A68EFA232AA125 and resource /oscore/observe2 (Exchange[R7], size 1)
08:19:55.912 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R7] send response null-2.05 MID= -1, Token=null, OptionSet={"Observe":0, "Content-Format":"text/plain"}, "one"
08:19:55.912 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R7, complete]!
08:19:55.912 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R7, complete] completed ACK-2.04 MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0100}, 4D D3 3B FC 90 86 BC 88 2F C2 A2 05 C2 2E 96!
08:19:55.913 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 32 bytes to 127.0.0.1:8195
08:19:55.913 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E7,T27) 127.0.0.1:8,194 ==> :8,195 [ACK-2.04 MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0100}, 4D D3 3B FC 90 86 BC 88 2F C2 A2 05 C2 2E 96]
08:19:55.913 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8195) received 32 bytes from 127.0.0.1:8194
08:19:55.914 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:55.914 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ResponseDecryptor - Removes E options from outer options which are not allowed there
08:19:55.914 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:55.914 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not considered due to it being disabled for this context
08:19:55.914 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L6, complete]!
08:19:55.914 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L6, complete] for token KeyToken[86A68EFA232AA125]
08:19:55.914 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L6, complete] for MID KeyMID[127.0.0.1:8194-28991]
08:19:55.914 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - local Exchange[L6, complete] completed CON-GET MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> -/- CON-FETCH MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0900}, acked AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8!
08:19:55.915 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.coap.ClientObserveRelation - Updated with ACK-2.05 MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0100, "Content-Format":"text/plain"}, "one"
08:19:55.915 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.coap.ClientObserveRelation - Wait for 62000ms fresh notifies.
NOTIFICATION: one
08:19:55.916 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.core.test.CountingCoapHandler - Received 1. Notification: ACK-2.05 MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0100, "Content-Format":"text/plain"}, "one"
08:19:56.652 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R7] send response NON-2.05 MID= -1, Token=null, OptionSet={"Observe":1, "Content-Format":"text/plain"}, "two"
08:19:56.653 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[R7] added with generated mid KeyMID[127.0.0.1:8195-31688], NON-2.04 MID=31688, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x0101}, 52 82 A2 6B 2D 57 56 04 A8 18 12 C6 9A 79 95 9E
08:19:56.653 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 34 bytes to 127.0.0.1:8195
08:19:56.654 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E8,T27) 127.0.0.1:8,194 ==> :8,195 [NON-2.04 MID=31688, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x0101}, 52 82 A2 6B 2D 57 56 04 A8 18 12 C6 9A 79 95 9E]
08:19:56.654 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8195) received 34 bytes from 127.0.0.1:8194
08:19:56.654 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.observe.InMemoryObservationStore - looking up observation for token Token=86A68EFA232AA125: CON-FETCH MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0900}, acked AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:56.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.BaseMatcher - re-created exchange from original observe request: CON-FETCH MID=28991, Token=86A68EFA232AA125, OptionSet={"Observe":0, "Object-Security":0x0900}, AE 59 08 1A 62 8F 42 E4 DB 8E B9 1E B1 84 20 35 54 67 FF FD 23 1A 19 90 25 A8
08:19:56.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8194-31688]
08:19:56.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:56.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ResponseDecryptor - Removes E options from outer options which are not allowed there
08:19:56.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:56.655 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not considered due to it being disabled for this context
08:19:56.656 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L8, complete]!
08:19:56.656 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.coap.ClientObserveRelation - Updated with NON-2.05 MID=31688, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x0101, "Content-Format":"text/plain"}, "two"
08:19:56.656 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.coap.ClientObserveRelation - Wait for 62000ms fresh notifies.
NOTIFICATION: two
08:19:56.656 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.core.test.CountingCoapHandler - Received 2. Notification: NON-2.05 MID=31688, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x0101, "Content-Format":"text/plain"}, "two"
08:19:56.657 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityContextLayer - Request: CON-GET MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:56.657 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L9] send request
08:19:56.657 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[L9] prepare retransmission for CON-GET MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:56.659 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Request: CON-GET MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:56.659 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L9] replace CON-GET MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> by CON-FETCH MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x0901}, 19 95 45 75 DB C3 A5 25 33 D0 8D 43 5B FD 48 B9 FB 14 0C C6 C9 B9 4B 14 B1 A5 0A
08:19:56.660 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L9] added with generated mid KeyMID[127.0.0.1:8194-28992], CON-FETCH MID=28992, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x0901}, 19 95 45 75 DB C3 A5 25 33 D0 8D 43 5B FD 48 B9 FB 14 0C C6 C9 B9 4B 14 B1 A5 0A
08:19:56.660 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap Exchange[L9] added with token KeyToken[86A68EFA232AA125], CON-FETCH MID=28992, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x0901}, 19 95 45 75 DB C3 A5 25 33 D0 8D 43 5B FD 48 B9 FB 14 0C C6 C9 B9 4B 14 B1 A5 0A
08:19:56.660 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.UdpMatcher - tracking open request [KeyMID[127.0.0.1:8194-28992], KeyToken[86A68EFA232AA125]]
08:19:56.661 [UDP-Sender-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 45 bytes to 127.0.0.1:8194
08:19:56.661 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E9,T24) 127.0.0.1:8,195 ==> :8,194 [CON-FETCH MID=28992, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x0901}, 19 95 45 75 DB C3 A5 25 33 D0 8D 43 5B FD 48 B9 FB 14 0C C6 C9 B9 4B 14 B1 A5 0A]
08:19:56.661 [UDP-Receiver-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (127.0.0.1:8194) received 45 bytes from 127.0.0.1:8195
08:19:56.662 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.deduplication.SweepDeduplicator - add exchange for KeyMID[127.0.0.1:8195-28992]
08:19:56.662 [CoapServer(main)#1] INFO org.eclipse.californium.oscore.RequestDecryptor - Removes E options from outer options which are not allowed there
08:19:56.662 [CoapServer(main)#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:56.662 [CoapServer(main)#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not initiated due to it being disabled for this context
08:19:56.662 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R10] replace CON-FETCH MID=28992, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> by CON-GET MID=28992, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data>
08:19:56.663 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.observe.ObserveRelation - Canceling observe relation 127.0.0.1:8195#86A68EFA232AA125 with /oscore/observe2 (Exchange[R7])
08:19:56.663 [CoapServer(main)#1] INFO org.eclipse.californium.core.CoapResource - remove observe relation between 127.0.0.1:8195#86A68EFA232AA125 and resource /oscore/observe2 (Exchange[R7], size 0)
08:19:56.663 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.stack.ReliabilityLayer - Exchange[R10] send response null-2.05 MID= -1, Token=null, OptionSet={"Content-Format":"text/plain"}, "two"
08:19:56.664 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R10, complete]!
08:19:56.664 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R10, complete] completed ACK-2.04 MID=28992, Token=86A68EFA232AA125, OptionSet={"Object-Security":0x0102}, 37 10 24 E0 C9 5C 0F C8 AD EE 14 BD B2 0E!
08:19:56.664 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[R7, complete]!
08:19:56.664 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[R7, complete] for MID KeyMID[127.0.0.1:8195-31688]
08:19:56.664 [UDP-Sender-localhost/127.0.0.1:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (Thread[UDP-Sender-localhost/127.0.0.1:0[0],5,Californium/Elements]) sent 30 bytes to 127.0.0.1:8195
08:19:56.664 [CoapServer(main)#1] DEBUG org.eclipse.californium.core.network.Exchange - Remote Exchange[R7, complete] completed NON-2.04 MID=31688, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x0101}, 52 82 A2 6B 2D 57 56 04 A8 18 12 C6 9A 79 95 9E!
08:19:56.665 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - >> (E10,T27) 127.0.0.1:8,194 ==> :8,195 [ACK-2.04 MID=28992, Token=86A68EFA232AA125, OptionSet={"Object-Security":0x0102}, 37 10 24 E0 C9 5C 0F C8 AD EE 14 BD B2 0E]
08:19:56.665 [UDP-Receiver-0.0.0.0/0.0.0.0:0[0]] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector (0.0.0.0:8195) received 30 bytes from 127.0.0.1:8194
08:19:56.665 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ObjectSecurityLayer - Incoming response is OSCORE protected
08:19:56.665 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.ResponseDecryptor - Removes E options from outer options which are not allowed there
08:19:56.665 [:CoapEndpoint-UDP-0.0.0.0:0#1] INFO org.eclipse.californium.oscore.OptionJuggle - Removing inner only E options from the outer options
08:19:56.665 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.oscore.ContextRederivation - Context re-derivation not considered due to it being disabled for this context
08:19:56.666 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - Exchange[L9, complete]!
08:19:56.666 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L9, complete] for token KeyToken[86A68EFA232AA125]
08:19:56.666 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.InMemoryMessageExchangeStore - coap removing Exchange[L9, complete] for MID KeyMID[127.0.0.1:8194-28992]
08:19:56.666 [:CoapEndpoint-UDP-0.0.0.0:0#1] DEBUG org.eclipse.californium.core.network.Exchange - local Exchange[L9, complete] completed CON-GET MID= -1, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x, "Uri-Path":["oscore","observe2"]}, <empty data> -/- CON-FETCH MID=28992, Token=86A68EFA232AA125, OptionSet={"Observe":1, "Object-Security":0x0901}, acked 19 95 45 75 DB C3 A5 25 33 D0 8D 43 5B FD 48 B9 FB 14 0C C6 C9 B9 4B 14 B1 A5 0A!
08:19:56.667 [main] DEBUG org.eclipse.californium.rule.CoapThreadsRule - shutdown
08:19:56.667 [main] INFO org.eclipse.californium.core.CoapServer - Destroying server
08:19:56.667 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://127.0.0.1:8194
08:19:56.667 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://127.0.0.1:8194
08:19:56.667 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8194] stopping ...
08:19:56.668 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8194, address /127.0.0.1
08:19:56.668 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [/127.0.0.1:8194] has stopped.
08:19:56.668 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://127.0.0.1:8194
08:19:56.668 [main] INFO org.eclipse.californium.core.CoapServer - CoAP server has been destroyed
08:19:56.668 [main] INFO org.eclipse.californium.core.network.CoapEndpoint - coap Destroying endpoint at coap://0.0.0.0:8195
08:19:56.668 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopping endpoint at coap://0.0.0.0:8195
08:19:56.668 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8195] stopping ...
08:19:56.668 [main] DEBUG org.eclipse.californium.elements.util.DirectDatagramSocketImpl - closing port 8195, address /0.0.0.0
08:19:56.668 [main] DEBUG org.eclipse.californium.elements.UDPConnector - UDPConnector on [0.0.0.0/0.0.0.0:8195] has stopped.
08:19:56.669 [main] DEBUG org.eclipse.californium.core.network.CoapEndpoint - coap Stopped endpoint at coap://0.0.0.0:8195
08:19:56.669 [main] INFO org.eclipse.californium.elements.rule.TestNameLoggerRule - Test testObserve
08:19:56.669 [main] INFO org.eclipse.californium.elements.rule.NetworkRule - 0 rules active.
[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.789 s - in org.eclipse.californium.oscore.OSCoreObserveTest
[INFO]
[INFO] Results:
[INFO]
[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO]
[INFO] --- maven-surefire-plugin:2.22.2:test (native-tests) @ cf-oscore ---
[INFO] Toolchain in maven-surefire-plugin: JDK[/opt/tools/java/oracle/jdk-7/latest]
[INFO]
[INFO] -------------------------------------------------------
[INFO] T E S T S
[INFO] -------------------------------------------------------
[INFO]
[INFO] Results:
[INFO]
[INFO] Tests run: 0, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO]
[INFO] --- maven-source-plugin:3.2.1:jar-no-fork (attach-sources) @ cf-oscore ---
[INFO]
[INFO] --- revapi-maven-plugin:0.14.6:check (default) @ cf-oscore ---
[INFO] Skipping execution
[INFO]
[INFO] --- eclipse-jarsigner-plugin:1.3.1:sign (sign-jars) @ cf-oscore ---
[INFO] Signing jar: /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT.jar
[INFO] Signing jar: /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT-javadoc.jar
[INFO] Signing jar: /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT-sources.jar
[INFO]
[INFO] --- maven-install-plugin:2.5.2:install (default-install) @ cf-oscore ---
[INFO] Installing /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT.jar to /home/jenkins/.m2/repository/org/eclipse/californium/cf-oscore/3.3.1-SNAPSHOT/cf-oscore-3.3.1-SNAPSHOT.jar
[INFO] Installing /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/pom.xml to /home/jenkins/.m2/repository/org/eclipse/californium/cf-oscore/3.3.1-SNAPSHOT/cf-oscore-3.3.1-SNAPSHOT.pom
[INFO] Installing /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT-javadoc.jar to /home/jenkins/.m2/repository/org/eclipse/californium/cf-oscore/3.3.1-SNAPSHOT/cf-oscore-3.3.1-SNAPSHOT-javadoc.jar
[INFO] Installing /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT-sources.jar to /home/jenkins/.m2/repository/org/eclipse/californium/cf-oscore/3.3.1-SNAPSHOT/cf-oscore-3.3.1-SNAPSHOT-sources.jar
[INFO]
[INFO] --- nexus-staging-maven-plugin:1.6.8:deploy (injected-nexus-deploy) @ cf-oscore ---
[INFO] Performing deferred deploys (gathering into "/home/jenkins/agent/workspace/californium-3.3.x-nightly/target/nexus-staging/deferred")...
[INFO] Installing /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT.jar to /home/jenkins/agent/workspace/californium-3.3.x-nightly/target/nexus-staging/deferred/org/eclipse/californium/cf-oscore/3.3.1-SNAPSHOT/cf-oscore-3.3.1-SNAPSHOT.jar
[INFO] Installing /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/pom.xml to /home/jenkins/agent/workspace/californium-3.3.x-nightly/target/nexus-staging/deferred/org/eclipse/californium/cf-oscore/3.3.1-SNAPSHOT/cf-oscore-3.3.1-SNAPSHOT.pom
[INFO] Installing /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT-javadoc.jar to /home/jenkins/agent/workspace/californium-3.3.x-nightly/target/nexus-staging/deferred/org/eclipse/californium/cf-oscore/3.3.1-SNAPSHOT/cf-oscore-3.3.1-SNAPSHOT-javadoc.jar
[INFO] Installing /home/jenkins/agent/workspace/californium-3.3.x-nightly/cf-oscore/target/cf-oscore-3.3.1-SNAPSHOT-sources.jar to /home/jenkins/agent/workspace/californium-3.3.x-nightly/target/nexus-staging/deferred/org/eclipse/californium/cf-oscore/3.3.1-SNAPSHOT/cf-oscore-3.3.1-SNAPSHOT-sources.jar
[INFO] Deploying remotely...
[INFO] Bulk deploying locally gathered artifacts from directory:
[INFO] * Bulk deploying locally gathered snapshot artifacts
[INFO] * Bulk deploy of locally gathered snapshot artifacts finished.
[INFO] Remote deploy finished with success.
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary for Californium (Cf) Parent 3.3.1-SNAPSHOT:
[INFO]
[INFO] Californium (Cf) Parent ............................ SUCCESS [ 5.484 s]
[INFO] Californium (Cf) Custom Assembly Descriptors ....... SUCCESS [ 2.138 s]
[INFO] Californium (Cf) BOM ............................... SUCCESS [ 0.033 s]
[INFO] Californium (Cf) Legal Documents ................... SUCCESS [ 1.620 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [ 1.221 s]
[INFO] Californium (Cf) Element Connector ................. SUCCESS [ 15.651 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [02:29 min]
[INFO] Californium (Cf) Element Connector TCP netty ....... SUCCESS [ 31.016 s]
[INFO] Californium (Cf) Core .............................. SUCCESS [01:55 min]
[INFO] Cf-Unix-Health ..................................... SUCCESS [ 4.848 s]
[INFO] Cf-command-line-interface .......................... SUCCESS [ 7.243 s]
[INFO] Cf-cli-tcp-netty ................................... SUCCESS [ 8.839 s]
[INFO] Californium (Cf) Proxy2 ............................ SUCCESS [ 10.648 s]
[INFO] Californium (Cf) OSGi .............................. SUCCESS [ 9.656 s]
[INFO] Californium (Cf) Demo Applications ................. SUCCESS [ 0.810 s]
[INFO] Cf-PlugtestServer .................................. SUCCESS [ 10.958 s]
[INFO] Cf-PlugtestClient .................................. SUCCESS [ 5.246 s]
[INFO] Cf-ExampleCrossProxy2 .............................. SUCCESS [ 9.144 s]
[INFO] Cf-OSCORE .......................................... SUCCESS [ 31.226 s]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 07:02 min
[INFO] Finished at: 2022-03-03T08:20:14Z
[INFO] ------------------------------------------------------------------------
Recording test results
[Checks API] No suitable checks publisher found.
Finished: SUCCESS
![[Jenkins]](/californium/static/1d85bb5a/images/svgs/logo.svg){kind=logo}
