NRF 52840 matter window covering accessory is displaying no response on Apple Home app with Home pop mini

Hi

 I use a HomePod mini as a border router,commissioning six window covering samples.They are working normally.But after two days,I found two samples displaying no response on the Apple    HOME app.Must be added again.And then I found when I power off the samples for a while,they are displaying no response after power on.I've been waiting for a long time, and the app still shows the same thing.

 NCS Version: v2.3.0

 Home pop mini version:16.4

How should I debug this issue,

Regards

Danny

Parents
  • Hi,

    Have you checked the device logs from the nRF52840 to see if it gives any indications to what happened? Logging can be enabled/increased as described in Thread and Matter.

    If you could also do a sniffer trace, that could indicate what is not working. Ideally a sniffer trace when the issue occurs as well would be useful, but might not be easy to capture if the issue happens after a long time.

    If you can provide the device logs and sniffer logs to us, we might be able to help determine what is going on.

    Best regards,
    Jørgen

  • Are you sure the sniffer is started on the correct channel? Looks like there is very few packets if your image shows the entire sniffer log.

    Can you try enabling DEBUG log level in your application build according to my previous links, to see if this provides more details in the device logs?

  • Hi,

       The public version of the windowcovering sample I tested also has this phenomenon. Can you reproduce it?

       Following the process:Commission the sample,and Power off the sample for a while,and then turn on the power,the home app show no repond all the time.But The light_bulb  sample is working normally following the same process.

       WireShake configuration:(Use Nrf52840 Doggle),Are there any issues with the configuration?

        NRF52840 window covering log:

    00> I: nRF5 802154 radio initialized
    00> I: 4 Sectors of 4096 bytes
    00> I: alloc wra: 1, e70
    00> I: data wra: 1, 63c
    00> *** Booting Zephyr OS build v3.2.99-ncs2 ***
    00> I: Init CHIP stack
    00> I: 214 [DL]BLE address: E5:B2:6F:10:63:30
    00> I: 218 [DL]OpenThread SED interval is 500ms
    00> I: 257 [DL]OpenThread ifconfig up and thread start
    00> I: 261 [DL]OpenThread started: OK
    00> I: 261 [DL]Setting OpenThread device type to SYNCHRONIZED SLEEPY END DEVICE
    00> I: uart_init...
    00> I: StartUartTimer
    00> I: 262 [SVR]Server initializing...
    00> I: 263 [TS]Last Known Good Time: 2023-04-25T17:20:00
    00> I: 267 [FP]Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xCCA458A73881E3D2, FabricId 0x0000000000000001, NodeId 0x00000000E0B83703, VendorId 0x1349
    00> I: 270 [FP]Fabric index 0x2 was retrieved from storage. Compressed FabricId 0xF64035E83E412165, FabricId 0x0000000000000002, NodeId 0x000000001F8A003D, VendorId 0x1384
    00> I: 272 [DMG]AccessControl: initializing
    00> I: 272 [DMG]Examples::AccessControlDelegate::Init
    00> I: 273 [DMG]AccessControl: setting
    00> I: 273 [DMG]DefaultAclStoragE: 4866 [DL]SRP update error: timed out waiting on server response
    00> I: UartSleep set pin gpio@50000000 2
    00> 
    00> I: CancelUartTimer
    00> I: UartSleep set end
    00> 
    00> I: 22909 [EM]>>> [E:21114r M:60693269] (U) Msg RX from 0:2A93603EA476CBA2 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    00> I: 22910 [EM]<<< [E:21114r M:235396625 (Ack:60693269)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 22911 [IN](U) Sending msg 235396625 to IP address 'UDP:[fd85:4025:13b2:0:ef0e:d145:69fc:5121]:61284'
    00> E: 40037 [SC]CASESession timed out while waiting for a response from the peer. Current state was 5
    00> E: 40037 [IN]CASE Session establishment failed: 32
    00> I: 40037 [IN]CASE Server enabling CASE session setups
    00> D: 40038 [IN]SecureSession[0x20003578]: Released - Type:2 LSID:64331
    00> D: 40038 [IN]SecureSession[0x20003578]: Allocated Type:2 LSID:64332
    00> I: 40039 [DL]OpenThread SED interval is 500ms
    00> I: 117410 [EM]>>> [E:21115r M:60693270] (U) Msg RX from 0:5BECF2A9E3B6E5F2 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    00> I: 117411 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20004ea8
    00> I: 117411 [IN]CASE Server disabling CASE session setups
    00> I: 117412 [EM]<<< [E:21115r M:235396626 (Ack:60693270)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 117413 [IN](U) Sending msg 235396626 to IP address 'UDP:[fd85:4025:13b2:0:ef0e:d145:69fc:5121]:61284'
    00> I: 117414 [SC]Received Sigma1 msg
    00> I: 117418 [EM]<<< [E:21115r M:235396627 (Ack:60693270)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:33 (SecureChannel:CASE_Sigma2Resume)
    00> I: 117420 [IN](U) Sending msg 235396627 to IP address 'UDP:[fd85:4025:13b2:0:ef0e:d145:69fc:5121]:61284'
    00> I: 117422 [DL]OpenThread SED interval is 500ms
    00> I: 118817 [IN](U) Sending msg 235396627 to IP address 'UDP:[fd85:4025:13b2:0:ef0e:d145:69fc:5121]:61284'
    00> I: 118839 [EM]>>> [E:21115r M:60693271 (Ack:235396627)] (U) Msg RX from 0:5BECF2A9E3B6E5F2 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    00> I: 118840 [SC]Success status report received. Session was established
    00> I: 118881 [SC]SecureSession[0x20003578]: Moving from state 'kEstablishing' --> 'kActive'
    00> D: 118881 [IN]SecureSession[0x20003578]: Activated - Type:2 LSID:64332
    00> D: 118882 [IN]New secure session activated for device <5ABECE966C84D61C, 1>, LSID:64332 PSID:57552!
    00> I: 118882 [IN]CASE Session established to peer: <5ABECE966C84D61C, 1>
    00> I: 118883 [IN]CASE Server enabling CASE session setups
    00> D: 118883 [IN]SecureSession[0x20003630]: Allocated Type:2 LSID:64333
    00> I: 118884 [DL]OpenThread SED interval is 500ms
    00> I: 118884 [EM]<<< [E:21115r M:235396628 (Ack:60693271)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 118885 [IN](U) Sending msg 235396628 to IP address 'UDP:[fd85:4025:13b2:0:ef0e:d145:69fc:5121]:61284'
    00> I: 119023 [EM]>>> [E:21116r M:134862488] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:03 (IM:SubscribeRequest)
    00> D: 119024 [IM]Received Subscribe request
    00> I: 119030 [DMG]Final negotiated min/max parameters: Min = 0s, Max = 2s
    00> I: 119031 [DL]OpenThread SED interval is 500ms
    00> E: 119034 [DMG]Error retrieving data from clusterId: 0x0000_001F, err = b
    00> I: 119041 [EM]<<< [E:21116r M:30572577 (Ack:134862488)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 119042 [IN](S) Sending msg 30572577 on secure session with LSID: 64332
    00> I: 119045 [EM]>>> [E:21115r M:60693272 (Ack:235396627)] (U) Msg RX from 0:5BECF2A9E3B6E5F2 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> D: 119191 [IN]Received a duplicate message with MessageCounter:134862488 on exchange 21116r
    00> I: 119192 [EM]>>> [E:21116r M:134862488] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:03 (IM:SubscribeRequest)
    00> I: 119193 [EM]<<< [E:21116r M:30572578 (Ack:134862488)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 119194 [IN](S) Sending msg 30572578 on secure session with LSID: 64332
    00> I: 120435 [IN](S) Sending msg 30572577 on secure session with LSID: 64332
    00> I: 121782 [IN](S) Sending msg 30572577 on secure session with LSID: 64332
    00> I: 121807 [EM]>>> [E:21116r M:134862490 (Ack:30572577)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 121831 [EM]>>> [E:21116r M:134862489 (Ack:30572577)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 121832 [IM]Received status response, status is 0x00
    00> E: 121843 [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b
    00> I: 121850 [EM]<<< [E:21116r M:30572579 (Ack:134862489)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 121852 [IN](S) Sending msg 30572579 on secure session with LSID: 64332
    00> I: 121957 [EM]>>> [E:21116r M:134862491 (Ack:30572577)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 123199 [IN](S) Sending msg 30572579 on secure session with LSID: 64332
    00> I: 123879 [EM]>>> [E:21116r M:134862493 (Ack:30572579)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 124880 [EM]>>> [E:21116r M:134862492 (Ack:30572579)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 124880 [IM]Received status response, status is 0x00
    00> E: 124886 [DMG]Error retrieving data from clusterId: 0x0000_002A, err = b
    00> I: 124892 [EM]<<< [E:21116r M:30572580 (Ack:134862492)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 124893 [IN](S) Sending msg 30572580 on secure session with LSID: 64332
    00> I: 126301 [IN](S) Sending msg 30572580 on secure session with LSID: 64332
    00> I: 126325 [EM]>>> [E:21116r M:134862494 (Ack:30572580)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 126326 [IM]Received status response, status is 0x00
    00> E: 126330 [DMG]Error retrieving data from clusterId: 0x0000_0030, err = b
    00> I: 126338 [EM]<<< [E:21116r M:30572581 (Ack:134862494)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 126338 [IN](S) Sending msg 30572581 on secure session with LSID: 64332
    00> I: 126451 [EM]>>> [E:21116r M:134862495 (Ack:30572580)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 127677 [IN](S) Sending msg 30572581 on secure session with LSID: 64332
    00> I: 128379 [EM]>>> [E:21116r M:134862497 (Ack:30572581)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 129380 [EM]>>> [E:21116r M:134862496 (Ack:30572581)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 129380 [IM]Received status response, status is 0x00
    00> E: 129385 [DMG]Error retrieving data from clusterId: 0x0000_0031, err = b
    00> I: 129391 [EM]<<< [E:21116r M:30572582 (Ack:134862496)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 129391 [IN](S) Sending msg 30572582 on secure session with LSID: 64332
    00> I: 130380 [EM]>>> [E:21116r M:134862498 (Ack:30572582)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 130380 [IM]Received status response, status is 0x00
    00> E: 130385 [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b
    00> I: 130391 [EM]<<< [E:21116r M:30572583 (Ack:134862498)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 130392 [IN](S) Sending msg 30572583 on secure session with LSID: 64332
    00> I: 131787 [IN](S) Sending msg 30572583 on secure session with LSID: 64332
    00> I: 131813 [EM]>>> [E:21116r M:134862499 (Ack:30572583)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 131814 [IM]Received status response, status is 0x00
    00> E: 131818 [DL]_WriteThreadNetworkDiagnosticAttributeToTlv failed: Error CHIP:0x0000000B
    00> E: 131818 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b
    00> I: 131828 [EM]<<< [E:21116r M:30572584 (Ack:134862499)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 131828 [IN](S) Sending msg 30572584 on secure session with LSID: 64332
    00> I: 131934 [EM]>>> [E:21116r M:134862500 (Ack:30572583)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> D: 132002 [IN]Received a duplicate message with MessageCounter:134862499 on exchange 21116r
    00> I: 132002 [EM]>>> [E:21116r M:134862499 (Ack:30572583)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 132004 [EM]<<< [E:21116r M:30572585 (Ack:134862499)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 132005 [IN](S) Sending msg 30572585 on secure session with LSID: 64332
    00> I: 133163 [IN](S) Sending msg 30572584 on secure session with LSID: 64332
    00> I: 133879 [EM]>>> [E:21116r M:134862502 (Ack:30572584)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 134880 [EM]>>> [E:21116r M:134862501 (Ack:30572584)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 134880 [IM]Received status response, status is 0x00
    00> E: 134886 [DL]_WriteThreadNetworkDiagnosticAttributeToTlv failed: Error CHIP:0x0000000B
    00> E: 134886 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b
    00> I: 134892 [EM]<<< [E:21116r M:30572586 (Ack:134862501)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 134893 [IN](S) Sending msg 30572586 on secure session with LSID: 64332
    00> I: 136297 [IN](S) Sending msg 30572586 on secure session with LSID: 64332
    00> I: 136322 [EM]>>> [E:21116r M:134862503 (Ack:30572586)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 136322 [IM]Received status response, status is 0x00
    00> E: 136341 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b
    00> I: 136348 [EM]<<< [E:21116r M:30572587 (Ack:134862503)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 136349 [IN](S) Sending msg 30572587 on secure session with LSID: 64332
    00> I: 136446 [EM]>>> [E:21116r M:134862504 (Ack:30572586)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 137727 [IN](S) Sending msg 30572587 on secure session with LSID: 64332
    00> I: 137752 [EM]>>> [E:21116r M:134862505 (Ack:30572587)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 137753 [IM]Received status response, status is 0x00
    00> E: 137755 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b
    00> I: 137763 [EM]<<< [E:21116r M:30572588 (Ack:134862505)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 137764 [IN](S) Sending msg 30572588 on secure session with LSID: 64332
    00> I: 137866 [EM]>>> [E:21116r M:134862506 (Ack:30572587)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 139142 [IN](S) Sending msg 30572588 on secure session with LSID: 64332
    00> I: 139165 [EM]>>> [E:21116r M:134862507 (Ack:30572588)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 139166 [IM]Received status response, status is 0x00
    00> E: 139169 [DMG]Error retrieving data from clusterId: 0x0000_003C, err = b
    00> I: 139177 [EM]<<< [E:21116r M:30572589 (Ack:134862507)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 139178 [IN](S) Sending msg 30572589 on secure session with LSID: 64332
    00> I: 139282 [EM]>>> [E:21116r M:134862508 (Ack:30572588)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 140587 [IN](S) Sending msg 30572589 on secure session with LSID: 64332
    00> I: 140613 [EM]>>> [E:21116r M:134862509 (Ack:30572589)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 140613 [IM]Received status response, status is 0x00
    00> E: 140619 [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    00> I: 140627 [EM]<<< [E:21116r M:30572590 (Ack:134862509)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 140628 [IN](S) Sending msg 30572590 on secure session with LSID: 64332
    00> I: 140719 [EM]>>> [E:21116r M:134862510 (Ack:30572589)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 141380 [EM]>>> [E:21116r M:134862511 (Ack:30572590)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 141380 [IM]Received status response, status is 0x00
    00> E: 141383 [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    00> I: 141388 [EM]<<< [E:21116r M:30572591 (Ack:134862511)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 141389 [IN](S) Sending msg 30572591 on secure session with LSID: 64332
    00> I: 141880 [EM]>>> [E:21116r M:134862512 (Ack:30572591)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 141880 [IM]Received status response, status is 0x00
    00> E: 141885 [DMG]Error retrieving data from clusterId: 0x0000_003F, err = b
    00> I: 141891 [EM]<<< [E:21116r M:30572592 (Ack:134862512)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 141891 [IN](S) Sending msg 30572592 on secure session with LSID: 64332
    00> I: 142880 [EM]>>> [E:21116r M:134862513 (Ack:30572592)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 142880 [IM]Received status response, status is 0x00
    00> E: 142883 [DMG]Error retrieving data from clusterId: 0x0000_001D, err = b
    00> I: 142889 [EM]<<< [E:21116r M:30572593 (Ack:134862513)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 142889 [IN](S) Sending msg 30572593 on secure session with LSID: 64332
    00> I: 143880 [EM]>>> [E:21116r M:134862514 (Ack:30572593)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 143880 [IM]Received status response, status is 0x00
    00> E: 143884 [DMG]Error retrieving data from clusterId: 0x0000_0102, err = b
    00> I: 143890 [EM]<<< [E:21116r M:30572594 (Ack:134862514)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 143891 [IN](S) Sending msg 30572594 on secure session with LSID: 64332
    00> I: 145271 [IN](S) Sending msg 30572594 on secure session with LSID: 64332
    00> I: 145296 [EM]>>> [E:21116r M:134862515 (Ack:30572594)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 145297 [IM]Received status response, status is 0x00
    00> E: 145299 [DMG]Error retrieving data from clusterId: 0x0000_0102, err = b
    00> I: 145307 [EM]<<< [E:21116r M:30572595 (Ack:134862515)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 145308 [IN](S) Sending msg 30572595 on secure session with LSID: 64332
    00> I: 145417 [EM]>>> [E:21116r M:134862516 (Ack:30572594)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 146701 [IN](S) Sending msg 30572595 on secure session with LSID: 64332
    00> I: 148087 [IN](S) Sending msg 30572595 on secure session with LSID: 64332
    00> I: 148380 [EM]>>> [E:21116r M:134862518 (Ack:30572595)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 148399 [EM]>>> [E:21116r M:134862517 (Ack:30572595)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 148399 [IM]Received status response, status is 0x00
    00> I: 148402 [EM]<<< [E:21116r M:30572596 (Ack:134862517)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 148404 [IN](S) Sending msg 30572596 on secure session with LSID: 64332
    00> I: 148492 [EM]>>> [E:21116r M:134862519 (Ack:30572595)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 149786 [IN](S) Sending msg 30572596 on secure session with LSID: 64332
    00> I: 149810 [EM]>>> [E:21116r M:134862520 (Ack:30572596)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 149810 [IM]Received status response, status is 0x00
    00> I: 149811 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 149812 [EM]<<< [E:21116r M:30572597 (Ack:134862520)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:04 (IM:SubscribeResponse)
    00> I: 149813 [IN](S) Sending msg 30572597 on secure session with LSID: 64332
    00> I: 151190 [IN](S) Sending msg 30572597 on secure session with LSID: 64332
    00> I: 151380 [EM]>>> [E:21116r M:134862522 (Ack:30572597)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 151380 [DL]OpenThread SED interval is 500ms
    00> I: 151393 [EM]>>> [E:21117r M:134862523] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:02 (IM:ReadRequest)
    00> D: 151394 [IM]Received Read request
    00> I: 151395 [DL]OpenThread SED interval is 500ms
    00> I: 151397 [EM]<<< [E:21117r M:30572598 (Ack:134862523)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 151397 [IN](S) Sending msg 30572598 on secure session with LSID: 64332
    00> I: 151405 [EM]>>> [E:21116r M:134862524 (Ack:30572597)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 151814 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 151820 [EM]<<< [E:48703i M:30572599] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 151820 [IN](S) Sending msg 30572599 on secure session with LSID: 64332
    00> I: 151822 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 151842 [EM]>>> [E:21117r M:134862525 (Ack:30572598)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 153223 [IN](S) Sending msg 30572599 on secure session with LSID: 64332
    00> I: 153380 [EM]>>> [E:48703i M:134862526 (Ack:30572599)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 153380 [IM]Received status response, status is 0x00
    00> I: 153381 [DL]OpenThread SED interval is 500ms
    00> I: 153382 [EM]<<< [E:48703i M:30572600 (Ack:134862526)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 153382 [IN](S) Sending msg 30572600 on secure session with LSID: 64332
    00> I: 153410 [EM]>>> [E:48703i M:134862527 (Ack:30572599)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 153411 [EM]OnMessageReceived failed, err = 70
    00> D: 153424 [IN]Received a duplicate message with MessageCounter:134862526 on exchange 48703i
    00> I: 153425 [EM]>>> [E:48703i M:134862526 (Ack:30572599)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 153426 [EM]<<< [E:48703i M:30572601 (Ack:134862526)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 153427 [IN](S) Sending msg 30572601 on secure session with LSID: 64332
    00> I: 153824 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 153829 [EM]<<< [E:48704i M:30572602] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 153830 [IN](S) Sending msg 30572602 on secure session with LSID: 64332
    00> I: 153831 [DL]OpenThread SED interval is 500ms
    00> I: 153832 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 155178 [IN](S) Sending msg 30572602 on secure session with LSID: 64332
    00> I: 155380 [EM]>>> [E:48704i M:134862528 (Ack:30572602)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 155380 [IM]Received status response, status is 0x00
    00> I: 155381 [DL]OpenThread SED interval is 500ms
    00> I: 155382 [EM]<<< [E:48704i M:30572603 (Ack:134862528)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 155382 [IN](S) Sending msg 30572603 on secure session with LSID: 64332
    00> I: 155413 [EM]>>> [E:48704i M:134862529 (Ack:30572602)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 155414 [EM]OnMessageReceived failed, err = 70
    00> D: 155427 [IN]Received a duplicate message with MessageCounter:134862528 on exchange 48704i
    00> I: 155428 [EM]>>> [E:48704i M:134862528 (Ack:30572602)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 155429 [EM]<<< [E:48704i M:30572604 (Ack:134862528)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 155429 [IN](S) Sending msg 30572604 on secure session with LSID: 64332
    00> I: 155832 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 155838 [EM]<<< [E:48705i M:30572605] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 155838 [IN](S) Sending msg 30572605 on secure session with LSID: 64332
    00> I: 155840 [DL]OpenThread SED interval is 500ms
    00> I: 155840 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 157216 [IN](S) Sending msg 30572605 on secure session with LSID: 64332
    00> I: 157380 [EM]>>> [E:48705i M:134862530 (Ack:30572605)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 157380 [IM]Received status response, status is 0x00
    00> I: 157381 [DL]OpenThread SED interval is 500ms
    00> I: 157382 [EM]<<< [E:48705i M:30572606 (Ack:134862530)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 157382 [IN](S) Sending msg 30572606 on secure session with LSID: 64332
    00> I: 157398 [EM]>>> [E:48705i M:134862531 (Ack:30572605)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 157398 [EM]OnMessageReceived failed, err = 70
    00> D: 157419 [IN]Received a duplicate message with MessageCounter:134862530 on exchange 48705i
    00> I: 157419 [EM]>>> [E:48705i M:134862530 (Ack:30572605)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 157421 [EM]<<< [E:48705i M:30572607 (Ack:134862530)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 157421 [IN](S) Sending msg 30572607 on secure session with LSID: 64332
    00> I: 157841 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 157846 [EM]<<< [E:48706i M:30572608] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 157847 [IN](S) Sending msg 30572608 on secure session with LSID: 64332
    00> I: 157849 [DL]OpenThread SED interval is 500ms
    00> I: 157849 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 159247 [IN](S) Sending msg 30572608 on secure session with LSID: 64332
    00> I: 159380 [EM]>>> [E:48706i M:134862532 (Ack:30572608)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 159380 [IM]Received status response, status is 0x00
    00> I: 159381 [DL]OpenThread SED interval is 500ms
    00> I: 159382 [EM]<<< [E:48706i M:30572609 (Ack:134862532)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 159382 [IN](S) Sending msg 30572609 on secure session with LSID: 64332
    00> I: 159404 [EM]>>> [E:48706i M:134862533 (Ack:30572608)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 159404 [EM]OnMessageReceived failed, err = 70
    00> I: 159850 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 159856 [EM]<<< [E:48707i M:30572610] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 159856 [IN](S) Sending msg 30572610 on secure session with LSID: 64332
    00> I: 159858 [DL]OpenThread SED interval is 500ms
    00> I: 159858 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 161209 [IN](S) Sending msg 30572610 on secure session with LSID: 64332
    00> I: 161233 [EM]>>> [E:48707i M:134862534 (Ack:30572610)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 161233 [IM]Received status response, status is 0x00
    00> I: 161234 [DL]OpenThread SED interval is 500ms
    00> I: 161235 [EM]<<< [E:48707i M:30572611 (Ack:134862534)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 161235 [IN](S) Sending msg 30572611 on secure session with LSID: 64332
    00> I: 161259 [EM]>>> [E:48707i M:134862535 (Ack:30572610)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 161260 [EM]OnMessageReceived failed, err = 70
    00> I: 161859 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 161865 [EM]<<< [E:48708i M:30572612] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 161865 [IN](S) Sending msg 30572612 on secure session with LSID: 64332
    00> I: 161867 [DL]OpenThread SED interval is 500ms
    00> I: 161867 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 161899 [EM]>>> [E:48708i M:134862536 (Ack:30572612)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 161900 [IM]Received status response, status is 0x00
    00> I: 161900 [DL]OpenThread SED interval is 500ms
    00> I: 161901 [EM]<<< [E:48708i M:30572613 (Ack:134862536)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 161902 [IN](S) Sending msg 30572613 on secure session with LSID: 64332
    00> I: 163869 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 163875 [EM]<<< [E:48709i M:30572614] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 163875 [IN](S) Sending msg 30572614 on secure session with LSID: 64332
    00> I: 163877 [DL]OpenThread SED interval is 500ms
    00> I: 163877 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 165284 [IN](S) Sending msg 30572614 on secure session with LSID: 64332
    00> I: 165309 [EM]>>> [E:48709i M:134862537 (Ack:30572614)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 165310 [IM]Received status response, status is 0x00
    00> I: 165310 [DL]OpenThread SED interval is 500ms
    00> I: 165311 [EM]<<< [E:48709i M:30572615 (Ack:134862537)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 165313 [IN](S) Sending msg 30572615 on secure session with LSID: 64332
    00> I: 165326 [EM]>>> [E:48709i M:134862538 (Ack:30572614)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 165326 [EM]OnMessageReceived failed, err = 70
    00> I: 165878 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 165884 [EM]<<< [E:48710i M:30572616] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 165884 [IN](S) Sending msg 30572616 on secure session with LSID: 64332
    00> I: 165886 [DL]OpenThread SED interval is 500ms
    00> I: 165886 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 167232 [IN](S) Sending msg 30572616 on secure session with LSID: 64332
    00> I: 167255 [EM]>>> [E:48710i M:134862539 (Ack:30572616)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 167256 [IM]Received status response, status is 0x00
    00> I: 167256 [DL]OpenThread SED interval is 500ms
    00> I: 167257 [EM]<<< [E:48710i M:30572617 (Ack:134862539)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 167257 [IN](S) Sending msg 30572617 on secure session with LSID: 64332
    00> I: 167280 [EM]>>> [E:48710i M:134862540 (Ack:30572616)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 167281 [EM]OnMessageReceived failed, err = 70
    00> I: 167886 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 167892 [EM]<<< [E:48711i M:30572618] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 167892 [IN](S) Sending msg 30572618 on secure session with LSID: 64332
    00> I: 167894 [DL]OpenThread SED interval is 500ms
    00> I: 167894 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 169232 [IN](S) Sending msg 30572618 on secure session with LSID: 64332
    00> I: 169895 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 170581 [IN](S) Sending msg 30572618 on secure session with LSID: 64332
    00> I: 170880 [EM]>>> [E:48711i M:134862542 (Ack:30572618)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 170892 [EM]>>> [E:48711i M:134862541 (Ack:30572618)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 170893 [IM]Received status response, status is 0x00
    00> I: 170893 [DL]OpenThread SED interval is 500ms
    00> I: 170895 [EM]<<< [E:48711i M:30572619 (Ack:134862541)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 170896 [IN](S) Sending msg 30572619 on secure session with LSID: 64332
    00> I: 170904 [EM]<<< [E:48712i M:30572620] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 170905 [IN](S) Sending msg 30572620 on secure session with LSID: 64332
    00> I: 170906 [DL]OpenThread SED interval is 500ms
    00> I: 170906 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 170909 [EM]>>> [E:48711i M:134862543 (Ack:30572618)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 170910 [EM]OnMessageReceived failed, err = 70
    00> I: 171880 [EM]>>> [E:48712i M:134862544 (Ack:30572620)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 171880 [IM]Received status response, status is 0x00
    00> I: 171881 [DL]OpenThread SED interval is 500ms
    00> I: 171882 [EM]<<< [E:48712i M:30572621 (Ack:134862544)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 171882 [IN](S) Sending msg 30572621 on secure session with LSID: 64332
    00> I: 172907 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 172913 [EM]<<< [E:48713i M:30572622] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 172913 [IN](S) Sending msg 30572622 on secure session with LSID: 64332
    00> I: 172915 [DL]OpenThread SED interval is 500ms
    00> I: 172915 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 173880 [EM]>>> [E:48713i M:134862545 (Ack:30572622)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 173880 [IM]Received status response, status is 0x00
    00> I: 173881 [DL]OpenThread SED interval is 500ms
    00> I: 173882 [EM]<<< [E:48713i M:30572623 (Ack:134862545)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 173882 [IN](S) Sending msg 30572623 on secure session with LSID: 64332
    00> I: 174916 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 174922 [EM]<<< [E:48714i M:30572624] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 174922 [IN](S) Sending msg 30572624 on secure session with LSID: 64332
    00> I: 174924 [DL]OpenThread SED interval is 500ms
    00> I: 174925 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 176311 [IN](S) Sending msg 30572624 on secure session with LSID: 64332
    00> I: 176336 [EM]>>> [E:48714i M:134862546 (Ack:30572624)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 176337 [IM]Received status response, status is 0x00
    00> I: 176337 [DL]OpenThread SED interval is 500ms
    00> I: 176338 [EM]<<< [E:48714i M:30572625 (Ack:134862546)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 176339 [IN](S) Sending msg 30572625 on secure session with LSID: 64332
    00> I: 176364 [EM]>>> [E:48714i M:134862547 (Ack:30572624)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 176364 [EM]OnMessageReceived failed, err = 70
    00> I: 176925 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 176930 [EM]<<< [E:48715i M:30572626] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 176931 [IN](S) Sending msg 30572626 on secure session with LSID: 64332
    00> I: 176933 [DL]OpenThread SED interval is 500ms
    00> I: 176933 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 178262 [IN](S) Sending msg 30572626 on secure session with LSID: 64332
    00> I: 178935 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 179629 [IN](S) Sending msg 30572626 on secure session with LSID: 64332
    00> I: 179880 [EM]>>> [E:48715i M:134862549 (Ack:30572626)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 179893 [EM]>>> [E:48715i M:134862548 (Ack:30572626)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 179894 [IM]Received status response, status is 0x00
    00> I: 179894 [DL]OpenThread SED interval is 500ms
    00> I: 179895 [EM]<<< [E:48715i M:30572627 (Ack:134862548)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 179896 [IN](S) Sending msg 30572627 on secure session with LSID: 64332
    00> I: 179902 [EM]<<< [E:48716i M:30572628] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 179903 [IN](S) Sending msg 30572628 on secure session with LSID: 64332
    00> I: 179904 [DL]OpenThread SED interval is 500ms
    00> I: 179905 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 179910 [EM]>>> [E:48715i M:134862550 (Ack:30572626)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 179911 [EM]OnMessageReceived failed, err = 70

    Best regards,
    Danny

Reply
  • Hi,

       The public version of the windowcovering sample I tested also has this phenomenon. Can you reproduce it?

       Following the process:Commission the sample,and Power off the sample for a while,and then turn on the power,the home app show no repond all the time.But The light_bulb  sample is working normally following the same process.

       WireShake configuration:(Use Nrf52840 Doggle),Are there any issues with the configuration?

        NRF52840 window covering log:

    00> I: nRF5 802154 radio initialized
    00> I: 4 Sectors of 4096 bytes
    00> I: alloc wra: 1, e70
    00> I: data wra: 1, 63c
    00> *** Booting Zephyr OS build v3.2.99-ncs2 ***
    00> I: Init CHIP stack
    00> I: 214 [DL]BLE address: E5:B2:6F:10:63:30
    00> I: 218 [DL]OpenThread SED interval is 500ms
    00> I: 257 [DL]OpenThread ifconfig up and thread start
    00> I: 261 [DL]OpenThread started: OK
    00> I: 261 [DL]Setting OpenThread device type to SYNCHRONIZED SLEEPY END DEVICE
    00> I: uart_init...
    00> I: StartUartTimer
    00> I: 262 [SVR]Server initializing...
    00> I: 263 [TS]Last Known Good Time: 2023-04-25T17:20:00
    00> I: 267 [FP]Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xCCA458A73881E3D2, FabricId 0x0000000000000001, NodeId 0x00000000E0B83703, VendorId 0x1349
    00> I: 270 [FP]Fabric index 0x2 was retrieved from storage. Compressed FabricId 0xF64035E83E412165, FabricId 0x0000000000000002, NodeId 0x000000001F8A003D, VendorId 0x1384
    00> I: 272 [DMG]AccessControl: initializing
    00> I: 272 [DMG]Examples::AccessControlDelegate::Init
    00> I: 273 [DMG]AccessControl: setting
    00> I: 273 [DMG]DefaultAclStoragE: 4866 [DL]SRP update error: timed out waiting on server response
    00> I: UartSleep set pin gpio@50000000 2
    00> 
    00> I: CancelUartTimer
    00> I: UartSleep set end
    00> 
    00> I: 22909 [EM]>>> [E:21114r M:60693269] (U) Msg RX from 0:2A93603EA476CBA2 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    00> I: 22910 [EM]<<< [E:21114r M:235396625 (Ack:60693269)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 22911 [IN](U) Sending msg 235396625 to IP address 'UDP:[fd85:4025:13b2:0:ef0e:d145:69fc:5121]:61284'
    00> E: 40037 [SC]CASESession timed out while waiting for a response from the peer. Current state was 5
    00> E: 40037 [IN]CASE Session establishment failed: 32
    00> I: 40037 [IN]CASE Server enabling CASE session setups
    00> D: 40038 [IN]SecureSession[0x20003578]: Released - Type:2 LSID:64331
    00> D: 40038 [IN]SecureSession[0x20003578]: Allocated Type:2 LSID:64332
    00> I: 40039 [DL]OpenThread SED interval is 500ms
    00> I: 117410 [EM]>>> [E:21115r M:60693270] (U) Msg RX from 0:5BECF2A9E3B6E5F2 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    00> I: 117411 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20004ea8
    00> I: 117411 [IN]CASE Server disabling CASE session setups
    00> I: 117412 [EM]<<< [E:21115r M:235396626 (Ack:60693270)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 117413 [IN](U) Sending msg 235396626 to IP address 'UDP:[fd85:4025:13b2:0:ef0e:d145:69fc:5121]:61284'
    00> I: 117414 [SC]Received Sigma1 msg
    00> I: 117418 [EM]<<< [E:21115r M:235396627 (Ack:60693270)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:33 (SecureChannel:CASE_Sigma2Resume)
    00> I: 117420 [IN](U) Sending msg 235396627 to IP address 'UDP:[fd85:4025:13b2:0:ef0e:d145:69fc:5121]:61284'
    00> I: 117422 [DL]OpenThread SED interval is 500ms
    00> I: 118817 [IN](U) Sending msg 235396627 to IP address 'UDP:[fd85:4025:13b2:0:ef0e:d145:69fc:5121]:61284'
    00> I: 118839 [EM]>>> [E:21115r M:60693271 (Ack:235396627)] (U) Msg RX from 0:5BECF2A9E3B6E5F2 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    00> I: 118840 [SC]Success status report received. Session was established
    00> I: 118881 [SC]SecureSession[0x20003578]: Moving from state 'kEstablishing' --> 'kActive'
    00> D: 118881 [IN]SecureSession[0x20003578]: Activated - Type:2 LSID:64332
    00> D: 118882 [IN]New secure session activated for device <5ABECE966C84D61C, 1>, LSID:64332 PSID:57552!
    00> I: 118882 [IN]CASE Session established to peer: <5ABECE966C84D61C, 1>
    00> I: 118883 [IN]CASE Server enabling CASE session setups
    00> D: 118883 [IN]SecureSession[0x20003630]: Allocated Type:2 LSID:64333
    00> I: 118884 [DL]OpenThread SED interval is 500ms
    00> I: 118884 [EM]<<< [E:21115r M:235396628 (Ack:60693271)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 118885 [IN](U) Sending msg 235396628 to IP address 'UDP:[fd85:4025:13b2:0:ef0e:d145:69fc:5121]:61284'
    00> I: 119023 [EM]>>> [E:21116r M:134862488] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:03 (IM:SubscribeRequest)
    00> D: 119024 [IM]Received Subscribe request
    00> I: 119030 [DMG]Final negotiated min/max parameters: Min = 0s, Max = 2s
    00> I: 119031 [DL]OpenThread SED interval is 500ms
    00> E: 119034 [DMG]Error retrieving data from clusterId: 0x0000_001F, err = b
    00> I: 119041 [EM]<<< [E:21116r M:30572577 (Ack:134862488)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 119042 [IN](S) Sending msg 30572577 on secure session with LSID: 64332
    00> I: 119045 [EM]>>> [E:21115r M:60693272 (Ack:235396627)] (U) Msg RX from 0:5BECF2A9E3B6E5F2 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> D: 119191 [IN]Received a duplicate message with MessageCounter:134862488 on exchange 21116r
    00> I: 119192 [EM]>>> [E:21116r M:134862488] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:03 (IM:SubscribeRequest)
    00> I: 119193 [EM]<<< [E:21116r M:30572578 (Ack:134862488)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 119194 [IN](S) Sending msg 30572578 on secure session with LSID: 64332
    00> I: 120435 [IN](S) Sending msg 30572577 on secure session with LSID: 64332
    00> I: 121782 [IN](S) Sending msg 30572577 on secure session with LSID: 64332
    00> I: 121807 [EM]>>> [E:21116r M:134862490 (Ack:30572577)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 121831 [EM]>>> [E:21116r M:134862489 (Ack:30572577)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 121832 [IM]Received status response, status is 0x00
    00> E: 121843 [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b
    00> I: 121850 [EM]<<< [E:21116r M:30572579 (Ack:134862489)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 121852 [IN](S) Sending msg 30572579 on secure session with LSID: 64332
    00> I: 121957 [EM]>>> [E:21116r M:134862491 (Ack:30572577)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 123199 [IN](S) Sending msg 30572579 on secure session with LSID: 64332
    00> I: 123879 [EM]>>> [E:21116r M:134862493 (Ack:30572579)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 124880 [EM]>>> [E:21116r M:134862492 (Ack:30572579)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 124880 [IM]Received status response, status is 0x00
    00> E: 124886 [DMG]Error retrieving data from clusterId: 0x0000_002A, err = b
    00> I: 124892 [EM]<<< [E:21116r M:30572580 (Ack:134862492)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 124893 [IN](S) Sending msg 30572580 on secure session with LSID: 64332
    00> I: 126301 [IN](S) Sending msg 30572580 on secure session with LSID: 64332
    00> I: 126325 [EM]>>> [E:21116r M:134862494 (Ack:30572580)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 126326 [IM]Received status response, status is 0x00
    00> E: 126330 [DMG]Error retrieving data from clusterId: 0x0000_0030, err = b
    00> I: 126338 [EM]<<< [E:21116r M:30572581 (Ack:134862494)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 126338 [IN](S) Sending msg 30572581 on secure session with LSID: 64332
    00> I: 126451 [EM]>>> [E:21116r M:134862495 (Ack:30572580)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 127677 [IN](S) Sending msg 30572581 on secure session with LSID: 64332
    00> I: 128379 [EM]>>> [E:21116r M:134862497 (Ack:30572581)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 129380 [EM]>>> [E:21116r M:134862496 (Ack:30572581)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 129380 [IM]Received status response, status is 0x00
    00> E: 129385 [DMG]Error retrieving data from clusterId: 0x0000_0031, err = b
    00> I: 129391 [EM]<<< [E:21116r M:30572582 (Ack:134862496)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 129391 [IN](S) Sending msg 30572582 on secure session with LSID: 64332
    00> I: 130380 [EM]>>> [E:21116r M:134862498 (Ack:30572582)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 130380 [IM]Received status response, status is 0x00
    00> E: 130385 [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b
    00> I: 130391 [EM]<<< [E:21116r M:30572583 (Ack:134862498)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 130392 [IN](S) Sending msg 30572583 on secure session with LSID: 64332
    00> I: 131787 [IN](S) Sending msg 30572583 on secure session with LSID: 64332
    00> I: 131813 [EM]>>> [E:21116r M:134862499 (Ack:30572583)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 131814 [IM]Received status response, status is 0x00
    00> E: 131818 [DL]_WriteThreadNetworkDiagnosticAttributeToTlv failed: Error CHIP:0x0000000B
    00> E: 131818 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b
    00> I: 131828 [EM]<<< [E:21116r M:30572584 (Ack:134862499)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 131828 [IN](S) Sending msg 30572584 on secure session with LSID: 64332
    00> I: 131934 [EM]>>> [E:21116r M:134862500 (Ack:30572583)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> D: 132002 [IN]Received a duplicate message with MessageCounter:134862499 on exchange 21116r
    00> I: 132002 [EM]>>> [E:21116r M:134862499 (Ack:30572583)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 132004 [EM]<<< [E:21116r M:30572585 (Ack:134862499)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 132005 [IN](S) Sending msg 30572585 on secure session with LSID: 64332
    00> I: 133163 [IN](S) Sending msg 30572584 on secure session with LSID: 64332
    00> I: 133879 [EM]>>> [E:21116r M:134862502 (Ack:30572584)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 134880 [EM]>>> [E:21116r M:134862501 (Ack:30572584)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 134880 [IM]Received status response, status is 0x00
    00> E: 134886 [DL]_WriteThreadNetworkDiagnosticAttributeToTlv failed: Error CHIP:0x0000000B
    00> E: 134886 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b
    00> I: 134892 [EM]<<< [E:21116r M:30572586 (Ack:134862501)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 134893 [IN](S) Sending msg 30572586 on secure session with LSID: 64332
    00> I: 136297 [IN](S) Sending msg 30572586 on secure session with LSID: 64332
    00> I: 136322 [EM]>>> [E:21116r M:134862503 (Ack:30572586)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 136322 [IM]Received status response, status is 0x00
    00> E: 136341 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b
    00> I: 136348 [EM]<<< [E:21116r M:30572587 (Ack:134862503)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 136349 [IN](S) Sending msg 30572587 on secure session with LSID: 64332
    00> I: 136446 [EM]>>> [E:21116r M:134862504 (Ack:30572586)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 137727 [IN](S) Sending msg 30572587 on secure session with LSID: 64332
    00> I: 137752 [EM]>>> [E:21116r M:134862505 (Ack:30572587)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 137753 [IM]Received status response, status is 0x00
    00> E: 137755 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b
    00> I: 137763 [EM]<<< [E:21116r M:30572588 (Ack:134862505)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 137764 [IN](S) Sending msg 30572588 on secure session with LSID: 64332
    00> I: 137866 [EM]>>> [E:21116r M:134862506 (Ack:30572587)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 139142 [IN](S) Sending msg 30572588 on secure session with LSID: 64332
    00> I: 139165 [EM]>>> [E:21116r M:134862507 (Ack:30572588)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 139166 [IM]Received status response, status is 0x00
    00> E: 139169 [DMG]Error retrieving data from clusterId: 0x0000_003C, err = b
    00> I: 139177 [EM]<<< [E:21116r M:30572589 (Ack:134862507)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 139178 [IN](S) Sending msg 30572589 on secure session with LSID: 64332
    00> I: 139282 [EM]>>> [E:21116r M:134862508 (Ack:30572588)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 140587 [IN](S) Sending msg 30572589 on secure session with LSID: 64332
    00> I: 140613 [EM]>>> [E:21116r M:134862509 (Ack:30572589)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 140613 [IM]Received status response, status is 0x00
    00> E: 140619 [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    00> I: 140627 [EM]<<< [E:21116r M:30572590 (Ack:134862509)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 140628 [IN](S) Sending msg 30572590 on secure session with LSID: 64332
    00> I: 140719 [EM]>>> [E:21116r M:134862510 (Ack:30572589)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 141380 [EM]>>> [E:21116r M:134862511 (Ack:30572590)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 141380 [IM]Received status response, status is 0x00
    00> E: 141383 [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    00> I: 141388 [EM]<<< [E:21116r M:30572591 (Ack:134862511)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 141389 [IN](S) Sending msg 30572591 on secure session with LSID: 64332
    00> I: 141880 [EM]>>> [E:21116r M:134862512 (Ack:30572591)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 141880 [IM]Received status response, status is 0x00
    00> E: 141885 [DMG]Error retrieving data from clusterId: 0x0000_003F, err = b
    00> I: 141891 [EM]<<< [E:21116r M:30572592 (Ack:134862512)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 141891 [IN](S) Sending msg 30572592 on secure session with LSID: 64332
    00> I: 142880 [EM]>>> [E:21116r M:134862513 (Ack:30572592)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 142880 [IM]Received status response, status is 0x00
    00> E: 142883 [DMG]Error retrieving data from clusterId: 0x0000_001D, err = b
    00> I: 142889 [EM]<<< [E:21116r M:30572593 (Ack:134862513)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 142889 [IN](S) Sending msg 30572593 on secure session with LSID: 64332
    00> I: 143880 [EM]>>> [E:21116r M:134862514 (Ack:30572593)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 143880 [IM]Received status response, status is 0x00
    00> E: 143884 [DMG]Error retrieving data from clusterId: 0x0000_0102, err = b
    00> I: 143890 [EM]<<< [E:21116r M:30572594 (Ack:134862514)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 143891 [IN](S) Sending msg 30572594 on secure session with LSID: 64332
    00> I: 145271 [IN](S) Sending msg 30572594 on secure session with LSID: 64332
    00> I: 145296 [EM]>>> [E:21116r M:134862515 (Ack:30572594)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 145297 [IM]Received status response, status is 0x00
    00> E: 145299 [DMG]Error retrieving data from clusterId: 0x0000_0102, err = b
    00> I: 145307 [EM]<<< [E:21116r M:30572595 (Ack:134862515)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 145308 [IN](S) Sending msg 30572595 on secure session with LSID: 64332
    00> I: 145417 [EM]>>> [E:21116r M:134862516 (Ack:30572594)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 146701 [IN](S) Sending msg 30572595 on secure session with LSID: 64332
    00> I: 148087 [IN](S) Sending msg 30572595 on secure session with LSID: 64332
    00> I: 148380 [EM]>>> [E:21116r M:134862518 (Ack:30572595)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 148399 [EM]>>> [E:21116r M:134862517 (Ack:30572595)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 148399 [IM]Received status response, status is 0x00
    00> I: 148402 [EM]<<< [E:21116r M:30572596 (Ack:134862517)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 148404 [IN](S) Sending msg 30572596 on secure session with LSID: 64332
    00> I: 148492 [EM]>>> [E:21116r M:134862519 (Ack:30572595)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 149786 [IN](S) Sending msg 30572596 on secure session with LSID: 64332
    00> I: 149810 [EM]>>> [E:21116r M:134862520 (Ack:30572596)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 149810 [IM]Received status response, status is 0x00
    00> I: 149811 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 149812 [EM]<<< [E:21116r M:30572597 (Ack:134862520)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:04 (IM:SubscribeResponse)
    00> I: 149813 [IN](S) Sending msg 30572597 on secure session with LSID: 64332
    00> I: 151190 [IN](S) Sending msg 30572597 on secure session with LSID: 64332
    00> I: 151380 [EM]>>> [E:21116r M:134862522 (Ack:30572597)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 151380 [DL]OpenThread SED interval is 500ms
    00> I: 151393 [EM]>>> [E:21117r M:134862523] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:02 (IM:ReadRequest)
    00> D: 151394 [IM]Received Read request
    00> I: 151395 [DL]OpenThread SED interval is 500ms
    00> I: 151397 [EM]<<< [E:21117r M:30572598 (Ack:134862523)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 151397 [IN](S) Sending msg 30572598 on secure session with LSID: 64332
    00> I: 151405 [EM]>>> [E:21116r M:134862524 (Ack:30572597)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 151814 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 151820 [EM]<<< [E:48703i M:30572599] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 151820 [IN](S) Sending msg 30572599 on secure session with LSID: 64332
    00> I: 151822 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 151842 [EM]>>> [E:21117r M:134862525 (Ack:30572598)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 153223 [IN](S) Sending msg 30572599 on secure session with LSID: 64332
    00> I: 153380 [EM]>>> [E:48703i M:134862526 (Ack:30572599)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 153380 [IM]Received status response, status is 0x00
    00> I: 153381 [DL]OpenThread SED interval is 500ms
    00> I: 153382 [EM]<<< [E:48703i M:30572600 (Ack:134862526)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 153382 [IN](S) Sending msg 30572600 on secure session with LSID: 64332
    00> I: 153410 [EM]>>> [E:48703i M:134862527 (Ack:30572599)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 153411 [EM]OnMessageReceived failed, err = 70
    00> D: 153424 [IN]Received a duplicate message with MessageCounter:134862526 on exchange 48703i
    00> I: 153425 [EM]>>> [E:48703i M:134862526 (Ack:30572599)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 153426 [EM]<<< [E:48703i M:30572601 (Ack:134862526)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 153427 [IN](S) Sending msg 30572601 on secure session with LSID: 64332
    00> I: 153824 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 153829 [EM]<<< [E:48704i M:30572602] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 153830 [IN](S) Sending msg 30572602 on secure session with LSID: 64332
    00> I: 153831 [DL]OpenThread SED interval is 500ms
    00> I: 153832 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 155178 [IN](S) Sending msg 30572602 on secure session with LSID: 64332
    00> I: 155380 [EM]>>> [E:48704i M:134862528 (Ack:30572602)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 155380 [IM]Received status response, status is 0x00
    00> I: 155381 [DL]OpenThread SED interval is 500ms
    00> I: 155382 [EM]<<< [E:48704i M:30572603 (Ack:134862528)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 155382 [IN](S) Sending msg 30572603 on secure session with LSID: 64332
    00> I: 155413 [EM]>>> [E:48704i M:134862529 (Ack:30572602)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 155414 [EM]OnMessageReceived failed, err = 70
    00> D: 155427 [IN]Received a duplicate message with MessageCounter:134862528 on exchange 48704i
    00> I: 155428 [EM]>>> [E:48704i M:134862528 (Ack:30572602)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 155429 [EM]<<< [E:48704i M:30572604 (Ack:134862528)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 155429 [IN](S) Sending msg 30572604 on secure session with LSID: 64332
    00> I: 155832 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 155838 [EM]<<< [E:48705i M:30572605] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 155838 [IN](S) Sending msg 30572605 on secure session with LSID: 64332
    00> I: 155840 [DL]OpenThread SED interval is 500ms
    00> I: 155840 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 157216 [IN](S) Sending msg 30572605 on secure session with LSID: 64332
    00> I: 157380 [EM]>>> [E:48705i M:134862530 (Ack:30572605)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 157380 [IM]Received status response, status is 0x00
    00> I: 157381 [DL]OpenThread SED interval is 500ms
    00> I: 157382 [EM]<<< [E:48705i M:30572606 (Ack:134862530)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 157382 [IN](S) Sending msg 30572606 on secure session with LSID: 64332
    00> I: 157398 [EM]>>> [E:48705i M:134862531 (Ack:30572605)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 157398 [EM]OnMessageReceived failed, err = 70
    00> D: 157419 [IN]Received a duplicate message with MessageCounter:134862530 on exchange 48705i
    00> I: 157419 [EM]>>> [E:48705i M:134862530 (Ack:30572605)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 157421 [EM]<<< [E:48705i M:30572607 (Ack:134862530)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 157421 [IN](S) Sending msg 30572607 on secure session with LSID: 64332
    00> I: 157841 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 157846 [EM]<<< [E:48706i M:30572608] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 157847 [IN](S) Sending msg 30572608 on secure session with LSID: 64332
    00> I: 157849 [DL]OpenThread SED interval is 500ms
    00> I: 157849 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 159247 [IN](S) Sending msg 30572608 on secure session with LSID: 64332
    00> I: 159380 [EM]>>> [E:48706i M:134862532 (Ack:30572608)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 159380 [IM]Received status response, status is 0x00
    00> I: 159381 [DL]OpenThread SED interval is 500ms
    00> I: 159382 [EM]<<< [E:48706i M:30572609 (Ack:134862532)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 159382 [IN](S) Sending msg 30572609 on secure session with LSID: 64332
    00> I: 159404 [EM]>>> [E:48706i M:134862533 (Ack:30572608)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 159404 [EM]OnMessageReceived failed, err = 70
    00> I: 159850 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 159856 [EM]<<< [E:48707i M:30572610] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 159856 [IN](S) Sending msg 30572610 on secure session with LSID: 64332
    00> I: 159858 [DL]OpenThread SED interval is 500ms
    00> I: 159858 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 161209 [IN](S) Sending msg 30572610 on secure session with LSID: 64332
    00> I: 161233 [EM]>>> [E:48707i M:134862534 (Ack:30572610)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 161233 [IM]Received status response, status is 0x00
    00> I: 161234 [DL]OpenThread SED interval is 500ms
    00> I: 161235 [EM]<<< [E:48707i M:30572611 (Ack:134862534)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 161235 [IN](S) Sending msg 30572611 on secure session with LSID: 64332
    00> I: 161259 [EM]>>> [E:48707i M:134862535 (Ack:30572610)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 161260 [EM]OnMessageReceived failed, err = 70
    00> I: 161859 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 161865 [EM]<<< [E:48708i M:30572612] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 161865 [IN](S) Sending msg 30572612 on secure session with LSID: 64332
    00> I: 161867 [DL]OpenThread SED interval is 500ms
    00> I: 161867 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 161899 [EM]>>> [E:48708i M:134862536 (Ack:30572612)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 161900 [IM]Received status response, status is 0x00
    00> I: 161900 [DL]OpenThread SED interval is 500ms
    00> I: 161901 [EM]<<< [E:48708i M:30572613 (Ack:134862536)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 161902 [IN](S) Sending msg 30572613 on secure session with LSID: 64332
    00> I: 163869 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 163875 [EM]<<< [E:48709i M:30572614] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 163875 [IN](S) Sending msg 30572614 on secure session with LSID: 64332
    00> I: 163877 [DL]OpenThread SED interval is 500ms
    00> I: 163877 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 165284 [IN](S) Sending msg 30572614 on secure session with LSID: 64332
    00> I: 165309 [EM]>>> [E:48709i M:134862537 (Ack:30572614)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 165310 [IM]Received status response, status is 0x00
    00> I: 165310 [DL]OpenThread SED interval is 500ms
    00> I: 165311 [EM]<<< [E:48709i M:30572615 (Ack:134862537)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 165313 [IN](S) Sending msg 30572615 on secure session with LSID: 64332
    00> I: 165326 [EM]>>> [E:48709i M:134862538 (Ack:30572614)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 165326 [EM]OnMessageReceived failed, err = 70
    00> I: 165878 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 165884 [EM]<<< [E:48710i M:30572616] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 165884 [IN](S) Sending msg 30572616 on secure session with LSID: 64332
    00> I: 165886 [DL]OpenThread SED interval is 500ms
    00> I: 165886 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 167232 [IN](S) Sending msg 30572616 on secure session with LSID: 64332
    00> I: 167255 [EM]>>> [E:48710i M:134862539 (Ack:30572616)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 167256 [IM]Received status response, status is 0x00
    00> I: 167256 [DL]OpenThread SED interval is 500ms
    00> I: 167257 [EM]<<< [E:48710i M:30572617 (Ack:134862539)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 167257 [IN](S) Sending msg 30572617 on secure session with LSID: 64332
    00> I: 167280 [EM]>>> [E:48710i M:134862540 (Ack:30572616)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 167281 [EM]OnMessageReceived failed, err = 70
    00> I: 167886 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 167892 [EM]<<< [E:48711i M:30572618] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 167892 [IN](S) Sending msg 30572618 on secure session with LSID: 64332
    00> I: 167894 [DL]OpenThread SED interval is 500ms
    00> I: 167894 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 169232 [IN](S) Sending msg 30572618 on secure session with LSID: 64332
    00> I: 169895 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 170581 [IN](S) Sending msg 30572618 on secure session with LSID: 64332
    00> I: 170880 [EM]>>> [E:48711i M:134862542 (Ack:30572618)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 170892 [EM]>>> [E:48711i M:134862541 (Ack:30572618)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 170893 [IM]Received status response, status is 0x00
    00> I: 170893 [DL]OpenThread SED interval is 500ms
    00> I: 170895 [EM]<<< [E:48711i M:30572619 (Ack:134862541)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 170896 [IN](S) Sending msg 30572619 on secure session with LSID: 64332
    00> I: 170904 [EM]<<< [E:48712i M:30572620] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 170905 [IN](S) Sending msg 30572620 on secure session with LSID: 64332
    00> I: 170906 [DL]OpenThread SED interval is 500ms
    00> I: 170906 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 170909 [EM]>>> [E:48711i M:134862543 (Ack:30572618)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 170910 [EM]OnMessageReceived failed, err = 70
    00> I: 171880 [EM]>>> [E:48712i M:134862544 (Ack:30572620)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 171880 [IM]Received status response, status is 0x00
    00> I: 171881 [DL]OpenThread SED interval is 500ms
    00> I: 171882 [EM]<<< [E:48712i M:30572621 (Ack:134862544)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 171882 [IN](S) Sending msg 30572621 on secure session with LSID: 64332
    00> I: 172907 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 172913 [EM]<<< [E:48713i M:30572622] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 172913 [IN](S) Sending msg 30572622 on secure session with LSID: 64332
    00> I: 172915 [DL]OpenThread SED interval is 500ms
    00> I: 172915 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 173880 [EM]>>> [E:48713i M:134862545 (Ack:30572622)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 173880 [IM]Received status response, status is 0x00
    00> I: 173881 [DL]OpenThread SED interval is 500ms
    00> I: 173882 [EM]<<< [E:48713i M:30572623 (Ack:134862545)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 173882 [IN](S) Sending msg 30572623 on secure session with LSID: 64332
    00> I: 174916 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 174922 [EM]<<< [E:48714i M:30572624] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 174922 [IN](S) Sending msg 30572624 on secure session with LSID: 64332
    00> I: 174924 [DL]OpenThread SED interval is 500ms
    00> I: 174925 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 176311 [IN](S) Sending msg 30572624 on secure session with LSID: 64332
    00> I: 176336 [EM]>>> [E:48714i M:134862546 (Ack:30572624)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 176337 [IM]Received status response, status is 0x00
    00> I: 176337 [DL]OpenThread SED interval is 500ms
    00> I: 176338 [EM]<<< [E:48714i M:30572625 (Ack:134862546)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 176339 [IN](S) Sending msg 30572625 on secure session with LSID: 64332
    00> I: 176364 [EM]>>> [E:48714i M:134862547 (Ack:30572624)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 176364 [EM]OnMessageReceived failed, err = 70
    00> I: 176925 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 176930 [EM]<<< [E:48715i M:30572626] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 176931 [IN](S) Sending msg 30572626 on secure session with LSID: 64332
    00> I: 176933 [DL]OpenThread SED interval is 500ms
    00> I: 176933 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 178262 [IN](S) Sending msg 30572626 on secure session with LSID: 64332
    00> I: 178935 [DMG]Refresh subscribe timer sync after 2 seconds
    00> I: 179629 [IN](S) Sending msg 30572626 on secure session with LSID: 64332
    00> I: 179880 [EM]>>> [E:48715i M:134862549 (Ack:30572626)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 179893 [EM]>>> [E:48715i M:134862548 (Ack:30572626)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0001:01 (IM:StatusResponse)
    00> I: 179894 [IM]Received status response, status is 0x00
    00> I: 179894 [DL]OpenThread SED interval is 500ms
    00> I: 179895 [EM]<<< [E:48715i M:30572627 (Ack:134862548)] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> I: 179896 [IN](S) Sending msg 30572627 on secure session with LSID: 64332
    00> I: 179902 [EM]<<< [E:48716i M:30572628] (S) Msg TX to 1:5ABECE966C84D61C [E3D2] --- Type 0001:05 (IM:ReportData)
    00> I: 179903 [IN](S) Sending msg 30572628 on secure session with LSID: 64332
    00> I: 179904 [DL]OpenThread SED interval is 500ms
    00> I: 179905 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 2 seconds
    00> I: 179910 [EM]>>> [E:48715i M:134862550 (Ack:30572626)] (S) Msg RX from 1:5ABECE966C84D61C [E3D2] --- Type 0000:10 (SecureChannel:StandaloneAck)
    00> E: 179911 [EM]OnMessageReceived failed, err = 70

    Best regards,
    Danny

Children
  • Hi,

      Can you help me test it?

    Best regards,
    Danny

  • Hi,

    Are you using our window covering sample with the default configuration, meaning that the SSED support is enabled? Are they planning to use this feature? Do you see the same problem if SSED support turned off?

    Can you please provide us with the .pcap file from the sniffer trace? Make sure you perform the sniffer trace on the correct channel. You should be able to get the channel from the serial terminal after commissioning to your ecosystem, by entering the command "ot channel". If you can also provide the networkkey, that would enable us to read the sniffer trace correctly (you can send the info to me in a private message to avoid publishing in public thread):

    uart:~$ ot channel
    11
    Done
    uart:~$ ot networkkey
    00112233445566778899aabbccddeeff
    Done

    Best regards,
    Jørgen

  • Hi,

    I use the default configuration,SSED is enabled.Yesterday, I went to the agent to reproduce this issue. The nrf52840dk board burned the window covering routine, added the device to the HOME app, shut it down for 2 minutes, and then turned it on again. The problem also reappeared. At present, due to the thread sniffer not working properly, it is still unable to capture packets.Home pop mini'sversion is 16.4.

    Best regards,

    Danny

  • Hi,

    Out Matter team needs a sniffer trace to understand what happens and help resolve this issue. Can you elaborate more on what is not working with the sniffer trace? 

    Have you made sure that you start the sniffer on the correct channel used by the device after being commissioned into the Apple HomePod Mini ecosystem?

    I see there is a filter applied in you image from the sniffer log, have you tried removing this?

    If you are not able to get the sniffer running on your current setup, could you try to set it up on a different computer, carefully following all the steps in the user guide to set it up?

    Best regards,
    Jørgen

Related