[IN] UDP Message Received packet nb : 1 SrcAddr : fd0c:2c33:b20c:afbe:a138:2bda:5bd9:3830[35582] DestAddr : fd0c:2c33:b20c:afbe:4e7f:4763:73fc:574b[5540] Payload Length 215 [EM] Received message of type 0x30 with protocolId (0, 0) and MessageCounter:91589302 on exchange 14986r [EM] Handling via exchange: 14986r, Delegate: 0x2001e008 [IN] CASE Server received Sigma1 message. Starting handshake. EC 0x2001f2f8 [IN] CASE Server disabling CASE session setups [EM] Sending Standalone Ack for MessageCounter:91589302 on exchange 14986r [IN] Prepared unauthenticated message 0x2001738c to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 14986r with MessageCounter:81926845. [IN] Sending unauthenticated msg 0x2001738c with MessageCounter:81926845 to 0x0000000000000000 at monotonic time: 00000000001F13D7 msec [EM] Flushed pending ack for MessageCounter:91589302 on exchange 14986r [SC] Received Sigma1 msg [SC] Found MRP parameters in the message [SC] Peer assigned session key ID 15886 [SC] Including MRP parameters [EM] Piggybacking Ack for MessageCounter:91589302 on exchange: 14986r [IN] Prepared unauthenticated message 0x2001f5ac to 0x0000000000000000 (0) of type 0x33 and protocolId (0, 0) on exchange 14986r with MessageCounter:81926846. [IN] Sending unauthenticated msg 0x2001f5ac with MessageCounter:81926846 to 0x0000000000000000 at monotonic time: 00000000001F13DE msec [SC] Sent Sigma2Resume msg [IN] UDP Message Received packet nb : 2 SrcAddr : fd0c:2c33:b20c:afbe:a138:2bda:5bd9:3830[35582] DestAddr : fd0c:2c33:b20c:afbe:4e7f:4763:73fc:574b[5540] Payload Length 34 [EM] Received message of type 0x40 with protocolId (0, 0) and MessageCounter:91589303 on exchange 14986r [EM] Found matching exchange: 14986r, Delegate: 0x2001e024 [EM] Rxd Ack; Removing MessageCounter:81926846 from Retrans Table on exchange 14986r [EM] Removed CHIP MessageCounter:81926846 from RetransTable on exchange 14986r [SC] Success status report received. Session was established [SC] SecureSession[0x2001d398]: Moving from state 'kEstablishing' --> 'kActive' [IN] SecureSession[0x2001d398]: Activated - Type:2 LSID:37257 [IN] New secure session activated for device <000000000001B669, 1>, LSID:37257 PSID:15886! [IN] CASE Session established to peer: <000000000001B669, 1> [IN] CASE Server enabling CASE session setups [IN] SecureSession[0x2001d450]: Allocated Type:2 LSID:37258 [SC] Allocated SecureSession (0x2001d450) - waiting for Sigma1 msg [EM] Sending Standalone Ack for MessageCounter:91589303 on exchange 14986r [IN] Prepared unauthenticated message 0x200173c4 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 14986r with MessageCounter:81926847. [IN] Sending unauthenticated msg 0x200173c4 with MessageCounter:81926847 to 0x0000000000000000 at monotonic time: 00000000001F1434 msec [EM] Flushed pending ack for MessageCounter:91589303 on exchange 14986r [IN] UDP Message Received packet nb : 3 SrcAddr : fd0c:2c33:b20c:afbe:a138:2bda:5bd9:3830[35582] DestAddr : fd0c:2c33:b20c:afbe:4e7f:4763:73fc:574b[5540] Payload Length 59 [EM] Received message of type 0x3 with protocolId (0, 1) and MessageCounter:176026663 on exchange 14987r [EM] Handling via exchange: 14987r, Delegate: 0x2000b344 [IM] Received Subscribe request [DMG] Final negotiated min/max parameters: Min = 1s, Max = 3s [DMG] IM RH moving to [GeneratingReports] [DMG] Building Reports for ReadHandler with LastReportGeneration = lu DirtyGeneration = lu [DMG] Cluster 28, Attribute 1 is dirty [DMG] Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0001 (expanded=0) [DMG] Sending report (payload has 51 bytes)... [DMG] IM RH moving to [AwaitingReportResponse] [EM] Piggybacking Ack for MessageCounter:176026663 on exchange: 14987r [IN] Prepared secure message 0x2001f5ac to 0x000000000001B669 (1) of type 0x5 and protocolId (0, 1) on exchange 14987r with MessageCounter:6545139. [IN] Sending encrypted msg 0x2001f5ac with MessageCounter:6545139 to 0x000000000001B669 (1) at monotonic time: 00000000001F1458 msec [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages [DMG] All ReadHandler-s are clean, clear [IN] UDP Message Received packet nb : 4 SrcAddr : fd0c:2c33:b20c:afbe:a138:2bda:5bd9:3830[35582] DestAddr : fd0c:2c33:b20c:afbe:4e7f:4763:73fc:574b[5540] Payload Length 42 [EM] Received message of type 0x1 with protocolId (0, 1) and MessageCounter:176026664 on exchange 14987r [EM] Found matching exchange: 14987r, Delegate: 0x2000cd38 [EM] Rxd Ack; Removing MessageCounter:6545139 from Retrans Table on exchange 14987r [EM] Removed CHIP MessageCounter:6545139 from RetransTable on exchange 14987r [IM] Received status response, status is 0x00 [DMG] Refresh Subscribe Sync Timer with max 3 seconds [EM] Piggybacking Ack for MessageCounter:176026664 on exchange: 14987r [IN] Prepared secure message 0x2001f5ac to 0x000000000001B669 (1) of type 0x4 and protocolId (0, 1) on exchange 14987r with MessageCounter:6545140. [IN] Sending encrypted msg 0x2001f5ac with MessageCounter:6545140 to 0x000000000001B669 (1) at monotonic time: 00000000001F14A1 msec [DMG] OnReportConfirm: NumReports = 0 [DMG] IM RH moving to [GeneratingReports] [DMG] All ReadHandler-s are clean, clear [IN] UDP Message Received packet nb : 5 SrcAddr : fd0c:2c33:b20c:afbe:a138:2bda:5bd9:3830[35582] DestAddr : fd0c:2c33:b20c:afbe:4e7f:4763:73fc:574b[5540] Payload Length 34 [EM] Received message of type 0x10 with protocolId (0, 0) and MessageCounter:176026665 on exchange 14987r [EM] Found matching exchange: 14987r, Delegate: 0 [EM] Rxd Ack; Removing MessageCounter:6545140 from Retrans Table on exchange 14987r [EM] Removed CHIP MessageCounter:6545140 from RetransTable on exchange 14987r [DMG] Unblock report hold after min 1 seconds [DMG] Refresh subscribe timer sync after 2 seconds [DMG] Building Reports for ReadHandler with LastReportGeneration = lu DirtyGeneration = lu [DMG] Sending report (payload has 11 bytes)... [DMG] IM RH moving to [AwaitingReportResponse] [IN] Prepared secure message 0x2001f5ac to 0x000000000001B669 (1) of type 0x5 and protocolId (0, 1) on exchange 7860i with MessageCounter:6545141. [IN] Sending encrypted msg 0x2001f5ac with MessageCounter:6545141 to 0x000000000001B669 (1) at monotonic time: 00000000001F2059 msec [DMG] Refresh Subscribe Sync Timer with max 3 seconds [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [IN] UDP Message Received packet nb : 6 SrcAddr : fd0c:2c33:b20c:afbe:a138:2bda:5bd9:3830[35582] DestAddr : fd0c:2c33:b20c:afbe:4e7f:4763:73fc:574b[5540] Payload Length 42 [EM] Received message of type 0x1 with protocolId (0, 1) and MessageCounter:176026666 on exchange 7860i [EM] Found matching exchange: 7860i, Delegate: 0x2000cd38 [EM] Rxd Ack; Removing MessageCounter:6545141 from Retrans Table on exchange 7860i [EM] Removed CHIP MessageCounter:6545141 from RetransTable on exchange 7860i [IM] Received status response, status is 0x00 [DMG] OnReportConfirm: NumReports = 0 [DMG] IM RH moving to [GeneratingReports] [EM] Sending Standalone Ack for MessageCounter:176026666 on exchange 7860i [IN] Prepared secure message 0x20017414 to 0x000000000001B669 (1) of type 0x10 and protocolId (0, 0) on exchange 7860i with MessageCounter:6545142. [IN] Sending encrypted msg 0x20017414 with MessageCounter:6545142 to 0x000000000001B669 (1) at monotonic time: 00000000001F2091 msec [EM] Flushed pending ack for MessageCounter:176026666 on exchange 7860i [DMG] All ReadHandler- [DMG] Unblock report hold after min 1 seconds [DMG] Refresh subscribe timer sync after 2 seconds [DMG] Building Reports for ReadHandler with LastReportGeneration = lu DirtyGeneration = lu [DMG] Sending report (payload has 11 bytes)... [DMG] IM RH moving to [AwaitingReportResponse] [IN] Prepared secure message 0x2001f5ac to 0x000000000001B669 (1) of type 0x5 and protocolId (0, 1) on exchange 7861i with MessageCounter:6545143. [IN] Sending encrypted msg 0x2001f5ac with MessageCounter:6545143 to 0x000000000001B669 (1) at monotonic time: 00000000001F2C13 msec [DMG] Refresh Subscribe Sync Timer with max 3 seconds [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [IN] UDP Message Received packet nb : 7 SrcAddr : fd0c:2c33:b20c:afbe:a138:2bda:5bd9:3830[35582] DestAddr : fd0c:2c33:b20c:afbe:4e7f:4763:73fc:574b[5540] Payload Length 42 [EM] Received message of type 0x1 with protocolId (0, 1) and MessageCounter:176026667 on exchange 7861i [EM] Found matching exchange: 7861i, Delegate: 0x2000cd38 [EM] Rxd Ack; Removing MessageCounter:6545143 from Retrans Table on exchange 7861i [EM] Removed CHIP MessageCounter:6545143 from RetransTable on exchange 7861i [IM] Received status response, status is 0x00 [DMG] OnReportConfirm: NumReports = 0 [DMG] IM RH moving to [GeneratingReports] [EM] Sending Standalone Ack for MessageCounter:176026667 on exchange 7861i [IN] Prepared secure message 0x20017414 to 0x000000000001B669 (1) of type 0x10 and protocolId (0, 0) on exchange 7861i with MessageCounter:6545144. [IN] Sending encrypted msg 0x20017414 with MessageCounter:6545144 to 0x000000000001B669 (1) at monotonic time: 00000000001F2C4B msec [EM] Flushed pending ack for MessageCounter:176026667 on exchange 7861i [DMG] All ReadHandler- [DMG] Unblock report hold after min 1 seconds [DMG] Refresh subscribe timer sync after 2 seconds [DMG] Building Reports for ReadHandler with LastReportGeneration = lu DirtyGeneration = lu [DMG] Sending report (payload has 11 bytes)... [DMG] IM RH moving to [AwaitingReportResponse] [IN] Prepared secure message 0x2001f5ac to 0x000000000001B669 (1) of type 0x5 and protocolId (0, 1) on exchange 7862i with MessageCounter:6545145. [IN] Sending encrypted msg 0x2001f5ac with MessageCounter:6545145 to 0x000000000001B669 (1) at monotonic time: 00000000001F37CD msec [DMG] Refresh Subscribe Sync Timer with max 3 seconds [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [IN] UDP Message Received packet nb : 8 SrcAddr : fd0c:2c33:b20c:afbe:a138:2bda:5bd9:3830[35582] DestAddr : fd0c:2c33:b20c:afbe:4e7f:4763:73fc:574b[5540] Payload Length 42 [EM] Received message of type 0x1 with protocolId (0, 1) and MessageCounter:176026668 on exchange 7862i [EM] Found matching exchange: 7862i, Delegate: 0x2000cd38 [EM] Rxd Ack; Removing MessageCounter:6545145 from Retrans Table on exchange 7862i [EM] Removed CHIP MessageCounter:6545145 from RetransTable on exchange 7862i [IM] Received status response, status is 0x00 [DMG] OnReportConfirm: NumReports = 0 [DMG] IM RH moving to [GeneratingReports] [EM] Sending Standalone Ack for MessageCounter:176026668 on exchange 7862i [IN] Prepared secure message 0x20017414 to 0x000000000001B669 (1) of type 0x10 and protocolId (0, 0) on exchange 7862i with MessageCounter:6545146. [IN] Sending encrypted msg 0x20017414 with MessageCounter:6545146 to 0x000000000001B669 (1) at monotonic time: 00000000001F3807 msec [EM] Flushed pending ack for MessageCounter:176026668 on exchange 7862i [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [DMG] Unblock report hold after min 1 seconds [DMG] Refresh subscribe timer sync after 2 seconds [DMG] Building Reports for ReadHandler with LastReportGeneration = lu DirtyGeneration = lu [DMG] Sending report (payload has 11 bytes)... [DMG] IM RH moving to [AwaitingReportResponse] [IN] Prepared secure message 0x2001f5ac to 0x000000000001B669 (1) of type 0x5 and protocolId (0, 1) on exchange 7863i with MessageCounter:6545147. [IN] Sending encrypted msg 0x2001f5ac with MessageCounter:6545147 to 0x000000000001B669 (1) at monotonic time: 00000000001F4387 msec [DMG] Refresh Subscribe Sync Timer with max 3 seconds [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [EM] Retransmitting MessageCounter:6545147 on exchange 7863i Send Cnt 1 [IN] Sending encrypted msg 0x2001f5ac with MessageCounter:6545147 to 0x000000000001B669 (1) at monotonic time: 00000000001F44E4 msec [EM] Retransmitting MessageCounter:6545147 on exchange 7863i Send Cnt 2 [IN] Sending encrypted msg 0x2001f5ac with MessageCounter:6545147 to 0x000000000001B669 (1) at monotonic time: 00000000001F461B msec [DMG] Unblock report hold after min 1 seconds [EM] Retransmitting MessageCounter:6545147 on exchange 7863i Send Cnt 3 [IN] Sending encrypted msg 0x2001f5ac with MessageCounter:6545147 to 0x000000000001B669 (1) at monotonic time: 00000000001F4841 msec [DMG] Refresh subscribe timer sync after 2 seconds [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [EM] Retransmitting MessageCounter:6545147 on exchange 7863i Send Cnt 4 [IN] Sending encrypted msg 0x2001f5ac with MessageCounter:6545147 to 0x000000000001B669 (1) at monotonic time: 00000000001F7EA6 msec [DMG] Time out! failed to receive status response from Exchange: 7863i [DMG] OnReportConfirm: NumReports = 0 [DMG] IM RH moving to [AwaitingDestruction] [EM] Failed to Send CHIP MessageCounter:6545147 on exchange 7863i sendCount: 4 max retries: 4