[Matter] Communication with Fan Device created from Matter: Template is unstable

Hello,

I am implementing Fan Device (provisional) on nRF52840 with Matter over Thread. I implemented Fan Device Endpoint and Cluster with ZAP tool according to Adding clusters to Matter Application. Clusters and Attributes were set as default.

I created zcl_callbacks.cpp and implemented the MatterPostAttributeChangeCallback() function. To check the operation, I added only the code that outputs the log in the function called in app_task.cpp.

zcl_callbacks.cpp (excerpt):

void MatterPostAttributeChangeCallback(const chip::app::ConcreteAttributePath& attributePath, uint8_t type, uint16_t size, uint8_t* value)
{
    ClusterId clusterId = attributePath.mClusterId;
    AttributeId attributeId = attributePath.mAttributeId;

    if (clusterId != FanControl::Id)
    {
        return;
    }

    switch (attributeId)
    {
        case FanControl::Attributes::FanMode::Id:
            AppTask::Instance().FanModeChanged(static_cast<FanControl::FanModeType>(*value));
            break;
        case FanControl::Attributes::FanModeSequence::Id:
            AppTask::Instance().FanModeSequenceChanged(static_cast<FanControl::FanModeSequenceType>(*value));
            break;
        case FanControl::Attributes::PercentSetting::Id:
            AppTask::Instance().PercentSettingChanged(*value);
            break;
        default:
            return;
    }
}

app_task.cpp (excerpt):

void AppTask::FanModeChanged(const Clusters::FanControl::FanModeType type)
{
	LOG_INF("FanMode: %d", (uint8_t) type);
}

void AppTask::FanModeSequenceChanged(const Clusters::FanControl::FanModeSequenceType type)
{
	LOG_INF("FanModeSequence: %d", (uint8_t) type);
}

void AppTask::PercentSettingChanged(const uint8_t percent)
{
	LOG_INF("Percent: %d", percent);
}

I used a configuration based on prj_no_dfu.conf that added log output.

#
# Copyright (c) 2021 Nordic Semiconductor ASA
#
# SPDX-License-Identifier: LicenseRef-Nordic-5-Clause
#

# This sample uses Kconfig.defaults to set options common for all
# samples. This file should contain only options specific for this sample
# or overrides of default values.

# Enable CHIP
CONFIG_CHIP=y
CONFIG_CHIP_ENABLE_PAIRING_AUTOSTART=y
CONFIG_CHIP_PROJECT_CONFIG="src/chip_project_config.h"
# 32768 == 0x8000 (example Product ID added temporaly,
# but it must be changed with proper PID from the list:
# https://github.com/project-chip/connectedhomeip/blob/482e6fd03196a6de45465a90003947ef4b86e0b1/docs/examples/discussion/PID_allocation_for_example_apps.md)
CONFIG_CHIP_DEVICE_PRODUCT_ID=32768
CONFIG_STD_CPP14=y

# Add support for LEDs and buttons on Nordic development kits
CONFIG_DK_LIBRARY=y

# Bluetooth Low Energy configuration
CONFIG_BT_DEVICE_NAME="MatterTemplate"

# Other settings
CONFIG_THREAD_NAME=y
CONFIG_MPU_STACK_GUARD=y
CONFIG_RESET_ON_FATAL_ERROR=n
CONFIG_CHIP_LIB_SHELL=y

# Disable NFC commissioning
CONFIG_CHIP_NFC_COMMISSIONING=n

# Disable Matter OTA DFU
CONFIG_CHIP_OTA_REQUESTOR=n

# Disable QSPI NOR
CONFIG_CHIP_QSPI_NOR=n

# Reduce application size
CONFIG_USE_SEGGER_RTT=y

CONFIG_RTT_CONSOLE=y
CONFIG_UART_CONSOLE=n

CONFIG_LOG=y
CONFIG_LOG_BACKEND_RTT=y
CONFIG_LOG_MODE_IMMEDIATE=y

The build has completed successfully. I wrote the binary to nRF52840.

Following the Matter: Template article, I used an iPhone 8 to commission the nRF52840 to a Matter network with an Apple TV 4K configured as a Border Router. This also completed successfully and was recognized as a Fan Device by the iPhone.

However, the Fan Device control does not work properly. When turning on/off or adjusting airflow, the device frequently goes into a "no response" state and does not accept any operations for a certain period of time. Ican switch it on/off after a certain period of time (if you leave it for a certain period of time, it will become "no response" again and you will not be able to switch it. Looking at the logs, it seems that the CASE session is being restarted). The air volume adjustment remains in a "no response" state and is almost impossible to operate.

As a side note, perhaps the entire Matter over Thread network is malfunctioning, but when the Fan Device is "no response" and I operate the Nanoleaf Essentials Bulb that is participating in the Matter network, it also becomes "no response".

When I wrote the Matter: Light bulb sample to the nRF52840 as is and made it work, it did not become "no response" and worked normally.

I can't find the cause by looking at the logs and sources myself. Is it because the Fan Device is provisional that it does not work properly?

