I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 621196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa21a D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 621696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa21b D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 622196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa21c D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 622696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa21d D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 623196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa21e D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 623696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa21f D: Current event: 8 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ 8 I: Event type: 8 ~~~~~~~~~~~~~~G]Endpoint 1, Cluster 0x0000_0402 update version to 819fa220 D: Current event: 8 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~I: Event ut result: 0 D: Event 624696 [DMG0 D: Event in queue: 8 I: Event type: 8 D: 625196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa222 D: Current event: 8 I: Event pin queu 625696 [DMG0000_0402 u23 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 626196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa224 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 626696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa225 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 627196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa226 D: Current event: 8 I: 627357 [EM]>>> [E:36460r M:194927176] (U) Msg RX from 0:DEF17CDE5AF763F3 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) D: 627369 [EM]Handling via exchange: 36460r, Delegate: 0x200042e0 I: 627375 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20004ff0 I: 627383 [IN]CASE Server disabling CASE session setups I: 627388 [EM]<<< [E:36460r M:171505224 (Ack:194927176)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 627401 [IN](U) Sending msg 171505224 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43888' D: 627411 [EM]Flushed pending ack for MessageCounter:194927176 on exchange 36460r I: 627418 [SC]Received Sigma1 msg D: 627422 [SC]Found MRP parameters in the message D: 627426 [SC]Peer assigned session key ID 52854 I: 627435 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000001 D: 627657 [SC]Including MRP parameters I: 627661 [EM]<<< [E:36460r M:171505225 (Ack:194927176)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) I: 627674 [IN](U) Sending msg 171505225 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43888' I: 627685 [SC]Sent Sigma2 msg I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 627697 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa227 D: Current event: 8 D: 628041 [EM]Retransmitting MessageCounter:171505225 on exchange 36460r Send Cnt 1 I: 628049 [IN](U) Sending msg 171505225 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43888' I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 628196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa228 D: Current event: 8 D: 628432 [EM]Retransmitting MessageCounter:171505225 on exchange 36460r Send Cnt 2 I: 628440 [IN](U) Sending msg 171505225 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43888' I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 628696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa229 D: Current event: 8 D: 628975 [EM]Retransmitting MessageCounter:171505225 on exchange 36460r Send Cnt 3 I: 628983 [IN](U) Sending msg 171505225 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43888' I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 629196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa22a D: Current event: 8 I: 629392 [EM]>>> [E:36460r M:194927177 (Ack:171505225)] (U) Msg RX from 0:DEF17CDE5AF763F3 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) D: 629406 [EM]Found matching exchange: 36460r, Delegate: 0x200042fc D: 629412 [EM]Rxd Ack; Removing MessageCounter:171505225 from Retrans Table on exchange 36460r I: 629421 [EM]<<< [E:36460r M:171505226 (Ack:194927177)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 629434 [IN](U) Sending msg 171505226 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43888' D: 629445 [EM]Flushed pending ack for MessageCounter:194927177 on exchange 36460r I: 629452 [SC]Received Sigma3 msg E: 629460 [SC]The device does not support GetClock_RealTimeMS() API: 3. Falling back to Last Known Good UTC Time I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 629696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa22b D: Current event: 8 D: 629749 [SC]Sending status report. Protocol code 0, exchange 36460 I: 629756 [EM]<<< [E:36460r M:171505227 (Ack:194927177)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 629769 [IN](U) Sending msg 171505227 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43888' I: 629780 [SC]SecureSession[0x20003e58]: Moving from state 'kEstablishing' --> 'kActive' D: 629788 [IN]SecureSession[0x20003e58]: Activated - Type:2 LSID:56918 D: 629795 [IN]New secure session activated for device <000000000001B669, 1>, LSID:56918 PSID:52854! I: 629804 [IN]CASE Session established to peer: <000000000001B669, 1> 0SE Server enabling CASE session setups D: 629815 [IN]SecureSession[0x20003f10]: Allocated Type:2 LSID:56919 D: 629823 [SC]Allocated SecureSession (0x20003f10) - waiting for Sigma1 msg I: 629832 [EM]>>> [E:36461r M:118052773] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0001:02 (IM:ReadRequest) D: 629843 [EM]Handling via exchange: 36461r, Delegate: 0x2000d504 D: 629849 [IM]Received Read request D: 629852 [DMG]IM RH moving to [GeneratingReports] D: 629857 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 629866 [DMG] Cluster 402, Attribute 0 is dirty D: 629871 [DMG]Reading attribute: Cluster=0x0000_0402 Endpoint=1 AttributeId=0x0000_0000 (expanded=0) D: 629880 [DMG] Sending report (payload has 38 bytes)... I: 629886 [EM]<<< [E:36461r M:112064972 (Ack:118052773)] (S) Msg TX to 1:000000000001B669 [0EBD] --- Type 0001:05 (IM:ReportData) I: 629898 [IN](S) Sending msg 112064972 on secure session with LSID: 56918 D: 629905 [DMG] OnReportConfirm: NumReports = 0 D: 629910 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 629918 [DMG]IM RH moving to [AwaitingDestruction] D: 629923 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 629930 [EM]>>> [E:36460r M:194927178 (Ack:171505227)] (U) Msg RX from 0:DEF17CDE5AF763F3 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 629942 [EM]Found matching exchange: 36460r, Delegate: (nil) D: 629948 [EM]Rxd Ack; Removing MessageCounter:171505227 from Retrans Table on exchange 36460r I: 629959 [EM]>>> [E:36461r M:118052774 (Ack:112064972)] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 629973 [EM]Found matching exchange: 36461r, Delegate12064972 from Retrans Table on exchange 36461r I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 630196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa22c D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 630696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa22d D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 631196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa22e D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 631696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa22f D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 632196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa230 D: Current event: 8 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~[2D~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~I: Event put result: 0 D: Event in queue:8t in queue: type: 8 DDMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa232 D: Current event: 8 put result: 0 D: Event in queue: date version t update vera234 D: Current event:I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 634696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa235 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 635196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa236 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 635696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa237 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 636196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa238 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 636696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa239 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 637196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa23a D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 637696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa23b D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 638196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa23c D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 638696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa23d D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 639197 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa23e D: Current event: 8 I: 639207 [EM]>>> [E:48953r M:98860027] (U) Msg RX from 0:07C22687C52EECCC [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) D: 639218 [EM]Handling via exchange: 48953r, Delegate: 0x200042e0 I: 639224 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20004ff0 I: 639231 [IN]CASE Server disabling CASE session setups I: 639236 [EM]<<< [E:48953r M:171505228 (Ack:98860027)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 639249 [IN](U) Sending msg 171505228 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:33677' D: 639260 [EM]Flushed pending ack for MessageCounter:98860027 on exchange 48953r I: 639267 [SC]Received Sigma1 msg D: 639270 [SC]Found MRP parameters in the message D: 639275 [SC]Peer assigned session key ID 18077 I: 639283 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000001 D: 639505 [SC]Including MRP parameters I: 639510 [EM]<<< [E:48953r M:171505229 (Ack:98860027)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) I: 639522 [IN](U) Sending msg 171505229 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:33677' I: 639533 [SC]Sent Sigma2 msg I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 639697 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa23f D: Current event: 8 I: 639724 [EM]>>> [E:48953r M:98860028 (Ack:171505229)] (U) Msg RX from 0:07C22687C52EECCC [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) D: 639737 [EM]Found matching exchange: 48953r, Delegate: 0x200042fc D: 639743 [EM]Rxd Ack; Removing MessageCounter:171505229 from Retrans Table on exchange 48953r I: 639751 [EM]<<< [E:48953r M:171505230 (Ack:98860028)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 639764 [IN](U) Sending msg 171505230 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:33677' D: 639774 [EM]Flushed pending ack for MessageCounter:98860028 on exchange 48953r I: 639782 [SC]Received Sigma3 msg E: 639789 [SC]The device does not support GetClock_RealTimeMS() API: 3. Falling back to Last Known Good UTC Time D: 640067 [SC]Sending status report. Protocol code 0, exchange 48953 I: 640074 [EM]<<< [E:48953r M:171505231 (Ack:98860028)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 640086 [IN](U) Sending msg 171505231 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:33677' I: 640098 [SC]SecureSession[0x20003f10]: Moving from state 'kEstablishing' --> 'kActive' D: 640106 [IN]SecureSession[0x20003f10]: Activated - Type:2 LSID:56919 D: 640113 [IN]New secure session activated for device <000000000001B669, 1>, LSID:56919 PSID:18077! I: 640122 [IN]CASE Session established to peer: <000000000001B669, 1> I: 640128 [IN]CASE Server enabling CASE session setups D: 640133 [IN]SecureSession[0x20003fc8]: Allocated Type:2 LSID:56920 D: 640139 [SC]Allocated SecureSession (0x20003fc8) - waiting for Sigma1 msg I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 640196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa240 D: Current event: 8 I: 640207 [EM]>>> [E:48954r M:114641056] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0001:02 (IM:ReadRequest) D: 640219 [EM]Handling via excha48954r, Delegate: 0x2000d504 D: 640225 [IM]Received Read request D: 640229 [DMG]IM RH moving to [GeneratingReports] D: 640233 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 640242 [DMG] Cluster 402, Attribute 0 is dirty D: 640247 [DMG]Reading attribute: Cluster=0x0000_0402 Endpoint=1 AttributeId=0x0000_0000 (expanded=0) D: 640256 [DMG] Sending report (payload has 38 bytes)... I: 640263 [EM]<<< [E:48954r M:177937470 (Ack:114641056)] (S) Msg TX to 1:000000000001B669 [0EBD] --- Type 0001:05 (IM:ReportData) I: 640274 [IN](S) Sending msg 177937470 on secure session with LSID: 56919 D: 640282 [DMG] OnReportConfirm: NumReports = 0 D: 640286 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 640294 [DMG]IM RH moving to [AwaitingDestruction] D: 640299 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 640305 [EM]>>> [E:48953r M:98860029 (Ack:171505231)] (U) Msg RX from 0:07C22687C52EECCC [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 640318 [EM]Found matching exchange: 48953r, Delegate: (nil) D: 640324 [EM]Rxd Ack; Removing MessageCounter:171505231 from Retrans Table on exchange 48953r I: 640357 [EM]>>> [E:48954r M:114641057 (Ack:177937470)] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 640370 [EM]Found matching exchange: 48954r, Delegate: (nil) D: 640376 [EM]Rxd Ack; Removing MessageCounter:177937470 from Retrans Table on exchange 48954r I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 640696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa241 D: Current event: 8 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~: Event put result: 0 D: Event in queue: 8 I: Event type: 8 ]Endpoint 1, Cluster 0x0000_0402 update version to 819fa242 D: Current event: 8 I: Evet: 0 D: Event in queue: 8 I: EveD: 641696t 1, Cluster 0x0000_04807 [EM]>>>30 (SecureChannel:CASE_Sigma1) D: 641819 [EM]Ha 0x200042e0 recei handshaI: 641837 [E:21398r M:17150232 (Ack:161002278)] (U) Msg TX to00000000 [00071505232 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:53902' D: 641860 [EM]Flushed pending ack for MessageCoun1002278 on exchange 21398r I: 641868 [SC]Received Sigma1 msg D: 641871 [ [SC]CASE matched destination ID: fab, [SC]Including MRP parameters I: 642113 [EM]<<< [E:21398r M:171505233 (Ack:161002278)] (U) Msg TX to 0:00000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) I: 642126 [IN](U) Sending msg 171505233 to IP address 'UDP:[fd89:2db1:2' I: 642137C]Sent Sigma2 msg I: Eveent in queueuster 0x0000_0402 update versiot event: 8D: 642472nsmitting Meson exchange Cnt 1 I: 64248[IN](U) Sending msg 171505233 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:53902' ent in queue: 8 I: Event type: 8 D: 642696 [DMG]EndpoD: 642889 [EM]Retransmitting MessageCounter:171505233 on exchange 21398r Send Cnt 2 I: 642896 [IN](U) Sending msg 171505233 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:53902' I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 643196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa246 D: Current event: 8 D: 643538 [EM]Retransmitting MessageCounter:171505233 on exchange 21398r Send Cnt 3 I: 643546 [IN](U) Sending msg 171505233 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:53902' I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 643696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa247 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 644196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa248 D: Current event: 8 I: 644260 [EM]>>> [E:21398r M:161002279 (Ack:171505233)] (U) Msg RX from 0:71A7065B34F50E23 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) D: 644273 [EM]Found matching exchange: 21398r, Delegate: 0x200042fc D: 644279 [EM]Rxd Ack; Removing MessageCounter:171505233 from Retrans Table on exchange 21398r I: 644288 [EM]<<< [E:21398r M:171505234 (Ack:161002279)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 644300 [IN](U) Sending msg 171505234 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:53902' D: 644311 [EM]Flushed pending ack for MessageCounter:161002279 on exchange 21398r I: 644318 [SC]Received Sigma3 msg E: 644326 [SC]The device does not support GetClock_RealTimeMS() API: 3. Falling back to Last Known Good UTC Time D: 644606 [SC]Sending status report. Protocol code 0, exchange 21398 I: 644612 [EM]<<< [E:21398r M:171505235 (Ack:161002279)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 644625 [IN](U) Sending msg 171505235 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:53902' I: 644637 [SC]SecureSession[0x20003fc8]: Moving from state 'kEstablishing' --> 'kActive' D: 644645 [IN]SecureSession[0x20003fc8]: Activated - Type:2 LSID:56920 D: 644652 [IN]New secure session activated for device <000000000001B669, 1>, LSID:56920 PSID:28438! I: 644660 [IN]CASE Session established to peer: <000000000001B669, 1> I: 644667 [IN]CASE Server enabling CASE session setups D: 644672 [IN]SecureSession[0x20004080]: Allocated Type:2 LSID:56921 D: 644678 [SC]Allocated SecureSession (0x20004080) - waiting for Sigma1 msg I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 644696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa249 D: Current event: 8 I: 644724 [EM]>>> [E:21399r M:61419847] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0001:02 (IM:ReadRequest) D: 644735 [EM]Handling via exchange: 21399r, Delegate: 0x2000d504 D: 644741 [IM]Received Read request D: 644745 [DMG]IM RH moving to [GeneratingReports] D: 644750 [DMG]Building Reports for ReadHandlxpanded=0) D: 644773 [DMG] Sending report (payload has 38 bytes)... I: 644779 [EM]<<< [E:21399r M:254479144 (Ack:61419847)] (S) Msg TX to 1:000000000001B669 [0EBD] --- Type 0001:05 (IM:ReportData) I: 644790 [IN](S) Sending msg 254479144 on secure session with LSID: 56920 D: 644798 [DMG] OnReportConfirm: NumReports = 0 D: 644803 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 644811 [DMG]IM RH moving to [AwaitingDestruction] D: 644816 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 644822 [EM]>>> [E:21398r M:161002280 (Ack:171505235)] (U) Msg RX from 0:71A7065B34F50E23 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 644835 [EM]Found matching exchange: 21398r, Delegate: (nil) D: 644841 [EM]Rxd Ack; Removing MessageCounter:171505235 from Retrans Table on exchange 21398r I: 644863 [EM]>>> [E:21399r M:61419848 (Ack:254479144)] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 644876 [EM]Found matching exchange: 21399r, Delegate: (nil) D: 644882 [EM]Rxd Ack; Removing MessageCounter:254479144 from Retrans Table on exchange 21399r I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 645196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa24a D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 645696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa24b D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 646196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa24c D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 646696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa24d D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 647196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa24e D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 647696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa24f D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 648196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa250 D: Current event: 8 I: 648233 [EM]>>> [E:63983r M:37139537] (U) Msg RX from 0:0CD9B77345A39702 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) D: 648244 [EM]Handling via exchange: 63983r, Delegate: 0x200042e0 I: 648250 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0r disabling CASE session setups I: 648263 [EM]<<< [E:63983r M:171505236 (Ack:37139537)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 648275 [IN](U) Sending msg 171505236 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43404' D: 648286 [EM]Flushed pending ack for MessageCounter:37139537 on exchange 63983r I: 648293 [SC]Received Sigma1 msg D: 648296 [SC]Found MRP parameters in the message D: 648301 [SC]Peer assigned session key ID 57418 I: 648309 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000001 D: 648530 [SC]Including MRP parameters I: 648535 [EM]<<< [E:63983r M:171505237 (Ack:37139537)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) I: 648547 [IN](U) Sending msg 171505237 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43404' I: 648558 [SC]Sent Sigma2 msg I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 648697 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa251 D: Current event: 8 I: 648706 [EM]>>> [E:63983r M:37139538 (Ack:171505237)] (U) Msg RX from 0:0CD9B77345A39702 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) D: 648719 [EM]Found matching exchange: 63983r, Delegate: 0x200042fc D: 648725 [EM]Rxd Ack; Removing MessageCounter:171505237 from Retrans Table on exchange 63983r I: 648734 [EM]<<< [E:63983r M:171505238 (Ack:37139538)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 648746 [IN](U) Sending msg 171505238 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43404' D: 648757 [EM]Flushed pending ack for MessageCounter:37139538 on exchange 63983r I: 648764 [SC]Received Sigma3 msg E: 648771 [SC]The device does not support GetClock_RealTimeMS() API: 3. Falling back to Last Known Good UTC Time D: 649048 [SC]Sending status report. Protocol code 0, exchange 63983 I: 649055 [EM]<<< [E:63983r M:171505239 (Ack:37139538)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 649067 [IN](U) Sending msg 171505239 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43404' I: 649079 [SC]SecureSession[0x20004080]: Moving from state 'kEstablishing' --> 'kActive' D: 649088 [IN]SecureSession[0x20004080]: Activated - Type:2 LSID:56921 D: 649094 [IN]New secure session activated for device <000000000001B669, 1>, LSID:56921 PSID:57418! I: 649103 [IN]CASE Session established to peer: <000000000001B669, 1> I: 649109 [IN]CASE Server enabling CASE session setups D: 649114 [IN]SecureSession[0x20004138]: Allocated Type:2 LSID:56922 D: 649120 [SC]Allocated SecureSession (0x20004138) - waiting for Sigma1 msg I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 649197 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa252 D: Current event: 8 I: 649208 [EM]>>> [E:63984r M:62937537] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0001:02 (IM:ReadRequest) D: 649218 [EM]Handling via exchange: 63984r, Delegate: 0x2000d504 D: 649224 [IM]Received Read request D: 649228 [DMG]IM RH moving to [GeneratingReports] D: 649232 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 649241 [DMG] Cluster 402, Attribute 0 is dirty D: 649246 [DMG]Reading attribute: Cluster=0x0000_0402 Endpoint=1 AttributeId=0x0000_0000 (expanded=0) D: 649255 [DMG] Sending report (payload has 38 bytes)... I: 649262 [EM]<<< [E:63984r M:116368882 (Ack:62937537)] (S) Msg TX to 1:000000000001B669 [0EBD] --- Type 0001:05 (IM:ReportData) I: 649273 [IN](S) Sending msg 116368882 on secure session with LSID: 56921 D: 649281 [DMG] OnReportConfirm: NumReports = 0 D: 649285 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 649293 [DMG]IM RH moving to [AwaitingDestruction] D: 649298 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 649304 [EM]>>> [E:63983r M:37139539 (Ack:171505239)] (U) Msg RX from 0:0CD9B77345A39702 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 649317 [EM]Found matching exchange: 63983r, Delegate: (nil) D: 649323 [EM]Rxd Ack; Removing MessageCounter:171505239 from Retrans Table on exchange 63983r I: 649340 [EM]>>> [E:63984r M:62937538 (Ack:116368882)] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 649352 [EM]Found matching exchange: 63984r, Delegate: (nil) D: 649358 [EM]Rxd Ack; Removing MessageCounter:116368882 from Retrans Table on exchange 63984r ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ I: Eult: 0 D: Event in queue: 8 I: Event type: 8 D: 649696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa253 D: Current event: 8 I: Event put reEvent in queter 0x0000_0402 update version to 819fa254 D: Current event: 8 I: Evult: 0 D: Event in queue: 8 I: Event type: 8 D: 650696 [DMG]Endpoint 1, Clusvent put res D: 651196 [DMG]Endpoint 1, Clus0402 update ent: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 651696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa257 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 652196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa258 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 652696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa259 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 653196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa25a D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 653696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa25b D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 654196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa25c D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 654696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa25d D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 655196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa25e D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 655696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa25f D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 656196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa260 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 656696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa261 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 657196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa262 D: Current event: 8 I: Event put result: 0 D Event type:dpoint 1, Custer 0x0000D: Current e~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~[~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~I: Event put rqueue: 8 I: Event type: 8 D: 658 Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 658point 1, Cluster 0x0000_0402 update version to 819fa265 D: Current eEvent put result: 0 Dqueue: 8 I:: 8 D: 659196 [: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 659696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa267 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 660196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa268 D: Current event: 8 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 660696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa269 D: Current event: 8 I: 661101 [EM]>>> [E:41636r M:177685478] (U) Msg RX from 0:968ABD7FFF43BB6D [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) D: 661113 [EM]Handling via exchange: 41636r, Delegate: 0x200042e0 I: 661119 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20004ff0 I: 661126 [IN]CASE Server disabling CASE session setups I: 661131 [EM]<<< [E:41636r M:171505240 (Ack:177685478)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 661144 [IN](U) Sending msg 171505240 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:58614' D: 661154 [EM]Flushed pending ack for MessageCounter:177685478 on exchange 41636r I: 661162 [SC]Received Sigma1 msg D: 661165 [SC]Found MRP parameters in the message D: 661170 [SC]Peer assigned session key ID 50964 I: 661178 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000001 I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 661196 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa26a D: Current event: 8 D: 661413 [SC]Including MRP parameters I: 661417 [EM]<<< [E:41636r M:171505241 (Ack:177685478)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) I: 661430 [IN](U) Sending msg 171505241 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:58614' I: 661441 [SC]Sent Sigma2 msg I: 661573 [EM]>>> [E:41636r M:177685479 (Ack:171505241)] (U) Msg RX from 0:968ABD7FFF43BB6D [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) D: 661586 [EM]Found matching exchange: 41636r, Delegate: 0x200042fc D: 661592 [EM]Rxd Ack; Removing MessageCounter:171505241 from Retrans Table on exchange 41636r I: 661601 [EM]<<< [E:41636r M:171505242 (Ack:177685479)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 661614 [IN](U) Sending msg 171505242 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:58614' D: 661624 [EM]Flushed pending ack for MessageCounter:177685479 on exchange 41636r I: 661631 [SC]Received Sigma3 msg E: 661639 [SC]The device does not support GetClock_RealTimeMS() API: 3. Falling back to Last Known Good UTC Time I: Event put result: 0 D: Event in queue: 8 I: Event type: 8 D: 661696 [DMG]Endpoint 1, Cluster 0x0000_0402 update version to 819fa26b D: Current event: 8 D: 661933 [SC]Sending status report. Protocol code 0, exchange 41636 I: 661939 [EM]<<< [E:41636r M:171505243 (Ack:177685479)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 661952 [IN](U) Sending msg 171505243 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:58614' I: 661963 [SC]SecureSession[0x20004138]: Moving from state 'kEstablishing' --> 'kActive' D: 661972 [IN]SecureSession[0x20004138]: Activated - Type:2 LSID:56922 D: 661978 [IN]New secure session activated for device <000000000001B669, 1>, LSID:56922 PSID:50964! I: 661988 [IN]CASE Session established to peer: <000000000001B669, 1> I: 661994 [IN]CASE Server ena [SC]Allocated SecureSession (0x200041f0) - waiting for Sigma1 msg I: 662047 [EM]>>> [E:41637r M:35805232] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0001:08 (IM:InvokeCommandRequest) D: 662059 [EM]Handling via exchange: 41637r, Delegate: 0x2000d504 D: 662066 [DMG]Received command for Endpoint=1 Cluster=0x0000_0006 Command=0x0000_0000 I: 662073 [ZCL]On/Off set value: 1 0 I: 662077 [ZCL]Toggle on/off from 1 to 0 D: 662081 [DMG]Endpoint 1, Cluster 0x0000_0006 update version to 3a33f21f I: Event type: 7 D: Current event: 7 I: Event put result: 0 D: Event in queue: 7 D: 662094 [DMG]Command handler moving to [ Preparing] D: 662099 [DMG]Command handler moving to [AddingComm] D: 662104 [DMG]Command handler moving to [AddedComma] D: 662109 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 662116 [EM]<<< [E:41637r M:85085546 (Ack:35805232)] (S) Msg TX to 1:000000000001B669 [0EBD] --- Type 0001:09 (IM:InvokeCommandResponse) I: 662129 [IN](S) Sending msg 85085546 on secure session with LSID: 56922 D: 662136 [DMG]Command handler moving to [CommandSen] D: 662141 [DMG]Command handler moving to [AwaitingDe] I: 662147 [EM]>>> [E:41636r M:177685480 (Ack:171505243)] (U) Msg RX from 0:968ABD7FFF43BB6D [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 662160 [EM]Found matching exchange: 41636r, Delegate: (nil) D: 662167 [EM]Rxd Ack; Removing MessageCounter:171505243 from Retrans Table on exchange 41636r I: 662176 [EM]>>> [E:41637r M:35805233 (Ack:85085546)] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 662189 [EM]Found matching exchange: 41637r, Delegate: (nil) D: 662195 [EM]Rxd Ack; RemageCounter:85 Table on exchange 41637r I: 664180 [EM]>>> [E:25391r M:169302257] (U) Msg RX from 0:1AED29C75F2AF945 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) D: 664191 [EM]Handling via exchange: 25391r, Delegate: 0x200042e0 I: 664197 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20004ff0 I: 664204 [IN]CASE Server disabling CASE session setups I: 664209 [EM]<<< [E:25391r M:171505244 (Ack:169302257)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 664222 [IN](U) Sending msg 171505244 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43295' D: 664233 [EM]Flushed pending ack for MessageCounter:169302257 on exchange 25391r I: 664240 [SC]Received Sigma1 msg D: 664244 [SC]Found MRP parameters in th43834 I: 664256 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000001 D: 664478 [SC]Including MRP parameters I: 664482 [EM]<<< [E:25391r M:171505245 (Ack:169302257)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) I: 664495 [IN](U) Sending msg 171505245 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43295' I: 664506 [SC]Sent I: 664638 [EM]>>> [E:25391r M:169302258 (Ack:171505245)] (U) Msg RX from 0:1AED29C75F2AF945 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) D: 664651 [EM]Found matching exchange: 25391r, Delegate: 0x200042fc D: 664657 [EM]Rxd Ack; Removing MessageCounter:171505245 from Retrans Table on exchange 25391r I: 664666 [EM]<<< [E:25391r M:171505246 (Ack:169302258)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 664678 [IN](U) Sending msg 171505246 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43295' D: 664689 [EM]Flushed pending ack for MessageCounter:169302258 on exchange 25391r I: 664696 [SC]Received Sigma3 msg E: 664704 [SC]The device does not support GetClock_RealTimeMS() API: 3. Falling back to Last Known Good UTC Time D: 664980 [SC]Sending status report. Protocol code 0, exchange 25391 I: 664986 [EM]<<< [E:25391r M:171505247 (Ack:169302258)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 664999 [IN](U) Sending msg 171505247 to IP address 'UDP:[fd89:2db1:a6ad:f291:dde4:c847:f114:4f2a]:43295' I: 665011 [SC]SecureSession[0x200041f0]: Moving from state 'kEstablishing' --> 'kActive' D: 665019 [IN]SecureSession[0x200041f0]: Activated - Type:2 LSID:56923 D: 665026 [IN]New secure session activated for device <000000000001B669, 1>, LSID:56923 PSID:43834! I: 665036 [IN]CASE Session established to peer: <000000000001B669, 1> I: 665044 [IN]CASE Server enabling CASE session setups I: 665049 [SC]Evicting a slot for session with LSID: 56924, type: 2 I: 665056 [SC]Sorted sessions for eviction... D: 665060 [SC]Sorted Eviction Candidates (ranked from best candidate to worst): D: 665067 [SC] 0: [0x20003728] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 175922 D: 665080 [SC] 1: [0x200037e0] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 193777 D: 665093 [SC] 2: [0x20003670] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 305728 D: 665106 [SC] 3: [0x20003898] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 312074 D: 665119 [SC] 4: [0x20003950] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 318168 D: 665132 [SC] 5: [0x20003a08] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 362077 D: 665145 [SC] 6: [0x20003ac0] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 368316 D: 665158 [SC] 7: [0x20003b78] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 376733 D: 665171 [SC] 8: [0x20003c30] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 380594 D: 665184 [SC] 9: [0x20003ce8] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 384898 D: 665197 [SC] 10: [0x20003da0] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 572551 D: 665210 [SC] 11: [0x20003e58] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 629959 D: 665223 [SC] 12: [0x20003f10] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 640357 D: 665236 [SC] 13: [0x20003fc8] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 644863 D: 665249 [SC] 14: [0x20004080] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 649339 D: 665262 [SC] 15: [0x20004138] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 662176 D: 665275 [SC] 16: [0x200041f0] -- Peer: [1:000000000001B669] State: 'kActive', NumMatchingOnFabric: 16 NumMatchingOnPeer: 16 ActivityTime: 665011 I: 665288 [SC]Candidate Session[0x20003728] - Attempting to evict... D: 665294 [IN]SecureSession[0x20003728]: MarkForEviction Type:2 LSID:56907 I: 665301 [SC]SecureSession[0x20003728]: Moving from state 'kActive' --> 'kPendingEviction' D: 665309 [IN]SecureSession[0x20003728]: Released - Type:2 LSID:56907 I: 665315 [SC]Successfully evicted a session! D: 665320 [IN]SecureSession[0x20003728]: Allocated Type:2 LSID:56924 D: 665326 [SC]Allocated SecureSession (0x20003728) - waiting for Sigma1 msg I: 665333 [EM]>>> [E:25392r M:252961790] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0001:02 (IM:ReadRequest) D: 665344 [EM]Handling via exchange: 25392r, Delegate: 0x2000d504 D: 665349 [IM]Received Read request D: 665353 [DMG]IM RH moving to [GeneratingReports] D: 665358 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 665367 [DMG] Cluster 402, Attribute 0 is dirty D: 665372 [DMG]Reading attribute: Cluster=0x0000_0402 Endpoint=1 AttributeId=0x0000_0000 (expanded=0) D: 665381 [DMG] Sending report (payload has 38 bytes)... I: 665389 [EM]<<< [E:25392r M:248697776 (Ack:252961790)] (S) Msg TX to 1:000000000001B669 [0EBD] --- Type 0001:05 (IM:ReportData) I: 665400 [IN](S) Sending msg 248697776 on secure session with LSID: 56923 D: 665408 [DMG] OnReportConfirm: NumReports = 0 D: 665413 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 665421 [DMG]IM RH moving to [AwaitingDestruction] D: 665425 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 665431 [EM]>>> [E:25391r M:169302259 (Ack:171505247)] (U) Msg RX from 0:1AED29C75F2AF945 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 665446 [EM]Found matching exchange: 25391r, Delegate: (nil) D: 665451 [EM]Rxd Ack; Removing MessageCounter:171505247 from Retrans Table on exchange 25391r D: 665461 [IN]Received a duplicate message with MessageCounter:252961790 on exchange 25392r I: 665469 [EM]>>> [E:25392r M:252961790] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0001:02 (IM:ReadRequest) D: 665479 [EM]Found matching exchange: 25392r, Delegate: (nil) D: 665485 [EM]Forcing tx of solitary ack for duplicate MessageCounter:252961790 on exchange 25392r I: 665494 [EM]<<< [E:25392r M:248697777 (Ack:252961790)] (S) Msg TX to 1:000000000001B669 [0EBD] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 665507 [IN](S) Sending msg 248697777 on secure session with LSID: 56923 I: 665515 [EM]>>> [E:25392r M:252961791 (Ack:248697776)] (S) Msg RX from 1:000000000001B669 [0EBD] --- Type 0000:10 (SecureChannel:StandaloneAck) D: 665529 [EM]Found matching exchange: 25392r, Delegate: (nil) D: 665534 [EM]Rxd Ack; Removing MessageCounter:248697776 from Retrans Table on exchange 25392r ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~