ubuntu@ubuntu:~/apps$ ./chip-ota-provider-app --secured-device-port 5542 --KVS /tmp/chip_kvs_provider -f /home/ubuntu/matter-12.bin
[1767062373.863] [99108:99108] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs_provider
[1767062373.867] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062373.868] [99108:99108] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs, IGNORING.
[1767062373.875] [99108:99108] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini
[1767062373.875] [99108:99108] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini
[1767062373.875] [99108:99108] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini
[1767062373.884] [99108:99108] [DL] Wrote settings to /tmp/chip_counters.ini
[1767062373.884] [99108:99108] [DL] NVS set: chip-counters/reboot-count = 2 (0x2)
[1767062373.885] [99108:99108] [DL] Got Ethernet interface: eth0
[1767062373.886] [99108:99108] [DL] Found the primary Ethernet interface:eth0
[1767062373.887] [99108:99108] [DL] Got WiFi interface: wlan0
[1767062373.887] [99108:99108] [DL] Failed to reset WiFi statistic counts
[1767062373.887] [99108:99108] [PAF] WiFiPAF: WiFiPAFLayer::Init()
[1767062373.887] [99108:99108] [-] *** WARNING: Using temporary passcode 20202021 due to no neither --passcode or --spake2p-verifier-base64 given on command line or factory configuration. This is temporary and will disappear. Please update your scripts to explicitly configure onboarding credentials. ***
[1767062373.887] [99108:99108] [-] *** WARNING: Using temporary test discriminator 3840 due to --discriminator not given on command line or factory configuration. This is temporary and will disappear. Please update your scripts to explicitly configure onboarding credentials. ***
[1767062373.887] [99108:99108] [SPT] LinuxCommissionableDataProvider didn't get a PASE salt, generating one.
[1767062373.895] [99108:99108] [DL] Device Configuration:
[1767062373.895] [99108:99108] [DL]   Serial Number: TEST_SN
[1767062373.895] [99108:99108] [DL]   Vendor Id: 65521 (0xFFF1)
[1767062373.895] [99108:99108] [DL]   Product Id: 32769 (0x8001)
[1767062373.895] [99108:99108] [DL]   Product Name: TEST_PRODUCT
[1767062373.895] [99108:99108] [DL]   Hardware Version: 0
[1767062373.895] [99108:99108] [DL]   Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
[1767062373.895] [99108:99108] [DL]   Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
[1767062373.895] [99108:99108] [DL]   Manufacturing Date: (not set)
[1767062373.895] [99108:99108] [DL]   Device Type: 65535 (0xFFFF)
[1767062373.895] [99108:99108] [-] ==== Onboarding payload for Standard Commissioning Flow ====
[1767062373.895] [99108:99108] [SVR] SetupQRCode: [MT:-24J0AFN00KA0648G00]
[1767062373.895] [99108:99108] [SVR] Copy/paste the below URL in a browser to see the QR Code:
[1767062373.895] [99108:99108] [SVR] https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A-24J0AFN00KA0648G00
[1767062373.895] [99108:99108] [SVR] Manual pairing code: [34970112332]
[1767062373.896] [99108:99108] [SVR] Initializing subscription resumption storage...
[1767062373.902] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062373.902] [99108:99108] [SVR] Server initializing...
[1767062373.902] [99108:99108] [FP] Initializing FabricTable from persistent storage
[1767062373.902] [99108:99108] [TS] Last Known Good Time: [unknown]
[1767062373.902] [99108:99108] [TS] Setting Last Known Good Time to firmware build time 2023-10-14T01:16:48
[1767062373.910] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062373.910] [99108:99108] [DMG] AccessControl: initializing
[1767062373.910] [99108:99108] [DMG] Examples::AccessControlDelegate::Init
[1767062373.910] [99108:99108] [DMG] AccessControl: setting
[1767062373.910] [99108:99108] [DMG] DefaultAclStorage: initializing
[1767062373.910] [99108:99108] [DMG] DefaultAclStorage: 0 entries loaded
[1767062373.910] [99108:99108] [IN] UDP::Init bind&listen port=5542
[1767062373.910] [99108:99108] [IN] UDP::Init bound to port=5542
[1767062373.910] [99108:99108] [IN] TCP server listening on port 5542 for incoming connections
[1767062373.910] [99108:99108] [IN] WiFiPAFBase::Init - setting/overriding transport
[1767062373.910] [99108:99108] [IN] TransportMgr initialized
[1767062373.917] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062373.926] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062373.947] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062373.947] [99108:99108] [DMG] Ember attribute persistence requires setting up
[1767062373.947] [99108:99108] [ZCL] Using ZAP configuration...
[1767062373.948] [99108:99108] [EVL] LogEvent event number: 0x0000000000000000 priority: 2, endpoint id:  0x0 cluster id: 0x0000_0033 event id: 0x3 Epoch timestamp: 0x0000019B6D20063B
[1767062373.948] [99108:99108] [DMG] AccessControlCluster: initializing
[1767062373.948] [99108:99108] [IN] SecureSession[0xaaab13e62a80]: Allocated Type:1 LSID:39272
[1767062373.948] [99108:99108] [SC] Assigned local session key ID 39272
[1767062373.948] [99108:99108] [SC] Waiting for PBKDF param request
[1767062373.948] [99108:99108] [DIS] Updating services using commissioning mode 1
[1767062373.960] [99108:99108] [DIS] CHIP minimal mDNS started advertising.
[1767062373.975] [99108:99108] [DL] Using WiFi MAC for hostname
[1767062373.975] [99108:99108] [DIS] DNS-SD Pairing Instruction not set
[1767062373.975] [99108:99108] [DIS] Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15 cm=1 cp=0 jf=0
[1767062373.975] [99108:99108] [DIS] Responding with _matterc._udp.local
[1767062373.976] [99108:99108] [DIS] Responding with 1D39A217725C22AD._matterc._udp.local
[1767062373.976] [99108:99108] [DIS] Responding with D83ADD980FF5.local
[1767062373.976] [99108:99108] [DIS] Responding with _V65521._sub._matterc._udp.local
[1767062373.976] [99108:99108] [DIS] Responding with _S15._sub._matterc._udp.local
[1767062373.976] [99108:99108] [DIS] Responding with _L3840._sub._matterc._udp.local
[1767062373.976] [99108:99108] [DIS] Responding with _CM._sub._matterc._udp.local
[1767062373.976] [99108:99108] [DIS] Responding with 1D39A217725C22AD._matterc._udp.local
[1767062373.976] [99108:99108] [DIS] CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1D39A217725C22AD.
[1767062373.991] [99108:99108] [DIS] mDNS service published: _matterc._udp
[1767062373.991] [99108:99108] [DIS] Updating services using commissioning mode 1
[1767062374.001] [99108:99108] [DIS] CHIP minimal mDNS started advertising.
[1767062374.038] [99108:99108] [DL] Using WiFi MAC for hostname
[1767062374.038] [99108:99108] [DIS] DNS-SD Pairing Instruction not set
[1767062374.038] [99108:99108] [DIS] Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15 cm=1 cp=0 jf=0
[1767062374.038] [99108:99108] [DIS] Responding with _matterc._udp.local
[1767062374.038] [99108:99108] [DIS] Responding with 1D39A217725C22AD._matterc._udp.local
[1767062374.038] [99108:99108] [DIS] Responding with D83ADD980FF5.local
[1767062374.038] [99108:99108] [DIS] Responding with _V65521._sub._matterc._udp.local
[1767062374.038] [99108:99108] [DIS] Responding with _S15._sub._matterc._udp.local
[1767062374.038] [99108:99108] [DIS] Responding with _L3840._sub._matterc._udp.local
[1767062374.038] [99108:99108] [DIS] Responding with _CM._sub._matterc._udp.local
[1767062374.038] [99108:99108] [DIS] Responding with 1D39A217725C22AD._matterc._udp.local
[1767062374.038] [99108:99108] [DIS] CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1D39A217725C22AD.
[1767062374.054] [99108:99108] [DIS] mDNS service published: _matterc._udp
[1767062374.054] [99108:99108] [IN] CASE Server enabling CASE session setups
[1767062374.054] [99108:99108] [IN] SecureSession[0xaaab13e62400]: Allocated Type:2 LSID:39273
[1767062374.054] [99108:99108] [SC] Allocated SecureSession (0xaaab13e62400) - waiting for Sigma1 msg
[1767062374.054] [99108:99108] [SVR] Joining Multicast groups
[1767062374.054] [99108:99108] [ZCL] Emitting StartUp event
[1767062374.054] [99108:99108] [EVL] LogEvent event number: 0x0000000000000001 priority: 2, endpoint id:  0x0 cluster id: 0x0000_0028 event id: 0x0 Epoch timestamp: 0x0000019B6D2006A6
[1767062374.054] [99108:99108] [SVR] Server initialization complete
[1767062374.054] [99108:99108] [SVR] Server Listening...
[1767062374.054] [99108:99108] [DL] Device Configuration:
[1767062374.054] [99108:99108] [DL]   Serial Number: TEST_SN
[1767062374.054] [99108:99108] [DL]   Vendor Id: 65521 (0xFFF1)
[1767062374.054] [99108:99108] [DL]   Product Id: 32769 (0x8001)
[1767062374.054] [99108:99108] [DL]   Product Name: TEST_PRODUCT
[1767062374.054] [99108:99108] [DL]   Hardware Version: 0
[1767062374.054] [99108:99108] [DL]   Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
[1767062374.054] [99108:99108] [DL]   Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
[1767062374.054] [99108:99108] [DL]   Manufacturing Date: (not set)
[1767062374.055] [99108:99108] [DL]   Device Type: 65535 (0xFFFF)
[1767062374.055] [99108:99108] [SVR] SetupQRCode: [MT:-24J0AFN00KA0648G00]
[1767062374.055] [99108:99108] [SVR] Copy/paste the below URL in a browser to see the QR Code:
[1767062374.055] [99108:99108] [SVR] https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A-24J0AFN00KA0648G00
[1767062374.055] [99108:99108] [SVR] Manual pairing code: [34970112332]
[1767062374.055] [99108:99108] [SWU] Using OTA file: /home/ubuntu/matter-12.bin
[1767062374.055] [99108:99108] [SWU] Using ImageList file: (none)
[1767062374.059] [99108:99108] [DIS] Updating services using commissioning mode 1
[1767062374.068] [99108:99108] [DIS] CHIP minimal mDNS started advertising.
[1767062374.099] [99108:99108] [DL] Using WiFi MAC for hostname
[1767062374.099] [99108:99108] [DIS] DNS-SD Pairing Instruction not set
[1767062374.099] [99108:99108] [DIS] Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15 cm=1 cp=0 jf=0
[1767062374.099] [99108:99108] [DIS] Responding with _matterc._udp.local
[1767062374.099] [99108:99108] [DIS] Responding with 1D39A217725C22AD._matterc._udp.local
[1767062374.099] [99108:99108] [DIS] Responding with D83ADD980FF5.local
[1767062374.099] [99108:99108] [DIS] Responding with _V65521._sub._matterc._udp.local
[1767062374.099] [99108:99108] [DIS] Responding with _S15._sub._matterc._udp.local
[1767062374.099] [99108:99108] [DIS] Responding with _L3840._sub._matterc._udp.local
[1767062374.099] [99108:99108] [DIS] Responding with _CM._sub._matterc._udp.local
[1767062374.099] [99108:99108] [DIS] Responding with 1D39A217725C22AD._matterc._udp.local
[1767062374.099] [99108:99108] [DIS] CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1D39A217725C22AD.
[1767062374.115] [99108:99108] [DIS] mDNS service published: _matterc._udp
[1767062374.115] [99108:99108] [IM] No subscriptions to resume
[1767062390.235] [99108:99108] [EM] >>> [E:48006r S:0 M:218742604] (U) Msg RX from 0:F53E8273BDB807DF [0000] to 0000000000000000 --- Type 0000:20 (SecureChannel:PBKDFParamRequest) (B:98)
[1767062390.235] [99108:99108] [EM] Handling via exchange: 48006r, Delegate: 0xaaaad7f57140
[1767062390.235] [99108:99108] [SC] Received PBKDF param request
[1767062390.235] [99108:99108] [SC] Peer assigned session ID 12967
[1767062390.235] [99108:99108] [SC] Found MRP parameters in the message
[1767062390.236] [99108:99108] [EM] <<< [E:48006r S:0 M:173127403 (Ack:218742604)] (U) Msg TX from 0000000000000000 to 0:F53E8273BDB807DF [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) (B:174)
[1767062390.236] [99108:99108] [EM] ??1 [E:48006r S:0 M:173127403] (U) Msg Retransmission to 0:0000000000000000 scheduled for 364ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.236] [99108:99108] [SC] Sent PBKDF param response
[1767062390.236] [99108:99108] [SVR] Commissioning session establishment step started
[1767062390.246] [99108:99108] [EM] >>> [E:48006r S:0 M:218742605 (Ack:173127403)] (U) Msg RX from 0:F53E8273BDB807DF [0000] to 0000000000000000 --- Type 0000:22 (SecureChannel:PASE_Pake1) (B:96)
[1767062390.246] [99108:99108] [EM] Found matching exchange: 48006r, Delegate: 0xaaaad7f57140
[1767062390.246] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:173127403 from Retrans Table on exchange 48006r
[1767062390.246] [99108:99108] [SC] Received spake2p msg1
[1767062390.249] [99108:99108] [EM] <<< [E:48006r S:0 M:173127404 (Ack:218742605)] (U) Msg TX from 0000000000000000 to 0:F53E8273BDB807DF [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0000:23 (SecureChannel:PASE_Pake2) (B:131)
[1767062390.249] [99108:99108] [EM] ??1 [E:48006r S:0 M:173127404] (U) Msg Retransmission to 0:0000000000000000 scheduled for 396ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.249] [99108:99108] [SC] Sent spake2p msg2
[1767062390.253] [99108:99108] [EM] >>> [E:48006r S:0 M:218742606 (Ack:173127404)] (U) Msg RX from 0:F53E8273BDB807DF [0000] to 0000000000000000 --- Type 0000:24 (SecureChannel:PASE_Pake3) (B:63)
[1767062390.253] [99108:99108] [EM] Found matching exchange: 48006r, Delegate: 0xaaaad7f57140
[1767062390.253] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:173127404 from Retrans Table on exchange 48006r
[1767062390.253] [99108:99108] [SC] Received spake2p msg3
[1767062390.254] [99108:99108] [SC] Sending status report. Protocol code 0, exchange 48006
[1767062390.254] [99108:99108] [EM] <<< [E:48006r S:0 M:173127405 (Ack:218742606)] (U) Msg TX from 0000000000000000 to 0:F53E8273BDB807DF [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
[1767062390.254] [99108:99108] [EM] ??1 [E:48006r S:0 M:173127405] (U) Msg Retransmission to 0:0000000000000000 scheduled for 338ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.254] [99108:99108] [SC] SecureSession[0xaaab13e62a80, LSID:39272]: State change 'kEstablishing' --> 'kActive'
[1767062390.254] [99108:99108] [IN] SecureSession[0xaaab13e62a80]: Activated - Type:1 LSID:39272
[1767062390.254] [99108:99108] [IN] New secure session activated for device <FFFFFFFB00000000, 0>, LSID:39272 PSID:12967!
[1767062390.254] [99108:99108] [SVR] Commissioning completed session establishment step
[1767062390.254] [99108:99108] [DIS] Updating services using commissioning mode 0
[1767062390.265] [99108:99108] [DIS] CHIP minimal mDNS started advertising.
[1767062390.296] [99108:99108] [SVR] Device completed Rendezvous process
[1767062390.296] [99108:99108] [EM] >>> [E:48007r S:39272 M:265414446] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:136)
[1767062390.296] [99108:99108] [EM] Handling via exchange: 48007r, Delegate: 0xaaaad7f5e890
[1767062390.296] [99108:99108] [IM] Received Read request
[1767062390.296] [99108:99108] [DMG] ReadRequestMessage =
[1767062390.296] [99108:99108] [DMG] {
[1767062390.296] [99108:99108] [DMG]    AttributePathIBs =
[1767062390.296] [99108:99108] [DMG]    [
[1767062390.296] [99108:99108] [DMG]            AttributePathIB =
[1767062390.296] [99108:99108] [DMG]            {
[1767062390.296] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.296] [99108:99108] [DMG]                    Cluster = 0x30,
[1767062390.297] [99108:99108] [DMG]                    Attribute = 0x0000_0004,
[1767062390.297] [99108:99108] [DMG]            }
[1767062390.297] [99108:99108] [DMG]
[1767062390.297] [99108:99108] [DMG]            AttributePathIB =
[1767062390.297] [99108:99108] [DMG]            {
[1767062390.297] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.297] [99108:99108] [DMG]                    Cluster = 0x30,
[1767062390.297] [99108:99108] [DMG]                    Attribute = 0x0000_0000,
[1767062390.297] [99108:99108] [DMG]            }
[1767062390.297] [99108:99108] [DMG]
[1767062390.297] [99108:99108] [DMG]            AttributePathIB =
[1767062390.297] [99108:99108] [DMG]            {
[1767062390.297] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.297] [99108:99108] [DMG]                    Cluster = 0x30,
[1767062390.297] [99108:99108] [DMG]                    Attribute = 0x0000_0001,
[1767062390.297] [99108:99108] [DMG]            }
[1767062390.297] [99108:99108] [DMG]
[1767062390.297] [99108:99108] [DMG]            AttributePathIB =
[1767062390.297] [99108:99108] [DMG]            {
[1767062390.297] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.297] [99108:99108] [DMG]                    Cluster = 0x30,
[1767062390.297] [99108:99108] [DMG]                    Attribute = 0x0000_0002,
[1767062390.297] [99108:99108] [DMG]            }
[1767062390.297] [99108:99108] [DMG]
[1767062390.297] [99108:99108] [DMG]            AttributePathIB =
[1767062390.297] [99108:99108] [DMG]            {
[1767062390.297] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.297] [99108:99108] [DMG]                    Cluster = 0x30,
[1767062390.297] [99108:99108] [DMG]                    Attribute = 0x0000_0003,
[1767062390.297] [99108:99108] [DMG]            }
[1767062390.297] [99108:99108] [DMG]
[1767062390.297] [99108:99108] [DMG]            AttributePathIB =
[1767062390.297] [99108:99108] [DMG]            {
[1767062390.297] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.297] [99108:99108] [DMG]                    Cluster = 0x30,
[1767062390.297] [99108:99108] [DMG]                    Attribute = 0x0000_000C,
[1767062390.297] [99108:99108] [DMG]            }
[1767062390.297] [99108:99108] [DMG]
[1767062390.297] [99108:99108] [DMG]            AttributePathIB =
[1767062390.297] [99108:99108] [DMG]            {
[1767062390.297] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.298] [99108:99108] [DMG]                    Cluster = 0x28,
[1767062390.298] [99108:99108] [DMG]                    Attribute = 0x0000_0002,
[1767062390.298] [99108:99108] [DMG]            }
[1767062390.298] [99108:99108] [DMG]
[1767062390.298] [99108:99108] [DMG]            AttributePathIB =
[1767062390.298] [99108:99108] [DMG]            {
[1767062390.298] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.298] [99108:99108] [DMG]                    Cluster = 0x28,
[1767062390.298] [99108:99108] [DMG]                    Attribute = 0x0000_0004,
[1767062390.298] [99108:99108] [DMG]            }
[1767062390.298] [99108:99108] [DMG]
[1767062390.298] [99108:99108] [DMG]            AttributePathIB =
[1767062390.298] [99108:99108] [DMG]            {
[1767062390.298] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.298] [99108:99108] [DMG]                    Cluster = 0x38,
[1767062390.298] [99108:99108] [DMG]            }
[1767062390.298] [99108:99108] [DMG]
[1767062390.298] [99108:99108] [DMG]    ],
[1767062390.298] [99108:99108] [DMG]
[1767062390.298] [99108:99108] [DMG]    isFabricFiltered = false,
[1767062390.298] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.298] [99108:99108] [DMG] },
[1767062390.299] [99108:99108] [DMG] IM RH moving to [CanStartReporting]
[1767062390.303] [99108:99108] [DMG] Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
[1767062390.303] [99108:99108] [DMG] <RE:Run> Cluster 28, Attribute 4 is dirty
[1767062390.303] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0028 e=0 p=v r=r
[1767062390.303] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.303] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0028 e=0 p=v r=r
[1767062390.303] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.303] [99108:99108] [DMG] Reading attribute: Cluster=0x0000_0028 Endpoint=0x0 AttributeId=0x0000_0004 (expanded=0)
[1767062390.303] [99108:99108] [DMG] <RE:Run> Cluster 28, Attribute 2 is dirty
[1767062390.303] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0028 e=0 p=v r=r
[1767062390.303] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.303] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0028 e=0 p=v r=r
[1767062390.303] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.303] [99108:99108] [DMG] Reading attribute: Cluster=0x0000_0028 Endpoint=0x0 AttributeId=0x0000_0002 (expanded=0)
[1767062390.303] [99108:99108] [DMG] <RE:Run> Cluster 30, Attribute c is dirty
[1767062390.303] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.303] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.304] [99108:99108] [DMG] Failed to read attribute: UNSUPPORTED_ATTRIBUTE(134)
[1767062390.304] [99108:99108] [DMG] Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0030, attributeId: 0x0000_000Cerr = src/app/data-model-provider/ActionReturnStatus.cpp:104: IM Error 0x00000586: General error: 0x86 (UNSUPPORTED_ATTRIBUTE)
[1767062390.304] [99108:99108] [DMG] <RE:Run> Cluster 30, Attribute 3 is dirty
[1767062390.304] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.304] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.304] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.304] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.304] [99108:99108] [DMG] Reading attribute: Cluster=0x0000_0030 Endpoint=0x0 AttributeId=0x0000_0003 (expanded=0)
[1767062390.304] [99108:99108] [DMG] <RE:Run> Cluster 30, Attribute 2 is dirty
[1767062390.304] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.304] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.304] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.304] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.304] [99108:99108] [DMG] Reading attribute: Cluster=0x0000_0030 Endpoint=0x0 AttributeId=0x0000_0002 (expanded=0)
[1767062390.304] [99108:99108] [DMG] <RE:Run> Cluster 30, Attribute 1 is dirty
[1767062390.304] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.304] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.304] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.304] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.304] [99108:99108] [DMG] Reading attribute: Cluster=0x0000_0030 Endpoint=0x0 AttributeId=0x0000_0001 (expanded=0)
[1767062390.304] [99108:99108] [DMG] <RE:Run> Cluster 30, Attribute 0 is dirty
[1767062390.304] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.304] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.304] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.304] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.304] [99108:99108] [DMG] Reading attribute: Cluster=0x0000_0030 Endpoint=0x0 AttributeId=0x0000_0000 (expanded=0)
[1767062390.304] [99108:99108] [DMG] <RE:Run> Cluster 30, Attribute 4 is dirty
[1767062390.304] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.304] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.304] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v r=r
[1767062390.304] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.305] [99108:99108] [DMG] Reading attribute: Cluster=0x0000_0030 Endpoint=0x0 AttributeId=0x0000_0004 (expanded=0)
[1767062390.305] [99108:99108] [DMG] <RE> Sending report (payload has 223 bytes)...
[1767062390.305] [99108:99108] [EM] <<< [E:48007r S:39272 M:89607345 (Ack:265414446)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:05 (IM:ReportData) (B:257)
[1767062390.305] [99108:99108] [EM] ??1 [E:48007r S:39272 M:89607345] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 352ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.305] [99108:99108] [DMG] <RE> OnReportConfirm: NumReports = 0
[1767062390.305] [99108:99108] [DMG] <RE> ReportsInFlight = 0 with readHandler 0, RE has no more messages
[1767062390.305] [99108:99108] [DMG] IM RH moving to [AwaitingDestruction]
[1767062390.306] [99108:99108] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet
[1767062390.306] [99108:99108] [EM] >>> [E:48006r S:0 M:218742607 (Ack:173127405)] (U) Msg RX from 0:F53E8273BDB807DF [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[1767062390.306] [99108:99108] [EM] Found matching exchange: 48006r, Delegate: (nil)
[1767062390.306] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:173127405 from Retrans Table on exchange 48006r
[1767062390.312] [99108:99108] [EM] >>> [E:48008r S:39272 M:265414447] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:112)
[1767062390.312] [99108:99108] [EM] Handling via exchange: 48008r, Delegate: 0xaaaad7f5e890
[1767062390.312] [99108:99108] [IM] Received Read request
[1767062390.312] [99108:99108] [DMG] ReadRequestMessage =
[1767062390.312] [99108:99108] [DMG] {
[1767062390.312] [99108:99108] [DMG]    AttributePathIBs =
[1767062390.312] [99108:99108] [DMG]    [
[1767062390.313] [99108:99108] [DMG]            AttributePathIB =
[1767062390.313] [99108:99108] [DMG]            {
[1767062390.313] [99108:99108] [DMG]                    Cluster = 0x31,
[1767062390.313] [99108:99108] [DMG]                    Attribute = 0x0000_FFFC,
[1767062390.313] [99108:99108] [DMG]            }
[1767062390.313] [99108:99108] [DMG]
[1767062390.313] [99108:99108] [DMG]            AttributePathIB =
[1767062390.313] [99108:99108] [DMG]            {
[1767062390.313] [99108:99108] [DMG]                    Cluster = 0x31,
[1767062390.313] [99108:99108] [DMG]                    Attribute = 0x0000_0003,
[1767062390.313] [99108:99108] [DMG]            }
[1767062390.313] [99108:99108] [DMG]
[1767062390.313] [99108:99108] [DMG]            AttributePathIB =
[1767062390.313] [99108:99108] [DMG]            {
[1767062390.313] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.313] [99108:99108] [DMG]                    Cluster = 0x46,
[1767062390.313] [99108:99108] [DMG]                    Attribute = 0x0000_0006,
[1767062390.313] [99108:99108] [DMG]            }
[1767062390.313] [99108:99108] [DMG]
[1767062390.313] [99108:99108] [DMG]            AttributePathIB =
[1767062390.313] [99108:99108] [DMG]            {
[1767062390.313] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.313] [99108:99108] [DMG]                    Cluster = 0x46,
[1767062390.313] [99108:99108] [DMG]                    Attribute = 0x0000_0007,
[1767062390.313] [99108:99108] [DMG]            }
[1767062390.313] [99108:99108] [DMG]
[1767062390.313] [99108:99108] [DMG]            AttributePathIB =
[1767062390.313] [99108:99108] [DMG]            {
[1767062390.313] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.313] [99108:99108] [DMG]                    Cluster = 0x46,
[1767062390.313] [99108:99108] [DMG]                    Attribute = 0x0000_0000,
[1767062390.313] [99108:99108] [DMG]            }
[1767062390.313] [99108:99108] [DMG]
[1767062390.313] [99108:99108] [DMG]            AttributePathIB =
[1767062390.313] [99108:99108] [DMG]            {
[1767062390.313] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.313] [99108:99108] [DMG]                    Cluster = 0x46,
[1767062390.313] [99108:99108] [DMG]                    Attribute = 0x0000_0001,
[1767062390.314] [99108:99108] [DMG]            }
[1767062390.314] [99108:99108] [DMG]
[1767062390.314] [99108:99108] [DMG]            AttributePathIB =
[1767062390.314] [99108:99108] [DMG]            {
[1767062390.314] [99108:99108] [DMG]                    Endpoint = 0x0,
[1767062390.314] [99108:99108] [DMG]                    Cluster = 0x46,
[1767062390.314] [99108:99108] [DMG]                    Attribute = 0x0000_0002,
[1767062390.314] [99108:99108] [DMG]            }
[1767062390.314] [99108:99108] [DMG]
[1767062390.314] [99108:99108] [DMG]    ],
[1767062390.314] [99108:99108] [DMG]
[1767062390.314] [99108:99108] [DMG]    isFabricFiltered = false,
[1767062390.314] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.314] [99108:99108] [DMG] },
[1767062390.314] [99108:99108] [DMG] IM RH moving to [CanStartReporting]
[1767062390.315] [99108:99108] [DMG] Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
[1767062390.315] [99108:99108] [DMG] <RE:Run> Cluster 46, Attribute 2 is dirty
[1767062390.315] [99108:99108] [DMG] Read request on unknown cluster - no data version available
[1767062390.315] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0046 e=0 p=v r=r
[1767062390.315] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.315] [99108:99108] [DMG] Failed to read attribute: UNSUPPORTED_CLUSTER(195)
[1767062390.315] [99108:99108] [DMG] Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0002err = src/app/data-model-provider/ActionReturnStatus.cpp:104: IM Error 0x000005C3: General error: 0xc3 (UNSUPPORTED_CLUSTER)
[1767062390.315] [99108:99108] [DMG] <RE:Run> Cluster 46, Attribute 1 is dirty
[1767062390.315] [99108:99108] [DMG] Read request on unknown cluster - no data version available
[1767062390.315] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0046 e=0 p=v r=r
[1767062390.315] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.315] [99108:99108] [DMG] Failed to read attribute: UNSUPPORTED_CLUSTER(195)
[1767062390.315] [99108:99108] [DMG] Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0001err = src/app/data-model-provider/ActionReturnStatus.cpp:104: IM Error 0x000005C3: General error: 0xc3 (UNSUPPORTED_CLUSTER)
[1767062390.315] [99108:99108] [DMG] <RE:Run> Cluster 46, Attribute 0 is dirty
[1767062390.315] [99108:99108] [DMG] Read request on unknown cluster - no data version available
[1767062390.316] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0046 e=0 p=v r=r
[1767062390.316] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.316] [99108:99108] [DMG] Failed to read attribute: UNSUPPORTED_CLUSTER(195)
[1767062390.316] [99108:99108] [DMG] Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0000err = src/app/data-model-provider/ActionReturnStatus.cpp:104: IM Error 0x000005C3: General error: 0xc3 (UNSUPPORTED_CLUSTER)
[1767062390.316] [99108:99108] [DMG] <RE:Run> Cluster 46, Attribute 7 is dirty
[1767062390.316] [99108:99108] [DMG] Read request on unknown cluster - no data version available
[1767062390.316] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0046 e=0 p=v r=r
[1767062390.316] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.316] [99108:99108] [DMG] Failed to read attribute: UNSUPPORTED_CLUSTER(195)
[1767062390.316] [99108:99108] [DMG] Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0007err = src/app/data-model-provider/ActionReturnStatus.cpp:104: IM Error 0x000005C3: General error: 0xc3 (UNSUPPORTED_CLUSTER)
[1767062390.316] [99108:99108] [DMG] <RE:Run> Cluster 46, Attribute 6 is dirty
[1767062390.316] [99108:99108] [DMG] Read request on unknown cluster - no data version available
[1767062390.316] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0046 e=0 p=v r=r
[1767062390.316] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.316] [99108:99108] [DMG] Failed to read attribute: UNSUPPORTED_CLUSTER(195)
[1767062390.316] [99108:99108] [DMG] Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0006err = src/app/data-model-provider/ActionReturnStatus.cpp:104: IM Error 0x000005C3: General error: 0xc3 (UNSUPPORTED_CLUSTER)
[1767062390.316] [99108:99108] [DMG] <RE:Run> Cluster 31, Attribute fffc is dirty
[1767062390.316] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0031 e=0 p=v r=r
[1767062390.316] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.316] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0031 e=0 p=v r=r
[1767062390.316] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.316] [99108:99108] [DMG] Reading attribute: Cluster=0x0000_0031 Endpoint=0x0 AttributeId=0x0000_FFFC (expanded=1)
[1767062390.316] [99108:99108] [DMG] <RE> Sending report (payload has 152 bytes)...
[1767062390.317] [99108:99108] [EM] <<< [E:48008r S:39272 M:89607346 (Ack:265414447)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:05 (IM:ReportData) (B:186)
[1767062390.317] [99108:99108] [EM] ??1 [E:48008r S:39272 M:89607346] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 380ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.317] [99108:99108] [DMG] <RE> OnReportConfirm: NumReports = 0
[1767062390.317] [99108:99108] [DMG] <RE> ReportsInFlight = 0 with readHandler 0, RE has no more messages
[1767062390.317] [99108:99108] [DMG] IM RH moving to [AwaitingDestruction]
[1767062390.317] [99108:99108] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet
[1767062390.317] [99108:99108] [EM] >>> [E:48007r S:39272 M:265414448 (Ack:89607345)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.317] [99108:99108] [EM] Found matching exchange: 48007r, Delegate: (nil)
[1767062390.318] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:89607345 from Retrans Table on exchange 48007r
[1767062390.325] [99108:99108] [EM] >>> [E:48009r S:39272 M:265414449] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:65)
[1767062390.325] [99108:99108] [EM] Handling via exchange: 48009r, Delegate: 0xaaaad7f5e890
[1767062390.325] [99108:99108] [DMG] InvokeRequestMessage =
[1767062390.325] [99108:99108] [DMG] {
[1767062390.325] [99108:99108] [DMG]    suppressResponse = false,
[1767062390.325] [99108:99108] [DMG]    timedRequest = false,
[1767062390.325] [99108:99108] [DMG]    InvokeRequests =
[1767062390.326] [99108:99108] [DMG]    [
[1767062390.326] [99108:99108] [DMG]            CommandDataIB =
[1767062390.326] [99108:99108] [DMG]            {
[1767062390.326] [99108:99108] [DMG]                    CommandPathIB =
[1767062390.326] [99108:99108] [DMG]                    {
[1767062390.326] [99108:99108] [DMG]                            EndpointId = 0x0,
[1767062390.326] [99108:99108] [DMG]                            ClusterId = 0x30,
[1767062390.326] [99108:99108] [DMG]                            CommandId = 0x0,
[1767062390.326] [99108:99108] [DMG]                    },
[1767062390.326] [99108:99108] [DMG]
[1767062390.326] [99108:99108] [DMG]                    CommandFields =
[1767062390.326] [99108:99108] [DMG]                    {
[1767062390.326] [99108:99108] [DMG]                            0x0 = 60 (unsigned),
[1767062390.326] [99108:99108] [DMG]                            0x1 = 3 (unsigned),
[1767062390.326] [99108:99108] [DMG]                    },
[1767062390.326] [99108:99108] [DMG]            },
[1767062390.326] [99108:99108] [DMG]
[1767062390.326] [99108:99108] [DMG]    ],
[1767062390.326] [99108:99108] [DMG]
[1767062390.326] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.326] [99108:99108] [DMG] },
[1767062390.326] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=o r=i
[1767062390.326] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.326] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=a r=i
[1767062390.326] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.326] [99108:99108] [DMG] Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0000
[1767062390.326] [99108:99108] [FS] GeneralCommissioning: Received ArmFailSafe (60s)
[1767062390.326] [99108:99108] [DMG] Command handler moving to [NewRespons]
[1767062390.326] [99108:99108] [DMG] Command handler moving to [ Preparing]
[1767062390.326] [99108:99108] [DMG] Command handler moving to [AddingComm]
[1767062390.326] [99108:99108] [DMG] Command handler moving to [AddedComma]
[1767062390.327] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 1
[1767062390.327] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 0
[1767062390.327] [99108:99108] [DMG] Command handler moving to [AwaitingDe]
[1767062390.327] [99108:99108] [EM] <<< [E:48009r S:39272 M:89607347 (Ack:265414449)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70)
[1767062390.327] [99108:99108] [EM] ??1 [E:48009r S:39272 M:89607347] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 404ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.327] [99108:99108] [DMG] Command response sender moving to [AllInvokeR]
[1767062390.328] [99108:99108] [EM] >>> [E:48008r S:39272 M:265414450 (Ack:89607346)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.328] [99108:99108] [EM] Found matching exchange: 48008r, Delegate: (nil)
[1767062390.328] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:89607346 from Retrans Table on exchange 48008r
[1767062390.333] [99108:99108] [EM] >>> [E:48010r S:39272 M:265414451] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:70)
[1767062390.333] [99108:99108] [EM] Handling via exchange: 48010r, Delegate: 0xaaaad7f5e890
[1767062390.333] [99108:99108] [DMG] InvokeRequestMessage =
[1767062390.333] [99108:99108] [DMG] {
[1767062390.333] [99108:99108] [DMG]    suppressResponse = false,
[1767062390.333] [99108:99108] [DMG]    timedRequest = false,
[1767062390.333] [99108:99108] [DMG]    InvokeRequests =
[1767062390.333] [99108:99108] [DMG]    [
[1767062390.333] [99108:99108] [DMG]            CommandDataIB =
[1767062390.333] [99108:99108] [DMG]            {
[1767062390.333] [99108:99108] [DMG]                    CommandPathIB =
[1767062390.333] [99108:99108] [DMG]                    {
[1767062390.333] [99108:99108] [DMG]                            EndpointId = 0x0,
[1767062390.333] [99108:99108] [DMG]                            ClusterId = 0x30,
[1767062390.333] [99108:99108] [DMG]                            CommandId = 0x2,
[1767062390.333] [99108:99108] [DMG]                    },
[1767062390.333] [99108:99108] [DMG]
[1767062390.333] [99108:99108] [DMG]                    CommandFields =
[1767062390.333] [99108:99108] [DMG]                    {
[1767062390.333] [99108:99108] [DMG]                            0x0 = 0 (unsigned),
[1767062390.333] [99108:99108] [DMG]                            0x1 = "XX" (2 chars),
[1767062390.333] [99108:99108] [DMG]                            0x2 = 4 (unsigned),
[1767062390.334] [99108:99108] [DMG]                    },
[1767062390.334] [99108:99108] [DMG]            },
[1767062390.334] [99108:99108] [DMG]
[1767062390.334] [99108:99108] [DMG]    ],
[1767062390.334] [99108:99108] [DMG]
[1767062390.334] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.334] [99108:99108] [DMG] },
[1767062390.334] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=o r=i
[1767062390.334] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.334] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=a r=i
[1767062390.334] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.334] [99108:99108] [DMG] Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0002
[1767062390.343] [99108:99108] [DL] Wrote settings to /tmp/chip_config.ini
[1767062390.343] [99108:99108] [DL] NVS set: chip-config/regulatory-location = 0 (0x0)
[1767062390.350] [99108:99108] [DL] Wrote settings to /tmp/chip_config.ini
[1767062390.350] [99108:99108] [DL] NVS set: chip-config/country-code = "XX"
[1767062390.350] [99108:99108] [DMG] Command handler moving to [NewRespons]
[1767062390.350] [99108:99108] [DMG] Command handler moving to [ Preparing]
[1767062390.350] [99108:99108] [DMG] Command handler moving to [AddingComm]
[1767062390.350] [99108:99108] [DMG] Command handler moving to [AddedComma]
[1767062390.350] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 1
[1767062390.350] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 0
[1767062390.350] [99108:99108] [DMG] Command handler moving to [AwaitingDe]
[1767062390.350] [99108:99108] [EM] <<< [E:48010r S:39272 M:89607348 (Ack:265414451)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70)
[1767062390.351] [99108:99108] [EM] ??1 [E:48010r S:39272 M:89607348] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 409ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.351] [99108:99108] [DMG] Command response sender moving to [AllInvokeR]
[1767062390.352] [99108:99108] [EM] >>> [E:48009r S:39272 M:265414452 (Ack:89607347)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.352] [99108:99108] [EM] Found matching exchange: 48009r, Delegate: (nil)
[1767062390.352] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:89607347 from Retrans Table on exchange 48009r
[1767062390.356] [99108:99108] [EM] >>> [E:48011r S:39272 M:265414453] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
[1767062390.356] [99108:99108] [EM] Handling via exchange: 48011r, Delegate: 0xaaaad7f5e890
[1767062390.357] [99108:99108] [DMG] InvokeRequestMessage =
[1767062390.357] [99108:99108] [DMG] {
[1767062390.357] [99108:99108] [DMG]    suppressResponse = false,
[1767062390.357] [99108:99108] [DMG]    timedRequest = false,
[1767062390.357] [99108:99108] [DMG]    InvokeRequests =
[1767062390.357] [99108:99108] [DMG]    [
[1767062390.357] [99108:99108] [DMG]            CommandDataIB =
[1767062390.357] [99108:99108] [DMG]            {
[1767062390.357] [99108:99108] [DMG]                    CommandPathIB =
[1767062390.357] [99108:99108] [DMG]                    {
[1767062390.357] [99108:99108] [DMG]                            EndpointId = 0x0,
[1767062390.357] [99108:99108] [DMG]                            ClusterId = 0x3e,
[1767062390.357] [99108:99108] [DMG]                            CommandId = 0x2,
[1767062390.357] [99108:99108] [DMG]                    },
[1767062390.358] [99108:99108] [DMG]
[1767062390.358] [99108:99108] [DMG]                    CommandFields =
[1767062390.358] [99108:99108] [DMG]                    {
[1767062390.358] [99108:99108] [DMG]                            0x0 = 2 (unsigned),
[1767062390.358] [99108:99108] [DMG]                    },
[1767062390.358] [99108:99108] [DMG]            },
[1767062390.358] [99108:99108] [DMG]
[1767062390.358] [99108:99108] [DMG]    ],
[1767062390.358] [99108:99108] [DMG]
[1767062390.358] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.358] [99108:99108] [DMG] },
[1767062390.358] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o r=i
[1767062390.359] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.359] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a r=i
[1767062390.359] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.359] [99108:99108] [DMG] Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002
[1767062390.359] [99108:99108] [ZCL] OpCreds: Certificate Chain request received for PAI
[1767062390.359] [99108:99108] [DMG] Command handler moving to [NewRespons]
[1767062390.359] [99108:99108] [DMG] Command handler moving to [ Preparing]
[1767062390.359] [99108:99108] [DMG] Command handler moving to [AddingComm]
[1767062390.359] [99108:99108] [DMG] Command handler moving to [AddedComma]
[1767062390.359] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 1
[1767062390.359] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 0
[1767062390.359] [99108:99108] [DMG] Command handler moving to [AwaitingDe]
[1767062390.360] [99108:99108] [EM] <<< [E:48011r S:39272 M:89607349 (Ack:265414453)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:09 (IM:InvokeCommandResponse) (B:531)
[1767062390.360] [99108:99108] [EM] ??1 [E:48011r S:39272 M:89607349] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 348ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.360] [99108:99108] [DMG] Command response sender moving to [AllInvokeR]
[1767062390.362] [99108:99108] [EM] >>> [E:48010r S:39272 M:265414454 (Ack:89607348)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.362] [99108:99108] [EM] Found matching exchange: 48010r, Delegate: (nil)
[1767062390.362] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:89607348 from Retrans Table on exchange 48010r
[1767062390.365] [99108:99108] [EM] >>> [E:48012r S:39272 M:265414455] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
[1767062390.365] [99108:99108] [EM] Handling via exchange: 48012r, Delegate: 0xaaaad7f5e890
[1767062390.365] [99108:99108] [DMG] InvokeRequestMessage =
[1767062390.365] [99108:99108] [DMG] {
[1767062390.365] [99108:99108] [DMG]    suppressResponse = false,
[1767062390.365] [99108:99108] [DMG]    timedRequest = false,
[1767062390.366] [99108:99108] [DMG]    InvokeRequests =
[1767062390.366] [99108:99108] [DMG]    [
[1767062390.366] [99108:99108] [DMG]            CommandDataIB =
[1767062390.366] [99108:99108] [DMG]            {
[1767062390.366] [99108:99108] [DMG]                    CommandPathIB =
[1767062390.366] [99108:99108] [DMG]                    {
[1767062390.366] [99108:99108] [DMG]                            EndpointId = 0x0,
[1767062390.366] [99108:99108] [DMG]                            ClusterId = 0x3e,
[1767062390.366] [99108:99108] [DMG]                            CommandId = 0x2,
[1767062390.366] [99108:99108] [DMG]                    },
[1767062390.366] [99108:99108] [DMG]
[1767062390.367] [99108:99108] [DMG]                    CommandFields =
[1767062390.367] [99108:99108] [DMG]                    {
[1767062390.367] [99108:99108] [DMG]                            0x0 = 1 (unsigned),
[1767062390.367] [99108:99108] [DMG]                    },
[1767062390.367] [99108:99108] [DMG]            },
[1767062390.367] [99108:99108] [DMG]
[1767062390.367] [99108:99108] [DMG]    ],
[1767062390.367] [99108:99108] [DMG]
[1767062390.367] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.367] [99108:99108] [DMG] },
[1767062390.367] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o r=i
[1767062390.367] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.367] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a r=i
[1767062390.367] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.367] [99108:99108] [DMG] Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002
[1767062390.367] [99108:99108] [ZCL] OpCreds: Certificate Chain request received for DAC
[1767062390.367] [99108:99108] [DMG] Command handler moving to [NewRespons]
[1767062390.367] [99108:99108] [DMG] Command handler moving to [ Preparing]
[1767062390.367] [99108:99108] [DMG] Command handler moving to [AddingComm]
[1767062390.367] [99108:99108] [DMG] Command handler moving to [AddedComma]
[1767062390.368] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 1
[1767062390.368] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 0
[1767062390.368] [99108:99108] [DMG] Command handler moving to [AwaitingDe]
[1767062390.368] [99108:99108] [EM] <<< [E:48012r S:39272 M:89607350 (Ack:265414455)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:09 (IM:InvokeCommandResponse) (B:559)
[1767062390.368] [99108:99108] [EM] ??1 [E:48012r S:39272 M:89607350] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 378ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.368] [99108:99108] [DMG] Command response sender moving to [AllInvokeR]
[1767062390.369] [99108:99108] [EM] >>> [E:48011r S:39272 M:265414456 (Ack:89607349)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.369] [99108:99108] [EM] Found matching exchange: 48011r, Delegate: (nil)
[1767062390.369] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:89607349 from Retrans Table on exchange 48011r
[1767062390.371] [99108:99108] [EM] >>> [E:48013r S:39272 M:265414457] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:94)
[1767062390.371] [99108:99108] [EM] Handling via exchange: 48013r, Delegate: 0xaaaad7f5e890
[1767062390.371] [99108:99108] [DMG] InvokeRequestMessage =
[1767062390.371] [99108:99108] [DMG] {
[1767062390.371] [99108:99108] [DMG]    suppressResponse = false,
[1767062390.371] [99108:99108] [DMG]    timedRequest = false,
[1767062390.371] [99108:99108] [DMG]    InvokeRequests =
[1767062390.371] [99108:99108] [DMG]    [
[1767062390.371] [99108:99108] [DMG]            CommandDataIB =
[1767062390.371] [99108:99108] [DMG]            {
[1767062390.371] [99108:99108] [DMG]                    CommandPathIB =
[1767062390.371] [99108:99108] [DMG]                    {
[1767062390.371] [99108:99108] [DMG]                            EndpointId = 0x0,
[1767062390.371] [99108:99108] [DMG]                            ClusterId = 0x3e,
[1767062390.371] [99108:99108] [DMG]                            CommandId = 0x0,
[1767062390.372] [99108:99108] [DMG]                    },
[1767062390.372] [99108:99108] [DMG]
[1767062390.372] [99108:99108] [DMG]                    CommandFields =
[1767062390.372] [99108:99108] [DMG]                    {
[1767062390.372] [99108:99108] [DMG]                            0x0 = [
[1767062390.372] [99108:99108] [DMG]                                            0xfe, 0x34, 0xb2, 0xf6, 0x89, 0x18, 0x4e, 0x69, 0x75, 0x5e, 0x52, 0xfb, 0x2b, 0xdb, 0x9f, 0x0d, 0xda, 0xf9, 0x30, 0x89, 0xed, 0xd8, 0x21, 0x27, 0xc6, 0xc9, 0x56, 0xf5, 0xb4, 0xa8, 0xe6, 0x61,
[1767062390.372] [99108:99108] [DMG]                            ] (32 bytes)
[1767062390.372] [99108:99108] [DMG]                    },
[1767062390.372] [99108:99108] [DMG]            },
[1767062390.372] [99108:99108] [DMG]
[1767062390.372] [99108:99108] [DMG]    ],
[1767062390.372] [99108:99108] [DMG]
[1767062390.372] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.372] [99108:99108] [DMG] },
[1767062390.372] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o r=i
[1767062390.372] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.372] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a r=i
[1767062390.372] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.372] [99108:99108] [DMG] Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0000
[1767062390.372] [99108:99108] [ZCL] OpCreds: Received an AttestationRequest command
[1767062390.373] [99108:99108] [ZCL] OpCreds: AttestationRequest successful.
[1767062390.373] [99108:99108] [DMG] Command handler moving to [NewRespons]
[1767062390.373] [99108:99108] [DMG] Command handler moving to [ Preparing]
[1767062390.373] [99108:99108] [DMG] Command handler moving to [AddingComm]
[1767062390.373] [99108:99108] [DMG] Command handler moving to [AddedComma]
[1767062390.373] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 1
[1767062390.373] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 0
[1767062390.373] [99108:99108] [DMG] Command handler moving to [AwaitingDe]
[1767062390.373] [99108:99108] [EM] <<< [E:48013r S:39272 M:89607351 (Ack:265414457)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:09 (IM:InvokeCommandResponse) (B:718)
[1767062390.374] [99108:99108] [EM] ??1 [E:48013r S:39272 M:89607351] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 387ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.374] [99108:99108] [DMG] Command response sender moving to [AllInvokeR]
[1767062390.374] [99108:99108] [EM] >>> [E:48012r S:39272 M:265414458 (Ack:89607350)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.374] [99108:99108] [EM] Found matching exchange: 48012r, Delegate: (nil)
[1767062390.374] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:89607350 from Retrans Table on exchange 48012r
[1767062390.422] [99108:99108] [EM] >>> [E:48014r S:39272 M:265414459] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:94)
[1767062390.422] [99108:99108] [EM] Handling via exchange: 48014r, Delegate: 0xaaaad7f5e890
[1767062390.422] [99108:99108] [DMG] InvokeRequestMessage =
[1767062390.422] [99108:99108] [DMG] {
[1767062390.422] [99108:99108] [DMG]    suppressResponse = false,
[1767062390.422] [99108:99108] [DMG]    timedRequest = false,
[1767062390.422] [99108:99108] [DMG]    InvokeRequests =
[1767062390.422] [99108:99108] [DMG]    [
[1767062390.422] [99108:99108] [DMG]            CommandDataIB =
[1767062390.422] [99108:99108] [DMG]            {
[1767062390.422] [99108:99108] [DMG]                    CommandPathIB =
[1767062390.422] [99108:99108] [DMG]                    {
[1767062390.422] [99108:99108] [DMG]                            EndpointId = 0x0,
[1767062390.422] [99108:99108] [DMG]                            ClusterId = 0x3e,
[1767062390.422] [99108:99108] [DMG]                            CommandId = 0x4,
[1767062390.422] [99108:99108] [DMG]                    },
[1767062390.422] [99108:99108] [DMG]
[1767062390.422] [99108:99108] [DMG]                    CommandFields =
[1767062390.422] [99108:99108] [DMG]                    {
[1767062390.422] [99108:99108] [DMG]                            0x0 = [
[1767062390.422] [99108:99108] [DMG]                                            0x49, 0xe0, 0xba, 0xc1, 0xe4, 0xf4, 0xdd, 0x2d, 0xc6, 0xf1, 0xc1, 0x3d, 0x42, 0x28, 0x82, 0x5f, 0x93, 0x67, 0x5a, 0xcc, 0x69, 0x40, 0xbb, 0xf9, 0xf5, 0x17, 0xad, 0x35, 0xa4, 0xf2, 0xc6, 0xd2,
[1767062390.422] [99108:99108] [DMG]                            ] (32 bytes)
[1767062390.422] [99108:99108] [DMG]                    },
[1767062390.422] [99108:99108] [DMG]            },
[1767062390.422] [99108:99108] [DMG]
[1767062390.423] [99108:99108] [DMG]    ],
[1767062390.423] [99108:99108] [DMG]
[1767062390.423] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.423] [99108:99108] [DMG] },
[1767062390.423] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o r=i
[1767062390.423] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.423] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a r=i
[1767062390.423] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.423] [99108:99108] [DMG] Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0004
[1767062390.423] [99108:99108] [ZCL] OpCreds: Received a CSRRequest command
[1767062390.424] [99108:99108] [ZCL] OpCreds: AllocatePendingOperationalKey succeeded
[1767062390.425] [99108:99108] [ZCL] OpCreds: CSRRequest successful.
[1767062390.425] [99108:99108] [DMG] Command handler moving to [NewRespons]
[1767062390.425] [99108:99108] [DMG] Command handler moving to [ Preparing]
[1767062390.425] [99108:99108] [DMG] Command handler moving to [AddingComm]
[1767062390.425] [99108:99108] [DMG] Command handler moving to [AddedComma]
[1767062390.425] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 1
[1767062390.425] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 0
[1767062390.425] [99108:99108] [DMG] Command handler moving to [AwaitingDe]
[1767062390.425] [99108:99108] [EM] <<< [E:48014r S:39272 M:89607352 (Ack:265414459)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:09 (IM:InvokeCommandResponse) (B:376)
[1767062390.425] [99108:99108] [EM] ??1 [E:48014r S:39272 M:89607352] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 387ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.425] [99108:99108] [DMG] Command response sender moving to [AllInvokeR]
[1767062390.426] [99108:99108] [EM] >>> [E:48013r S:39272 M:265414460 (Ack:89607351)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.426] [99108:99108] [EM] Found matching exchange: 48013r, Delegate: (nil)
[1767062390.426] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:89607351 from Retrans Table on exchange 48013r
[1767062390.436] [99108:99108] [EM] >>> [E:48015r S:39272 M:265414461] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:293)
[1767062390.436] [99108:99108] [EM] Handling via exchange: 48015r, Delegate: 0xaaaad7f5e890
[1767062390.437] [99108:99108] [DMG] InvokeRequestMessage =
[1767062390.437] [99108:99108] [DMG] {
[1767062390.437] [99108:99108] [DMG]    suppressResponse = false,
[1767062390.437] [99108:99108] [DMG]    timedRequest = false,
[1767062390.437] [99108:99108] [DMG]    InvokeRequests =
[1767062390.437] [99108:99108] [DMG]    [
[1767062390.437] [99108:99108] [DMG]            CommandDataIB =
[1767062390.437] [99108:99108] [DMG]            {
[1767062390.437] [99108:99108] [DMG]                    CommandPathIB =
[1767062390.437] [99108:99108] [DMG]                    {
[1767062390.437] [99108:99108] [DMG]                            EndpointId = 0x0,
[1767062390.437] [99108:99108] [DMG]                            ClusterId = 0x3e,
[1767062390.437] [99108:99108] [DMG]                            CommandId = 0xb,
[1767062390.437] [99108:99108] [DMG]                    },
[1767062390.437] [99108:99108] [DMG]
[1767062390.437] [99108:99108] [DMG]                    CommandFields =
[1767062390.437] [99108:99108] [DMG]                    {
[1767062390.437] [99108:99108] [DMG]                            0x0 = [
[1767062390.437] [99108:99108] [DMG]                                            0x15, 0x30, 0x01, 0x01, 0x01, 0x24, 0x02, 0x01, 0x37, 0x03, 0x24, 0x14, 0x01, 0x18, 0x26, 0x04, 0x80, 0x22, 0x81, 0x27, 0x26, 0x05, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x06, 0x24, 0x14, 0x01, 0x18, 0x24, 0x07, 0x01, 0x24, 0x08, 0x01, 0x30, 0x09, 0x41, 0x04, 0x5f, 0x31, 0xca, 0x34, 0xf1, 0x18, 0x41, 0x25, 0xa1, 0x9d, 0xb5, 0xf7, 0x9c, 0x11, 0x15, 0xd4, 0x7b, 0x42, 0xe3, 0x61, 0x91, 0x4d, 0xac, 0x28, 0x6d, 0xa1, 0x2b, 0xa0, 0xf6, 0x32, 0x7b, 0x6b, 0x33, 0x9b, 0xc3, 0x46, 0xf1, 0xfd, 0xce, 0xcb, 0xd1, 0x54, 0x92, 0xe9, 0xde, 0x90, 0x55, 0xdb, 0x6e, 0x0c, 0x9b, 0xd4, 0x0d, 0x90, 0x76, 0xb9, 0x42, 0x10, 0x8e, 0xa2, 0xfc, 0x83, 0xea, 0xe0, 0x37, 0x0a, 0x35, 0x01, 0x29, 0x01, 0x18, 0x24, 0x02, 0x60, 0x30, 0x04, 0x14, 0xc0, 0x56, 0x06, 0x52, 0x55, 0x15, 0x55, 0xe8, 0x80, 0x8d, 0x5c, 0xe0, 0xef, 0x84, 0x9d, 0xb6, 0x3e, 0x69, 0x3e, 0xb2, 0x30, 0x05, 0x14, 0xc0, 0x56, 0x06, 0x52, 0x55, 0x15, 0x55, 0xe8, 0x80, 0x8d, 0x5c, 0xe0, 0xef, 0x84, 0x9d, 0xb6, 0x3e, 0x69, 0x3e, 0xb2, 0x18, 0x30, 0x0b, 0x40, 0xad, 0xbf, 0x14, 0xb4, 0xba, 0xf0, 0x7d, 0xbc, 0x51, 0xa7, 0x1e, 0x5a, 0x38, 0x9d, 0x10, 0x47, 0x72, 0x68, 0x7e, 0x2f, 0xa0, 0x70, 0x49, 0xbe, 0xe9, 0xaf, 0x0b, 0xd8, 0xb5, 0xbd, 0x59, 0xff, 0xfe, 0x49, 0xd9, 0x04, 0xc0, 0xbe, 0xc3, 0x32, 0x52, 0xcb, 0xa2, 0x74, 0xfc, 0xb6, 0x30, 0x8c, 0x9e, 0x36, 0x7d, 0xe2, 0x09, 0xbd, 0x85, 0xff, 0x57, 0x2d, 0xc6, 0xaa, 0x3b, 0x79, 0x5e, 0x16, 0x18,
[1767062390.437] [99108:99108] [DMG]                            ] (231 bytes)
[1767062390.437] [99108:99108] [DMG]                    },
[1767062390.437] [99108:99108] [DMG]            },
[1767062390.437] [99108:99108] [DMG]
[1767062390.437] [99108:99108] [DMG]    ],
[1767062390.437] [99108:99108] [DMG]
[1767062390.437] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.437] [99108:99108] [DMG] },
[1767062390.438] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o r=i
[1767062390.438] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.438] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a r=i
[1767062390.438] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.438] [99108:99108] [DMG] Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B
[1767062390.438] [99108:99108] [ZCL] OpCreds: Received an AddTrustedRootCertificate command
[1767062390.439] [99108:99108] [ZCL] OpCreds: AddTrustedRootCertificate successful.
[1767062390.439] [99108:99108] [DMG] Command handler moving to [NewRespons]
[1767062390.439] [99108:99108] [DMG] Command handler moving to [ Preparing]
[1767062390.439] [99108:99108] [DMG] Command handler moving to [AddingComm]
[1767062390.439] [99108:99108] [DMG] Command handler moving to [AddedComma]
[1767062390.439] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 1
[1767062390.439] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 0
[1767062390.439] [99108:99108] [DMG] Command handler moving to [AwaitingDe]
[1767062390.439] [99108:99108] [EM] <<< [E:48015r S:39272 M:89607353 (Ack:265414461)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
[1767062390.440] [99108:99108] [EM] ??1 [E:48015r S:39272 M:89607353] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 380ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.440] [99108:99108] [DMG] Command response sender moving to [AllInvokeR]
[1767062390.440] [99108:99108] [EM] >>> [E:48014r S:39272 M:265414462 (Ack:89607352)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.440] [99108:99108] [EM] Found matching exchange: 48014r, Delegate: (nil)
[1767062390.440] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:89607352 from Retrans Table on exchange 48014r
[1767062390.442] [99108:99108] [EM] >>> [E:48016r S:39272 M:265414463] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:566)
[1767062390.442] [99108:99108] [EM] Handling via exchange: 48016r, Delegate: 0xaaaad7f5e890
[1767062390.442] [99108:99108] [DMG] InvokeRequestMessage =
[1767062390.442] [99108:99108] [DMG] {
[1767062390.442] [99108:99108] [DMG]    suppressResponse = false,
[1767062390.442] [99108:99108] [DMG]    timedRequest = false,
[1767062390.442] [99108:99108] [DMG]    InvokeRequests =
[1767062390.442] [99108:99108] [DMG]    [
[1767062390.442] [99108:99108] [DMG]            CommandDataIB =
[1767062390.442] [99108:99108] [DMG]            {
[1767062390.442] [99108:99108] [DMG]                    CommandPathIB =
[1767062390.442] [99108:99108] [DMG]                    {
[1767062390.442] [99108:99108] [DMG]                            EndpointId = 0x0,
[1767062390.442] [99108:99108] [DMG]                            ClusterId = 0x3e,
[1767062390.442] [99108:99108] [DMG]                            CommandId = 0x6,
[1767062390.442] [99108:99108] [DMG]                    },
[1767062390.442] [99108:99108] [DMG]
[1767062390.442] [99108:99108] [DMG]                    CommandFields =
[1767062390.442] [99108:99108] [DMG]                    {
[1767062390.442] [99108:99108] [DMG]                            0x0 = [
[1767062390.443] [99108:99108] [DMG]                                            0x15, 0x30, 0x01, 0x01, 0x01, 0x24, 0x02, 0x01, 0x37, 0x03, 0x24, 0x13, 0x02, 0x18, 0x26, 0x04, 0x80, 0x22, 0x81, 0x27, 0x26, 0x05, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x06, 0x24, 0x15, 0x01, 0x24, 0x11, 0x02, 0x18, 0x24, 0x07, 0x01, 0x24, 0x08, 0x01, 0x30, 0x09, 0x41, 0x04, 0x39, 0x07, 0x8b, 0xef, 0x98, 0xd8, 0x89, 0x22, 0xd4, 0x81, 0x8e, 0xe1, 0x32, 0xe7, 0x59, 0x38, 0x03, 0x6c, 0x4f, 0x81, 0x11, 0x68, 0x9c, 0x69, 0x31, 0x54, 0xe3, 0x9e, 0xa9, 0xed, 0x94, 0xdd, 0x79, 0x0a, 0xd8, 0x95, 0x69, 0x9d, 0x24, 0x4e, 0x7f, 0x93, 0x3c, 0xcd, 0xbf, 0xcd, 0x7a, 0x57, 0xb6, 0x76, 0x57, 0x96, 0x6a, 0x26, 0x6d, 0x3c, 0x0d, 0xdd, 0x00, 0x4f, 0xf6, 0x7b, 0xf0, 0x74, 0x37, 0x0a, 0x35, 0x01, 0x28, 0x01, 0x18, 0x24, 0x02, 0x01, 0x36, 0x03, 0x04, 0x02, 0x04, 0x01, 0x18, 0x30, 0x04, 0x14, 0x0c, 0xaf, 0xaa, 0x93, 0x14, 0xcb, 0xab, 0x22, 0x88, 0xf9, 0xec, 0x7a, 0x89, 0x1a, 0xbe, 0x24, 0xe7, 0xa5, 0xeb, 0xfe, 0x30, 0x05, 0x14, 0xd9, 0xf8, 0x9e, 0xe7, 0xd3, 0x31, 0xda, 0xa6, 0x07, 0x0c, 0x26, 0xab, 0x83, 0xa8, 0x0e, 0x7a, 0x4d, 0xdf, 0x23, 0xba, 0x18, 0x30, 0x0b, 0x40, 0x02, 0xb6, 0x6b, 0x7a, 0x8c, 0xb8, 0xff, 0x06, 0x18, 0x65, 0xd6, 0x4d, 0x39, 0x6f, 0x1f, 0xec, 0x59, 0xaf, 0x78, 0xec, 0x4b, 0x39, 0xde, 0x1c, 0xbf, 0xa5, 0x87, 0x0b, 0x46, 0xc2, 0x0c, 0x87, 0x68, 0x1d, 0x15, 0x08, 0xd1, 0x62, 0x23, 0xb4, 0x66, 0x8b, 0x8a, 0x7b, 0xad, 0x63, 0x6a, 0x19, 0x60, 0x7b, 0xa8, 0x69, 0xf4, 0x7d, 0xa1, 0xb7, 0x98, 0xa0, 0xdb, 0x72, 0x27, 0x34, 0xca, 0x49, 0x18,
[1767062390.443] [99108:99108] [DMG]                            ] (241 bytes)
[1767062390.443] [99108:99108] [DMG]                            0x1 = [
[1767062390.443] [99108:99108] [DMG]                                            0x15, 0x30, 0x01, 0x01, 0x01, 0x24, 0x02, 0x01, 0x37, 0x03, 0x24, 0x14, 0x01, 0x18, 0x26, 0x04, 0x80, 0x22, 0x81, 0x27, 0x26, 0x05, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x06, 0x24, 0x13, 0x02, 0x18, 0x24, 0x07, 0x01, 0x24, 0x08, 0x01, 0x30, 0x09, 0x41, 0x04, 0x3e, 0x8b, 0x23, 0xd8, 0x23, 0xe5, 0x6a, 0x29, 0xa4, 0xaf, 0xeb, 0xb6, 0xc1, 0x03, 0xab, 0xe7, 0x16, 0x37, 0x41, 0x73, 0x0d, 0xeb, 0x70, 0xbd, 0x8b, 0x46, 0x6b, 0x29, 0x67, 0x6f, 0x4a, 0xf9, 0x5c, 0x02, 0xac, 0xc3, 0xc1, 0xa9, 0x82, 0xd9, 0x36, 0x4c, 0xbe, 0x05, 0xa8, 0x63, 0xbc, 0xb6, 0x93, 0x89, 0x59, 0xe5, 0xa2, 0xe0, 0x3f, 0x19, 0x01, 0xc6, 0xed, 0x1a, 0x03, 0x33, 0x3f, 0x7e, 0x37, 0x0a, 0x35, 0x01, 0x29, 0x01, 0x18, 0x24, 0x02, 0x60, 0x30, 0x04, 0x14, 0xd9, 0xf8, 0x9e, 0xe7, 0xd3, 0x31, 0xda, 0xa6, 0x07, 0x0c, 0x26, 0xab, 0x83, 0xa8, 0x0e, 0x7a, 0x4d, 0xdf, 0x23, 0xba, 0x30, 0x05, 0x14, 0xc0, 0x56, 0x06, 0x52, 0x55, 0x15, 0x55, 0xe8, 0x80, 0x8d, 0x5c, 0xe0, 0xef, 0x84, 0x9d, 0xb6, 0x3e, 0x69, 0x3e, 0xb2, 0x18, 0x30, 0x0b, 0x40, 0x46, 0x03, 0xfd, 0xf3, 0x5f, 0x9c, 0xb5, 0x59, 0xe3, 0xd1, 0xbd, 0x15, 0x28, 0x3f, 0x4b, 0xfd, 0xa9, 0x40, 0x3c, 0x3b, 0x63, 0x3c, 0x7f, 0x84, 0xfd, 0x87, 0xe1, 0xc6, 0x3c, 0x24, 0x42, 0x77, 0x40, 0x78, 0xdd, 0x07, 0x1c, 0x9b, 0x32, 0x70, 0xf0, 0x58, 0x5a, 0x77, 0xab, 0xe5, 0x3b, 0x08, 0x43, 0xcb, 0xd8, 0xc4, 0x95, 0xde, 0xc9, 0xf8, 0xbd, 0x61, 0xfb, 0xdb, 0xc8, 0xbd, 0xa5, 0x6e, 0x18,
[1767062390.443] [99108:99108] [DMG]                            ] (231 bytes)
[1767062390.443] [99108:99108] [DMG]                            0x2 = [
[1767062390.443] [99108:99108] [DMG]                                            0x74, 0x65, 0x6d, 0x70, 0x6f, 0x72, 0x61, 0x72, 0x79, 0x20, 0x69, 0x70, 0x6b, 0x20, 0x30, 0x31,
[1767062390.443] [99108:99108] [DMG]                            ] (16 bytes)
[1767062390.443] [99108:99108] [DMG]                            0x3 = 112233 (unsigned),
[1767062390.443] [99108:99108] [DMG]                            0x4 = 65521 (unsigned),
[1767062390.443] [99108:99108] [DMG]                    },
[1767062390.443] [99108:99108] [DMG]            },
[1767062390.443] [99108:99108] [DMG]
[1767062390.443] [99108:99108] [DMG]    ],
[1767062390.443] [99108:99108] [DMG]
[1767062390.443] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.443] [99108:99108] [DMG] },
[1767062390.443] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o r=i
[1767062390.443] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.443] [99108:99108] [DMG] AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a r=i
[1767062390.443] [99108:99108] [DMG] AccessControl: implicit admin (PASE)
[1767062390.443] [99108:99108] [DMG] Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0006
[1767062390.443] [99108:99108] [ZCL] OpCreds: Received an AddNOC command
[1767062390.444] [99108:99108] [FP] Validating NOC chain
[1767062390.446] [99108:99108] [FP] NOC chain validation successful
[1767062390.446] [99108:99108] [FP] Added new fabric at index: 0x1
[1767062390.446] [99108:99108] [FP] Assigned compressed fabric ID: 0x0B704147E0F6AD41, node ID: 0x0000000000000002
[1767062390.446] [99108:99108] [TS] Last Known Good Time: 2023-10-14T01:16:48
[1767062390.446] [99108:99108] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00
[1767062390.446] [99108:99108] [TS] Retaining current Last Known Good Time
[1767062390.454] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.463] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.469] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.469] [99108:99108] [DMG] AccessControl: validating f=1 p=a a=c s=1 t=0
[1767062390.469] [99108:99108] [DMG]   validating subject 0x000000000001B669
[1767062390.479] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.479] [99108:99108] [EVL] LogEvent event number: 0x0000000000000002 priority: 1, endpoint id:  0x0 cluster id: 0x0000_001F event id: 0x0 Epoch timestamp: 0x0000019B6D2046CF
[1767062390.479] [99108:99108] [ZCL] OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x000000000001B669
[1767062390.480] [99108:99108] [DL] Using WiFi MAC for hostname
[1767062390.480] [99108:99108] [DIS] Advertise operational node 0B704147E0F6AD41-0000000000000002
[1767062390.480] [99108:99108] [DIS] Responding with _matter._tcp.local
[1767062390.480] [99108:99108] [DIS] Responding with 0B704147E0F6AD41-0000000000000002._matter._tcp.local
[1767062390.480] [99108:99108] [DIS] Responding with 0B704147E0F6AD41-0000000000000002._matter._tcp.local
[1767062390.480] [99108:99108] [DIS] Responding with D83ADD980FF5.local
[1767062390.480] [99108:99108] [DIS] Responding with _I0B704147E0F6AD41._sub._matter._tcp.local
[1767062390.480] [99108:99108] [DIS] CHIP minimal mDNS configured as 'Operational device'; instance name: 0B704147E0F6AD41-0000000000000002.
[1767062390.501] [99108:99108] [DIS] mDNS service published: _matter._tcp
[1767062390.501] [99108:99108] [DMG] Command handler moving to [NewRespons]
[1767062390.501] [99108:99108] [DMG] Command handler moving to [ Preparing]
[1767062390.501] [99108:99108] [DMG] Command handler moving to [AddingComm]
[1767062390.501] [99108:99108] [DMG] Command handler moving to [AddedComma]
[1767062390.501] [99108:99108] [ZCL] OpCreds: successfully created fabric index 0x1 via AddNOC
[1767062390.501] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 1
[1767062390.501] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 0
[1767062390.501] [99108:99108] [DMG] Command handler moving to [AwaitingDe]
[1767062390.501] [99108:99108] [EM] <<< [E:48016r S:39272 M:89607354 (Ack:265414463)] (S) Msg TX from 0000000000000000 to 1:FFFFFFFB00000000 [AD41] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70)
[1767062390.501] [99108:99108] [EM] ??1 [E:48016r S:39272 M:89607354] (S) Msg Retransmission to 1:FFFFFFFB00000000 scheduled for 337ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.502] [99108:99108] [DMG] Command response sender moving to [AllInvokeR]
[1767062390.502] [99108:99108] [EM] >>> [E:48015r S:39272 M:265414464 (Ack:89607353)] (S) Msg RX from 1:FFFFFFFB00000000 [AD41] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.502] [99108:99108] [EM] Found matching exchange: 48015r, Delegate: (nil)
[1767062390.502] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:89607353 from Retrans Table on exchange 48015r
[1767062390.506] [99108:99108] [EM] >>> [E:48016r S:39272 M:265414465 (Ack:89607354)] (S) Msg RX from 1:FFFFFFFB00000000 [AD41] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.506] [99108:99108] [EM] Found matching exchange: 48016r, Delegate: (nil)
[1767062390.506] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:89607354 from Retrans Table on exchange 48016r
[1767062390.707] [99108:99108] [EM] >>> [E:48017r S:0 M:218742608] (U) Msg RX from 0:291A47C6365E8E35 [0000] to 0000000000000000 --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196)
[1767062390.707] [99108:99108] [EM] Handling via exchange: 48017r, Delegate: 0xaaaad7f54a30
[1767062390.707] [99108:99108] [IN] CASE Server received Sigma1 message . Starting handshake. EC 0xaaab13e61130
[1767062390.707] [99108:99108] [SC] Received Sigma1 msg
[1767062390.707] [99108:99108] [SC] Found MRP parameters in the message
[1767062390.707] [99108:99108] [SC] Peer (Initiator) assigned session ID 12968
[1767062390.708] [99108:99108] [SC] CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000002
[1767062390.712] [99108:99108] [EM] <<< [E:48017r S:0 M:173127406 (Ack:218742608)] (U) Msg TX from 0000000000000000 to 0:291A47C6365E8E35 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:751)
[1767062390.712] [99108:99108] [EM] ??1 [E:48017r S:0 M:173127406] (U) Msg Retransmission to 0:0000000000000000 scheduled for 375ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.713] [99108:99108] [SC] Sent Sigma2 msg
[1767062390.720] [99108:99108] [EM] >>> [E:48017r S:0 M:218742609 (Ack:173127406)] (U) Msg RX from 0:291A47C6365E8E35 [0000] to 0000000000000000 --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598)
[1767062390.720] [99108:99108] [EM] Found matching exchange: 48017r, Delegate: 0xaaaad7f54a68
[1767062390.720] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:173127406 from Retrans Table on exchange 48017r
[1767062390.720] [99108:99108] [SC] Received Sigma3 msg
[1767062390.729] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.738] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.743] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.743] [99108:99108] [SC] Sending status report. Protocol code 0, exchange 48017
[1767062390.744] [99108:99108] [EM] <<< [E:48017r S:0 M:173127407 (Ack:218742609)] (U) Msg TX from 0000000000000000 to 0:291A47C6365E8E35 [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
[1767062390.744] [99108:99108] [EM] ??1 [E:48017r S:0 M:173127407] (U) Msg Retransmission to 0:0000000000000000 scheduled for 376ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.744] [99108:99108] [SC] SecureSession[0xaaab13e62400, LSID:39273]: State change 'kEstablishing' --> 'kActive'
[1767062390.744] [99108:99108] [IN] SecureSession[0xaaab13e62400]: Activated - Type:2 LSID:39273
[1767062390.744] [99108:99108] [IN] New secure session activated for device <000000000001B669, 1>, LSID:39273 PSID:12968!
[1767062390.745] [99108:99108] [IN] CASE Session established to peer: <000000000001B669, 1>
[1767062390.745] [99108:99108] [IN] SecureSession[0xaaab13e70dc0]: Allocated Type:2 LSID:39274
[1767062390.745] [99108:99108] [SC] Allocated SecureSession (0xaaab13e70dc0) - waiting for Sigma1 msg
[1767062390.752] [99108:99108] [EM] >>> [E:48018r S:39273 M:250983156] (S) Msg RX from 1:000000000001B669 [AD41] to 0000000000000002 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
[1767062390.752] [99108:99108] [EM] Handling via exchange: 48018r, Delegate: 0xaaaad7f5e890
[1767062390.752] [99108:99108] [DMG] InvokeRequestMessage =
[1767062390.752] [99108:99108] [DMG] {
[1767062390.752] [99108:99108] [DMG]    suppressResponse = false,
[1767062390.752] [99108:99108] [DMG]    timedRequest = false,
[1767062390.752] [99108:99108] [DMG]    InvokeRequests =
[1767062390.752] [99108:99108] [DMG]    [
[1767062390.752] [99108:99108] [DMG]            CommandDataIB =
[1767062390.752] [99108:99108] [DMG]            {
[1767062390.752] [99108:99108] [DMG]                    CommandPathIB =
[1767062390.752] [99108:99108] [DMG]                    {
[1767062390.752] [99108:99108] [DMG]                            EndpointId = 0x0,
[1767062390.753] [99108:99108] [DMG]                            ClusterId = 0x30,
[1767062390.753] [99108:99108] [DMG]                            CommandId = 0x4,
[1767062390.753] [99108:99108] [DMG]                    },
[1767062390.753] [99108:99108] [DMG]
[1767062390.753] [99108:99108] [DMG]                    CommandFields =
[1767062390.753] [99108:99108] [DMG]                    {
[1767062390.753] [99108:99108] [DMG]                    },
[1767062390.753] [99108:99108] [DMG]            },
[1767062390.753] [99108:99108] [DMG]
[1767062390.753] [99108:99108] [DMG]    ],
[1767062390.753] [99108:99108] [DMG]
[1767062390.753] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062390.753] [99108:99108] [DMG] },
[1767062390.754] [99108:99108] [DMG] AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0030 e=0 p=o r=i
[1767062390.754] [99108:99108] [DMG] AccessControl: allowed
[1767062390.754] [99108:99108] [DMG] AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0030 e=0 p=a r=i
[1767062390.754] [99108:99108] [DMG] AccessControl: allowed
[1767062390.754] [99108:99108] [DMG] Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0004
[1767062390.754] [99108:99108] [FS] GeneralCommissioning: Received CommissioningComplete
[1767062390.763] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.769] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.770] [99108:99108] [FP] Metadata for Fabric 0x1 persisted to storage.
[1767062390.779] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.786] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.795] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.802] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.802] [99108:99108] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48
[1767062390.811] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.819] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.819] [99108:99108] [ZCL] OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0x0B704147E0F6AD41, FabricId 0000000000000001, NodeId 0000000000000002, VendorId 0xFFF1
[1767062390.828] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062390.829] [99108:99108] [FS] GeneralCommissioning: Successfully committed pending fabric data
[1767062390.829] [99108:99108] [FS] Fail-safe cleanly disarmed
[1767062390.829] [99108:99108] [DMG] Command handler moving to [NewRespons]
[1767062390.829] [99108:99108] [DMG] Command handler moving to [ Preparing]
[1767062390.829] [99108:99108] [DMG] Command handler moving to [AddingComm]
[1767062390.829] [99108:99108] [DMG] Command handler moving to [AddedComma]
[1767062390.829] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 1
[1767062390.829] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 0
[1767062390.829] [99108:99108] [DMG] Command handler moving to [AwaitingDe]
[1767062390.829] [99108:99108] [EM] <<< [E:48018r S:39273 M:185351438 (Ack:250983156)] (S) Msg TX from 0000000000000002 to 1:000000000001B669 [AD41] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:39259] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70)
[1767062390.830] [99108:99108] [EM] ??1 [E:48018r S:39273 M:185351438] (S) Msg Retransmission to 1:000000000001B669 scheduled for 385ms from now [State:Active II:500 AI:300 AT:4000]
[1767062390.830] [99108:99108] [DMG] Command response sender moving to [AllInvokeR]
[1767062390.830] [99108:99108] [SVR] Commissioning completed successfully
[1767062390.830] [99108:99108] [DIS] Updating services using commissioning mode 0
[1767062390.847] [99108:99108] [DIS] CHIP minimal mDNS started advertising.
[1767062390.886] [99108:99108] [DL] Using WiFi MAC for hostname
[1767062390.886] [99108:99108] [DIS] Advertise operational node 0B704147E0F6AD41-0000000000000002
[1767062390.886] [99108:99108] [DIS] Responding with _matter._tcp.local
[1767062390.886] [99108:99108] [DIS] Responding with 0B704147E0F6AD41-0000000000000002._matter._tcp.local
[1767062390.886] [99108:99108] [DIS] Responding with 0B704147E0F6AD41-0000000000000002._matter._tcp.local
[1767062390.886] [99108:99108] [DIS] Responding with D83ADD980FF5.local
[1767062390.886] [99108:99108] [DIS] Responding with _I0B704147E0F6AD41._sub._matter._tcp.local
[1767062390.886] [99108:99108] [DIS] CHIP minimal mDNS configured as 'Operational device'; instance name: 0B704147E0F6AD41-0000000000000002.
[1767062390.902] [99108:99108] [DIS] mDNS service published: _matter._tcp
[1767062390.902] [99108:99108] [IN] Expiring all PASE sessions
[1767062390.902] [99108:99108] [IN] SecureSession[0xaaab13e62a80]: MarkForEviction Type:1 LSID:39272
[1767062390.902] [99108:99108] [SC] SecureSession[0xaaab13e62a80, LSID:39272]: State change 'kActive' --> 'kPendingEviction'
[1767062390.902] [99108:99108] [IN] SecureSession[0xaaab13e62a80]: Released - Type:1 LSID:39272
[1767062390.902] [99108:99108] [PAF] WiFiPAF: Closing all WiFiPAF sessions to shutdown
[1767062390.902] [99108:99108] [EM] >>> [E:48017r S:0 M:218742610 (Ack:173127407)] (U) Msg RX from 0:291A47C6365E8E35 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[1767062390.902] [99108:99108] [EM] Found matching exchange: 48017r, Delegate: (nil)
[1767062390.902] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:173127407 from Retrans Table on exchange 48017r
[1767062390.903] [99108:99108] [EM] >>> [E:48018r S:39273 M:250983157 (Ack:185351438)] (S) Msg RX from 1:000000000001B669 [AD41] to 0000000000000002 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062390.903] [99108:99108] [EM] Found matching exchange: 48018r, Delegate: (nil)
[1767062390.903] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:185351438 from Retrans Table on exchange 48018r
[1767062395.040] [99108:99108] [EM] >>> [E:21007r S:0 M:95239245] (U) Msg RX from 0:4ACD867BA404D35F [0000] to 0000000000000000 --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196)
[1767062395.040] [99108:99108] [EM] Handling via exchange: 21007r, Delegate: 0xaaaad7f54a30
[1767062395.040] [99108:99108] [IN] CASE Server received Sigma1 message . Starting handshake. EC 0xaaab13e61130
[1767062395.040] [99108:99108] [SC] Received Sigma1 msg
[1767062395.040] [99108:99108] [SC] Found MRP parameters in the message
[1767062395.041] [99108:99108] [SC] Peer (Initiator) assigned session ID 16416
[1767062395.042] [99108:99108] [SC] CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000002
[1767062395.047] [99108:99108] [EM] <<< [E:21007r S:0 M:173127408 (Ack:95239245)] (U) Msg TX from 0000000000000000 to 0:4ACD867BA404D35F [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:33443] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:751)
[1767062395.047] [99108:99108] [EM] ??1 [E:21007r S:0 M:173127408] (U) Msg Retransmission to 0:0000000000000000 scheduled for 366ms from now [State:Active II:500 AI:300 AT:4000]
[1767062395.047] [99108:99108] [SC] Sent Sigma2 msg
[1767062395.054] [99108:99108] [EM] >>> [E:21007r S:0 M:95239246 (Ack:173127408)] (U) Msg RX from 0:4ACD867BA404D35F [0000] to 0000000000000000 --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598)
[1767062395.054] [99108:99108] [EM] Found matching exchange: 21007r, Delegate: 0xaaaad7f54a68
[1767062395.054] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:173127408 from Retrans Table on exchange 21007r
[1767062395.054] [99108:99108] [SC] Received Sigma3 msg
[1767062395.066] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062395.073] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062395.082] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062395.082] [99108:99108] [SC] Sending status report. Protocol code 0, exchange 21007
[1767062395.082] [99108:99108] [EM] <<< [E:21007r S:0 M:173127409 (Ack:95239246)] (U) Msg TX from 0000000000000000 to 0:4ACD867BA404D35F [0000] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:33443] --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
[1767062395.083] [99108:99108] [EM] ??1 [E:21007r S:0 M:173127409] (U) Msg Retransmission to 0:0000000000000000 scheduled for 340ms from now [State:Active II:500 AI:300 AT:4000]
[1767062395.083] [99108:99108] [SC] SecureSession[0xaaab13e70dc0, LSID:39274]: State change 'kEstablishing' --> 'kActive'
[1767062395.083] [99108:99108] [IN] SecureSession[0xaaab13e70dc0]: Activated - Type:2 LSID:39274
[1767062395.083] [99108:99108] [IN] New secure session activated for device <000000000001B669, 1>, LSID:39274 PSID:16416!
[1767062395.083] [99108:99108] [IN] CASE Session established to peer: <000000000001B669, 1>
[1767062395.083] [99108:99108] [IN] SecureSession[0xaaab13e62a80]: Allocated Type:2 LSID:39275
[1767062395.083] [99108:99108] [SC] Allocated SecureSession (0xaaab13e62a80) - waiting for Sigma1 msg
[1767062395.089] [99108:99108] [EM] >>> [E:21008r S:39274 M:135059909] (S) Msg RX from 1:000000000001B669 [AD41] to 0000000000000002 --- Type 0001:06 (IM:WriteRequest) (B:91)
[1767062395.089] [99108:99108] [EM] Handling via exchange: 21008r, Delegate: 0xaaaad7f5e890
[1767062395.089] [99108:99108] [IM] Received Write request
[1767062395.089] [99108:99108] [DMG] IM WH moving to [Initialized]
[1767062395.089] [99108:99108] [DMG] WriteRequestMessage =
[1767062395.089] [99108:99108] [DMG] {
[1767062395.089] [99108:99108] [DMG]    suppressResponse = false,
[1767062395.090] [99108:99108] [DMG]    timedRequest = false,
[1767062395.090] [99108:99108] [DMG]    AttributeDataIBs =
[1767062395.090] [99108:99108] [DMG]    [
[1767062395.090] [99108:99108] [DMG]            AttributeDataIB =
[1767062395.090] [99108:99108] [DMG]            {
[1767062395.090] [99108:99108] [DMG]                    AttributePathIB =
[1767062395.090] [99108:99108] [DMG]                    {
[1767062395.090] [99108:99108] [DMG]                            Endpoint = 0x0,
[1767062395.090] [99108:99108] [DMG]                            Cluster = 0x1f,
[1767062395.090] [99108:99108] [DMG]                            Attribute = 0x0000_0000,
[1767062395.090] [99108:99108] [DMG]                    }
[1767062395.090] [99108:99108] [DMG]
[1767062395.090] [99108:99108] [DMG]                    Data = [
[1767062395.090] [99108:99108] [DMG]
[1767062395.090] [99108:99108] [DMG]                            {
[1767062395.090] [99108:99108] [DMG]                                    0x1 = 5 (unsigned),
[1767062395.090] [99108:99108] [DMG]                                    0x2 = 2 (unsigned),
[1767062395.090] [99108:99108] [DMG]                                    0x3 = [
[1767062395.090] [99108:99108] [DMG]                                                    112233 (unsigned),
[1767062395.090] [99108:99108] [DMG]                                    ],
[1767062395.090] [99108:99108] [DMG]                                    0x4 = NULL
[1767062395.090] [99108:99108] [DMG]                            },
[1767062395.091] [99108:99108] [DMG]                            {
[1767062395.091] [99108:99108] [DMG]                                    0x1 = 3 (unsigned),
[1767062395.091] [99108:99108] [DMG]                                    0x2 = 2 (unsigned),
[1767062395.091] [99108:99108] [DMG]                                    0x3 = NULL
[1767062395.091] [99108:99108] [DMG]                                    0x4 = NULL
[1767062395.091] [99108:99108] [DMG]                            },
[1767062395.091] [99108:99108] [DMG]                    ],
[1767062395.091] [99108:99108] [DMG]            },
[1767062395.091] [99108:99108] [DMG]
[1767062395.091] [99108:99108] [DMG]    ],
[1767062395.091] [99108:99108] [DMG]
[1767062395.091] [99108:99108] [DMG]    moreChunkedMessages = false,
[1767062395.091] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062395.091] [99108:99108] [DMG] },
[1767062395.091] [99108:99108] [DMG] Writing attribute: Cluster=0x0000_001F Endpoint=0x0 AttributeId=0x0000_0000
[1767062395.091] [99108:99108] [DMG] AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_001F e=0 p=v r=w
[1767062395.091] [99108:99108] [DMG] AccessControl: allowed
[1767062395.091] [99108:99108] [DMG] AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_001F e=0 p=a r=w
[1767062395.091] [99108:99108] [DMG] AccessControl: allowed
[1767062395.092] [99108:99108] [DMG] AccessControl: validating f=1 p=a a=c s=1 t=0
[1767062395.092] [99108:99108] [DMG]   validating subject 0x000000000001B669
[1767062395.092] [99108:99108] [DMG] AccessControl: validating f=1 p=o a=c s=0 t=0
[1767062395.092] [99108:99108] [DMG] AccessControl: validating f=1 p=a a=c s=1 t=0
[1767062395.092] [99108:99108] [DMG]   validating subject 0x000000000001B669
[1767062395.101] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062395.101] [99108:99108] [EVL] LogEvent event number: 0x0000000000000003 priority: 1, endpoint id:  0x0 cluster id: 0x0000_001F event id: 0x0 Epoch timestamp: 0x0000019B6D2058DD
[1767062395.102] [99108:99108] [DMG] AccessControl: validating f=1 p=o a=c s=0 t=0
[1767062395.107] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062395.107] [99108:99108] [EVL] LogEvent event number: 0x0000000000000004 priority: 1, endpoint id:  0x0 cluster id: 0x0000_001F event id: 0x0 Epoch timestamp: 0x0000019B6D2058E3
[1767062395.108] [99108:99108] [DMG] IM WH moving to [AddStatus]
[1767062395.108] [99108:99108] [EM] <<< [E:21008r S:39274 M:136884495 (Ack:135059909)] (S) Msg TX from 0000000000000002 to 1:000000000001B669 [AD41] [UDP:[fe80::d413:86ff:fe79:b0ea%vethe68f907]:33443] --- Type 0001:07 (IM:WriteResponse) (B:62)
[1767062395.108] [99108:99108] [EM] ??1 [E:21008r S:39274 M:136884495] (S) Msg Retransmission to 1:000000000001B669 scheduled for 339ms from now [State:Active II:500 AI:300 AT:4000]
[1767062395.108] [99108:99108] [DMG] IM WH moving to [Sending]
[1767062395.108] [99108:99108] [DMG] IM WH moving to [Uninitialized]
[1767062395.109] [99108:99108] [EM] >>> [E:21007r S:0 M:95239247 (Ack:173127409)] (U) Msg RX from 0:4ACD867BA404D35F [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[1767062395.109] [99108:99108] [EM] Found matching exchange: 21007r, Delegate: (nil)
[1767062395.109] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:173127409 from Retrans Table on exchange 21007r
[1767062395.111] [99108:99108] [EM] >>> [E:21008r S:39274 M:135059910 (Ack:136884495)] (S) Msg RX from 1:000000000001B669 [AD41] to 0000000000000002 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062395.111] [99108:99108] [EM] Found matching exchange: 21008r, Delegate: (nil)
[1767062395.111] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:136884495 from Retrans Table on exchange 21008r
[1767062404.562] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.562] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.562] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.562] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.562] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.562] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.562] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.563] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.564] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.564] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.564] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.564] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.564] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.564] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.564] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.565] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.565] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.565] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.565] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.565] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.566] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.566] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.566] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.567] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.569] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.569] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.570] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.578] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.579] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.579] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.579] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.579] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.580] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062404.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.584] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.585] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.585] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.585] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.585] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.585] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.585] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.586] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.586] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.586] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.587] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.587] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.587] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.587] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.587] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.587] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.587] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.588] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.590] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.590] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.591] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.599] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.599] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.599] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.599] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.600] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.600] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.604] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.604] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.604] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.604] [99108:99108] [DIS] SRV record already actively processed.
[1767062405.604] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.605] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.605] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.605] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.606] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.606] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.606] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.606] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.607] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.607] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.607] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.607] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.607] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.607] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.607] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.607] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.608] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.608] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.608] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.608] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.608] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.608] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.609] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.609] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.611] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.617] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.617] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.617] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.617] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.623] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.623] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.623] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.623] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.623] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.623] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.627] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.627] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.628] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.628] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.628] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.628] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.628] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.628] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.628] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.628] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.628] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.628] [99108:99108] [DIS] SRV record already actively processed.
[1767062407.629] [99108:99108] [DIS] SRV record already actively processed.
[1767062418.342] [99108:99108] [EM] >>> [E:24385r S:0 M:38855552] (U) Msg RX from 0:E00B1FAA36C487E6 [0000] to 0000000000000000 --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:195)
[1767062418.342] [99108:99108] [EM] Handling via exchange: 24385r, Delegate: 0xaaaad7f54a30
[1767062418.342] [99108:99108] [IN] CASE Server received Sigma1 message . Starting handshake. EC 0xaaab13e611b0
[1767062418.342] [99108:99108] [SC] Received Sigma1 msg
[1767062418.343] [99108:99108] [SC] Found MRP parameters in the message
[1767062418.343] [99108:99108] [SC] Peer (Initiator) assigned session ID 7457
[1767062418.344] [99108:99108] [SC] CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000002
[1767062418.348] [99108:99108] [EM] <<< [E:24385r S:0 M:173127410 (Ack:38855552)] (U) Msg TX from 0000000000000000 to 0:E00B1FAA36C487E6 [0000] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:751)
[1767062418.348] [99108:99108] [EM] ??1 [E:24385r S:0 M:173127410] (U) Msg Retransmission to 0:0000000000000000 scheduled for 2520ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062418.348] [99108:99108] [SC] Sent Sigma2 msg
[1767062418.647] [99108:99108] [EM] >>> [E:24385r S:0 M:38855553 (Ack:173127410)] (U) Msg RX from 0:E00B1FAA36C487E6 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[1767062418.647] [99108:99108] [EM] Found matching exchange: 24385r, Delegate: 0xaaaad7f54a68
[1767062418.647] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:173127410 from Retrans Table on exchange 24385r
[1767062418.700] [99108:99108] [EM] >>> [E:24385r S:0 M:38855554 (Ack:173127410)] (U) Msg RX from 0:E00B1FAA36C487E6 [0000] to 0000000000000000 --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:595)
[1767062418.700] [99108:99108] [EM] Found matching exchange: 24385r, Delegate: 0xaaaad7f54a68
[1767062418.700] [99108:99108] [EM] CHIP MessageCounter:173127410 not in RetransTable on exchange 24385r
[1767062418.700] [99108:99108] [SC] Received Sigma3 msg
[1767062418.719] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062418.724] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062418.734] [99108:99108] [DL] Wrote settings to /tmp/chip_kvs_provider
[1767062418.734] [99108:99108] [SC] Sending status report. Protocol code 0, exchange 24385
[1767062418.734] [99108:99108] [EM] <<< [E:24385r S:0 M:173127411 (Ack:38855554)] (U) Msg TX from 0000000000000000 to 0:E00B1FAA36C487E6 [0000] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
[1767062418.734] [99108:99108] [EM] ??1 [E:24385r S:0 M:173127411] (U) Msg Retransmission to 0:0000000000000000 scheduled for 2815ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062418.735] [99108:99108] [SC] SecureSession[0xaaab13e62a80, LSID:39275]: State change 'kEstablishing' --> 'kActive'
[1767062418.735] [99108:99108] [IN] SecureSession[0xaaab13e62a80]: Activated - Type:2 LSID:39275
[1767062418.735] [99108:99108] [IN] New secure session activated for device <0000000000000001, 1>, LSID:39275 PSID:7457!
[1767062418.735] [99108:99108] [IN] CASE Session established to peer: <0000000000000001, 1>
[1767062418.735] [99108:99108] [IN] SecureSession[0xaaab13e60de0]: Allocated Type:2 LSID:39276
[1767062418.735] [99108:99108] [SC] Allocated SecureSession (0xaaab13e60de0) - waiting for Sigma1 msg
[1767062418.877] [99108:99108] [EM] >>> [E:24386r S:39275 M:190800183] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0001:08 (IM:InvokeCommandRequest) (B:84)
[1767062418.877] [99108:99108] [EM] Handling via exchange: 24386r, Delegate: 0xaaaad7f5e890
[1767062418.878] [99108:99108] [DMG] InvokeRequestMessage =
[1767062418.878] [99108:99108] [DMG] {
[1767062418.878] [99108:99108] [DMG]    suppressResponse = false,
[1767062418.878] [99108:99108] [DMG]    timedRequest = false,
[1767062418.878] [99108:99108] [DMG]    InvokeRequests =
[1767062418.878] [99108:99108] [DMG]    [
[1767062418.878] [99108:99108] [DMG]            CommandDataIB =
[1767062418.878] [99108:99108] [DMG]            {
[1767062418.878] [99108:99108] [DMG]                    CommandPathIB =
[1767062418.878] [99108:99108] [DMG]                    {
[1767062418.878] [99108:99108] [DMG]                            EndpointId = 0x0,
[1767062418.879] [99108:99108] [DMG]                            ClusterId = 0x29,
[1767062418.879] [99108:99108] [DMG]                            CommandId = 0x0,
[1767062418.879] [99108:99108] [DMG]                    },
[1767062418.879] [99108:99108] [DMG]
[1767062418.879] [99108:99108] [DMG]                    CommandFields =
[1767062418.879] [99108:99108] [DMG]                    {
[1767062418.879] [99108:99108] [DMG]                            0x0 = 4992 (unsigned),
[1767062418.879] [99108:99108] [DMG]                            0x1 = 17 (unsigned),
[1767062418.879] [99108:99108] [DMG]                            0x2 = 11 (unsigned),
[1767062418.879] [99108:99108] [DMG]                            0x3 = [
[1767062418.879] [99108:99108] [DMG]                                            0 (unsigned),
[1767062418.879] [99108:99108] [DMG]                            ],
[1767062418.880] [99108:99108] [DMG]                            0x4 = 1 (unsigned),
[1767062418.880] [99108:99108] [DMG]                            0x5 = "XX" (2 chars),
[1767062418.880] [99108:99108] [DMG]                            0x6 = false,
[1767062418.880] [99108:99108] [DMG]                    },
[1767062418.880] [99108:99108] [DMG]            },
[1767062418.880] [99108:99108] [DMG]
[1767062418.880] [99108:99108] [DMG]    ],
[1767062418.880] [99108:99108] [DMG]
[1767062418.880] [99108:99108] [DMG]    InteractionModelRevision = 12
[1767062418.880] [99108:99108] [DMG] },
[1767062418.881] [99108:99108] [DMG] AccessControl: checking f=1 a=c s=0x0000000000000001 t= c=0x0000_0029 e=0 p=o r=i
[1767062418.881] [99108:99108] [DMG] AccessControl: allowed
[1767062418.881] [99108:99108] [DMG] AccessControl: checking f=1 a=c s=0x0000000000000001 t= c=0x0000_0029 e=0 p=o r=i
[1767062418.881] [99108:99108] [DMG] AccessControl: allowed
[1767062418.881] [99108:99108] [DMG] Received command for Endpoint=0 Cluster=0x0000_0029 Command=0x0000_0000
[1767062418.881] [99108:99108] [ZCL] OTA Provider received QueryImage
[1767062418.881] [99108:99108] [ZCL]   VendorID: 0x1380
[1767062418.881] [99108:99108] [ZCL]   ProductID: 17
[1767062418.882] [99108:99108] [ZCL]   SoftwareVersion: 11
[1767062418.882] [99108:99108] [ZCL]   ProtocolsSupported: [
[1767062418.882] [99108:99108] [ZCL]     0
[1767062418.882] [99108:99108] [ZCL]   ]
[1767062418.882] [99108:99108] [ZCL]   HardwareVersion: 1
[1767062418.882] [99108:99108] [ZCL]   Location: XX
[1767062418.882] [99108:99108] [ZCL]   RequestorCanConsent: 0
[1767062418.883] [99108:99108] [SWU] Generated updateToken: BA6B52CD4D4C45BD90B115AB848E20
[1767062418.883] [99108:99108] [SWU] Generated URI: bdx://0000000000000002//home/ubuntu/matter-12.bin
[1767062418.883] [99108:99108] [BDX] Start polling for messages
[1767062418.884] [99108:99108] [DMG] Command handler moving to [NewRespons]
[1767062418.884] [99108:99108] [DMG] Command handler moving to [ Preparing]
[1767062418.884] [99108:99108] [DMG] Command handler moving to [AddingComm]
[1767062418.884] [99108:99108] [DMG] Command handler moving to [AddedComma]
[1767062418.884] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 1
[1767062418.884] [99108:99108] [DMG] Decreasing reference count for CommandHandlerImpl, remaining 0
[1767062418.884] [99108:99108] [DMG] Command handler moving to [AwaitingDe]
[1767062418.884] [99108:99108] [EM] <<< [E:24386r S:39275 M:172892863 (Ack:190800183)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0001:09 (IM:InvokeCommandResponse) (B:167)
[1767062418.885] [99108:99108] [EM] ??1 [E:24386r S:39275 M:172892863] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2487ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062418.885] [99108:99108] [DMG] Command response sender moving to [AllInvokeR]
[1767062418.885] [99108:99108] [EM] >>> [E:24385r S:0 M:38855555 (Ack:173127411)] (U) Msg RX from 0:E00B1FAA36C487E6 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[1767062418.886] [99108:99108] [EM] Found matching exchange: 24385r, Delegate: (nil)
[1767062418.886] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:173127411 from Retrans Table on exchange 24385r
[1767062419.086] [99108:99108] [EM] >>> [E:24386r S:39275 M:190800184 (Ack:172892863)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[1767062419.086] [99108:99108] [EM] Found matching exchange: 24386r, Delegate: (nil)
[1767062419.086] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892863 from Retrans Table on exchange 24386r
[1767062419.091] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800185] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:04 (BDX:ReceiveInit) (B:62)
[1767062419.091] [99108:99108] [EM] Handling via exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062419.091] [99108:99108] [BDX] OnMessageReceived: message 0x4 protocol (0, 2)
[1767062419.091] [99108:99108] [ATM] Handling received BDX Message
[1767062419.091] [99108:99108] [ATM] ReceiveInit
[1767062419.091] [99108:99108] [ATM]   Proposed Transfer Control: 0x20
[1767062419.091] [99108:99108] [ATM]   Range Control: 0x0
[1767062419.092] [99108:99108] [ATM]   Proposed Max Block Size: 1024
[1767062419.092] [99108:99108] [ATM]   Start Offset: 0x0000000000000000
[1767062419.092] [99108:99108] [ATM]   Proposed Max Length: 0x0000000000000000
[1767062419.092] [99108:99108] [ATM]   File Designator Length: 26
[1767062419.092] [99108:99108] [ATM]   File Designator: /home/ubuntu/matter-12.bin
[1767062419.134] [99108:99108] [BDX] OutputEvent type: InitReceived
[1767062419.134] [99108:99108] [ATM] Sending BDX Message
[1767062419.134] [99108:99108] [ATM] ReceiveAccept
[1767062419.134] [99108:99108] [ATM]   Transfer Control: 0x20
[1767062419.134] [99108:99108] [ATM]   Range Control: 0x0
[1767062419.134] [99108:99108] [ATM]   Max Block Size: 1024
[1767062419.134] [99108:99108] [ATM]   Length: 0x0000000000000000
[1767062419.185] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062419.185] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892864 (Ack:190800185)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:05 (BDX:ReceiveAccept) (B:38)
[1767062419.185] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892864] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2517ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062419.287] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800186 (Ack:172892864)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062419.287] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062419.287] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892864 from Retrans Table on exchange 24387r
[1767062419.287] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062419.336] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062419.387] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062419.387] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892865 (Ack:190800186)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062419.387] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892865] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2558ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062419.659] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800187 (Ack:172892865)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062419.659] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062419.659] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892865 from Retrans Table on exchange 24387r
[1767062419.659] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062419.690] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062419.740] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062419.741] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892866 (Ack:190800187)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062419.741] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892866] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2451ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062420.024] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800188 (Ack:172892866)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062420.024] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062420.024] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892866 from Retrans Table on exchange 24387r
[1767062420.024] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062420.044] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062420.094] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062420.095] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892867 (Ack:190800188)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062420.095] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892867] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2804ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062420.401] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800189 (Ack:172892867)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062420.401] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062420.401] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892867 from Retrans Table on exchange 24387r
[1767062420.401] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062420.447] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062420.498] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062420.498] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892868 (Ack:190800189)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062420.499] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892868] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2768ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062420.771] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800190 (Ack:172892868)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062420.771] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062420.771] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892868 from Retrans Table on exchange 24387r
[1767062420.771] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062420.800] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062420.851] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062420.851] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892869 (Ack:190800190)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062420.852] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892869] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2978ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062421.141] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800191 (Ack:172892869)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062421.141] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062421.141] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892869 from Retrans Table on exchange 24387r
[1767062421.141] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062421.154] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062421.204] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062421.204] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892870 (Ack:190800191)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062421.205] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892870] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2671ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062421.510] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800192 (Ack:172892870)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062421.510] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062421.510] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892870 from Retrans Table on exchange 24387r
[1767062421.510] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062421.556] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062421.607] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062421.607] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892871 (Ack:190800192)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062421.608] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892871] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2988ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062421.878] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800193 (Ack:172892871)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062421.879] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062421.879] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892871 from Retrans Table on exchange 24387r
[1767062421.879] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062421.909] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062421.960] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062421.960] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892872 (Ack:190800193)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062421.961] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892872] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2967ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062422.252] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800194 (Ack:172892872)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062422.252] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062422.252] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892872 from Retrans Table on exchange 24387r
[1767062422.252] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062422.262] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062422.313] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062422.313] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892873 (Ack:190800194)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062422.314] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892873] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2659ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062422.623] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800195 (Ack:172892873)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062422.624] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062422.624] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892873 from Retrans Table on exchange 24387r
[1767062422.624] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062422.665] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062422.716] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062422.716] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892874 (Ack:190800195)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062422.717] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892874] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2655ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062422.995] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800196 (Ack:172892874)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062422.995] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062422.995] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892874 from Retrans Table on exchange 24387r
[1767062422.995] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062423.018] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062423.069] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062423.069] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892875 (Ack:190800196)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062423.070] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892875] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2775ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062423.366] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800197 (Ack:172892875)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062423.366] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062423.366] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892875 from Retrans Table on exchange 24387r
[1767062423.366] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
[1767062423.371] [99108:99108] [BDX] OutputEvent type: QueryReceived
[1767062423.421] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062423.422] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892876 (Ack:190800197)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0002:11 (BDX:Block) (B:1062)
[1767062423.422] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892876] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2799ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062423.737] [99108:99108] [EM] >>> [E:24387r S:39275 M:190800198 (Ack:172892876)] (S) Msg RX from 1:0000000000000001 [AD41] to 0000000000000002 --- Type 0002:10 (BDX:BlockQuery) (B:38)
[1767062423.737] [99108:99108] [EM] Found matching exchange: 24387r, Delegate: 0xaaaad7f416e8
[1767062423.738] [99108:99108] [EM] Rxd Ack; Removing MessageCounter:172892876 from Retrans Table on exchange 24387r
[1767062423.738] [99108:99108] [BDX] OnMessageReceived: message 0x10 protocol (0, 2)
^C[1767062423.757] [99108:99108] [SWU] Scheduling BdxOtaSender to ABORT TRANSFER
[1767062423.757] [99108:99108] [BDX] OutputEvent type: MsgToSend
[1767062423.757] [99108:99108] [EM] <<< [E:24387r S:39275 M:172892877 (Ack:190800198)] (S) Msg TX from 0000000000000002 to 1:0000000000000001 [AD41] [UDP:[fd11:14::9975:20f:d2cc:8114]:5540] --- Type 0000:40 (SecureChannel:StatusReport) (B:42)
[1767062423.757] [99108:99108] [EM] ??1 [E:24387r S:39275 M:172892877] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2822ms from now [State:Idle II:3000 AI:2200 AT:0]
[1767062423.757] [99108:99108] [ZCL] Emitting ShutDown event
[1767062423.758] [99108:99108] [EVL] LogEvent event number: 0x0000000000000005 priority: 2, endpoint id:  0x0 cluster id: 0x0000_0028 event id: 0x1 Epoch timestamp: 0x0000019B6D20C8CD
[1767062423.758] [99108:99108] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet
[1767062423.758] [99108:99108] [IN] SecureSession[0xaaab13e60de0]: Released - Type:2 LSID:39276
[1767062423.781] [99108:99108] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet
[1767062423.781] [99108:99108] [IN] SecureSession[0xaaab13e62400]: MarkForEviction Type:2 LSID:39273
[1767062423.781] [99108:99108] [SC] SecureSession[0xaaab13e62400, LSID:39273]: State change 'kActive' --> 'kPendingEviction'
[1767062423.781] [99108:99108] [IN] SecureSession[0xaaab13e62400]: Released - Type:2 LSID:39273
[1767062423.781] [99108:99108] [IN] SecureSession[0xaaab13e70dc0]: MarkForEviction Type:2 LSID:39274
[1767062423.781] [99108:99108] [SC] SecureSession[0xaaab13e70dc0, LSID:39274]: State change 'kActive' --> 'kPendingEviction'
[1767062423.781] [99108:99108] [IN] SecureSession[0xaaab13e70dc0]: Released - Type:2 LSID:39274
[1767062423.781] [99108:99108] [IN] SecureSession[0xaaab13e62a80]: MarkForEviction Type:2 LSID:39275
[1767062423.781] [99108:99108] [SC] SecureSession[0xaaab13e62a80, LSID:39275]: State change 'kActive' --> 'kPendingEviction'
[1767062423.781] [99108:99108] [IN] SecureSession[0xaaab13e62a80]: Released - Type:2 LSID:39275
[1767062423.782] [99108:99108] [DMG] AccessControl: finishing
[1767062423.782] [99108:99108] [DMG] Examples::AccessControlDelegate::Finish
[1767062423.794] [99108:99108] [DL] Wrote settings to /tmp/chip_counters.ini
[1767062423.794] [99108:99108] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1767062423.794] [99108:99108] [DL] Inet Layer shutdown
[1767062423.794] [99108:99108] [DL] WiFi-PAF Layer shutdown
[1767062423.794] [99108:99108] [PAF] WiFiPAF: Closing all WiFiPAF sessions to shutdown
[1767062423.794] [99108:99108] [DL] System Layer shutdown
[1767062423.795] [99108:99108] [DMG] AccessControlCluster: shutdown
[1767062423.796] [99108:99108] [BDX] Stop polling for messages
pure virtual method called
terminate called without an active exception
Aborted (core dumped)
ubuntu@ubuntu:~/apps$