How can I resolve this issue?


  • Development PC
    • OS: Windows 10
    • SDK: nRF Connect SDK 2.5.0
    • ZAP tool: v2024.01.20
  • Microcontroller
    • nRF52840
  • Border Router
    • Apple TV 4K Wi-Fi + Ethernet (tvOS 17.3)
  • Matter Controller
    • iPhone 8 (iOS 16.3)
  • Hi,

    Please upload the full application log from the device.

    Are you able to test with the chip-tool to see if you see the same behavior there as with Apple Home?

    Best regards,
    Marte

  • Marte-san,

    I have uploaded the logs obtained with J-Link RTT. Please confirm. Device-specific information such as Bluetooth MAC was hidden. I have confirmed that it works normally and sometimes becomes "no response".

    *** Booting nRF Connect SDK v2.5.0 ***
    [00:00:00.114,257] <inf> app: Init CHIP stack
    [00:00:00.213,500] <inf> chip: [DL]BLE address: XX:XX:XX:XX:XX:XX
    [00:00:00.264,160] <inf> chip: [DL]OpenThread ifconfig up and thread start
    [00:00:00.275,573] <inf> chip: [DL]OpenThread started: OK
    [00:00:00.285,583] <inf> chip: [DL]Setting OpenThread device type to MINIMAL END DEVICE
    [00:00:00.296,752] <inf> chip: [SVR]Subscription persistence not supported
    [00:00:00.305,175] <inf> chip: [SVR]Server initializing...
    [00:00:00.314,727] <inf> chip: [TS]Last Known Good Time: 2024-03-08T10:41:55
    [00:00:00.319,885] <inf> chip: [DL]SRP Client was started, detected server: fd3c:8e93:d3a0:d251:0000:00ff:fe00:fc11
    [00:00:00.343,597] <inf> chip: [FP]Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xFE8EEE732A353931, FabricId 0x0000000000000001, NodeId 0x0000000043E62CFE, VendorId 0x1349
    [00:00:00.364,898] <inf> chip: [FP]Fabric index 0x2 was retrieved from storage. Compressed FabricId 0xF459799FFD73555E, FabricId 0x0000000000000002, NodeId 0x0000000005BF1842, VendorId 0x1384
    [00:00:00.384,948] <inf> chip: [DMG]AccessControl: initializing
    [00:00:00.392,395] <inf> chip: [DMG]Examples::AccessControlDelegate::Init
    [00:00:00.400,817] <inf> chip: [DMG]AccessControl: setting
    [00:00:00.407,775] <inf> chip: [DMG]DefaultAclStorage: initializing
    [00:00:00.419,097] <inf> chip: [DMG]DefaultAclStorage: 2 entries loaded
    [00:00:00.427,246] <dbg> chip: LogV: [IN]UDP::Init bind&listen port=5540
    [00:00:00.435,577] <err> chip: [IN]SO_REUSEPORT failed: 109
    [00:00:00.444,244] <dbg> chip: LogV: [IN]UDP::Init bound to port=5540
    [00:00:00.452,606] <dbg> chip: LogV: [IN]BLEBase::Init - setting/overriding transport
    [00:00:00.463,409] <dbg> chip: LogV: [IN]TransportMgr initialized
    [00:00:00.509,490] <dbg> chip: LogV: [DL]Using Thread extended MAC for hostname.
    [00:00:00.536,102] <inf> chip: [ZCL]Using ZAP configuration...
    [00:00:00.545,440] <inf> chip: [DMG]AccessControlCluster: initializing
    [00:00:00.553,558] <dbg> chip: LogV: [DL]Boot reason: 1
    [00:00:00.560,424] <inf> chip: [ZCL]Initiating Admin Commissioning cluster.
    [00:00:00.569,122] <inf> chip: [SVR]Fabric already commissioned. Disabling BLE advertisement
    [00:00:00.579,193] <inf> chip: [DIS]Updating services using commissioning mode 0
    [00:00:00.588,195] <err> chip: [DIS]Failed to remove advertised services: 3
    [00:00:00.596,679] <dbg> chip: LogV: [DL]Using Thread extended MAC for hostname.
    [00:00:00.605,743] <inf> chip: [DIS]Advertise operational node FE8EEE732A353931-0000000043E62CFE
    [00:00:00.616,210] <err> chip: [DIS]Failed to advertise operational node: 3
    [00:00:00.624,725] <err> chip: [DIS]Failed to finalize service update: 3
    [00:00:00.632,934] <inf> chip: [DIS]Delaying proxy of operational discovery: missing delegate
    [00:00:00.643,127] <inf> chip: [IN]CASE Server enabling CASE session setups
    [00:00:00.652,008] <dbg> chip: LogV: [IN]SecureSession[0x20002b40]: Allocated Type:2 LSID:10258
    [00:00:00.662,322] <inf> chip: [SVR]Joining Multicast groups
    [00:00:00.671,081] <inf> chip: [SVR]Server Listening...
    [00:00:00.677,825] <inf> chip: [DL]Device Configuration:
    [00:00:00.685,668] <inf> chip: [DL]  Serial Number: 11223344556677889900
    [00:00:00.693,969] <inf> chip: [DL]  Vendor Id: 65521 (0xFFF1)
    [00:00:00.701,324] <inf> chip: [DL]  Product Id: 32768 (0x8000)
    [00:00:00.708,740] <inf> chip: [DL]  Product Name: not-specified
    [00:00:00.717,376] <inf> chip: [DL]  Hardware Version: 0
    [00:00:00.725,250] <inf> chip: [DL]  Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
    [00:00:00.735,656] <inf> chip: [DL]  Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
    [00:00:00.747,222] <inf> chip: [DL]  Manufacturing Date: (not set)
    [00:00:00.755,035] <inf> chip: [DL]  Device Type: 65535 (0xFFFF)
    [00:00:00.764,770] <inf> chip: [SVR]SetupQRCode: [MT:Y.K9042C00KA0648G00]
    [00:00:00.773,162] <inf> chip: [SVR]Copy/paste the below URL in a browser to see the QR Code:
    [00:00:00.783,294] <inf> chip: [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3AY.K9042C00KA0648G00
    [00:00:00.796,478] <inf> chip: [SVR]Manual pairing code: [34970112332]
    [00:00:00.805,236] <inf> chip: [DL]CHIP task running
    [00:00:00.811,828] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00001000)
    [00:00:00.823,699] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00001000)
    [00:00:00.835,754] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00001000)
    [00:00:00.847,686] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00000001)
    [00:00:00.857,116] <dbg> chip: LogV: [DL]   Thread Unicast Addresses:
    [00:00:00.865,173] <dbg> chip: LogV: [DL]        fd6f:342e:a35f:0:5afd:429e:1e22:faf2/64 valid preferred
    [00:00:00.876,647] <dbg> chip: LogV: [DL]        fd3c:8e93:d3a0:d251:0:ff:fe00:c402/64 valid rloc
    [00:00:00.887,268] <dbg> chip: LogV: [DL]        fd3c:8e93:d3a0:d251:fbf3:4563:aa6:ea54/64 valid
    [00:00:00.897,796] <dbg> chip: LogV: [DL]        fe80::dcad:fc6c:1f39:10e/64 valid preferred
    [00:00:00.948,974] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00000008)
    [00:00:00.963,317] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x01000000)
    [00:00:00.975,616] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00000004)
    [00:00:00.985,137] <dbg> chip: LogV: [DL]   Device Role: CHILD
    [00:00:00.988,311] <dbg> chip: LogV: [DL]SRP update succeeded
    [00:00:01.000,762] <inf> chip: [DIS]Setting operational delegate post init
    [00:00:01.011,779] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00000001)
    [00:00:01.021,148] <dbg> chip: LogV: [DL]   Thread Unicast Addresses:
    [00:00:01.029,205] <dbg> chip: LogV: [DL]        fd6f:342e:a35f:0:5afd:429e:1e22:faf2/64 valid preferred
    [00:00:01.040,649] <dbg> chip: LogV: [DL]        fd3c:8e93:d3a0:d251:0:ff:fe00:c402/64 valid rloc
    [00:00:01.051,300] <dbg> chip: LogV: [DL]        fd3c:8e93:d3a0:d251:fbf3:4563:aa6:ea54/64 valid
    [00:00:01.061,828] <dbg> chip: LogV: [DL]        fe80::dcad:fc6c:1f39:10e/64 valid preferred
    [00:00:01.074,401] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00001000)
    [00:00:01.086,303] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00001000)
    [00:00:01.098,236] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00000010)
    [00:00:01.110,260] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00000020)
    [00:00:01.122,192] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00000084)
    [00:00:01.131,652] <dbg> chip: LogV: [DL]   Device Role: CHILD
    [00:00:01.138,977] <dbg> chip: LogV: [DL]   Partition Id: 0x56ABF224
    [00:00:01.149,353] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00000200)
    [00:00:01.161,437] <dbg> chip: LogV: [DL]OpenThread State Changed (Flags: 0x00000001)
    [00:00:01.170,898] <dbg> chip: LogV: [DL]   Thread Unicast Addresses:
    [00:00:01.178,924] <dbg> chip: LogV: [DL]        fd6f:342e:a35f:0:5afd:429e:1e22:faf2/64 valid preferred
    [00:00:01.190,185] <dbg> chip: LogV: [DL]        fd3c:8e93:d3a0:d251:0:ff:fe00:c402/64 valid rloc
    [00:00:01.200,775] <dbg> chip: LogV: [DL]        fd3c:8e93:d3a0:d251:fbf3:4563:aa6:ea54/64 valid
    [00:00:01.211,425] <dbg> chip: LogV: [DL]        fe80::dcad:fc6c:1f39:10e/64 valid preferred
    [00:00:01.223,876] <err> chip: [SVR]Server initialization complete
    [00:00:01.231,689] <inf> chip: [DIS]Updating services using commissioning mode 0
    [00:00:01.240,631] <dbg> chip: LogV: [DL]Using Thread extended MAC for hostname.
    [00:00:01.249,755] <inf> chip: [DIS]Advertise operational node FE8EEE732A353931-0000000043E62CFE
    [00:00:01.260,589] <inf> chip: [DL]advertising srp service: FE8EEE732A353931-0000000043E62CFE._matter._tcp
    [00:00:01.272,125] <dbg> chip: LogV: [DL]Using Thread extended MAC for hostname.
    [00:00:01.281,188] <inf> chip: [DIS]Advertise operational node F459799FFD73555E-0000000005BF1842
    [00:00:01.292,114] <inf> chip: [DL]advertising srp service: F459799FFD73555E-0000000005BF1842._matter._tcp
    [00:00:02.492,675] <dbg> chip: LogV: [DL]SRP update succeeded
    [00:00:06.322,021] <inf> chip: [EM]>>> [E:31239r S:0 M:28882072] (U) Msg RX from 0:63DB6A8C78EE7F50 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    [00:00:06.337,524] <inf> chip: [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x20004500
    [00:00:06.348,815] <inf> chip: [IN]CASE Server disabling CASE session setups
    [00:00:06.357,727] <inf> chip: [EM]<<< [E:31239r S:0 M:235726967 (Ack:28882072)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:06.374,755] <inf> chip: [IN](U) Sending msg 235726967 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:64128'
    [00:00:06.388,488] <inf> chip: [SC]Received Sigma1 msg
    [00:00:06.399,627] <inf> chip: [EM]<<< [E:31239r S:0 M:235726968 (Ack:28882072)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:33 (SecureChannel:CASE_Sigma2Resume)
    [00:00:06.416,900] <inf> chip: [IN](U) Sending msg 235726968 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:64128'
    [00:00:06.451,507] <inf> chip: [EM]>>> [E:31239r S:0 M:28882073 (Ack:235726968)] (U) Msg RX from 0:63DB6A8C78EE7F50 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    [00:00:06.471,160] <inf> chip: [SC]Success status report received. Session was established
    [00:00:06.580,261] <inf> chip: [SC]SecureSession[0x20002b40]: Moving from state 'kEstablishing' --> 'kActive'
    [00:00:06.591,949] <dbg> chip: LogV: [IN]SecureSession[0x20002b40]: Activated - Type:2 LSID:10258
    [00:00:06.602,630] <dbg> chip: LogV: [IN]New secure session activated for device <4EAA76FA0B701439, 1>, LSID:10258 PSID:20025!
    [00:00:06.615,875] <inf> chip: [IN]CASE Session established to peer: <4EAA76FA0B701439, 1>
    [00:00:06.625,732] <inf> chip: [IN]CASE Server enabling CASE session setups
    [00:00:06.634,582] <dbg> chip: LogV: [IN]SecureSession[0x20002bf8]: Allocated Type:2 LSID:10259
    [00:00:06.645,263] <inf> chip: [EM]<<< [E:31239r S:0 M:235726969 (Ack:28882073)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:06.662,231] <inf> chip: [IN](U) Sending msg 235726969 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:64128'
    [00:00:06.678,771] <inf> chip: [EM]>>> [E:31240r S:10258 M:262376717] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:03 (IM:SubscribeRequest)
    [00:00:06.694,641] <dbg> chip: LogV: [IM]Received Subscribe request
    [00:00:06.707,122] <inf> chip: [DMG]Final negotiated min/max parameters: Min = 0s, Max = 60s
    [00:00:06.729,797] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b
    [00:00:06.746,185] <inf> chip: [EM]<<< [E:31240r S:10258 M:194980620 (Ack:262376717)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:06.762,268] <inf> chip: [IN](S) Sending msg 194980620 on secure session with LSID: 10258
    [00:00:06.877,716] <inf> chip: [EM]>>> [E:31240r S:10258 M:262376718 (Ack:194980620)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:06.894,470] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:06.910,400] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b
    [00:00:06.926,818] <inf> chip: [EM]<<< [E:31240r S:10258 M:194980621 (Ack:262376718)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:06.942,840] <inf> chip: [IN](S) Sending msg 194980621 on secure session with LSID: 10258
    [00:00:07.059,478] <inf> chip: [EM]>>> [E:31240r S:10258 M:262376719 (Ack:194980621)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:07.076,171] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:07.092,071] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    [00:00:07.109,100] <inf> chip: [EM]<<< [E:31240r S:10258 M:194980622 (Ack:262376719)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:07.125,183] <inf> chip: [IN](S) Sending msg 194980622 on secure session with LSID: 10258
    [00:00:07.238,281] <inf> chip: [EM]>>> [E:31240r S:10258 M:262376720 (Ack:194980622)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:07.255,187] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:07.270,019] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003F, err = b
    [00:00:07.286,468] <inf> chip: [EM]<<< [E:31240r S:10258 M:194980623 (Ack:262376720)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:07.302,612] <inf> chip: [IN](S) Sending msg 194980623 on secure session with LSID: 10258
    [00:00:07.411,560] <inf> chip: [EM]>>> [E:31240r S:10258 M:262376721 (Ack:194980623)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:07.428,283] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:07.446,380] <inf> chip: [EM]<<< [E:31240r S:10258 M:194980624 (Ack:262376721)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:07.462,615] <inf> chip: [IN](S) Sending msg 194980624 on secure session with LSID: 10258
    [00:00:07.571,197] <inf> chip: [EM]>>> [E:31240r S:10258 M:262376722 (Ack:194980624)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:07.587,982] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:07.596,618] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:00:07.608,367] <inf> chip: [EM]<<< [E:31240r S:10258 M:194980625 (Ack:262376722)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:04 (IM:SubscribeResponse)
    [00:00:07.625,061] <inf> chip: [IN](S) Sending msg 194980625 on secure session with LSID: 10258
    [00:00:07.654,418] <inf> chip: [EM]>>> [E:31240r S:10258 M:262376723 (Ack:194980625)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:12.205,139] <inf> chip: [EM]>>> [E:31241r S:10258 M:262376724] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:12.221,649] <dbg> chip: LogV: [IM]Received Read request
    [00:00:12.230,987] <inf> chip: [EM]<<< [E:31241r S:10258 M:194980626 (Ack:262376724)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:12.247,100] <inf> chip: [IN](S) Sending msg 194980626 on secure session with LSID: 10258
    [00:00:12.259,399] <inf> chip: [EM]>>> [E:31242r S:10258 M:262376725] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:12.275,085] <dbg> chip: LogV: [IM]Received Read request
    [00:00:12.285,827] <inf> chip: [EM]<<< [E:31242r S:10258 M:194980627 (Ack:262376725)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:12.301,971] <inf> chip: [IN](S) Sending msg 194980627 on secure session with LSID: 10258
    [00:00:12.314,331] <inf> chip: [EM]>>> [E:31241r S:10258 M:262376726 (Ack:194980626)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:12.334,991] <inf> chip: [EM]>>> [E:31242r S:10258 M:262376727 (Ack:194980627)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:12.355,010] <inf> chip: [EM]>>> [E:31243r S:10258 M:262376728] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:12.369,842] <dbg> chip: LogV: [IM]Received Read request
    [00:00:12.379,119] <inf> chip: [EM]<<< [E:31243r S:10258 M:194980628 (Ack:262376728)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:12.395,202] <inf> chip: [IN](S) Sending msg 194980628 on secure session with LSID: 10258
    [00:00:12.425,048] <inf> chip: [EM]>>> [E:31243r S:10258 M:262376729 (Ack:194980628)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:20.784,057] <inf> chip: [EM]>>> [E:24172r S:0 M:203146440] (U) Msg RX from 0:EB65CE361FEB97BF [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    [00:00:20.799,743] <inf> chip: [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x20004500
    [00:00:20.811,096] <inf> chip: [IN]CASE Server disabling CASE session setups
    [00:00:20.820,068] <inf> chip: [EM]<<< [E:24172r S:0 M:235726970 (Ack:203146440)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:20.837,127] <inf> chip: [IN](U) Sending msg 235726970 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:61475'
    [00:00:20.850,891] <inf> chip: [SC]Received Sigma1 msg
    [00:00:20.862,274] <inf> chip: [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000043E62CFE
    [00:00:21.083,465] <inf> chip: [EM]<<< [E:24172r S:0 M:235726971 (Ack:203146440)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
    [00:00:21.100,585] <inf> chip: [IN](U) Sending msg 235726971 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:61475'
    [00:00:21.115,692] <inf> chip: [SC]Sent Sigma2 msg
    [00:00:21.229,797] <inf> chip: [EM]>>> [E:24172r S:0 M:203146443 (Ack:235726971)] (U) Msg RX from 0:EB65CE361FEB97BF [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
    [00:00:21.247,436] <inf> chip: [EM]<<< [E:24172r S:0 M:235726972 (Ack:203146443)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:21.265,075] <inf> chip: [IN](U) Sending msg 235726972 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:61475'
    [00:00:21.279,266] <inf> chip: [SC]Received Sigma3 msg
    [00:00:21.290,313] <err> chip: [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
    [00:00:21.438,262] <err> chip: [DL]Long dispatch time: 134 ms, for event type 2
    [00:00:21.655,914] <inf> chip: [EM]<<< [E:24172r S:0 M:235726973 (Ack:203146443)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    [00:00:21.673,431] <inf> chip: [IN](U) Sending msg 235726973 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:61475'
    [00:00:21.689,605] <inf> chip: [SC]SecureSession[0x20002bf8]: Moving from state 'kEstablishing' --> 'kActive'
    [00:00:21.701,873] <dbg> chip: LogV: [IN]SecureSession[0x20002bf8]: Activated - Type:2 LSID:10259
    [00:00:21.714,172] <dbg> chip: LogV: [IN]New secure session activated for device <4EAA76FA0B701439, 1>, LSID:10259 PSID:8581!
    [00:00:21.729,003] <inf> chip: [IN]CASE Session established to peer: <4EAA76FA0B701439, 1>
    [00:00:21.740,692] <inf> chip: [IN]CASE Server enabling CASE session setups
    [00:00:21.751,373] <dbg> chip: LogV: [IN]SecureSession[0x20002cb0]: Allocated Type:2 LSID:10260
    [00:00:21.761,932] <err> chip: [DL]Long dispatch time: 314 ms, for event type 2
    [00:00:21.772,064] <inf> chip: [EM]>>> [E:24184r S:10259 M:7588237] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:21.787,628] <dbg> chip: LogV: [IM]Received Read request
    [00:00:21.797,760] <inf> chip: [EM]<<< [E:24184r S:10259 M:227170725 (Ack:7588237)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:21.813,812] <inf> chip: [IN](S) Sending msg 227170725 on secure session with LSID: 10259
    [00:00:21.826,232] <inf> chip: [EM]>>> [E:24172r S:0 M:203146445 (Ack:235726973)] (U) Msg RX from 0:EB65CE361FEB97BF [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:21.845,916] <inf> chip: [EM]>>> [E:24185r S:10259 M:7588238] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:21.862,335] <dbg> chip: LogV: [IM]Received Read request
    [00:00:21.873,413] <inf> chip: [EM]<<< [E:24185r S:10259 M:227170726 (Ack:7588238)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:21.890,869] <inf> chip: [IN](S) Sending msg 227170726 on secure session with LSID: 10259
    [00:00:21.903,839] <inf> chip: [EM]>>> [E:24186r S:10259 M:7588239] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:21.919,769] <dbg> chip: LogV: [IM]Received Read request
    [00:00:21.930,786] <inf> chip: [EM]<<< [E:24186r S:10259 M:227170727 (Ack:7588239)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:21.948,577] <inf> chip: [IN](S) Sending msg 227170727 on secure session with LSID: 10259
    [00:00:21.961,700] <inf> chip: [EM]>>> [E:24184r S:10259 M:7588240 (Ack:227170725)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:21.981,475] <inf> chip: [EM]>>> [E:24189r S:10259 M:7588241] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:00:21.996,734] <dbg> chip: LogV: [IM]Received Write request
    [00:00:22.078,277] <inf> chip: [EM]<<< [E:24189r S:10259 M:227170728 (Ack:7588241)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:00:22.096,435] <inf> chip: [IN](S) Sending msg 227170728 on secure session with LSID: 10259
    [00:00:22.117,431] <inf> chip: [EM]<<< [E:8243i S:10259 M:227170729] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:22.133,026] <inf> chip: [IN](S) Sending msg 227170729 on secure session with LSID: 10259
    [00:00:22.146,514] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:00:22.160,064] <inf> chip: [EM]>>> [E:24185r S:10259 M:7588242 (Ack:227170726)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:22.180,816] <inf> chip: [EM]>>> [E:24190r S:10259 M:7588243] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:22.197,357] <dbg> chip: LogV: [IM]Received Read request
    [00:00:22.206,939] <inf> chip: [EM]<<< [E:24190r S:10259 M:227170730 (Ack:7588243)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:22.223,358] <inf> chip: [IN](S) Sending msg 227170730 on secure session with LSID: 10259
    [00:00:22.236,480] <inf> chip: [EM]>>> [E:24186r S:10259 M:7588244 (Ack:227170727)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:22.256,958] <inf> chip: [EM]>>> [E:24193r S:10259 M:7588245] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:22.274,444] <dbg> chip: LogV: [IM]Received Read request
    [00:00:22.284,179] <inf> chip: [EM]<<< [E:24193r S:10259 M:227170731 (Ack:7588245)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:22.301,971] <inf> chip: [IN](S) Sending msg 227170731 on secure session with LSID: 10259
    [00:00:22.316,345] <inf> chip: [EM]>>> [E:24194r S:10259 M:7588246] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:22.332,580] <dbg> chip: LogV: [IM]Received Read request
    [00:00:22.343,841] <inf> chip: [EM]<<< [E:24194r S:10259 M:227170732 (Ack:7588246)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:22.361,694] <inf> chip: [IN](S) Sending msg 227170732 on secure session with LSID: 10259
    [00:00:22.374,877] <inf> chip: [EM]>>> [E:24189r S:10259 M:7588247 (Ack:227170728)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:22.394,531] <inf> chip: [EM]>>> [E:8243i S:10259 M:7588248 (Ack:227170729)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:22.413,360] <inf> chip: [IM]Received status response, status is 0x7d
    [00:00:22.424,468] <inf> chip: [EM]<<< [E:8243i S:10259 M:227170733 (Ack:7588248)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:22.442,047] <inf> chip: [IN](S) Sending msg 227170733 on secure session with LSID: 10259
    [00:00:22.454,315] <inf> chip: [EM]>>> [E:24190r S:10259 M:7588249 (Ack:227170730)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:22.473,419] <inf> chip: [EM]>>> [E:24195r S:10259 M:7588250] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:03 (IM:SubscribeRequest)
    [00:00:22.488,891] <dbg> chip: LogV: [IM]Received Subscribe request
    [00:00:22.498,168] <inf> chip: [DMG]Final negotiated min/max parameters: Min = 0s, Max = 60s
    [00:00:22.520,660] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b
    [00:00:22.537,841] <inf> chip: [EM]<<< [E:24195r S:10259 M:227170734 (Ack:7588250)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:22.553,833] <inf> chip: [IN](S) Sending msg 227170734 on secure session with LSID: 10259
    [00:00:22.567,657] <inf> chip: [EM]>>> [E:24196r S:10259 M:7588251] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:22.584,930] <dbg> chip: LogV: [IM]Received Read request
    [00:00:22.595,367] <inf> chip: [EM]<<< [E:24196r S:10259 M:227170735 (Ack:7588251)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:22.613,189] <inf> chip: [IN](S) Sending msg 227170735 on secure session with LSID: 10259
    [00:00:22.627,258] <inf> chip: [EM]>>> [E:24193r S:10259 M:7588252 (Ack:227170731)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:22.648,132] <inf> chip: [EM]>>> [E:24197r S:10259 M:7588253] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:22.665,985] <dbg> chip: LogV: [IM]Received Read request
    [00:00:22.676,055] <inf> chip: [EM]<<< [E:24197r S:10259 M:227170736 (Ack:7588253)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:22.694,366] <inf> chip: [IN](S) Sending msg 227170736 on secure session with LSID: 10259
    [00:00:22.709,808] <inf> chip: [EM]>>> [E:24194r S:10259 M:7588254 (Ack:227170732)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:22.731,445] <inf> chip: [EM]>>> [E:24198r S:10259 M:7588255] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:22.747,802] <dbg> chip: LogV: [IM]Received Read request
    [00:00:22.757,263] <inf> chip: [EM]<<< [E:24198r S:10259 M:227170737 (Ack:7588255)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:22.775,085] <inf> chip: [IN](S) Sending msg 227170737 on secure session with LSID: 10259
    [00:00:22.789,337] <inf> chip: [EM]>>> [E:24195r S:10259 M:7588256 (Ack:227170734)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:22.808,624] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:22.826,080] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b
    [00:00:22.844,573] <inf> chip: [EM]<<< [E:24195r S:10259 M:227170738 (Ack:7588256)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:22.862,426] <inf> chip: [IN](S) Sending msg 227170738 on secure session with LSID: 10259
    [00:00:22.875,885] <inf> chip: [EM]>>> [E:24196r S:10259 M:7588257 (Ack:227170735)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:22.897,155] <inf> chip: [EM]>>> [E:24200r S:10259 M:7588258] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:22.913,055] <dbg> chip: LogV: [IM]Received Read request
    [00:00:22.924,407] <inf> chip: [EM]<<< [E:24200r S:10259 M:227170739 (Ack:7588258)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:22.942,749] <inf> chip: [IN](S) Sending msg 227170739 on secure session with LSID: 10259
    [00:00:22.956,359] <inf> chip: [EM]>>> [E:24197r S:10259 M:7588259 (Ack:227170736)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:22.977,905] <inf> chip: [EM]>>> [E:24201r S:10259 M:7588260] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:00:22.994,781] <dbg> chip: LogV: [IM]Received Write request
    [00:00:23.005,371] <inf> chip: [EM]<<< [E:24201r S:10259 M:227170740 (Ack:7588260)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:00:23.024,108] <inf> chip: [IN](S) Sending msg 227170740 on secure session with LSID: 10259
    [00:00:23.040,405] <inf> chip: [EM]>>> [E:24198r S:10259 M:7588261 (Ack:227170737)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:23.062,316] <inf> chip: [EM]>>> [E:24195r S:10259 M:7588262 (Ack:227170738)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:23.080,780] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:23.097,076] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    [00:00:23.115,020] <inf> chip: [EM]<<< [E:24195r S:10259 M:227170741 (Ack:7588262)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:23.131,317] <inf> chip: [IN](S) Sending msg 227170741 on secure session with LSID: 10259
    [00:00:23.145,019] <inf> chip: [EM]>>> [E:24200r S:10259 M:7588263 (Ack:227170739)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:23.166,564] <inf> chip: [EM]>>> [E:24201r S:10259 M:7588264 (Ack:227170740)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:23.281,707] <inf> chip: [EM]>>> [E:24195r S:10259 M:7588265 (Ack:227170741)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:23.298,706] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:23.313,812] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003F, err = b
    [00:00:23.331,176] <inf> chip: [EM]<<< [E:24195r S:10259 M:227170742 (Ack:7588265)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:23.347,259] <inf> chip: [IN](S) Sending msg 227170742 on secure session with LSID: 10259
    [00:00:23.508,911] <inf> chip: [EM]>>> [E:24195r S:10259 M:7588266 (Ack:227170742)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:23.527,526] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:23.547,973] <inf> chip: [EM]<<< [E:24195r S:10259 M:227170743 (Ack:7588266)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:23.564,208] <inf> chip: [IN](S) Sending msg 227170743 on secure session with LSID: 10259
    [00:00:23.578,002] <inf> chip: [EM]>>> [E:24203r S:10259 M:7588267] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:00:23.594,421] <dbg> chip: LogV: [IM]Received Read request
    [00:00:23.606,475] <inf> chip: [EM]<<< [E:24203r S:10259 M:227170744 (Ack:7588267)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:23.624,114] <inf> chip: [IN](S) Sending msg 227170744 on secure session with LSID: 10259
    [00:00:23.750,122] <inf> chip: [EM]>>> [E:24195r S:10259 M:7588268 (Ack:227170743)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:23.768,768] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:23.780,792] <inf> chip: [EM]<<< [E:24195r S:10259 M:227170745 (Ack:7588268)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:23.797,302] <inf> chip: [IN](S) Sending msg 227170745 on secure session with LSID: 10259
    [00:00:23.810,272] <inf> chip: [EM]>>> [E:24203r S:10259 M:7588269 (Ack:227170744)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:23.835,540] <inf> chip: [EM]>>> [E:24195r S:10259 M:7588270 (Ack:227170745)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:23.852,844] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:23.861,846] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:00:23.874,053] <inf> chip: [EM]<<< [E:24195r S:10259 M:227170746 (Ack:7588270)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:04 (IM:SubscribeResponse)
    [00:00:23.891,174] <inf> chip: [IN](S) Sending msg 227170746 on secure session with LSID: 10259
    [00:00:23.921,997] <inf> chip: [EM]>>> [E:24195r S:10259 M:7588271 (Ack:227170746)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:26.049,224] <inf> chip: [EM]>>> [E:24206r S:10259 M:7588272] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:00:26.063,995] <dbg> chip: LogV: [IM]Received Write request
    [00:00:26.072,113] <inf> app: FanMode: 3
    [00:00:26.149,353] <inf> chip: [EM]<<< [E:24206r S:10259 M:227170747 (Ack:7588272)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:00:26.165,466] <inf> chip: [IN](S) Sending msg 227170747 on secure session with LSID: 10259
    [00:00:26.182,739] <inf> chip: [EM]<<< [E:8244i S:10259 M:227170748] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:26.198,638] <inf> chip: [IN](S) Sending msg 227170748 on secure session with LSID: 10259
    [00:00:26.211,914] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:00:26.224,548] <inf> chip: [EM]>>> [E:24206r S:10259 M:7588273 (Ack:227170747)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:26.244,598] <inf> chip: [EM]>>> [E:24207r S:10259 M:7588274] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:00:26.259,429] <dbg> chip: LogV: [IM]Received Write request
    [00:00:26.267,517] <inf> app: Percent: 100
    [00:00:26.274,169] <inf> chip: [EM]<<< [E:24207r S:10259 M:227170749 (Ack:7588274)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:00:26.290,252] <inf> chip: [IN](S) Sending msg 227170749 on secure session with LSID: 10259
    [00:00:26.303,375] <inf> chip: [EM]>>> [E:8244i S:10259 M:7588275 (Ack:227170748)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:26.321,807] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:26.331,207] <inf> chip: [EM]<<< [E:8244i S:10259 M:227170750 (Ack:7588275)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:26.348,266] <inf> chip: [IN](S) Sending msg 227170750 on secure session with LSID: 10259
    [00:00:26.365,173] <inf> chip: [EM]<<< [E:8245i S:10259 M:227170751] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:26.379,882] <inf> chip: [IN](S) Sending msg 227170751 on secure session with LSID: 10259
    [00:00:26.391,387] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:00:26.403,900] <inf> chip: [EM]>>> [E:24207r S:10259 M:7588276 (Ack:227170749)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:26.423,797] <inf> chip: [EM]>>> [E:8245i S:10259 M:7588277 (Ack:227170751)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:26.440,277] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:26.449,554] <inf> chip: [EM]<<< [E:8245i S:10259 M:227170752 (Ack:7588277)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:26.466,613] <inf> chip: [IN](S) Sending msg 227170752 on secure session with LSID: 10259
    [00:00:29.393,798] <inf> chip: [EM]>>> [E:24208r S:10259 M:7588278] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:00:29.408,538] <dbg> chip: LogV: [IM]Received Write request
    [00:00:29.416,564] <inf> app: FanMode: 0
    [00:00:29.492,767] <inf> app: Percent: 0
    [00:00:29.499,359] <inf> chip: [EM]<<< [E:24208r S:10259 M:227170753 (Ack:7588278)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:00:29.515,502] <inf> chip: [IN](S) Sending msg 227170753 on secure session with LSID: 10259
    [00:00:29.533,203] <inf> chip: [EM]<<< [E:8246i S:10259 M:227170754] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:00:29.549,255] <inf> chip: [IN](S) Sending msg 227170754 on secure session with LSID: 10259
    [00:00:29.560,821] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:00:29.574,218] <inf> chip: [EM]>>> [E:24208r S:10259 M:7588279 (Ack:227170753)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:29.594,146] <inf> chip: [EM]>>> [E:8246i S:10259 M:7588280 (Ack:227170754)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:00:29.610,717] <inf> chip: [IM]Received status response, status is 0x00
    [00:00:29.619,995] <inf> chip: [EM]<<< [E:8246i S:10259 M:227170755 (Ack:7588280)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:00:29.637,145] <inf> chip: [IN](S) Sending msg 227170755 on secure session with LSID: 10259
    [00:01:29.573,852] <inf> chip: [DMG]Refresh subscribe timer sync after 60 seconds
    [00:01:29.587,432] <inf> chip: [EM]<<< [E:8247i S:10259 M:227170756] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:01:29.602,050] <inf> chip: [IN](S) Sending msg 227170756 on secure session with LSID: 10259
    [00:01:29.613,952] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:01:29.630,737] <inf> chip: [EM]>>> [E:8247i S:10259 M:7588281 (Ack:227170756)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:01:29.647,186] <inf> chip: [IM]Received status response, status is 0x00
    [00:01:29.656,463] <inf> chip: [EM]<<< [E:8247i S:10259 M:227170757 (Ack:7588281)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:01:29.673,767] <inf> chip: [IN](S) Sending msg 227170757 on secure session with LSID: 10259
    [00:02:29.625,488] <inf> chip: [DMG]Refresh subscribe timer sync after 60 seconds
    [00:02:29.639,282] <inf> chip: [EM]<<< [E:8248i S:10259 M:227170758] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:02:29.653,808] <inf> chip: [IN](S) Sending msg 227170758 on secure session with LSID: 10259
    [00:02:29.665,283] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:02:29.687,103] <inf> chip: [EM]>>> [E:8248i S:10259 M:7588282 (Ack:227170758)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:02:29.703,552] <inf> chip: [IM]Received status response, status is 0x00
    [00:02:29.712,860] <inf> chip: [EM]<<< [E:8248i S:10259 M:227170759 (Ack:7588282)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:02:29.729,919] <inf> chip: [IN](S) Sending msg 227170759 on secure session with LSID: 10259
    [00:03:29.677,642] <inf> chip: [DMG]Refresh subscribe timer sync after 60 seconds
    [00:03:29.691,253] <inf> chip: [EM]<<< [E:8249i S:10259 M:227170760] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:29.705,902] <inf> chip: [IN](S) Sending msg 227170760 on secure session with LSID: 10259
    [00:03:29.717,803] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:03:29.735,626] <inf> chip: [EM]>>> [E:8249i S:10259 M:7588283 (Ack:227170760)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:03:29.752,075] <inf> chip: [IM]Received status response, status is 0x00
    [00:03:29.761,413] <inf> chip: [EM]<<< [E:8249i S:10259 M:227170761 (Ack:7588283)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:29.778,411] <inf> chip: [IN](S) Sending msg 227170761 on secure session with LSID: 10259
    [00:03:47.601,165] <inf> chip: [EM]>>> [E:24212r S:10259 M:7588284] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:47.617,340] <dbg> chip: LogV: [IM]Received Read request
    [00:03:47.626,708] <inf> chip: [EM]<<< [E:24212r S:10259 M:227170762 (Ack:7588284)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:47.642,639] <inf> chip: [IN](S) Sending msg 227170762 on secure session with LSID: 10259
    [00:03:47.654,907] <inf> chip: [EM]>>> [E:24213r S:10259 M:7588285] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:47.670,349] <dbg> chip: LogV: [IM]Received Read request
    [00:03:47.681,304] <inf> chip: [EM]<<< [E:24213r S:10259 M:227170763 (Ack:7588285)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:47.697,174] <inf> chip: [IN](S) Sending msg 227170763 on secure session with LSID: 10259
    [00:03:47.709,930] <inf> chip: [EM]>>> [E:24212r S:10259 M:7588286 (Ack:227170762)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:47.730,377] <inf> chip: [EM]>>> [E:24213r S:10259 M:7588287 (Ack:227170763)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:47.750,213] <inf> chip: [EM]>>> [E:24214r S:10259 M:7588288] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:47.764,923] <dbg> chip: LogV: [IM]Received Read request
    [00:03:47.774,169] <inf> chip: [EM]<<< [E:24214r S:10259 M:227170764 (Ack:7588288)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:47.790,100] <inf> chip: [IN](S) Sending msg 227170764 on secure session with LSID: 10259
    [00:03:47.822,418] <inf> chip: [EM]>>> [E:24214r S:10259 M:7588289 (Ack:227170764)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:55.519,439] <inf> chip: [EM]>>> [E:25422r S:0 M:195782958] (U) Msg RX from 0:EA12EF10D798472E [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    [00:03:55.535,186] <inf> chip: [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x20004500
    [00:03:55.546,752] <inf> chip: [IN]CASE Server disabling CASE session setups
    [00:03:55.555,969] <inf> chip: [EM]<<< [E:25422r S:0 M:235726974 (Ack:195782958)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:55.573,516] <inf> chip: [IN](U) Sending msg 235726974 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:56936'
    [00:03:55.587,707] <inf> chip: [SC]Received Sigma1 msg
    [00:03:55.599,792] <inf> chip: [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000043E62CFE
    [00:03:55.822,662] <inf> chip: [EM]<<< [E:25422r S:0 M:235726975 (Ack:195782958)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
    [00:03:55.839,965] <inf> chip: [IN](U) Sending msg 235726975 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:56936'
    [00:03:55.854,827] <inf> chip: [SC]Sent Sigma2 msg
    [00:03:55.991,790] <inf> chip: [EM]>>> [E:25422r S:0 M:195782961 (Ack:235726975)] (U) Msg RX from 0:EA12EF10D798472E [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
    [00:03:56.009,826] <inf> chip: [EM]<<< [E:25422r S:0 M:235726976 (Ack:195782961)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:56.027,374] <inf> chip: [IN](U) Sending msg 235726976 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:56936'
    [00:03:56.041,503] <inf> chip: [SC]Received Sigma3 msg
    [00:03:56.053,070] <err> chip: [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
    [00:03:56.201,080] <err> chip: [DL]Long dispatch time: 133 ms, for event type 2
    [00:03:56.305,786] <inf> chip: [EM]<<< [E:25422r S:0 M:235726977 (Ack:195782961)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    [00:03:56.323,089] <inf> chip: [IN](U) Sending msg 235726977 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:56936'
    [00:03:56.338,104] <inf> chip: [SC]SecureSession[0x20002cb0]: Moving from state 'kEstablishing' --> 'kActive'
    [00:03:56.350,677] <dbg> chip: LogV: [IN]SecureSession[0x20002cb0]: Activated - Type:2 LSID:10260
    [00:03:56.361,663] <dbg> chip: LogV: [IN]New secure session activated for device <4EAA76FA0B701439, 1>, LSID:10260 PSID:58503!
    [00:03:56.376,678] <inf> chip: [IN]CASE Session established to peer: <4EAA76FA0B701439, 1>
    [00:03:56.387,023] <inf> chip: [IN]CASE Server enabling CASE session setups
    [00:03:56.397,430] <dbg> chip: LogV: [IN]SecureSession[0x20002d68]: Allocated Type:2 LSID:10261
    [00:03:56.409,851] <err> chip: [DL]Long dispatch time: 200 ms, for event type 2
    [00:03:56.420,166] <inf> chip: [EM]>>> [E:25434r S:10260 M:95347988] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:56.436,950] <dbg> chip: LogV: [IM]Received Read request
    [00:03:56.446,929] <inf> chip: [EM]<<< [E:25434r S:10260 M:160532401 (Ack:95347988)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:56.465,209] <inf> chip: [IN](S) Sending msg 160532401 on secure session with LSID: 10260
    [00:03:56.479,248] <inf> chip: [EM]>>> [E:25422r S:0 M:195782962 (Ack:235726977)] (U) Msg RX from 0:EA12EF10D798472E [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:56.499,908] <inf> chip: [EM]>>> [E:25435r S:10260 M:95347989] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:56.515,106] <dbg> chip: LogV: [IM]Received Read request
    [00:03:56.525,970] <inf> chip: [EM]<<< [E:25435r S:10260 M:160532402 (Ack:95347989)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:56.544,921] <inf> chip: [IN](S) Sending msg 160532402 on secure session with LSID: 10260
    [00:03:56.558,044] <inf> chip: [EM]>>> [E:25436r S:10260 M:95347990] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:56.574,859] <dbg> chip: LogV: [IM]Received Read request
    [00:03:56.584,442] <inf> chip: [EM]<<< [E:25436r S:10260 M:160532403 (Ack:95347990)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:56.602,478] <inf> chip: [IN](S) Sending msg 160532403 on secure session with LSID: 10260
    [00:03:56.615,081] <inf> chip: [EM]>>> [E:25434r S:10260 M:95347991 (Ack:160532401)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:56.634,643] <inf> chip: [EM]>>> [E:25438r S:10260 M:95347992] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:03:56.652,740] <dbg> chip: LogV: [IM]Received Write request
    [00:03:56.734,710] <inf> chip: [EM]<<< [E:25438r S:10260 M:160532404 (Ack:95347992)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:03:56.751,190] <inf> chip: [IN](S) Sending msg 160532404 on secure session with LSID: 10260
    [00:03:56.771,118] <inf> chip: [EM]<<< [E:8250i S:10260 M:160532405] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:56.788,208] <inf> chip: [IN](S) Sending msg 160532405 on secure session with LSID: 10260
    [00:03:56.801,910] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:03:56.815,277] <inf> chip: [EM]>>> [E:25435r S:10260 M:95347993 (Ack:160532402)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:56.835,998] <inf> chip: [EM]>>> [E:25439r S:10260 M:95347994] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:56.853,027] <dbg> chip: LogV: [IM]Received Read request
    [00:03:56.862,854] <inf> chip: [EM]<<< [E:25439r S:10260 M:160532406 (Ack:95347994)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:56.879,028] <inf> chip: [IN](S) Sending msg 160532406 on secure session with LSID: 10260
    [00:03:56.892,028] <inf> chip: [EM]>>> [E:25436r S:10260 M:95347995 (Ack:160532403)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:56.912,445] <inf> chip: [EM]>>> [E:25440r S:10260 M:95347996] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:56.928,466] <dbg> chip: LogV: [IM]Received Read request
    [00:03:56.938,720] <inf> chip: [EM]<<< [E:25440r S:10260 M:160532407 (Ack:95347996)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:56.956,390] <inf> chip: [IN](S) Sending msg 160532407 on secure session with LSID: 10260
    [00:03:56.969,177] <inf> chip: [EM]>>> [E:25441r S:10260 M:95347997] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:56.986,602] <dbg> chip: LogV: [IM]Received Read request
    [00:03:56.997,711] <inf> chip: [EM]<<< [E:25441r S:10260 M:160532408 (Ack:95347997)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:57.014,190] <inf> chip: [IN](S) Sending msg 160532408 on secure session with LSID: 10260
    [00:03:57.030,517] <inf> chip: [EM]>>> [E:25438r S:10260 M:95347998 (Ack:160532404)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:57.049,804] <inf> chip: [EM]>>> [E:8250i S:10260 M:95347999 (Ack:160532405)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:03:57.069,854] <inf> chip: [IM]Received status response, status is 0x7d
    [00:03:57.081,146] <inf> chip: [EM]<<< [E:8250i S:10260 M:160532409 (Ack:95347999)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:57.098,937] <inf> chip: [IN](S) Sending msg 160532409 on secure session with LSID: 10260
    [00:03:57.111,968] <inf> chip: [EM]>>> [E:25439r S:10260 M:95348000 (Ack:160532406)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:57.131,713] <inf> chip: [EM]>>> [E:25442r S:10260 M:95348001] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:03 (IM:SubscribeRequest)
    [00:03:57.147,338] <dbg> chip: LogV: [IM]Received Subscribe request
    [00:03:57.156,890] <inf> chip: [DMG]Final negotiated min/max parameters: Min = 0s, Max = 60s
    [00:03:57.179,534] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b
    [00:03:57.196,197] <inf> chip: [EM]<<< [E:25442r S:10260 M:160532410 (Ack:95348001)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:57.212,585] <inf> chip: [IN](S) Sending msg 160532410 on secure session with LSID: 10260
    [00:03:57.226,379] <inf> chip: [EM]>>> [E:25443r S:10260 M:95348002] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:57.243,713] <dbg> chip: LogV: [IM]Received Read request
    [00:03:57.254,425] <inf> chip: [EM]<<< [E:25443r S:10260 M:160532411 (Ack:95348002)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:57.272,521] <inf> chip: [IN](S) Sending msg 160532411 on secure session with LSID: 10260
    [00:03:57.286,041] <inf> chip: [EM]>>> [E:25440r S:10260 M:95348003 (Ack:160532407)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:57.305,908] <inf> chip: [EM]>>> [E:25444r S:10260 M:95348004] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:57.323,211] <dbg> chip: LogV: [IM]Received Read request
    [00:03:57.333,587] <inf> chip: [EM]<<< [E:25444r S:10260 M:160532412 (Ack:95348004)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:57.351,959] <inf> chip: [IN](S) Sending msg 160532412 on secure session with LSID: 10260
    [00:03:57.365,692] <inf> chip: [EM]>>> [E:25441r S:10260 M:95348005 (Ack:160532408)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:57.387,176] <inf> chip: [EM]>>> [E:25445r S:10260 M:95348006] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:57.406,982] <dbg> chip: LogV: [IM]Received Read request
    [00:03:57.418,212] <inf> chip: [EM]<<< [E:25445r S:10260 M:160532413 (Ack:95348006)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:57.435,943] <inf> chip: [IN](S) Sending msg 160532413 on secure session with LSID: 10260
    [00:03:57.451,141] <inf> chip: [EM]>>> [E:25442r S:10260 M:95348007 (Ack:160532410)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:03:57.470,428] <inf> chip: [IM]Received status response, status is 0x00
    [00:03:57.488,494] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b
    [00:03:57.505,310] <inf> chip: [EM]<<< [E:25442r S:10260 M:160532414 (Ack:95348007)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:57.521,728] <inf> chip: [IN](S) Sending msg 160532414 on secure session with LSID: 10260
    [00:03:57.535,095] <inf> chip: [EM]>>> [E:25443r S:10260 M:95348008 (Ack:160532411)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:57.555,847] <inf> chip: [EM]>>> [E:25444r S:10260 M:95348009 (Ack:160532412)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:57.576,843] <inf> chip: [EM]>>> [E:25446r S:10260 M:95348010] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:03:57.592,529] <dbg> chip: LogV: [IM]Received Write request
    [00:03:57.604,492] <inf> chip: [EM]<<< [E:25446r S:10260 M:160532415 (Ack:95348010)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:03:57.623,535] <inf> chip: [IN](S) Sending msg 160532415 on secure session with LSID: 10260
    [00:03:57.637,512] <inf> chip: [EM]>>> [E:25447r S:10260 M:95348011] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:57.655,029] <dbg> chip: LogV: [IM]Received Read request
    [00:03:57.665,252] <inf> chip: [EM]<<< [E:25447r S:10260 M:160532416 (Ack:95348011)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:57.684,722] <inf> chip: [IN](S) Sending msg 160532416 on secure session with LSID: 10260
    [00:03:57.699,981] <inf> chip: [EM]>>> [E:25445r S:10260 M:95348012 (Ack:160532413)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:57.719,512] <inf> chip: [EM]>>> [E:25442r S:10260 M:95348013 (Ack:160532414)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:03:57.738,220] <inf> chip: [IM]Received status response, status is 0x00
    [00:03:57.755,737] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    [00:03:57.773,712] <inf> chip: [EM]<<< [E:25442r S:10260 M:160532417 (Ack:95348013)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:57.790,191] <inf> chip: [IN](S) Sending msg 160532417 on secure session with LSID: 10260
    [00:03:57.803,497] <inf> chip: [EM]>>> [E:25446r S:10260 M:95348014 (Ack:160532415)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:57.823,944] <inf> chip: [EM]>>> [E:25447r S:10260 M:95348015 (Ack:160532416)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:57.972,290] <inf> chip: [EM]>>> [E:25442r S:10260 M:95348016 (Ack:160532417)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:03:57.989,440] <inf> chip: [IM]Received status response, status is 0x00
    [00:03:58.004,211] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003F, err = b
    [00:03:58.021,759] <inf> chip: [EM]<<< [E:25442r S:10260 M:160532418 (Ack:95348016)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:58.037,994] <inf> chip: [IN](S) Sending msg 160532418 on secure session with LSID: 10260
    [00:03:58.172,119] <inf> chip: [EM]>>> [E:25442r S:10260 M:95348017 (Ack:160532418)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:03:58.190,612] <inf> chip: [IM]Received status response, status is 0x00
    [00:03:58.211,456] <inf> chip: [EM]<<< [E:25442r S:10260 M:160532419 (Ack:95348017)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:58.227,783] <inf> chip: [IN](S) Sending msg 160532419 on secure session with LSID: 10260
    [00:03:58.241,729] <inf> chip: [EM]>>> [E:25449r S:10260 M:95348018] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:03:58.258,453] <dbg> chip: LogV: [IM]Received Read request
    [00:03:58.270,446] <inf> chip: [EM]<<< [E:25449r S:10260 M:160532420 (Ack:95348018)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:58.289,581] <inf> chip: [IN](S) Sending msg 160532420 on secure session with LSID: 10260
    [00:03:58.379,943] <inf> chip: [EM]>>> [E:25442r S:10260 M:95348019 (Ack:160532419)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:03:58.398,529] <inf> chip: [IM]Received status response, status is 0x00
    [00:03:58.414,306] <inf> chip: [EM]<<< [E:25442r S:10260 M:160532421 (Ack:95348019)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:03:58.430,633] <inf> chip: [IN](S) Sending msg 160532421 on secure session with LSID: 10260
    [00:03:58.443,847] <inf> chip: [EM]>>> [E:25449r S:10260 M:95348020 (Ack:160532420)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:03:58.514,709] <inf> chip: [EM]>>> [E:25442r S:10260 M:95348021 (Ack:160532421)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:03:58.531,860] <inf> chip: [IM]Received status response, status is 0x00
    [00:03:58.540,618] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:03:58.552,551] <inf> chip: [EM]<<< [E:25442r S:10260 M:160532422 (Ack:95348021)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:04 (IM:SubscribeResponse)
    [00:03:58.569,396] <inf> chip: [IN](S) Sending msg 160532422 on secure session with LSID: 10260
    [00:03:58.599,517] <inf> chip: [EM]>>> [E:25442r S:10260 M:95348022 (Ack:160532422)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:01.106,353] <inf> chip: [EM]>>> [E:25452r S:10260 M:95348023] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:04:01.121,826] <dbg> chip: LogV: [IM]Received Write request
    [00:04:01.129,943] <inf> app: FanMode: 3
    [00:04:01.209,411] <inf> chip: [EM]<<< [E:25452r S:10260 M:160532423 (Ack:95348023)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:04:01.225,982] <inf> chip: [IN](S) Sending msg 160532423 on secure session with LSID: 10260
    [00:04:01.244,506] <inf> chip: [EM]<<< [E:8251i S:10260 M:160532424] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:01.259,582] <inf> chip: [IN](S) Sending msg 160532424 on secure session with LSID: 10260
    [00:04:01.273,345] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:04:01.288,085] <inf> chip: [EM]>>> [E:25452r S:10260 M:95348024 (Ack:160532423)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:01.308,685] <inf> chip: [EM]>>> [E:25453r S:10260 M:95348025] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:04:01.323,913] <dbg> chip: LogV: [IM]Received Write request
    [00:04:01.332,153] <inf> app: Percent: 100
    [00:04:01.339,172] <inf> chip: [EM]<<< [E:25453r S:10260 M:160532425 (Ack:95348025)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:04:01.355,895] <inf> chip: [IN](S) Sending msg 160532425 on secure session with LSID: 10260
    [00:04:01.368,682] <inf> chip: [EM]>>> [E:8251i S:10260 M:95348026 (Ack:160532424)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:01.386,657] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:01.396,148] <inf> chip: [EM]<<< [E:8251i S:10260 M:160532426 (Ack:95348026)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:01.415,252] <inf> chip: [IN](S) Sending msg 160532426 on secure session with LSID: 10260
    [00:04:01.433,532] <inf> chip: [EM]<<< [E:8252i S:10260 M:160532427] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:01.448,455] <inf> chip: [IN](S) Sending msg 160532427 on secure session with LSID: 10260
    [00:04:01.459,991] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:04:01.473,144] <inf> chip: [EM]>>> [E:25453r S:10260 M:95348027 (Ack:160532425)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:01.493,469] <inf> chip: [EM]>>> [E:8252i S:10260 M:95348028 (Ack:160532427)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:01.510,406] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:01.519,683] <inf> chip: [EM]<<< [E:8252i S:10260 M:160532428 (Ack:95348028)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:01.537,261] <inf> chip: [IN](S) Sending msg 160532428 on secure session with LSID: 10260
    [00:04:04.107,604] <inf> chip: [EM]>>> [E:25455r S:10260 M:95348029] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:04:04.122,894] <dbg> chip: LogV: [IM]Received Write request
    [00:04:04.130,920] <inf> app: FanMode: 0
    [00:04:04.210,662] <inf> app: Percent: 0
    [00:04:04.217,193] <inf> chip: [EM]<<< [E:25455r S:10260 M:160532429 (Ack:95348029)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:04:04.233,978] <inf> chip: [IN](S) Sending msg 160532429 on secure session with LSID: 10260
    [00:04:04.253,234] <inf> chip: [EM]<<< [E:8253i S:10260 M:160532430] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:04.268,005] <inf> chip: [IN](S) Sending msg 160532430 on secure session with LSID: 10260
    [00:04:04.281,433] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:04:04.295,440] <inf> chip: [EM]>>> [E:25455r S:10260 M:95348030 (Ack:160532429)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:04.315,856] <inf> chip: [EM]>>> [E:8253i S:10260 M:95348031 (Ack:160532430)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:04.332,733] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:04.342,346] <inf> chip: [EM]<<< [E:8253i S:10260 M:160532431 (Ack:95348031)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:04.359,863] <inf> chip: [IN](S) Sending msg 160532431 on secure session with LSID: 10260
    [00:04:23.698,425] <inf> chip: [EM]>>> [E:25456r S:10260 M:95348032] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:23.714,782] <dbg> chip: LogV: [IM]Received Read request
    [00:04:23.724,090] <inf> chip: [EM]<<< [E:25456r S:10260 M:160532432 (Ack:95348032)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:23.740,051] <inf> chip: [IN](S) Sending msg 160532432 on secure session with LSID: 10260
    [00:04:23.752,502] <inf> chip: [EM]>>> [E:25457r S:10260 M:95348033] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:23.769,622] <dbg> chip: LogV: [IM]Received Read request
    [00:04:23.778,839] <inf> chip: [EM]<<< [E:25457r S:10260 M:160532433 (Ack:95348033)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:23.794,952] <inf> chip: [IN](S) Sending msg 160532433 on secure session with LSID: 10260
    [00:04:23.807,281] <inf> chip: [EM]>>> [E:25456r S:10260 M:95348034 (Ack:160532432)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:23.828,063] <inf> chip: [EM]>>> [E:25457r S:10260 M:95348035 (Ack:160532433)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:23.848,175] <inf> chip: [EM]>>> [E:25458r S:10260 M:95348036] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:23.863,006] <dbg> chip: LogV: [IM]Received Read request
    [00:04:23.872,344] <inf> chip: [EM]<<< [E:25458r S:10260 M:160532434 (Ack:95348036)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:23.888,305] <inf> chip: [IN](S) Sending msg 160532434 on secure session with LSID: 10260
    [00:04:23.918,365] <inf> chip: [EM]>>> [E:25458r S:10260 M:95348037 (Ack:160532434)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:31.162,963] <inf> chip: [EM]>>> [E:2856r S:0 M:101251120] (U) Msg RX from 0:9806E7E81836223A [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    [00:04:31.178,771] <inf> chip: [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x20004500
    [00:04:31.190,124] <inf> chip: [IN]CASE Server disabling CASE session setups
    [00:04:31.199,401] <inf> chip: [EM]<<< [E:2856r S:0 M:235726978 (Ack:101251120)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:31.216,705] <inf> chip: [IN](U) Sending msg 235726978 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:64238'
    [00:04:31.230,651] <inf> chip: [SC]Received Sigma1 msg
    [00:04:31.242,736] <inf> chip: [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000043E62CFE
    [00:04:31.465,942] <inf> chip: [EM]<<< [E:2856r S:0 M:235726979 (Ack:101251120)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
    [00:04:31.483,062] <inf> chip: [IN](U) Sending msg 235726979 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:64238'
    [00:04:31.498,107] <inf> chip: [SC]Sent Sigma2 msg
    [00:04:31.615,386] <inf> chip: [EM]>>> [E:2856r S:0 M:101251123 (Ack:235726979)] (U) Msg RX from 0:9806E7E81836223A [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
    [00:04:31.633,178] <inf> chip: [EM]<<< [E:2856r S:0 M:235726980 (Ack:101251123)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:31.650,482] <inf> chip: [IN](U) Sending msg 235726980 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:64238'
    [00:04:31.664,642] <inf> chip: [SC]Received Sigma3 msg
    [00:04:31.675,628] <err> chip: [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
    [00:04:31.822,052] <err> chip: [DL]Long dispatch time: 131 ms, for event type 2
    [00:04:31.928,405] <inf> chip: [EM]<<< [E:2856r S:0 M:235726981 (Ack:101251123)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    [00:04:31.945,495] <inf> chip: [IN](U) Sending msg 235726981 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:64238'
    [00:04:31.961,303] <inf> chip: [SC]SecureSession[0x20002d68]: Moving from state 'kEstablishing' --> 'kActive'
    [00:04:31.973,693] <dbg> chip: LogV: [IN]SecureSession[0x20002d68]: Activated - Type:2 LSID:10261
    [00:04:31.986,175] <dbg> chip: LogV: [IN]New secure session activated for device <4EAA76FA0B701439, 1>, LSID:10261 PSID:46493!
    [00:04:32.001,312] <inf> chip: [IN]CASE Session established to peer: <4EAA76FA0B701439, 1>
    [00:04:32.011,352] <inf> chip: [IN]CASE Server enabling CASE session setups
    [00:04:32.022,125] <dbg> chip: LogV: [IN]SecureSession[0x20002e20]: Allocated Type:2 LSID:10262
    [00:04:32.034,149] <err> chip: [DL]Long dispatch time: 204 ms, for event type 2
    [00:04:32.044,128] <inf> chip: [EM]>>> [E:2865r S:10261 M:116668363] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:32.059,295] <dbg> chip: LogV: [IM]Received Read request
    [00:04:32.069,274] <inf> chip: [EM]<<< [E:2865r S:10261 M:7063525 (Ack:116668363)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:32.085,083] <inf> chip: [IN](S) Sending msg 7063525 on secure session with LSID: 10261
    [00:04:32.097,320] <inf> chip: [EM]>>> [E:2856r S:0 M:101251124 (Ack:235726981)] (U) Msg RX from 0:9806E7E81836223A [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:32.117,675] <inf> chip: [EM]>>> [E:2866r S:10261 M:116668364] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:32.134,338] <dbg> chip: LogV: [IM]Received Read request
    [00:04:32.145,233] <inf> chip: [EM]<<< [E:2866r S:10261 M:7063526 (Ack:116668364)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:32.162,689] <inf> chip: [IN](S) Sending msg 7063526 on secure session with LSID: 10261
    [00:04:32.175,506] <inf> chip: [EM]>>> [E:2867r S:10261 M:116668365] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:32.191,101] <dbg> chip: LogV: [IM]Received Read request
    [00:04:32.202,301] <inf> chip: [EM]<<< [E:2867r S:10261 M:7063527 (Ack:116668365)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:32.220,275] <inf> chip: [IN](S) Sending msg 7063527 on secure session with LSID: 10261
    [00:04:32.233,062] <inf> chip: [EM]>>> [E:2865r S:10261 M:116668366 (Ack:7063525)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:32.254,211] <inf> chip: [EM]>>> [E:2868r S:10261 M:116668367] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:04:32.272,399] <dbg> chip: LogV: [IM]Received Write request
    [00:04:32.352,752] <inf> chip: [EM]<<< [E:2868r S:10261 M:7063528 (Ack:116668367)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:04:32.369,323] <inf> chip: [IN](S) Sending msg 7063528 on secure session with LSID: 10261
    [00:04:32.390,258] <inf> chip: [EM]<<< [E:8254i S:10261 M:7063529] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:32.406,585] <inf> chip: [IN](S) Sending msg 7063529 on secure session with LSID: 10261
    [00:04:32.420,379] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:04:32.433,959] <inf> chip: [EM]>>> [E:2866r S:10261 M:116668368 (Ack:7063526)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:32.454,284] <inf> chip: [EM]>>> [E:2869r S:10261 M:116668369] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:32.470,794] <dbg> chip: LogV: [IM]Received Read request
    [00:04:32.481,109] <inf> chip: [EM]<<< [E:2869r S:10261 M:7063530 (Ack:116668369)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:32.498,992] <inf> chip: [IN](S) Sending msg 7063530 on secure session with LSID: 10261
    [00:04:32.511,474] <inf> chip: [EM]>>> [E:2867r S:10261 M:116668370 (Ack:7063527)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:32.531,799] <inf> chip: [EM]>>> [E:2871r S:10261 M:116668371] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:32.548,492] <dbg> chip: LogV: [IM]Received Read request
    [00:04:32.559,539] <inf> chip: [EM]<<< [E:2871r S:10261 M:7063531 (Ack:116668371)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:32.576,995] <inf> chip: [IN](S) Sending msg 7063531 on secure session with LSID: 10261
    [00:04:32.589,965] <inf> chip: [EM]>>> [E:2872r S:10261 M:116668372] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:32.605,987] <dbg> chip: LogV: [IM]Received Read request
    [00:04:32.616,973] <inf> chip: [EM]<<< [E:2872r S:10261 M:7063532 (Ack:116668372)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:32.635,009] <inf> chip: [IN](S) Sending msg 7063532 on secure session with LSID: 10261
    [00:04:32.648,071] <inf> chip: [EM]>>> [E:2868r S:10261 M:116668373 (Ack:7063528)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:32.667,694] <inf> chip: [EM]>>> [E:8254i S:10261 M:116668374 (Ack:7063529)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:32.687,316] <inf> chip: [IM]Received status response, status is 0x7d
    [00:04:32.696,929] <inf> chip: [EM]<<< [E:8254i S:10261 M:7063533 (Ack:116668374)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:32.714,538] <inf> chip: [IN](S) Sending msg 7063533 on secure session with LSID: 10261
    [00:04:32.726,715] <inf> chip: [EM]>>> [E:2869r S:10261 M:116668375 (Ack:7063530)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:32.747,985] <inf> chip: [EM]>>> [E:2873r S:10261 M:116668376] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:03 (IM:SubscribeRequest)
    [00:04:32.765,045] <dbg> chip: LogV: [IM]Received Subscribe request
    [00:04:32.774,353] <inf> chip: [DMG]Final negotiated min/max parameters: Min = 0s, Max = 60s
    [00:04:32.796,600] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b
    [00:04:32.813,507] <inf> chip: [EM]<<< [E:2873r S:10261 M:7063534 (Ack:116668376)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:32.829,376] <inf> chip: [IN](S) Sending msg 7063534 on secure session with LSID: 10261
    [00:04:32.842,529] <inf> chip: [EM]>>> [E:2874r S:10261 M:116668377] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:32.859,252] <dbg> chip: LogV: [IM]Received Read request
    [00:04:32.869,384] <inf> chip: [EM]<<< [E:2874r S:10261 M:7063535 (Ack:116668377)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:32.886,840] <inf> chip: [IN](S) Sending msg 7063535 on secure session with LSID: 10261
    [00:04:32.899,505] <inf> chip: [EM]>>> [E:2871r S:10261 M:116668378 (Ack:7063531)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:32.920,715] <inf> chip: [EM]>>> [E:2875r S:10261 M:116668379] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:32.937,438] <dbg> chip: LogV: [IM]Received Read request
    [00:04:32.949,066] <inf> chip: [EM]<<< [E:2875r S:10261 M:7063536 (Ack:116668379)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:32.967,163] <inf> chip: [IN](S) Sending msg 7063536 on secure session with LSID: 10261
    [00:04:32.981,384] <inf> chip: [EM]>>> [E:2872r S:10261 M:116668380 (Ack:7063532)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:33.002,197] <inf> chip: [EM]>>> [E:2876r S:10261 M:116668381] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:33.019,042] <dbg> chip: LogV: [IM]Received Read request
    [00:04:33.030,090] <inf> chip: [EM]<<< [E:2876r S:10261 M:7063537 (Ack:116668381)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:33.048,675] <inf> chip: [IN](S) Sending msg 7063537 on secure session with LSID: 10261
    [00:04:33.062,438] <inf> chip: [EM]>>> [E:2873r S:10261 M:116668382 (Ack:7063534)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:33.082,183] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:33.101,470] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b
    [00:04:33.122,283] <inf> chip: [EM]<<< [E:2873r S:10261 M:7063538 (Ack:116668382)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:33.139,709] <inf> chip: [IN](S) Sending msg 7063538 on secure session with LSID: 10261
    [00:04:33.154,357] <inf> chip: [EM]>>> [E:2874r S:10261 M:116668383 (Ack:7063535)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:33.174,407] <inf> chip: [EM]>>> [E:2877r S:10261 M:116668384] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:33.190,826] <dbg> chip: LogV: [IM]Received Read request
    [00:04:33.200,866] <inf> chip: [EM]<<< [E:2877r S:10261 M:7063539 (Ack:116668384)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:33.218,902] <inf> chip: [IN](S) Sending msg 7063539 on secure session with LSID: 10261
    [00:04:33.232,818] <inf> chip: [EM]>>> [E:2875r S:10261 M:116668385 (Ack:7063536)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:33.253,845] <inf> chip: [EM]>>> [E:2878r S:10261 M:116668386] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:04:33.271,087] <dbg> chip: LogV: [IM]Received Write request
    [00:04:33.282,409] <inf> chip: [EM]<<< [E:2878r S:10261 M:7063540 (Ack:116668386)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:04:33.300,628] <inf> chip: [IN](S) Sending msg 7063540 on secure session with LSID: 10261
    [00:04:33.314,178] <inf> chip: [EM]>>> [E:2876r S:10261 M:116668387 (Ack:7063537)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:33.368,011] <inf> chip: [EM]>>> [E:2873r S:10261 M:116668388 (Ack:7063538)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:33.386,596] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:33.404,357] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    [00:04:33.422,119] <inf> chip: [EM]<<< [E:2873r S:10261 M:7063541 (Ack:116668388)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:33.438,262] <inf> chip: [IN](S) Sending msg 7063541 on secure session with LSID: 10261
    [00:04:33.451,477] <inf> chip: [EM]>>> [E:2877r S:10261 M:116668389 (Ack:7063539)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:33.472,656] <inf> chip: [EM]>>> [E:2878r S:10261 M:116668390 (Ack:7063540)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:33.608,551] <inf> chip: [EM]>>> [E:2873r S:10261 M:116668391 (Ack:7063541)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:33.625,396] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:33.640,197] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003F, err = b
    [00:04:33.657,836] <inf> chip: [EM]<<< [E:2873r S:10261 M:7063542 (Ack:116668391)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:33.674,072] <inf> chip: [IN](S) Sending msg 7063542 on secure session with LSID: 10261
    [00:04:33.861,694] <inf> chip: [EM]>>> [E:2873r S:10261 M:116668392 (Ack:7063542)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:33.879,974] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:33.900,207] <inf> chip: [EM]<<< [E:2873r S:10261 M:7063543 (Ack:116668392)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:33.916,412] <inf> chip: [IN](S) Sending msg 7063543 on secure session with LSID: 10261
    [00:04:33.930,023] <inf> chip: [EM]>>> [E:2882r S:10261 M:116668393] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:33.946,197] <dbg> chip: LogV: [IM]Received Read request
    [00:04:33.958,953] <inf> chip: [EM]<<< [E:2882r S:10261 M:7063544 (Ack:116668393)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:33.976,501] <inf> chip: [IN](S) Sending msg 7063544 on secure session with LSID: 10261
    [00:04:34.096,496] <inf> chip: [EM]>>> [E:2873r S:10261 M:116668394 (Ack:7063543)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:34.114,196] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:34.132,110] <inf> chip: [EM]<<< [E:2873r S:10261 M:7063545 (Ack:116668394)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:34.149,902] <inf> chip: [IN](S) Sending msg 7063545 on secure session with LSID: 10261
    [00:04:34.162,658] <inf> chip: [EM]>>> [E:2882r S:10261 M:116668395 (Ack:7063544)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:34.246,032] <inf> chip: [EM]>>> [E:2873r S:10261 M:116668396 (Ack:7063545)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:34.262,908] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:34.271,850] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:04:34.283,691] <inf> chip: [EM]<<< [E:2873r S:10261 M:7063546 (Ack:116668396)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:04 (IM:SubscribeResponse)
    [00:04:34.300,689] <inf> chip: [IN](S) Sending msg 7063546 on secure session with LSID: 10261
    [00:04:34.346,954] <inf> chip: [EM]>>> [E:2873r S:10261 M:116668397 (Ack:7063546)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:37.939,239] <inf> chip: [EM]>>> [E:2885r S:10261 M:116668398] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:37.955,657] <dbg> chip: LogV: [IM]Received Read request
    [00:04:37.964,874] <inf> chip: [EM]<<< [E:2885r S:10261 M:7063547 (Ack:116668398)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:37.980,651] <inf> chip: [IN](S) Sending msg 7063547 on secure session with LSID: 10261
    [00:04:37.993,225] <inf> chip: [EM]>>> [E:2886r S:10261 M:116668399] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:38.010,009] <dbg> chip: LogV: [IM]Received Read request
    [00:04:38.019,226] <inf> chip: [EM]<<< [E:2886r S:10261 M:7063548 (Ack:116668399)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:38.035,125] <inf> chip: [IN](S) Sending msg 7063548 on secure session with LSID: 10261
    [00:04:38.047,241] <inf> chip: [EM]>>> [E:2885r S:10261 M:116668400 (Ack:7063547)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:38.067,657] <inf> chip: [EM]>>> [E:2886r S:10261 M:116668401 (Ack:7063548)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:38.087,493] <inf> chip: [EM]>>> [E:2887r S:10261 M:116668402] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:38.102,264] <dbg> chip: LogV: [IM]Received Read request
    [00:04:38.111,602] <inf> chip: [EM]<<< [E:2887r S:10261 M:7063549 (Ack:116668402)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:38.127,410] <inf> chip: [IN](S) Sending msg 7063549 on secure session with LSID: 10261
    [00:04:38.157,348] <inf> chip: [EM]>>> [E:2887r S:10261 M:116668403 (Ack:7063549)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:45.186,126] <inf> chip: [EM]>>> [E:6886r S:0 M:132935975] (U) Msg RX from 0:AF6BD3548E223E50 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    [00:04:45.201,904] <inf> chip: [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x20004500
    [00:04:45.213,439] <inf> chip: [IN]CASE Server disabling CASE session setups
    [00:04:45.222,625] <inf> chip: [EM]<<< [E:6886r S:0 M:235726982 (Ack:132935975)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:45.239,929] <inf> chip: [IN](U) Sending msg 235726982 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:51791'
    [00:04:45.254,028] <inf> chip: [SC]Received Sigma1 msg
    [00:04:45.266,387] <inf> chip: [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000043E62CFE
    [00:04:45.490,600] <inf> chip: [EM]<<< [E:6886r S:0 M:235726983 (Ack:132935975)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
    [00:04:45.507,781] <inf> chip: [IN](U) Sending msg 235726983 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:51791'
    [00:04:45.522,338] <inf> chip: [SC]Sent Sigma2 msg
    [00:04:45.675,720] <inf> chip: [EM]>>> [E:6886r S:0 M:132935978 (Ack:235726983)] (U) Msg RX from 0:AF6BD3548E223E50 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
    [00:04:45.693,847] <inf> chip: [EM]<<< [E:6886r S:0 M:235726984 (Ack:132935978)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:45.711,364] <inf> chip: [IN](U) Sending msg 235726984 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:51791'
    [00:04:45.725,402] <inf> chip: [SC]Received Sigma3 msg
    [00:04:45.737,335] <err> chip: [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
    [00:04:45.886,352] <err> chip: [DL]Long dispatch time: 134 ms, for event type 2
    [00:04:45.995,880] <inf> chip: [EM]<<< [E:6886r S:0 M:235726985 (Ack:132935978)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    [00:04:46.013,000] <inf> chip: [IN](U) Sending msg 235726985 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:51791'
    [00:04:46.028,106] <inf> chip: [SC]SecureSession[0x20002e20]: Moving from state 'kEstablishing' --> 'kActive'
    [00:04:46.040,832] <dbg> chip: LogV: [IN]SecureSession[0x20002e20]: Activated - Type:2 LSID:10262
    [00:04:46.053,253] <dbg> chip: LogV: [IN]New secure session activated for device <4EAA76FA0B701439, 1>, LSID:10262 PSID:28742!
    [00:04:46.070,098] <inf> chip: [IN]CASE Session established to peer: <4EAA76FA0B701439, 1>
    [00:04:46.081,634] <inf> chip: [IN]CASE Server enabling CASE session setups
    [00:04:46.090,911] <dbg> chip: LogV: [IN]SecureSession[0x20002ed8]: Allocated Type:2 LSID:10263
    [00:04:46.101,806] <err> chip: [DL]Long dispatch time: 206 ms, for event type 2
    [00:04:46.111,938] <inf> chip: [EM]>>> [E:6898r S:10262 M:179020580] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:46.126,922] <dbg> chip: LogV: [IM]Received Read request
    [00:04:46.136,749] <inf> chip: [EM]<<< [E:6898r S:10262 M:38590309 (Ack:179020580)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:46.152,984] <inf> chip: [IN](S) Sending msg 38590309 on secure session with LSID: 10262
    [00:04:46.164,978] <inf> chip: [EM]>>> [E:6886r S:0 M:132935979 (Ack:235726985)] (U) Msg RX from 0:AF6BD3548E223E50 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:46.185,333] <inf> chip: [EM]>>> [E:6899r S:10262 M:179020581] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:46.200,592] <dbg> chip: LogV: [IM]Received Read request
    [00:04:46.211,730] <inf> chip: [EM]<<< [E:6899r S:10262 M:38590310 (Ack:179020581)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:46.230,926] <inf> chip: [IN](S) Sending msg 38590310 on secure session with LSID: 10262
    [00:04:46.244,110] <inf> chip: [EM]>>> [E:6900r S:10262 M:179020582] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:46.260,833] <dbg> chip: LogV: [IM]Received Read request
    [00:04:46.270,751] <inf> chip: [EM]<<< [E:6900r S:10262 M:38590311 (Ack:179020582)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:46.288,543] <inf> chip: [IN](S) Sending msg 38590311 on secure session with LSID: 10262
    [00:04:46.301,177] <inf> chip: [EM]>>> [E:6898r S:10262 M:179020583 (Ack:38590309)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:46.322,357] <inf> chip: [EM]>>> [E:6902r S:10262 M:179020584] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:04:46.337,646] <dbg> chip: LogV: [IM]Received Write request
    [00:04:46.419,311] <inf> chip: [EM]<<< [E:6902r S:10262 M:38590312 (Ack:179020584)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:04:46.436,065] <inf> chip: [IN](S) Sending msg 38590312 on secure session with LSID: 10262
    [00:04:46.458,587] <inf> chip: [EM]<<< [E:8255i S:10262 M:38590313] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:46.474,761] <inf> chip: [IN](S) Sending msg 38590313 on secure session with LSID: 10262
    [00:04:46.486,938] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:04:46.501,129] <inf> chip: [EM]>>> [E:6899r S:10262 M:179020585 (Ack:38590310)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:46.520,294] <inf> chip: [EM]>>> [E:6903r S:10262 M:179020586] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:46.537,109] <dbg> chip: LogV: [IM]Received Read request
    [00:04:46.547,027] <inf> chip: [EM]<<< [E:6903r S:10262 M:38590314 (Ack:179020586)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:46.563,140] <inf> chip: [IN](S) Sending msg 38590314 on secure session with LSID: 10262
    [00:04:46.576,568] <inf> chip: [EM]>>> [E:6900r S:10262 M:179020587 (Ack:38590311)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:46.597,412] <inf> chip: [EM]>>> [E:6904r S:10262 M:179020588] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:46.613,952] <dbg> chip: LogV: [IM]Received Read request
    [00:04:46.625,427] <inf> chip: [EM]<<< [E:6904r S:10262 M:38590315 (Ack:179020588)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:46.643,127] <inf> chip: [IN](S) Sending msg 38590315 on secure session with LSID: 10262
    [00:04:46.655,609] <inf> chip: [EM]>>> [E:6905r S:10262 M:179020589] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:46.671,936] <dbg> chip: LogV: [IM]Received Read request
    [00:04:46.682,098] <inf> chip: [EM]<<< [E:6905r S:10262 M:38590316 (Ack:179020589)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:46.699,737] <inf> chip: [IN](S) Sending msg 38590316 on secure session with LSID: 10262
    [00:04:46.713,775] <inf> chip: [EM]>>> [E:6902r S:10262 M:179020590 (Ack:38590312)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:46.734,527] <inf> chip: [EM]>>> [E:8255i S:10262 M:179020591 (Ack:38590313)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:46.753,082] <inf> chip: [IM]Received status response, status is 0x7d
    [00:04:46.762,786] <inf> chip: [EM]<<< [E:8255i S:10262 M:38590317 (Ack:179020591)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:46.780,273] <inf> chip: [IN](S) Sending msg 38590317 on secure session with LSID: 10262
    [00:04:46.793,090] <inf> chip: [EM]>>> [E:6903r S:10262 M:179020592 (Ack:38590314)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:46.812,927] <inf> chip: [EM]>>> [E:6906r S:10262 M:179020593] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:03 (IM:SubscribeRequest)
    [00:04:46.828,460] <dbg> chip: LogV: [IM]Received Subscribe request
    [00:04:46.837,707] <inf> chip: [DMG]Final negotiated min/max parameters: Min = 0s, Max = 60s
    [00:04:46.860,443] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b
    [00:04:46.877,136] <inf> chip: [EM]<<< [E:6906r S:10262 M:38590318 (Ack:179020593)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:46.893,280] <inf> chip: [IN](S) Sending msg 38590318 on secure session with LSID: 10262
    [00:04:46.906,951] <inf> chip: [EM]>>> [E:6907r S:10262 M:179020594] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:46.923,858] <dbg> chip: LogV: [IM]Received Read request
    [00:04:46.934,631] <inf> chip: [EM]<<< [E:6907r S:10262 M:38590319 (Ack:179020594)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:46.953,277] <inf> chip: [IN](S) Sending msg 38590319 on secure session with LSID: 10262
    [00:04:46.966,979] <inf> chip: [EM]>>> [E:6904r S:10262 M:179020595 (Ack:38590315)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:46.988,647] <inf> chip: [EM]>>> [E:6908r S:10262 M:179020596] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:47.005,462] <dbg> chip: LogV: [IM]Received Read request
    [00:04:47.016,265] <inf> chip: [EM]<<< [E:6908r S:10262 M:38590320 (Ack:179020596)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:47.034,851] <inf> chip: [IN](S) Sending msg 38590320 on secure session with LSID: 10262
    [00:04:47.050,842] <inf> chip: [EM]>>> [E:6905r S:10262 M:179020597 (Ack:38590316)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:47.072,235] <inf> chip: [EM]>>> [E:6909r S:10262 M:179020598] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:47.088,958] <dbg> chip: LogV: [IM]Received Read request
    [00:04:47.098,785] <inf> chip: [EM]<<< [E:6909r S:10262 M:38590321 (Ack:179020598)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:47.116,729] <inf> chip: [IN](S) Sending msg 38590321 on secure session with LSID: 10262
    [00:04:47.131,256] <inf> chip: [EM]>>> [E:6906r S:10262 M:179020599 (Ack:38590318)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:47.150,268] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:47.169,464] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b
    [00:04:47.186,645] <inf> chip: [EM]<<< [E:6906r S:10262 M:38590322 (Ack:179020599)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:47.203,094] <inf> chip: [IN](S) Sending msg 38590322 on secure session with LSID: 10262
    [00:04:47.216,644] <inf> chip: [EM]>>> [E:6907r S:10262 M:179020600 (Ack:38590319)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:47.237,731] <inf> chip: [EM]>>> [E:6910r S:10262 M:179020601] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:47.254,882] <dbg> chip: LogV: [IM]Received Read request
    [00:04:47.265,045] <inf> chip: [EM]<<< [E:6910r S:10262 M:38590323 (Ack:179020601)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:47.283,599] <inf> chip: [IN](S) Sending msg 38590323 on secure session with LSID: 10262
    [00:04:47.297,149] <inf> chip: [EM]>>> [E:6908r S:10262 M:179020602 (Ack:38590320)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:47.318,145] <inf> chip: [EM]>>> [E:6911r S:10262 M:179020603] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:04:47.335,479] <dbg> chip: LogV: [IM]Received Write request
    [00:04:47.347,808] <inf> chip: [EM]<<< [E:6911r S:10262 M:38590324 (Ack:179020603)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:04:47.366,027] <inf> chip: [IN](S) Sending msg 38590324 on secure session with LSID: 10262
    [00:04:47.380,279] <inf> chip: [EM]>>> [E:6909r S:10262 M:179020604 (Ack:38590321)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:47.402,374] <inf> chip: [EM]>>> [E:6906r S:10262 M:179020605 (Ack:38590322)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:47.419,403] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:47.435,211] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    [00:04:47.452,789] <inf> chip: [EM]<<< [E:6906r S:10262 M:38590325 (Ack:179020605)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:47.468,994] <inf> chip: [IN](S) Sending msg 38590325 on secure session with LSID: 10262
    [00:04:47.481,994] <inf> chip: [EM]>>> [E:6910r S:10262 M:179020606 (Ack:38590323)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:47.502,624] <inf> chip: [EM]>>> [E:6911r S:10262 M:179020607 (Ack:38590324)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:47.673,187] <inf> chip: [EM]>>> [E:6906r S:10262 M:179020608 (Ack:38590325)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:47.689,910] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:47.705,047] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003F, err = b
    [00:04:47.722,747] <inf> chip: [EM]<<< [E:6906r S:10262 M:38590326 (Ack:179020608)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:47.739,227] <inf> chip: [IN](S) Sending msg 38590326 on secure session with LSID: 10262
    [00:04:47.892,486] <inf> chip: [EM]>>> [E:6906r S:10262 M:179020609 (Ack:38590326)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:47.910,919] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:47.931,121] <inf> chip: [EM]<<< [E:6906r S:10262 M:38590327 (Ack:179020609)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:47.947,326] <inf> chip: [IN](S) Sending msg 38590327 on secure session with LSID: 10262
    [00:04:47.960,357] <inf> chip: [EM]>>> [E:6914r S:10262 M:179020610] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:47.977,325] <dbg> chip: LogV: [IM]Received Read request
    [00:04:47.988,525] <inf> chip: [EM]<<< [E:6914r S:10262 M:38590328 (Ack:179020610)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:48.006,530] <inf> chip: [IN](S) Sending msg 38590328 on secure session with LSID: 10262
    [00:04:48.141,113] <inf> chip: [EM]>>> [E:6906r S:10262 M:179020611 (Ack:38590327)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:48.159,637] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:48.178,955] <inf> chip: [EM]<<< [E:6906r S:10262 M:38590329 (Ack:179020611)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:48.196,594] <inf> chip: [IN](S) Sending msg 38590329 on secure session with LSID: 10262
    [00:04:48.210,296] <inf> chip: [EM]>>> [E:6914r S:10262 M:179020612 (Ack:38590328)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:48.323,364] <inf> chip: [EM]>>> [E:6906r S:10262 M:179020613 (Ack:38590329)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:04:48.340,576] <inf> chip: [IM]Received status response, status is 0x00
    [00:04:48.349,395] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:04:48.361,724] <inf> chip: [EM]<<< [E:6906r S:10262 M:38590330 (Ack:179020613)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:04 (IM:SubscribeResponse)
    [00:04:48.378,662] <inf> chip: [IN](S) Sending msg 38590330 on secure session with LSID: 10262
    [00:04:48.416,870] <inf> chip: [EM]>>> [E:6906r S:10262 M:179020614 (Ack:38590330)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:53.879,089] <inf> chip: [EM]>>> [E:6917r S:10262 M:179020615] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:53.895,874] <dbg> chip: LogV: [IM]Received Read request
    [00:04:53.905,334] <inf> chip: [EM]<<< [E:6917r S:10262 M:38590331 (Ack:179020615)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:53.921,569] <inf> chip: [IN](S) Sending msg 38590331 on secure session with LSID: 10262
    [00:04:53.934,753] <inf> chip: [EM]>>> [E:6918r S:10262 M:179020616] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:53.950,988] <dbg> chip: LogV: [IM]Received Read request
    [00:04:53.960,968] <inf> chip: [EM]<<< [E:6918r S:10262 M:38590332 (Ack:179020616)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:53.978,698] <inf> chip: [IN](S) Sending msg 38590332 on secure session with LSID: 10262
    [00:04:53.991,851] <inf> chip: [EM]>>> [E:6917r S:10262 M:179020617 (Ack:38590331)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:54.016,052] <inf> chip: [EM]>>> [E:6918r S:10262 M:179020618 (Ack:38590332)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:54.035,980] <inf> chip: [EM]>>> [E:6919r S:10262 M:179020619] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:04:54.051,269] <dbg> chip: LogV: [IM]Received Read request
    [00:04:54.060,760] <inf> chip: [EM]<<< [E:6919r S:10262 M:38590333 (Ack:179020619)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:54.076,843] <inf> chip: [IN](S) Sending msg 38590333 on secure session with LSID: 10262
    [00:04:54.107,086] <inf> chip: [EM]>>> [E:6920r S:10262 M:179020620] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:04:54.123,870] <dbg> chip: LogV: [IM]Received Write request
    [00:04:54.131,988] <inf> app: Percent: 72
    [00:04:54.138,885] <inf> chip: [EM]<<< [E:6920r S:10262 M:38590334 (Ack:179020620)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:04:54.155,426] <inf> chip: [IN](S) Sending msg 38590334 on secure session with LSID: 10262
    [00:04:54.175,415] <inf> chip: [EM]<<< [E:8256i S:10262 M:38590335] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:04:54.191,406] <inf> chip: [IN](S) Sending msg 38590335 on secure session with LSID: 10262
    [00:04:54.204,315] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:04:54.216,827] <inf> chip: [EM]>>> [E:6919r S:10262 M:179020621 (Ack:38590333)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:04:55.019,775] <inf> chip: [IN](S) Sending msg 38590334 on secure session with LSID: 10262
    [00:04:55.057,800] <inf> chip: [IN](S) Sending msg 38590335 on secure session with LSID: 10262
    [00:04:55.890,930] <inf> chip: [IN](S) Sending msg 38590334 on secure session with LSID: 10262
    [00:04:55.963,836] <inf> chip: [IN](S) Sending msg 38590335 on secure session with LSID: 10262
    [00:04:57.041,595] <inf> chip: [IN](S) Sending msg 38590334 on secure session with LSID: 10262
    [00:04:57.059,295] <inf> chip: [IN](S) Sending msg 38590335 on secure session with LSID: 10262
    [00:04:58.404,418] <inf> chip: [IN](S) Sending msg 38590334 on secure session with LSID: 10262
    [00:04:58.584,167] <inf> chip: [IN](S) Sending msg 38590335 on secure session with LSID: 10262
    [00:05:00.375,762] <err> chip: [EM]Failed to Send CHIP MessageCounter:38590334 on exchange 6920r sendCount: 4 max retries: 4
    [00:05:00.388,885] <dbg> chip: LogV: [IN]SecureSession[0x20002e20]: MarkAsDefunct Type:2 LSID:10262
    [00:05:00.399,658] <inf> chip: [SC]SecureSession[0x20002e20]: Moving from state 'kActive' --> 'kDefunct'
    [00:05:00.550,323] <err> chip: [EM]Failed to Send CHIP MessageCounter:38590335 on exchange 8256i sendCount: 4 max retries: 4
    [00:05:02.898,498] <dbg> chip: LogV: [IN]SecureSession[0x20002e20]: MarkAsDefunct Type:2 LSID:10262
    [00:05:02.909,210] <err> chip: [DMG]Time out! failed to receive status response from Exchange: 8256i
    [00:05:03.319,702] <inf> chip: [EM]>>> [E:64356r S:0 M:134770410] (U) Msg RX from 0:9A3BCA5CDAA9EFB9 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    [00:05:03.335,571] <inf> chip: [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x20004500
    [00:05:03.347,076] <inf> chip: [IN]CASE Server disabling CASE session setups
    [00:05:03.356,323] <inf> chip: [EM]<<< [E:64356r S:0 M:235726986 (Ack:134770410)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:03.373,748] <inf> chip: [IN](U) Sending msg 235726986 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:65445'
    [00:05:03.387,847] <inf> chip: [SC]Received Sigma1 msg
    [00:05:03.400,604] <inf> chip: [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000043E62CFE
    [00:05:03.623,565] <inf> chip: [EM]<<< [E:64356r S:0 M:235726987 (Ack:134770410)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
    [00:05:03.640,869] <inf> chip: [IN](U) Sending msg 235726987 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:65445'
    [00:05:03.656,097] <inf> chip: [SC]Sent Sigma2 msg
    [00:05:03.836,242] <inf> chip: [EM]>>> [E:64356r S:0 M:134770413 (Ack:235726987)] (U) Msg RX from 0:9A3BCA5CDAA9EFB9 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
    [00:05:03.854,187] <inf> chip: [EM]<<< [E:64356r S:0 M:235726988 (Ack:134770413)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:03.871,673] <inf> chip: [IN](U) Sending msg 235726988 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:65445'
    [00:05:03.885,711] <inf> chip: [SC]Received Sigma3 msg
    [00:05:03.897,033] <err> chip: [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
    [00:05:04.045,623] <err> chip: [DL]Long dispatch time: 134 ms, for event type 2
    [00:05:04.152,954] <inf> chip: [EM]<<< [E:64356r S:0 M:235726989 (Ack:134770413)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    [00:05:04.170,318] <inf> chip: [IN](U) Sending msg 235726989 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:65445'
    [00:05:04.185,913] <inf> chip: [SC]SecureSession[0x20002ed8]: Moving from state 'kEstablishing' --> 'kActive'
    [00:05:04.198,333] <dbg> chip: LogV: [IN]SecureSession[0x20002ed8]: Activated - Type:2 LSID:10263
    [00:05:04.210,906] <dbg> chip: LogV: [IN]New secure session activated for device <4EAA76FA0B701439, 1>, LSID:10263 PSID:20365!
    [00:05:04.225,830] <inf> chip: [IN]CASE Session established to peer: <4EAA76FA0B701439, 1>
    [00:05:04.235,900] <inf> chip: [IN]CASE Server enabling CASE session setups
    [00:05:04.246,704] <dbg> chip: LogV: [IN]SecureSession[0x20002f90]: Allocated Type:2 LSID:10264
    [00:05:04.258,972] <err> chip: [DL]Long dispatch time: 204 ms, for event type 2
    [00:05:04.269,165] <inf> chip: [EM]>>> [E:64365r S:10263 M:141592566] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:04.284,423] <dbg> chip: LogV: [IM]Received Read request
    [00:05:04.293,945] <inf> chip: [EM]<<< [E:64365r S:10263 M:152424859 (Ack:141592566)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:04.309,997] <inf> chip: [IN](S) Sending msg 152424859 on secure session with LSID: 10263
    [00:05:04.322,296] <inf> chip: [EM]>>> [E:64356r S:0 M:134770414 (Ack:235726989)] (U) Msg RX from 0:9A3BCA5CDAA9EFB9 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:04.342,895] <inf> chip: [EM]>>> [E:64366r S:10263 M:141592567] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:04.359,588] <dbg> chip: LogV: [IM]Received Read request
    [00:05:04.370,788] <inf> chip: [EM]<<< [E:64366r S:10263 M:152424860 (Ack:141592567)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:04.388,610] <inf> chip: [IN](S) Sending msg 152424860 on secure session with LSID: 10263
    [00:05:04.401,733] <inf> chip: [EM]>>> [E:64367r S:10263 M:141592568] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:04.418,182] <dbg> chip: LogV: [IM]Received Read request
    [00:05:04.428,985] <inf> chip: [EM]<<< [E:64367r S:10263 M:152424861 (Ack:141592568)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:04.447,113] <inf> chip: [IN](S) Sending msg 152424861 on secure session with LSID: 10263
    [00:05:04.459,869] <inf> chip: [EM]>>> [E:64365r S:10263 M:141592569 (Ack:152424859)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:04.480,895] <inf> chip: [EM]>>> [E:64368r S:10263 M:141592570] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:05:04.497,680] <dbg> chip: LogV: [IM]Received Write request
    [00:05:04.576,354] <inf> chip: [EM]<<< [E:64368r S:10263 M:152424862 (Ack:141592570)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:05:04.592,864] <inf> chip: [IN](S) Sending msg 152424862 on secure session with LSID: 10263
    [00:05:04.605,712] <inf> chip: [EM]>>> [E:64366r S:10263 M:141592571 (Ack:152424860)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:04.626,647] <inf> chip: [EM]>>> [E:64369r S:10263 M:141592572] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:04.643,371] <dbg> chip: LogV: [IM]Received Read request
    [00:05:04.653,076] <inf> chip: [EM]<<< [E:64369r S:10263 M:152424863 (Ack:141592572)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:04.669,494] <inf> chip: [IN](S) Sending msg 152424863 on secure session with LSID: 10263
    [00:05:04.682,189] <inf> chip: [EM]>>> [E:64367r S:10263 M:141592573 (Ack:152424861)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:04.704,620] <inf> chip: [EM]>>> [E:64370r S:10263 M:141592574] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:04.721,435] <dbg> chip: LogV: [IM]Received Read request
    [00:05:04.730,895] <inf> chip: [EM]<<< [E:64370r S:10263 M:152424864 (Ack:141592574)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:04.748,535] <inf> chip: [IN](S) Sending msg 152424864 on secure session with LSID: 10263
    [00:05:04.761,657] <inf> chip: [EM]>>> [E:64371r S:10263 M:141592575] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:04.777,282] <dbg> chip: LogV: [IM]Received Read request
    [00:05:04.788,543] <inf> chip: [EM]<<< [E:64371r S:10263 M:152424865 (Ack:141592575)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:04.804,992] <inf> chip: [IN](S) Sending msg 152424865 on secure session with LSID: 10263
    [00:05:04.819,335] <inf> chip: [EM]>>> [E:64368r S:10263 M:141592576 (Ack:152424862)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:04.839,050] <inf> chip: [EM]>>> [E:64369r S:10263 M:141592577 (Ack:152424863)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:04.858,642] <inf> chip: [EM]>>> [E:64372r S:10263 M:141592578] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:03 (IM:SubscribeRequest)
    [00:05:04.874,511] <dbg> chip: LogV: [IM]Received Subscribe request
    [00:05:04.883,819] <inf> chip: [DMG]Final negotiated min/max parameters: Min = 0s, Max = 60s
    [00:05:04.909,057] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b
    [00:05:04.925,689] <inf> chip: [EM]<<< [E:64372r S:10263 M:152424866 (Ack:141592578)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:04.942,230] <inf> chip: [IN](S) Sending msg 152424866 on secure session with LSID: 10263
    [00:05:04.955,596] <inf> chip: [EM]>>> [E:64373r S:10263 M:141592579] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:04.972,839] <dbg> chip: LogV: [IM]Received Read request
    [00:05:04.984,466] <inf> chip: [EM]<<< [E:64373r S:10263 M:152424867 (Ack:141592579)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:05.002,655] <inf> chip: [IN](S) Sending msg 152424867 on secure session with LSID: 10263
    [00:05:05.016,387] <inf> chip: [EM]>>> [E:64370r S:10263 M:141592580 (Ack:152424864)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:05.037,078] <inf> chip: [EM]>>> [E:64374r S:10263 M:141592581] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:05.053,527] <dbg> chip: LogV: [IM]Received Read request
    [00:05:05.065,155] <inf> chip: [EM]<<< [E:64374r S:10263 M:152424868 (Ack:141592581)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:05.084,075] <inf> chip: [IN](S) Sending msg 152424868 on secure session with LSID: 10263
    [00:05:05.098,083] <inf> chip: [EM]>>> [E:64371r S:10263 M:141592582 (Ack:152424865)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:05.120,117] <inf> chip: [EM]>>> [E:64378r S:10263 M:141592583] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:05.137,420] <dbg> chip: LogV: [IM]Received Read request
    [00:05:05.148,712] <inf> chip: [EM]<<< [E:64378r S:10263 M:152424869 (Ack:141592583)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:05.167,968] <inf> chip: [IN](S) Sending msg 152424869 on secure session with LSID: 10263
    [00:05:05.182,495] <inf> chip: [EM]>>> [E:64372r S:10263 M:141592584 (Ack:152424866)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:05.201,782] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:05.220,520] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b
    [00:05:05.237,548] <inf> chip: [EM]<<< [E:64372r S:10263 M:152424870 (Ack:141592584)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:05.253,967] <inf> chip: [IN](S) Sending msg 152424870 on secure session with LSID: 10263
    [00:05:05.267,089] <inf> chip: [EM]>>> [E:64373r S:10263 M:141592585 (Ack:152424867)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:05.287,628] <inf> chip: [EM]>>> [E:64379r S:10263 M:141592586] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:05.304,870] <dbg> chip: LogV: [IM]Received Read request
    [00:05:05.315,643] <inf> chip: [EM]<<< [E:64379r S:10263 M:152424871 (Ack:141592586)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:05.332,763] <inf> chip: [IN](S) Sending msg 152424871 on secure session with LSID: 10263
    [00:05:05.346,313] <inf> chip: [EM]>>> [E:64374r S:10263 M:141592587 (Ack:152424868)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:05.367,553] <inf> chip: [EM]>>> [E:64380r S:10263 M:141592588] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:05:05.384,857] <dbg> chip: LogV: [IM]Received Write request
    [00:05:05.395,507] <inf> chip: [EM]<<< [E:64380r S:10263 M:152424872 (Ack:141592588)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:05:05.414,581] <inf> chip: [IN](S) Sending msg 152424872 on secure session with LSID: 10263
    [00:05:05.428,222] <inf> chip: [EM]>>> [E:64378r S:10263 M:141592589 (Ack:152424869)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:05.450,317] <inf> chip: [EM]>>> [E:64372r S:10263 M:141592590 (Ack:152424870)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:05.468,872] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:05.488,494] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    [00:05:05.507,904] <inf> chip: [EM]<<< [E:64372r S:10263 M:152424873 (Ack:141592590)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:05.524,230] <inf> chip: [IN](S) Sending msg 152424873 on secure session with LSID: 10263
    [00:05:05.537,597] <inf> chip: [EM]>>> [E:64379r S:10263 M:141592591 (Ack:152424871)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:05.558,959] <inf> chip: [EM]>>> [E:64380r S:10263 M:141592592 (Ack:152424872)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:05.669,036] <inf> chip: [EM]>>> [E:64372r S:10263 M:141592593 (Ack:152424873)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:05.686,309] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:05.701,568] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003F, err = b
    [00:05:05.719,482] <inf> chip: [EM]<<< [E:64372r S:10263 M:152424874 (Ack:141592593)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:05.735,870] <inf> chip: [IN](S) Sending msg 152424874 on secure session with LSID: 10263
    [00:05:05.898,132] <inf> chip: [EM]>>> [E:64372r S:10263 M:141592594 (Ack:152424874)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:05.915,252] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:05.937,316] <inf> chip: [EM]<<< [E:64372r S:10263 M:152424875 (Ack:141592594)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:05.953,704] <inf> chip: [IN](S) Sending msg 152424875 on secure session with LSID: 10263
    [00:05:05.967,498] <inf> chip: [EM]>>> [E:64381r S:10263 M:141592595] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:05.984,588] <dbg> chip: LogV: [IM]Received Read request
    [00:05:05.996,520] <inf> chip: [EM]<<< [E:64381r S:10263 M:152424876 (Ack:141592595)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:06.014,770] <inf> chip: [IN](S) Sending msg 152424876 on secure session with LSID: 10263
    [00:05:06.102,447] <inf> chip: [EM]>>> [E:64372r S:10263 M:141592596 (Ack:152424875)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:06.122,131] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:06.144,897] <inf> chip: [EM]<<< [E:64372r S:10263 M:152424877 (Ack:141592596)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:06.162,994] <inf> chip: [IN](S) Sending msg 152424877 on secure session with LSID: 10263
    [00:05:06.176,971] <inf> chip: [EM]>>> [E:64381r S:10263 M:141592597 (Ack:152424876)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:06.290,863] <inf> chip: [EM]>>> [E:64372r S:10263 M:141592598 (Ack:152424877)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:06.307,983] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:06.316,986] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:05:06.329,071] <inf> chip: [EM]<<< [E:64372r S:10263 M:152424878 (Ack:141592598)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:04 (IM:SubscribeResponse)
    [00:05:06.346,038] <inf> chip: [IN](S) Sending msg 152424878 on secure session with LSID: 10263
    [00:05:06.392,974] <inf> chip: [EM]>>> [E:64372r S:10263 M:141592599 (Ack:152424878)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:15.323,150] <inf> chip: [EM]>>> [E:64385r S:10263 M:141592600] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:15.339,660] <dbg> chip: LogV: [IM]Received Read request
    [00:05:15.348,999] <inf> chip: [EM]<<< [E:64385r S:10263 M:152424879 (Ack:141592600)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:15.365,173] <inf> chip: [IN](S) Sending msg 152424879 on secure session with LSID: 10263
    [00:05:15.377,655] <inf> chip: [EM]>>> [E:64386r S:10263 M:141592601] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:15.393,524] <dbg> chip: LogV: [IM]Received Read request
    [00:05:15.404,388] <inf> chip: [EM]<<< [E:64386r S:10263 M:152424880 (Ack:141592601)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:15.420,440] <inf> chip: [IN](S) Sending msg 152424880 on secure session with LSID: 10263
    [00:05:15.433,074] <inf> chip: [EM]>>> [E:64385r S:10263 M:141592602 (Ack:152424879)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:15.453,857] <inf> chip: [EM]>>> [E:64386r S:10263 M:141592603 (Ack:152424880)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:15.473,968] <inf> chip: [EM]>>> [E:64387r S:10263 M:141592604] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:15.488,891] <dbg> chip: LogV: [IM]Received Read request
    [00:05:15.498,138] <inf> chip: [EM]<<< [E:64387r S:10263 M:152424881 (Ack:141592604)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:15.514,312] <inf> chip: [IN](S) Sending msg 152424881 on secure session with LSID: 10263
    [00:05:15.543,670] <inf> chip: [EM]>>> [E:64387r S:10263 M:141592605 (Ack:152424881)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:22.668,395] <inf> chip: [EM]>>> [E:2928r S:0 M:161931919] (U) Msg RX from 0:09A40B68416ACB7D [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
    [00:05:22.684,326] <inf> chip: [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x20004500
    [00:05:22.695,739] <inf> chip: [IN]CASE Server disabling CASE session setups
    [00:05:22.704,986] <inf> chip: [EM]<<< [E:2928r S:0 M:235726990 (Ack:161931919)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:22.722,198] <inf> chip: [IN](U) Sending msg 235726990 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:58792'
    [00:05:22.736,267] <inf> chip: [SC]Received Sigma1 msg
    [00:05:22.748,046] <inf> chip: [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000043E62CFE
    [00:05:22.970,703] <inf> chip: [EM]<<< [E:2928r S:0 M:235726991 (Ack:161931919)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
    [00:05:22.987,731] <inf> chip: [IN](U) Sending msg 235726991 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:58792'
    [00:05:23.002,716] <inf> chip: [SC]Sent Sigma2 msg
    [00:05:23.187,988] <inf> chip: [EM]>>> [E:2928r S:0 M:161931922 (Ack:235726991)] (U) Msg RX from 0:09A40B68416ACB7D [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
    [00:05:23.205,841] <inf> chip: [EM]<<< [E:2928r S:0 M:235726992 (Ack:161931922)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:23.223,602] <inf> chip: [IN](U) Sending msg 235726992 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:58792'
    [00:05:23.237,609] <inf> chip: [SC]Received Sigma3 msg
    [00:05:23.248,748] <err> chip: [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
    [00:05:23.397,430] <err> chip: [DL]Long dispatch time: 134 ms, for event type 2
    [00:05:23.506,866] <inf> chip: [EM]<<< [E:2928r S:0 M:235726993 (Ack:161931922)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    [00:05:23.524,200] <inf> chip: [IN](U) Sending msg 235726993 to IP address 'UDP:[fd6f:342e:a35f:0:2aaf:7f54:48f:b66c]:58792'
    [00:05:23.539,123] <inf> chip: [SC]SecureSession[0x20002f90]: Moving from state 'kEstablishing' --> 'kActive'
    [00:05:23.552,032] <dbg> chip: LogV: [IN]SecureSession[0x20002f90]: Activated - Type:2 LSID:10264
    [00:05:23.563,079] <dbg> chip: LogV: [IN]New secure session activated for device <4EAA76FA0B701439, 1>, LSID:10264 PSID:41727!
    [00:05:23.579,437] <inf> chip: [IN]CASE Session established to peer: <4EAA76FA0B701439, 1>
    [00:05:23.591,094] <inf> chip: [IN]CASE Server enabling CASE session setups
    [00:05:23.601,867] <dbg> chip: LogV: [IN]SecureSession[0x20003048]: Allocated Type:2 LSID:10265
    [00:05:23.612,792] <err> chip: [DL]Long dispatch time: 206 ms, for event type 2
    [00:05:23.623,016] <inf> chip: [EM]>>> [E:2941r S:10264 M:59327113] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:23.638,031] <dbg> chip: LogV: [IM]Received Read request
    [00:05:23.648,071] <inf> chip: [EM]<<< [E:2941r S:10264 M:260079109 (Ack:59327113)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:23.664,489] <inf> chip: [IN](S) Sending msg 260079109 on secure session with LSID: 10264
    [00:05:23.676,971] <inf> chip: [EM]>>> [E:2928r S:0 M:161931923 (Ack:235726993)] (U) Msg RX from 0:09A40B68416ACB7D [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:23.696,868] <inf> chip: [EM]>>> [E:2942r S:10264 M:59327114] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:23.713,348] <dbg> chip: LogV: [IM]Received Read request
    [00:05:23.724,121] <inf> chip: [EM]<<< [E:2942r S:10264 M:260079110 (Ack:59327114)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:23.741,516] <inf> chip: [IN](S) Sending msg 260079110 on secure session with LSID: 10264
    [00:05:23.754,760] <inf> chip: [EM]>>> [E:2943r S:10264 M:59327115] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:23.770,263] <dbg> chip: LogV: [IM]Received Read request
    [00:05:23.781,158] <inf> chip: [EM]<<< [E:2943r S:10264 M:260079111 (Ack:59327115)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:23.799,011] <inf> chip: [IN](S) Sending msg 260079111 on secure session with LSID: 10264
    [00:05:23.812,103] <inf> chip: [EM]>>> [E:2941r S:10264 M:59327116 (Ack:260079109)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:23.833,068] <inf> chip: [EM]>>> [E:2946r S:10264 M:59327117] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:05:23.851,379] <dbg> chip: LogV: [IM]Received Write request
    [00:05:23.931,213] <inf> chip: [EM]<<< [E:2946r S:10264 M:260079112 (Ack:59327117)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:05:23.947,509] <inf> chip: [IN](S) Sending msg 260079112 on secure session with LSID: 10264
    [00:05:23.970,611] <inf> chip: [EM]<<< [E:8257i S:10264 M:260079113] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:23.987,030] <inf> chip: [IN](S) Sending msg 260079113 on secure session with LSID: 10264
    [00:05:23.999,298] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:05:24.012,512] <inf> chip: [EM]>>> [E:2942r S:10264 M:59327118 (Ack:260079110)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:24.032,867] <inf> chip: [EM]>>> [E:2947r S:10264 M:59327119] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:24.049,346] <dbg> chip: LogV: [IM]Received Read request
    [00:05:24.060,546] <inf> chip: [EM]<<< [E:2947r S:10264 M:260079114 (Ack:59327119)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:24.076,873] <inf> chip: [IN](S) Sending msg 260079114 on secure session with LSID: 10264
    [00:05:24.090,270] <inf> chip: [EM]>>> [E:2943r S:10264 M:59327120 (Ack:260079111)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:24.111,114] <inf> chip: [EM]>>> [E:2948r S:10264 M:59327121] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:24.129,211] <dbg> chip: LogV: [IM]Received Read request
    [00:05:24.138,946] <inf> chip: [EM]<<< [E:2948r S:10264 M:260079115 (Ack:59327121)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:24.156,616] <inf> chip: [IN](S) Sending msg 260079115 on secure session with LSID: 10264
    [00:05:24.169,403] <inf> chip: [EM]>>> [E:2949r S:10264 M:59327122] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:24.185,089] <dbg> chip: LogV: [IM]Received Read request
    [00:05:24.197,601] <inf> chip: [EM]<<< [E:2949r S:10264 M:260079116 (Ack:59327122)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:24.213,836] <inf> chip: [IN](S) Sending msg 260079116 on secure session with LSID: 10264
    [00:05:24.226,287] <inf> chip: [EM]>>> [E:2946r S:10264 M:59327123 (Ack:260079112)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:24.245,697] <inf> chip: [EM]>>> [E:8257i S:10264 M:59327124 (Ack:260079113)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:24.265,869] <inf> chip: [IM]Received status response, status is 0x7d
    [00:05:24.275,268] <inf> chip: [EM]<<< [E:8257i S:10264 M:260079117 (Ack:59327124)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:24.292,816] <inf> chip: [IN](S) Sending msg 260079117 on secure session with LSID: 10264
    [00:05:24.305,603] <inf> chip: [EM]>>> [E:2947r S:10264 M:59327125 (Ack:260079114)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:24.326,049] <inf> chip: [EM]>>> [E:2951r S:10264 M:59327126] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:03 (IM:SubscribeRequest)
    [00:05:24.342,590] <dbg> chip: LogV: [IM]Received Subscribe request
    [00:05:24.352,081] <inf> chip: [DMG]Final negotiated min/max parameters: Min = 0s, Max = 60s
    [00:05:24.373,992] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b
    [00:05:24.390,319] <inf> chip: [EM]<<< [E:2951r S:10264 M:260079118 (Ack:59327126)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:24.406,494] <inf> chip: [IN](S) Sending msg 260079118 on secure session with LSID: 10264
    [00:05:24.420,104] <inf> chip: [EM]>>> [E:2952r S:10264 M:59327127] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:24.437,835] <dbg> chip: LogV: [IM]Received Read request
    [00:05:24.449,432] <inf> chip: [EM]<<< [E:2952r S:10264 M:260079119 (Ack:59327127)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:24.467,346] <inf> chip: [IN](S) Sending msg 260079119 on secure session with LSID: 10264
    [00:05:24.480,865] <inf> chip: [EM]>>> [E:2948r S:10264 M:59327128 (Ack:260079115)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:24.500,244] <inf> chip: [EM]>>> [E:2953r S:10264 M:59327129] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:24.516,815] <dbg> chip: LogV: [IM]Received Read request
    [00:05:24.528,015] <inf> chip: [EM]<<< [E:2953r S:10264 M:260079120 (Ack:59327129)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:24.546,142] <inf> chip: [IN](S) Sending msg 260079120 on secure session with LSID: 10264
    [00:05:24.559,997] <inf> chip: [EM]>>> [E:2949r S:10264 M:59327130 (Ack:260079116)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:24.581,756] <inf> chip: [EM]>>> [E:2954r S:10264 M:59327131] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:24.597,717] <dbg> chip: LogV: [IM]Received Read request
    [00:05:24.609,527] <inf> chip: [EM]<<< [E:2954r S:10264 M:260079121 (Ack:59327131)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:24.628,753] <inf> chip: [IN](S) Sending msg 260079121 on secure session with LSID: 10264
    [00:05:24.641,326] <inf> chip: [EM]>>> [E:2951r S:10264 M:59327132 (Ack:260079118)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:24.660,583] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:24.679,321] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b
    [00:05:24.697,845] <inf> chip: [EM]<<< [E:2951r S:10264 M:260079122 (Ack:59327132)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:24.716,888] <inf> chip: [IN](S) Sending msg 260079122 on secure session with LSID: 10264
    [00:05:24.731,567] <inf> chip: [EM]>>> [E:2952r S:10264 M:59327133 (Ack:260079119)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:24.752,288] <inf> chip: [EM]>>> [E:2955r S:10264 M:59327134] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:24.769,439] <dbg> chip: LogV: [IM]Received Read request
    [00:05:24.779,937] <inf> chip: [EM]<<< [E:2955r S:10264 M:260079123 (Ack:59327134)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:24.797,912] <inf> chip: [IN](S) Sending msg 260079123 on secure session with LSID: 10264
    [00:05:24.812,133] <inf> chip: [EM]>>> [E:2953r S:10264 M:59327135 (Ack:260079120)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:24.833,099] <inf> chip: [EM]>>> [E:2956r S:10264 M:59327136] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:05:24.850,128] <dbg> chip: LogV: [IM]Received Write request
    [00:05:24.861,572] <inf> chip: [EM]<<< [E:2956r S:10264 M:260079124 (Ack:59327136)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:05:24.880,584] <inf> chip: [IN](S) Sending msg 260079124 on secure session with LSID: 10264
    [00:05:24.893,768] <inf> chip: [EM]>>> [E:2954r S:10264 M:59327137 (Ack:260079121)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:24.920,349] <inf> chip: [EM]>>> [E:2951r S:10264 M:59327138 (Ack:260079122)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:24.937,438] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:24.956,604] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
    [00:05:24.975,067] <inf> chip: [EM]<<< [E:2951r S:10264 M:260079125 (Ack:59327138)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:24.991,271] <inf> chip: [IN](S) Sending msg 260079125 on secure session with LSID: 10264
    [00:05:25.004,669] <inf> chip: [EM]>>> [E:2955r S:10264 M:59327139 (Ack:260079123)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:25.025,390] <inf> chip: [EM]>>> [E:2956r S:10264 M:59327140 (Ack:260079124)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:25.132,263] <inf> chip: [EM]>>> [E:2951r S:10264 M:59327141 (Ack:260079125)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:25.149,383] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:25.164,520] <err> chip: [DMG]Error retrieving data from clusterId: 0x0000_003F, err = b
    [00:05:25.181,671] <inf> chip: [EM]<<< [E:2951r S:10264 M:260079126 (Ack:59327141)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:25.197,937] <inf> chip: [IN](S) Sending msg 260079126 on secure session with LSID: 10264
    [00:05:25.380,889] <inf> chip: [EM]>>> [E:2951r S:10264 M:59327142 (Ack:260079126)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:25.399,291] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:25.419,372] <inf> chip: [EM]<<< [E:2951r S:10264 M:260079127 (Ack:59327142)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:25.435,485] <inf> chip: [IN](S) Sending msg 260079127 on secure session with LSID: 10264
    [00:05:25.449,310] <inf> chip: [EM]>>> [E:2957r S:10264 M:59327143] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:02 (IM:ReadRequest)
    [00:05:25.466,217] <dbg> chip: LogV: [IM]Received Read request
    [00:05:25.478,759] <inf> chip: [EM]<<< [E:2957r S:10264 M:260079128 (Ack:59327143)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:25.496,948] <inf> chip: [IN](S) Sending msg 260079128 on secure session with LSID: 10264
    [00:05:25.593,811] <inf> chip: [EM]>>> [E:2951r S:10264 M:59327144 (Ack:260079127)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:25.611,450] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:25.634,521] <inf> chip: [EM]<<< [E:2951r S:10264 M:260079129 (Ack:59327144)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:25.651,000] <inf> chip: [IN](S) Sending msg 260079129 on secure session with LSID: 10264
    [00:05:25.664,733] <inf> chip: [EM]>>> [E:2957r S:10264 M:59327145 (Ack:260079128)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:25.759,979] <inf> chip: [EM]>>> [E:2951r S:10264 M:59327146 (Ack:260079129)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:25.776,977] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:25.785,919] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:05:25.797,821] <inf> chip: [EM]<<< [E:2951r S:10264 M:260079130 (Ack:59327146)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:04 (IM:SubscribeResponse)
    [00:05:25.814,758] <inf> chip: [IN](S) Sending msg 260079130 on secure session with LSID: 10264
    [00:05:25.855,834] <inf> chip: [EM]>>> [E:2951r S:10264 M:59327147 (Ack:260079130)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:27.810,455] <inf> chip: [EM]>>> [E:2961r S:10264 M:59327148] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:05:27.825,653] <dbg> chip: LogV: [IM]Received Write request
    [00:05:27.833,709] <inf> app: FanMode: 3
    [00:05:27.913,482] <inf> chip: [EM]<<< [E:2961r S:10264 M:260079131 (Ack:59327148)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:05:27.929,870] <inf> chip: [IN](S) Sending msg 260079131 on secure session with LSID: 10264
    [00:05:27.950,897] <inf> chip: [EM]<<< [E:8258i S:10264 M:260079132] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:27.966,094] <inf> chip: [IN](S) Sending msg 260079132 on secure session with LSID: 10264
    [00:05:27.979,705] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:05:27.992,706] <inf> chip: [EM]>>> [E:2961r S:10264 M:59327149 (Ack:260079131)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:28.012,939] <inf> chip: [EM]>>> [E:8258i S:10264 M:59327150 (Ack:260079132)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:28.029,815] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:28.039,367] <inf> chip: [EM]<<< [E:8258i S:10264 M:260079133 (Ack:59327150)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:28.056,884] <inf> chip: [IN](S) Sending msg 260079133 on secure session with LSID: 10264
    [00:05:30.122,985] <inf> chip: [EM]>>> [E:2963r S:10264 M:59327151] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:06 (IM:WriteRequest)
    [00:05:30.138,092] <dbg> chip: LogV: [IM]Received Write request
    [00:05:30.146,087] <inf> app: FanMode: 0
    [00:05:30.225,677] <inf> app: Percent: 0
    [00:05:30.232,269] <inf> chip: [EM]<<< [E:2963r S:10264 M:260079134 (Ack:59327151)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:07 (IM:WriteResponse)
    [00:05:30.248,657] <inf> chip: [IN](S) Sending msg 260079134 on secure session with LSID: 10264
    [00:05:30.270,172] <inf> chip: [EM]<<< [E:8259i S:10264 M:260079135] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0001:05 (IM:ReportData)
    [00:05:30.286,560] <inf> chip: [IN](S) Sending msg 260079135 on secure session with LSID: 10264
    [00:05:30.298,583] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
    [00:05:30.311,737] <inf> chip: [EM]>>> [E:2963r S:10264 M:59327152 (Ack:260079134)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:30.396,545] <inf> chip: [EM]>>> [E:8259i S:10264 M:59327153 (Ack:260079135)] (S) Msg RX from 1:4EAA76FA0B701439 [3931] --- Type 0001:01 (IM:StatusResponse)
    [00:05:30.413,360] <inf> chip: [IM]Received status response, status is 0x00
    [00:05:30.422,637] <inf> chip: [EM]<<< [E:8259i S:10264 M:260079136 (Ack:59327153)] (S) Msg TX to 1:4EAA76FA0B701439 [3931] --- Type 0000:10 (SecureChannel:StandaloneAck)
    [00:05:30.439,788] <inf> chip: [IN](S) Sending msg 260079136 on secure session with LSID: 10264

    I was able to build chip-tool, but I can't reproduce the behavior because my border router only has an Apple TV 4K, and I didn't understand how to use chip-tool even after reading Working with the CHIP Tool.

  • Hi,

    As you mentioned, the issue seems to be that the CASE session must be re-established, causing the device to go offline. After the new session is established, both devices will need time to retrieve subscriptions.

    You can try to change the logging mode to minimal with CONFIG_LOG_MODE_MINIMAL, as that might reduce the impact on the performance.

    Apart from this, everything looks correct, and it could be that the issue is with the Apple controller.

    Best regards,
    Marte

  • Marte-san,

    Thank you for answering. I set the logging mode to CONFIG_LOG_MODE_MINIMAL, but I didn't see any improvement.

    When I created the same Dimmable Light Device as the Matter: Light bulb sample from the Matter: Template sample, it worked properly, so this seems to be a problem unique to Fan Device.

    I don't have any other Border Routers or Matter Controllers so I can't try it right away, but I'll look into it again.

Related