Bootloader start...
OTA header pointer is NULL.
OTA image not pending
Application has been found at 0x1001f400 address
Address for wake up from sleep mode is set to: 0x1001f400
Ap--- PBM Config ---
0) 3 x 40 bytes
1) 14 x 128 bytes
2) 14 x 268 bytes
Total # PBMs: 31
# Reserved: 3, Tx: 21, Rx: 7
qvCHIP v1.4.32.1 ROMv0/0 (CL:0) r:3
t: 1
ResetCount[0]
[00:00:00.035][P][DL] BLEManagerImpl::Init() complete
[00:00:00.035][P][-] Initializing OpenThread stack
Stack register: OTHR
Unknown stack, register 0! new stackId: 0x0
[00:00:00.073][P][DL] OpenThread started: OK
[00:00:00.075][P][DL] Setting OpenThread device type to SLEEPY END DEVICE
[00:00:00.078][P][-] Starting OpenThread task
[00:00:00.085][P][-] Starting Platform Manager Event Loop
[00:00:00.090][P][-] ============================
[00:00:00.093][P][-] Qorvo DoorWindow-Sensor-app Launching
[00:00:00.098][P][-] ============================
[00:00:00.104][P][-] Current Software Version: 1.4-2.0.1
[00:00:00.108][P][DL] Device Configuration:
[00:00:00.113][P][DL]   Serial Number: 12345678
[00:00:00.117][P][DL]   Vendor Id: 65521 (0xFFF1)
[00:00:00.121][P][DL]   Product Id: 32775 (0x8007)
[00:00:00.126][P][DL]   Product Name: Qorvo QPG6200 DK Matter Contact
[00:00:00.130][P][DL]   Hardware Version: 1
[00:00:00.137][P][DL]   Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
[00:00:00.141][P][DL]   Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
[00:00:00.147][P][DL]   Manufacturing Date: 2022-12-13
[00:00:00.154][P][DL]   Device Type: 65535 (0xFFFF)
[00:00:00.160][P][SVR] SetupQRCode: [MT:ALG0242C00KA0648G00]
[00:00:00.164][P][SVR] Copy/paste the below URL in a browser to see the QR Code:
[00:00:00.169][P][SVR] https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3AALG0242C00KA0648G00
[00:00:00.177][P][SVR] Manual pairing code: [34970112332]
Initiated -> True
[00:00:00.186][P][-] UpdateClusterState StateValue::Set : 1
[00:00:00.192][P][ZCL] 0x100d7610 ep 1 clus 0x0000_0045 attr 0x0000_0000 not supported
[00:00:00.199][E][-] ERR: updating boolean status value 7f
[00:00:00.207][P][SVR] Initializing subscription resumption storage...
[00:00:00.216][P][SVR] Initializing subscription resumption storage...
[00:00:00.221][P][SVR] Server initializing...
[00:00:00.226][P][TS] Last Known Good Time: [unknown]
[00:00:00.229][P][TS] Setting Last Known Good Time to firmware build time 2023-10-14T01:16:48
[00:00:00.239][P][DMG] AccessControl: initializing
[00:00:00.242][P][DMG] Examples::AccessControlDelegate::Init
[00:00:00.247][P][DMG] AccessControl: setting
[00:00:00.252][P][DMG] DefaultAclStorage: initializing
[00:00:00.256][P][DMG] DefaultAclStorage: 0 entries loaded
[00:00:00.280][P][ZCL] Using ZAP configuration...
[00:00:00.285][P][DMG] AccessControlCluster: initializing
[00:00:00.286][P][ZCL] Initiating Admin Commissioning cluster.
[00:00:00.292][P][-] Setting log provider.
[00:00:00.296][P][ZCL] BooleanStateClusterInitCallback
[00:00:00.300][P][DIS] Updating services using commissioning mode 0
[00:00:00.305][E][DIS] Failed to remove advertised services: 3
[00:00:00.312][P][DIS] Advertise commission parameter vendorID=65521 productID=32775 discriminator=3840/15 cm=0 cp=0
[00:00:00.317][E][DIS] Failed to advertise extended commissionable node: 3
[00:00:00.327][E][DIS] Failed to finalize service update: 3
[00:00:00.334][P][IN] CASE Server enabling CASE session setups
[00:00:00.347][P][SVR] Server Listening...
[00:00:00.407][P][-] No fabrics, starting commissioning.
[00:00:00.407][E][DL] Long dispatch time: 200 ms, for event type 2
[00:00:00.412][P][-] StateValue::Get : 0
[00:00:00.418][P][DL] _OnPlatformEvent default:  event->Type = 32779
[00:00:00.423][P][-] UpdateClusterState StateValue::Set : 1
[00:00:00.428][P][ZCL] Cluster BooleanState: attribute StateValue set to 1
[00:00:00.444][P][DIS] Updating services using commissioning mode 1
[00:00:00.444][E][DIS] Failed to remove advertised services: 3
[00:00:00.451][P][DIS] Advertise commission parameter vendorID=65521 productID=32775 discriminator=3840/15 cm=1 cp=0
[00:00:00.456][E][DIS] Failed to advertise commissionable node: 3
[00:00:00.466][E][DIS] Failed to finalize service update: 3
[00:00:00.472][P][-] BLE advertising started. Waiting for Pairing.
[00:00:00.477][P][DL] CHIPoBLE start advertising
[00:00:00.491][P][DL] CHIPoBLE advertising started
[00:00:00.491][P][DL] _OnPlatformEvent default:  event->Type = 32781
[00:00:00.496][P][-] DoorWindow state changed to CLOSED
1 resets so far
[00:00:16.972][P][DL] BLE GATT connection established (con 1)
[00:00:16.974][P][DL] CHIPoBLE advertising stopped
[00:00:16.978][P][DL] _OnPlatformEvent default:  event->Type = 32781
[00:00:17.929][P][BLE] local and remote recv window sizes = 5
[00:00:17.930][P][BLE] selected BTP version 4
[00:00:17.933][P][BLE] using BTP fragment sizes rx 128 / tx 128.
[00:00:18.063][P][DL] Write request received for CHIPoBLE TX characteristic CCCD (con 1, len 0)
[00:00:18.064][P][DL] CHIPoBLE subscribe received
[00:00:18.075][P][DL] _OnPlatformEvent default:  event->Type = 32774
[00:00:18.158][P][EM] >>> [E:61293r S:0 M:149118352] (U) Msg RX from 0:37E31D84DED691D0 [0000] to 0000000000000000 --- Type 0000:20 (SecureChannel:PBKDFParamRequest) (B:98)
[00:00:18.162][P][EM] <<< [E:61293r S:0 M:228534692] (U) Msg TX from 0000000000000000 to 0:37E31D84DED691D0 [0000] [BLE] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) (B:170)
[00:00:18.176][P][SVR] Commissioning session establishment step started
[00:00:18.516][P][EM] >>> [E:61293r S:0 M:149118353] (U) Msg RX from 0:37E31D84DED691D0 [0000] to 0000000000000000 --- Type 0000:22 (SecureChannel:PASE_Pake1) (B:92)
[00:00:18.595][P][EM] <<< [E:61293r S:0 M:228534693] (U) Msg TX from 0000000000000000 to 0:37E31D84DED691D0 [0000] [BLE] --- Type 0000:23 (SecureChannel:PASE_Pake2) (B:127)
[00:00:18.874][P][EM] >>> [E:61293r S:0 M:149118354] (U) Msg RX from 0:37E31D84DED691D0 [0000] to 0000000000000000 --- Type 0000:24 (SecureChannel:PASE_Pake3) (B:59)
[00:00:18.877][P][EM] <<< [E:61293r S:0 M:228534694] (U) Msg TX from 0000000000000000 to 0:37E31D84DED691D0 [0000] [BLE] --- Type 0000:40 (SecureChannel:StatusReport) (B:30)
[00:00:18.895][P][SC] SecureSession[0x2000a1e0, LSID:12982]: State change 'kEstablishing' --> 'kActive'
[00:00:18.905][P][SVR] Commissioning completed session establishment step
[00:00:18.911][P][DIS] Updating services using commissioning mode 0
[00:00:18.920][E][DIS] Failed to remove advertised services: 3
[00:00:18.927][P][DIS] Advertise commission parameter vendorID=65521 productID=32775 discriminator=3840/15 cm=0 cp=0
[00:00:18.932][E][DIS] Failed to advertise extended commissionable node: 3
[00:00:18.943][E][DIS] Failed to finalize service update: 3
[00:00:18.949][P][SVR] Device completed Rendezvous process
[00:00:18.955][P][DL] _OnPlatformEvent default:  event->Type = 32792
[00:00:19.054][P][EM] >>> [E:61294r S:12982 M:20252964] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:19.059][P][EM] <<< [E:61294r S:12982 M:37035817] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:100)
[00:00:19.189][P][EM] >>> [E:61295r S:12982 M:20252965] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:52)
[00:00:19.193][P][EM] <<< [E:61295r S:12982 M:37035818] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
[00:00:19.325][P][EM] >>> [E:61296r S:12982 M:20252966] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:0A (IM:TimedRequest) (B:39)
[00:00:19.328][P][EM] <<< [E:61296r S:12982 M:37035819] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse) (B:38)
[00:00:19.459][P][EM] >>> [E:61296r S:12982 M:20252967] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:65)
[00:00:19.462][P][FS] GeneralCommissioning: Received ArmFailSafe (120s)
[00:00:19.477][P][EM] <<< [E:61296r S:12982 M:37035820] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
[00:00:19.551][P][EM] >>> [E:61297r S:12982 M:20252968] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:19.555][P][EM] <<< [E:61297r S:12982 M:37035821] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
[00:00:19.684][P][EM] >>> [E:61298r S:12982 M:20252969] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:19.688][P][EM] <<< [E:61298r S:12982 M:37035822] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
[00:00:19.819][P][EM] >>> [E:61299r S:12982 M:20252970] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:19.823][P][EM] <<< [E:61299r S:12982 M:37035823] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
[00:00:19.954][P][EM] >>> [E:61300r S:12982 M:20252971] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:19.958][P][EM] <<< [E:61300r S:12982 M:37035824] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
[00:00:20.088][P][EM] >>> [E:61301r S:12982 M:20252972] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:20.092][P][EM] <<< [E:61301r S:12982 M:37035825] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
[00:00:20.223][P][EM] >>> [E:61302r S:12982 M:20252973] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
[00:00:20.226][P][ZCL] OpCreds: Certificate Chain request received for DAC
[00:00:20.240][P][EM] <<< [E:61302r S:12982 M:37035826] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:547)
[00:00:20.898][P][EM] >>> [E:61303r S:12982 M:20252974] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:20.903][P][EM] <<< [E:61303r S:12982 M:37035827] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:100)
[00:00:21.033][P][EM] >>> [E:61304r S:12982 M:20252975] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:52)
[00:00:21.037][P][EM] <<< [E:61304r S:12982 M:37035828] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
[00:00:31.330][P][EM] >>> [E:61305r S:12982 M:20252976] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:0A (IM:TimedRequest) (B:39)
[00:00:31.332][P][EM] <<< [E:61305r S:12982 M:37035829] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse) (B:38)
[00:00:31.465][P][EM] >>> [E:61305r S:12982 M:20252977] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:65)
[00:00:31.468][P][FS] GeneralCommissioning: Received ArmFailSafe (120s)
[00:00:31.482][P][EM] <<< [E:61305r S:12982 M:37035830] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
[00:00:31.600][P][EM] >>> [E:61306r S:12982 M:20252978] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:31.604][P][EM] <<< [E:61306r S:12982 M:37035831] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
[00:00:31.735][P][EM] >>> [E:61307r S:12982 M:20252979] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:0A (IM:TimedRequest) (B:39)
[00:00:31.737][P][EM] <<< [E:61307r S:12982 M:37035832] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse) (B:38)
[00:00:31.870][P][EM] >>> [E:61307r S:12982 M:20252980] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:70)
[00:00:31.894][P][EM] <<< [E:61307r S:12982 M:37035833] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
[00:00:32.049][P][EM] >>> [E:61308r S:12982 M:20252981] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
[00:00:32.052][P][ZCL] OpCreds: Certificate Chain request received for PAI
[00:00:32.067][P][EM] <<< [E:61308r S:12982 M:37035834] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:507)
[00:00:32.814][P][EM] >>> [E:61309r S:12982 M:20252982] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:94)
[00:00:32.817][P][ZCL] OpCreds: Received an AttestationRequest command
[00:00:32.844][P][ZCL] OpCreds: AttestationRequest successful.
[00:00:32.847][P][EM] <<< [E:61309r S:12982 M:37035835] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:408)
[00:00:33.445][P][EM] >>> [E:61310r S:12982 M:20252983] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:96)
[00:00:33.448][P][ZCL] OpCreds: Received a CSRRequest command
[00:00:33.494][P][ZCL] OpCreds: AllocatePendingOperationalKey succeeded
[00:00:33.509][P][ZCL] OpCreds: CSRRequest successful.
[00:00:33.511][P][EM] <<< [E:61310r S:12982 M:37035836] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:375)
[00:00:34.702][P][EM] >>> [E:61311r S:12982 M:20252984] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:0A (IM:TimedRequest) (B:39)
[00:00:34.704][P][EM] <<< [E:61311r S:12982 M:37035837] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse) (B:38)
[00:00:34.836][P][EM] >>> [E:61311r S:12982 M:20252985] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:65)
[00:00:34.839][P][FS] GeneralCommissioning: Received ArmFailSafe (1s)
[00:00:34.854][P][EM] <<< [E:61311r S:12982 M:37035838] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
[00:00:35.852][P][FS] Fail-safe timer expired
[00:00:35.852][P][DL] _OnPlatformEvent default:  event->Type = 32784
[00:00:35.856][E][SVR] Failsafe timer expired
[00:00:35.862][P][SC] SecureSession[0x2000a1e0, LSID:12982]: State change 'kActive' --> 'kPendingEviction'
[00:00:35.866][E][SVR] Commissioning failed (attempt 1): 32
[00:00:35.876][P][BLE] Releasing end point's BLE connection back to application.
[00:00:35.888][P][DIS] Updating services using commissioning mode 1
[00:00:35.888][E][DIS] Failed to remove advertised services: 3
[00:00:35.895][P][DIS] Advertise commission parameter vendorID=65521 productID=32775 discriminator=3840/15 cm=1 cp=0
[00:00:35.900][E][DIS] Failed to advertise commissionable node: 3
[00:00:35.901][E][DIS] Failed to finalize service update: 3
[00:00:35.916][E][ZCL] OpCreds: Got FailSafeTimerExpired
[00:00:35.922][E][ZCL] OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry!
[00:00:35.927][P][TS] Pending Last Known Good Time: 2023-10-14T01:16:48
[00:00:35.935][P][TS] Previous Last Known Good Time: 2023-10-14T01:16:48
[00:00:35.941][P][TS] Reverted Last Known Good Time to previous value
[00:00:35.950][P][FS] Fail-safe cleanly disarmed
[00:00:36.005][P][DL] BLE GATT connection closed (con 1, reason 19)
[00:00:36.006][P][DL] CHIPoBLE start advertising
[00:00:36.019][P][DL] CHIPoBLE advertising started
[00:00:36.019][P][DL] _OnPlatformEvent default:  event->Type = 32781
[00:00:41.055][P][DL] BLE GATT connection established (con 1)
[00:00:41.056][P][DL] CHIPoBLE advertising stopped
[00:00:41.060][P][DL] _OnPlatformEvent default:  event->Type = 32781
[00:00:41.985][P][BLE] local and remote recv window sizes = 5
[00:00:41.986][P][BLE] selected BTP version 4
[00:00:41.991][P][BLE] using BTP fragment sizes rx 128 / tx 128.
[00:00:42.119][P][DL] Write request received for CHIPoBLE TX characteristic CCCD (con 1, len 0)
[00:00:42.120][P][DL] CHIPoBLE subscribe received
[00:00:42.130][P][DL] _OnPlatformEvent default:  event->Type = 32774
[00:00:42.214][P][EM] >>> [E:61312r S:0 M:149118355] (U) Msg RX from 0:941FBA85A14878C7 [0000] to 0000000000000000 --- Type 0000:20 (SecureChannel:PBKDFParamRequest) (B:98)
[00:00:42.218][P][EM] <<< [E:61312r S:0 M:228534695] (U) Msg TX from 0000000000000000 to 0:941FBA85A14878C7 [0000] [BLE] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) (B:170)
[00:00:42.223][P][SVR] Commissioning session establishment step started
[00:00:42.527][P][EM] >>> [E:61312r S:0 M:149118356] (U) Msg RX from 0:941FBA85A14878C7 [0000] to 0000000000000000 --- Type 0000:22 (SecureChannel:PASE_Pake1) (B:92)
[00:00:42.606][P][EM] <<< [E:61312r S:0 M:228534696] (U) Msg TX from 0000000000000000 to 0:941FBA85A14878C7 [0000] [BLE] --- Type 0000:23 (SecureChannel:PASE_Pake2) (B:127)
[00:00:42.885][P][EM] >>> [E:61312r S:0 M:149118357] (U) Msg RX from 0:941FBA85A14878C7 [0000] to 0000000000000000 --- Type 0000:24 (SecureChannel:PASE_Pake3) (B:59)
[00:00:42.888][P][EM] <<< [E:61312r S:0 M:228534697] (U) Msg TX from 0000000000000000 to 0:941FBA85A14878C7 [0000] [BLE] --- Type 0000:40 (SecureChannel:StatusReport) (B:30)
[00:00:42.906][P][SC] SecureSession[0x2000a1e0, LSID:12983]: State change 'kEstablishing' --> 'kActive'
[00:00:42.916][P][SVR] Commissioning completed session establishment step
[00:00:42.925][P][DIS] Updating services using commissioning mode 0
[00:00:42.931][E][DIS] Failed to remove advertised services: 3
[00:00:42.938][P][DIS] Advertise commission parameter vendorID=65521 productID=32775 discriminator=3840/15 cm=0 cp=0
[00:00:42.943][E][DIS] Failed to advertise extended commissionable node: 3
[00:00:42.953][E][DIS] Failed to finalize service update: 3
[00:00:42.959][P][SVR] Device completed Rendezvous process
[00:00:42.966][P][DL] _OnPlatformEvent default:  event->Type = 32792
[00:00:43.066][P][EM] >>> [E:61313r S:12983 M:161896006] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:43.071][P][EM] <<< [E:61313r S:12983 M:35257885] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:100)
[00:00:43.200][P][EM] >>> [E:61314r S:12983 M:161896007] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:52)
[00:00:43.204][P][EM] <<< [E:61314r S:12983 M:35257886] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
[00:00:43.335][P][EM] >>> [E:61315r S:12983 M:161896008] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:0A (IM:TimedRequest) (B:39)
[00:00:43.337][P][EM] <<< [E:61315r S:12983 M:35257887] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse) (B:38)
[00:00:43.470][P][EM] >>> [E:61315r S:12983 M:161896009] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:65)
[00:00:43.473][P][FS] GeneralCommissioning: Received ArmFailSafe (120s)
[00:00:43.487][P][EM] <<< [E:61315r S:12983 M:35257888] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
[00:00:43.562][P][EM] >>> [E:61316r S:12983 M:161896010] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:43.566][P][EM] <<< [E:61316r S:12983 M:35257889] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
[00:00:43.695][P][EM] >>> [E:61317r S:12983 M:161896011] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:43.699][P][EM] <<< [E:61317r S:12983 M:35257890] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
[00:00:43.830][P][EM] >>> [E:61318r S:12983 M:161896012] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:43.834][P][EM] <<< [E:61318r S:12983 M:35257891] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
[00:00:43.965][P][EM] >>> [E:61319r S:12983 M:161896013] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:43.969][P][EM] <<< [E:61319r S:12983 M:35257892] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
[00:00:44.099][P][EM] >>> [E:61320r S:12983 M:161896014] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:44.104][P][EM] <<< [E:61320r S:12983 M:35257893] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
[00:00:44.234][P][EM] >>> [E:61321r S:12983 M:161896015] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
[00:00:44.237][P][ZCL] OpCreds: Certificate Chain request received for DAC
[00:00:44.252][P][EM] <<< [E:61321r S:12983 M:35257894] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:547)
[00:00:44.954][P][EM] >>> [E:61322r S:12983 M:161896016] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:44.958][P][EM] <<< [E:61322r S:12983 M:35257895] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:100)
[00:00:45.089][P][EM] >>> [E:61323r S:12983 M:161896017] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:52)
[00:00:45.093][P][EM] <<< [E:61323r S:12983 M:35257896] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
[00:00:55.297][P][EM] >>> [E:61324r S:12983 M:161896018] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:0A (IM:TimedRequest) (B:39)
[00:00:55.299][P][EM] <<< [E:61324r S:12983 M:35257897] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse) (B:38)
[00:00:55.431][P][EM] >>> [E:61324r S:12983 M:161896019] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:65)
[00:00:55.434][P][FS] GeneralCommissioning: Received ArmFailSafe (120s)
[00:00:55.448][P][EM] <<< [E:61324r S:12983 M:35257898] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
[00:00:55.566][P][EM] >>> [E:61325r S:12983 M:161896020] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:00:55.570][P][EM] <<< [E:61325r S:12983 M:35257899] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
[00:00:55.791][P][EM] >>> [E:61326r S:12983 M:161896021] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:0A (IM:TimedRequest) (B:39)
[00:00:55.794][P][EM] <<< [E:61326r S:12983 M:35257900] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse) (B:38)
[00:00:55.925][P][EM] >>> [E:61326r S:12983 M:161896022] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:70)
[00:00:55.937][P][EM] <<< [E:61326r S:12983 M:35257901] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
[00:00:56.060][P][EM] >>> [E:61327r S:12983 M:161896023] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
[00:00:56.063][P][ZCL] OpCreds: Certificate Chain request received for PAI
[00:00:56.070][P][EM] <<< [E:61327r S:12983 M:35257902] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:507)
[00:00:56.735][P][EM] >>> [E:61328r S:12983 M:161896024] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:94)
[00:00:56.738][P][ZCL] OpCreds: Received an AttestationRequest command
[00:00:56.766][P][ZCL] OpCreds: AttestationRequest successful.
[00:00:56.768][P][EM] <<< [E:61328r S:12983 M:35257903] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:408)
[00:00:57.364][P][EM] >>> [E:61329r S:12983 M:161896025] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:96)
[00:00:57.367][P][ZCL] OpCreds: Received a CSRRequest command
[00:00:57.413][P][ZCL] OpCreds: AllocatePendingOperationalKey succeeded
[00:00:57.428][P][ZCL] OpCreds: CSRRequest successful.
[00:00:57.431][P][EM] <<< [E:61329r S:12983 M:35257904] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:376)
[00:00:59.796][P][EM] >>> [E:61330r S:12983 M:161896026] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:367)
[00:00:59.799][P][ZCL] OpCreds: Received an AddTrustedRootCertificate command
[00:00:59.831][P][ZCL] OpCreds: AddTrustedRootCertificate successful.
[00:00:59.833][P][EM] <<< [E:61330r S:12983 M:35257905] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:63)
[00:01:00.601][P][EM] >>> [E:61331r S:12983 M:161896027] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:713)
[00:01:00.604][P][ZCL] OpCreds: Received an AddNOC command
[00:01:00.627][P][FP] Validating NOC chain
[00:01:00.665][P][FP] NOC chain validation successful
[00:01:00.665][P][FP] Added new fabric at index: 0x1
[00:01:00.669][P][FP] Assigned compressed fabric ID: 0x5FEE8077DD1F6DC4, node ID: 0x0000000042D020D4
[00:01:00.675][P][TS] Last Known Good Time: 2023-10-14T01:16:48
[00:01:00.684][P][TS] New proposed Last Known Good Time: 2025-12-11T11:08:05
[00:01:00.690][P][TS] Updating pending Last Known Good Time to 2025-12-11T11:08:05
[00:01:00.759][P][ZCL] OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0xFFFFFFFD00010001
[00:01:00.760][P][DIS] Advertise operational node 5FEE8077DD1F6DC4-0000000042D020D4
[00:01:00.769][E][SVR] Operational advertising failed: 3
[00:01:00.777][P][ZCL] OpCreds: successfully created fabric index 0x1 via AddNOC
[00:01:00.783][P][EM] <<< [E:61331r S:12983 M:35257906] (S) Msg TX from 0000000000000000 to 1:FFFFFFFB00000000 [6DC4] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
[00:01:00.791][E][DL] Long dispatch time: 191 ms, for event type 7
[00:01:01.050][P][EM] >>> [E:61332r S:12983 M:161896028] (S) Msg RX from 1:FFFFFFFB00000000 [6DC4] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:171)
[00:01:01.056][P][EM] <<< [E:61332r S:12983 M:35257907] (S) Msg TX from 0000000000000000 to 1:FFFFFFFB00000000 [6DC4] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
[00:01:01.186][P][EM] >>> [E:61333r S:12983 M:161896029] (S) Msg RX from 1:FFFFFFFB00000000 [6DC4] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:01:01.190][P][EM] <<< [E:61333r S:12983 M:35257908] (S) Msg TX from 0000000000000000 to 1:FFFFFFFB00000000 [6DC4] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
[00:01:01.320][P][EM] >>> [E:61334r S:12983 M:161896030] (S) Msg RX from 1:FFFFFFFB00000000 [6DC4] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:73)
[00:01:01.364][P][DL] _OnPlatformEvent default:  event->Type = 32779
[00:01:02.953][P][DIS] Updating services using commissioning mode 0
[00:01:02.953][E][DIS] Failed to remove advertised services: 3
[00:01:02.959][P][DIS] Advertise operational node 5FEE8077DD1F6DC4-0000000042D020D4
[00:01:02.965][E][DIS] Failed to advertise operational node: 3
[00:01:02.972][E][DIS] Failed to finalize service update: 3
[00:01:03.709][P][DL] SRP Client was started, detected server: fdda:acff:9a70:0000:bb42:9982:181c:ef08
[00:01:03.711][P][DL] _OnPlatformEvent default:  event->Type = 32779
[00:01:03.719][P][ZCL] ThreadDiagnosticsDelegate: OnConnectionStatusChanged
[00:01:03.732][P][DL] _OnPlatformEvent default:  event->Type = 32769
[00:01:03.733][P][SVR] Joining Multicast groups
[00:01:03.743][P][EM] <<< [E:61334r S:12983 M:35257909] (S) Msg TX from 0000000000000000 to 1:FFFFFFFB00000000 [6DC4] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:65)
[00:01:03.747][P][DL] _OnPlatformEvent default:  event->Type = 32785
[00:01:03.760][P][DIS] Advertise operational node 5FEE8077DD1F6DC4-0000000042D020D4
[00:01:03.766][E][SVR] Operational advertising failed: 3
[00:01:03.774][P][DL] _OnPlatformEvent default:  event->Type = 32779
[00:01:04.659][P][DL] _OnPlatformEvent default:  event->Type = 32786
[00:01:04.659][P][SVR] Server initialization complete
[00:01:04.665][P][DIS] Updating services using commissioning mode 0
[00:01:04.670][P][DIS] Advertise operational node 5FEE8077DD1F6DC4-0000000042D020D4
[00:01:04.677][P][DL] advertising srp service: 5FEE8077DD1F6DC4-0000000042D020D4._matter._tcp
[00:01:04.698][P][DL] Initialising OTA Requestor
[00:01:04.699][P][SWU] Stopping the watchdog timer
[00:01:04.703][P][SWU] Starting the periodic query timer, timeout: 7200 seconds
[00:01:04.708][P][DL] _OnPlatformEvent default:  event->Type = 32790
[00:01:04.766][P][IM] No subscriptions to resume
[00:01:04.855][P][DL] BLE GATT connection closed (con 1, reason 19)
[00:01:06.094][P][EM] >>> [E:61335r S:0 M:149118358] (U) Msg RX from 0:24B449EC53F63A1D [0000] to 0000000000000000 --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196)
[00:01:06.095][P][IN] CASE Server received Sigma1 message . Starting handshake. EC 0x2000bb38
[00:01:06.110][P][EM] <<< [E:61335r S:0 M:228534698 (Ack:149118358)] (U) Msg TX from 0000000000000000 to 0:24B449EC53F63A1D [0000] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[00:01:06.119][P][SC] Received Sigma1 msg
[00:01:06.155][P][SC] CASE matched destination ID: fabricIndex 1, NodeID 0x0000000042D020D4
[00:01:06.198][P][EM] <<< [E:61335r S:0 M:228534699 (Ack:149118358)] (U) Msg TX from 0000000000000000 to 0:24B449EC53F63A1D [0000] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:894)
[00:01:06.200][P][EM] ??1 [E:61335r S:0 M:228534699] (U) Msg Retransmission to 0:0000000000000000 in 2411ms [State:Active II:500 AI:300 AT:4000]
[00:01:06.219][P][SC] Sent Sigma2 msg
[00:01:06.232][E][DL] Long dispatch time: 138 ms, for event type 3
[00:01:06.798][P][EM] >>> [E:61335r S:0 M:149118359 (Ack:228534699)] (U) Msg RX from 0:24B449EC53F63A1D [0000] to 0000000000000000 --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:744)
[00:01:06.800][P][EM] <<< [E:61335r S:0 M:228534700 (Ack:149118359)] (U) Msg TX from 0000000000000000 to 0:24B449EC53F63A1D [0000] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[00:01:06.815][P][SC] Received Sigma3 msg
[00:01:06.941][P][EM] <<< [E:61335r S:0 M:228534701 (Ack:149118359)] (U) Msg TX from 0000000000000000 to 0:24B449EC53F63A1D [0000] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
[00:01:06.943][P][EM] ??1 [E:61335r S:0 M:228534701] (U) Msg Retransmission to 0:0000000000000000 in 2336ms [State:Active II:500 AI:300 AT:4000]
[00:01:06.954][P][SC] SecureSession[0x2000a108, LSID:12981]: State change 'kEstablishing' --> 'kActive'
[00:01:06.976][P][IN] CASE Session established to peer: <0000000041EB19B6, 1>
[00:01:06.986][P][DL] _OnPlatformEvent default:  event->Type = 32792
[00:01:07.298][P][EM] >>> [E:61335r S:0 M:149118360 (Ack:228534701)] (U) Msg RX from 0:24B449EC53F63A1D [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[00:01:07.310][P][EM] >>> [E:61336r S:12981 M:182355354] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:01:07.318][P][EM] <<< [E:61336r S:12981 M:192194937 (Ack:182355354)] (S) Msg TX from 0000000042D020D4 to 1:0000000041EB19B6 [6DC4] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0001:05 (IM:ReportData) (B:70)
[00:01:07.330][P][EM] ??1 [E:61336r S:12981 M:192194937] (S) Msg Retransmission to 1:0000000041EB19B6 in 2387ms [State:Active II:500 AI:300 AT:4000]
[00:01:07.808][P][EM] >>> [E:61336r S:12981 M:182355355 (Ack:192194937)] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:07.820][P][EM] >>> [E:61337r S:12981 M:182355356] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:01:07.831][P][EM] <<< [E:61337r S:12981 M:192194938 (Ack:182355356)] (S) Msg TX from 0000000042D020D4 to 1:0000000041EB19B6 [6DC4] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0001:05 (IM:ReportData) (B:86)
[00:01:07.834][P][EM] ??1 [E:61337r S:12981 M:192194938] (S) Msg Retransmission to 1:0000000041EB19B6 in 2342ms [State:Active II:500 AI:300 AT:4000]
[00:01:08.323][P][EM] >>> [E:61337r S:12981 M:182355357 (Ack:192194938)] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:09.815][P][EM] >>> [E:61338r S:12981 M:182355358] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0001:02 (IM:ReadRequest) (B:51)
[00:01:09.820][P][EM] <<< [E:61338r S:12981 M:192194939 (Ack:182355358)] (S) Msg TX from 0000000042D020D4 to 1:0000000041EB19B6 [6DC4] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0001:05 (IM:ReportData) (B:78)
[00:01:09.831][P][EM] ??1 [E:61338r S:12981 M:192194939] (S) Msg Retransmission to 1:0000000041EB19B6 in 2341ms [State:Active II:500 AI:300 AT:4000]
[00:01:10.312][P][EM] >>> [E:61338r S:12981 M:182355359 (Ack:192194939)] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:10.346][P][EM] >>> [E:61339r S:12981 M:182355360] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0001:02 (IM:ReadRequest) (B:79)
[00:01:10.366][P][EM] <<< [E:61339r S:12981 M:192194940 (Ack:182355360)] (S) Msg TX from 0000000042D020D4 to 1:0000000041EB19B6 [6DC4] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0001:05 (IM:ReportData) (B:1209)
[00:01:10.369][P][EM] ??1 [E:61339r S:12981 M:192194940] (S) Msg Retransmission to 1:0000000041EB19B6 in 2353ms [State:Active II:500 AI:300 AT:4000]
[00:01:12.722][P][EM] <<1 [E:61339r S:12981 M:192194940] (S) Msg Retransmission to 1:0000000041EB19B6
[00:01:12.723][P][EM] ??2 [E:61339r S:12981 M:192194940] (S) Msg Retransmission to 1:0000000041EB19B6 in 2393ms [State:Active II:500 AI:300 AT:4000]
[00:01:13.410][P][EM] >>> [E:61339r S:12981 M:182355362 (Ack:192194940)] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:13.909][P][EM] >>> [E:61339r S:12981 M:182355361 (Ack:192194940)] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:13.911][P][IM] Received status response, status is 0x00
[00:01:13.937][P][EM] <<< [E:61339r S:12981 M:192194941 (Ack:182355361)] (S) Msg TX from 0000000042D020D4 to 1:0000000041EB19B6 [6DC4] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0001:05 (IM:ReportData) (B:1207)
[00:01:13.940][P][EM] ??1 [E:61339r S:12981 M:192194941] (S) Msg Retransmission to 1:0000000041EB19B6 in 2336ms [State:Active II:500 AI:300 AT:4000]
[00:01:14.394][P][EM] >>> [E:61339r S:12981 M:182355363 (Ack:192194941)] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:14.396][P][IM] Received status response, status is 0x00
[00:01:14.416][P][EM] <<< [E:61339r S:12981 M:192194942 (Ack:182355363)] (S) Msg TX from 0000000042D020D4 to 1:0000000041EB19B6 [6DC4] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0001:05 (IM:ReportData) (B:1189)
[00:01:14.419][P][EM] ??1 [E:61339r S:12981 M:192194942] (S) Msg Retransmission to 1:0000000041EB19B6 in 2341ms [State:Active II:500 AI:300 AT:4000]
[00:01:14.885][P][EM] >>> [E:61339r S:12981 M:182355364 (Ack:192194942)] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:14.886][P][IM] Received status response, status is 0x00
[00:01:14.913][P][EM] <<< [E:61339r S:12981 M:192194943 (Ack:182355364)] (S) Msg TX from 0000000042D020D4 to 1:0000000041EB19B6 [6DC4] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0001:05 (IM:ReportData) (B:1103)
[00:01:14.915][P][EM] ??1 [E:61339r S:12981 M:192194943] (S) Msg Retransmission to 1:0000000041EB19B6 in 2335ms [State:Active II:500 AI:300 AT:4000]
[00:01:15.364][P][EM] >>> [E:61339r S:12981 M:182355365 (Ack:192194943)] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:19.855][P][EM] >>> [E:61340r S:12981 M:182355366] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
[00:01:19.858][P][FS] GeneralCommissioning: Received CommissioningComplete
[00:01:19.910][P][FP] Metadata for Fabric 0x1 persisted to storage.
[00:01:20.006][P][TS] Committing Last Known Good Time to storage: 2025-12-11T11:08:05
[00:01:20.030][P][ZCL] OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0x5FEE8077DD1F6DC4, FabricId 1F893647E888551C, NodeId 0000000042D020D4, VendorId 0x6006
[00:01:20.040][P][FS] GeneralCommissioning: Successfully committed pending fabric data
[00:01:20.047][P][FS] Fail-safe cleanly disarmed
[00:01:20.057][P][EM] <<< [E:61340r S:12981 M:192194944 (Ack:182355366)] (S) Msg TX from 0000000042D020D4 to 1:0000000041EB19B6 [6DC4] [UDP:[fdda:acff:9a70:4cd5:fd77:ea90:d7b6:5694]:34128] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70)
[00:01:20.060][P][EM] ??1 [E:61340r S:12981 M:192194944] (S) Msg Retransmission to 1:0000000041EB19B6 in 2398ms [State:Active II:500 AI:300 AT:4000]
[00:01:20.081][E][DL] Long dispatch time: 228 ms, for event type 3
[00:01:20.094][P][EM] >>> [E:7140r S:0 M:144679442] (U) Msg RX from 0:10A10491D7B224C7 [0000] to 0000000000000000 --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196)
[00:01:20.100][P][IN] CASE Server received Sigma1 message . Starting handshake. EC 0x2000bb88
[00:01:20.115][P][EM] <<< [E:7140r S:0 M:228534702 (Ack:144679442)] (U) Msg TX from 0000000000000000 to 0:10A10491D7B224C7 [0000] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[00:01:20.123][P][SC] Received Sigma1 msg
[00:01:20.159][P][SC] CASE matched destination ID: fabricIndex 1, NodeID 0x0000000042D020D4
[00:01:20.214][P][EM] <<< [E:7140r S:0 M:228534703 (Ack:144679442)] (U) Msg TX from 0000000000000000 to 0:10A10491D7B224C7 [0000] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:894)
[00:01:20.217][P][EM] ??1 [E:7140r S:0 M:228534703] (U) Msg Retransmission to 0:0000000000000000 in 2385ms [State:Active II:500 AI:300 AT:4000]
[00:01:20.235][P][SC] Sent Sigma2 msg
[00:01:20.248][E][DL] Long dispatch time: 155 ms, for event type 3
[00:01:20.252][P][DL] _OnPlatformEvent default:  event->Type = 32783
[00:01:20.257][P][SWU] Device commissioned, schedule a default provider query
[00:01:20.264][P][SVR] Commissioning completed successfully
[00:01:20.271][P][DIS] Updating services using commissioning mode 0
[00:01:20.276][P][DIS] Advertise operational node 5FEE8077DD1F6DC4-0000000042D020D4
[00:01:20.283][P][SC] SecureSession[0x2000a1e0, LSID:12983]: State change 'kActive' --> 'kPendingEviction'
[00:01:20.418][P][EM] >>> [E:61340r S:12981 M:182355367 (Ack:192194944)] (S) Msg RX from 1:0000000041EB19B6 [6DC4] to 0000000042D020D4 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:21.020][P][EM] >>> [E:7140r S:0 M:144679443 (Ack:228534703)] (U) Msg RX from 0:10A10491D7B224C7 [0000] to 0000000000000000 --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:744)
[00:01:21.022][P][EM] <<< [E:7140r S:0 M:228534704 (Ack:144679443)] (U) Msg TX from 0000000000000000 to 0:10A10491D7B224C7 [0000] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[00:01:21.037][P][SC] Received Sigma3 msg
[00:01:21.148][P][EM] <<< [E:7140r S:0 M:228534705 (Ack:144679443)] (U) Msg TX from 0000000000000000 to 0:10A10491D7B224C7 [0000] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
[00:01:21.150][P][EM] ??1 [E:7140r S:0 M:228534705] (U) Msg Retransmission to 0:0000000000000000 in 2394ms [State:Active II:500 AI:300 AT:4000]
[00:01:21.173][P][SC] SecureSession[0x2000a2b8, LSID:12984]: State change 'kEstablishing' --> 'kActive'
[00:01:21.182][P][IN] CASE Session established to peer: <000000003343F767, 1>
[00:01:21.192][P][DL] _OnPlatformEvent default:  event->Type = 32792
[00:01:21.517][P][EM] >>> [E:7141r S:12984 M:182033227] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:03 (IM:SubscribeRequest) (B:57)
[00:01:21.656][P][DMG] Final negotiated min/max parameters: Min = 0s, Max = 1800s
[00:01:21.745][E][DL] Long dispatch time: 229 ms, for event type 3
[00:01:21.746][P][EM] >>> [E:7140r S:0 M:144679444 (Ack:228534705)] (U) Msg RX from 0:10A10491D7B224C7 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
[00:01:21.766][P][EM] <<< [E:7141r S:12984 M:129215785 (Ack:182033227)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:1204)
[00:01:21.769][P][EM] ??1 [E:7141r S:12984 M:129215785] (S) Msg Retransmission to 1:000000003343F767 in 2368ms [State:Active II:500 AI:300 AT:4000]
[00:01:22.018][P][EM] >>> [E:7141r S:12984 M:182033228 (Ack:129215785)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:22.019][P][IM] Received status response, status is 0x00
[00:01:22.050][P][EM] <<< [E:7141r S:12984 M:129215786 (Ack:182033228)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:1204)
[00:01:22.053][P][EM] ??1 [E:7141r S:12984 M:129215786] (S) Msg Retransmission to 1:000000003343F767 in 2383ms [State:Active II:500 AI:300 AT:4000]
[00:01:22.506][P][EM] >>> [E:7141r S:12984 M:182033229 (Ack:129215786)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:22.507][P][IM] Received status response, status is 0x00
[00:01:22.545][P][DL] GetActiveHardwareFaults
[00:01:22.546][P][DL] GetActiveRadioFaults
[00:01:22.550][P][DL] GetActiveNetworkFaults
[00:01:22.556][P][EM] <<< [E:7141r S:12984 M:129215787 (Ack:182033229)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:1194)
[00:01:22.560][P][EM] ??1 [E:7141r S:12984 M:129215787] (S) Msg Retransmission to 1:000000003343F767 in 2384ms [State:Active II:500 AI:300 AT:4000]
[00:01:22.990][P][EM] >>> [E:7141r S:12984 M:182033230 (Ack:129215787)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:22.991][P][IM] Received status response, status is 0x00
[00:01:23.006][P][DL] GetActiveNetworkFaults
[00:01:23.112][P][EM] <<< [E:7141r S:12984 M:129215788 (Ack:182033230)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:1185)
[00:01:23.115][P][EM] ??1 [E:7141r S:12984 M:129215788] (S) Msg Retransmission to 1:000000003343F767 in 2333ms [State:Active II:500 AI:300 AT:4000]
[00:01:23.133][E][DL] Long dispatch time: 127 ms, for event type 3
[00:01:23.475][P][EM] >>> [E:7141r S:12984 M:182033231 (Ack:129215788)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:23.477][P][IM] Received status response, status is 0x00
[00:01:23.673][P][EM] <<< [E:7141r S:12984 M:129215789 (Ack:182033231)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:1209)
[00:01:23.676][P][EM] ??1 [E:7141r S:12984 M:129215789] (S) Msg Retransmission to 1:000000003343F767 in 2358ms [State:Active II:500 AI:300 AT:4000]
[00:01:23.694][E][DL] Long dispatch time: 202 ms, for event type 3
[00:01:23.852][P][EM] >>> [E:7141r S:12984 M:182033232 (Ack:129215789)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:23.853][P][IM] Received status response, status is 0x00
[00:01:23.881][P][EM] <<< [E:7141r S:12984 M:129215790 (Ack:182033232)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:1170)
[00:01:23.884][P][EM] ??1 [E:7141r S:12984 M:129215790] (S) Msg Retransmission to 1:000000003343F767 in 2336ms [State:Active II:500 AI:300 AT:4000]
[00:01:24.343][P][EM] >>> [E:7141r S:12984 M:182033233 (Ack:129215790)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:24.344][P][IM] Received status response, status is 0x00
[00:01:24.376][P][DL] Get the fixed label with index:0 at endpoint:0
[00:01:24.377][P][DL] Get the fixed label with index:1 at endpoint:0
[00:01:24.383][P][DL] Get the fixed label with index:2 at endpoint:0
[00:01:24.389][P][DL] Get the fixed label with index:3 at endpoint:0
[00:01:24.400][P][EM] <<< [E:7141r S:12984 M:129215791 (Ack:182033233)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:1201)
[00:01:24.404][P][EM] ??1 [E:7141r S:12984 M:129215791] (S) Msg Retransmission to 1:000000003343F767 in 2371ms [State:Active II:500 AI:300 AT:4000]
[00:01:24.824][P][EM] >>> [E:7141r S:12984 M:182033234 (Ack:129215791)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:24.826][P][IM] Received status response, status is 0x00
[00:01:24.859][P][EM] <<< [E:7141r S:12984 M:129215792 (Ack:182033234)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:1202)
[00:01:24.862][P][EM] ??1 [E:7141r S:12984 M:129215792] (S) Msg Retransmission to 1:000000003343F767 in 2403ms [State:Active II:500 AI:300 AT:4000]
[00:01:25.311][P][EM] >>> [E:7141r S:12984 M:182033235 (Ack:129215792)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:25.312][P][IM] Received status response, status is 0x00
[00:01:25.335][P][EM] <<< [E:7141r S:12984 M:129215793 (Ack:182033235)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:378)
[00:01:25.337][P][EM] ??1 [E:7141r S:12984 M:129215793] (S) Msg Retransmission to 1:000000003343F767 in 2396ms [State:Active II:500 AI:300 AT:4000]
[00:01:25.804][P][EM] >>> [E:7141r S:12984 M:182033236 (Ack:129215793)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:25.807][P][IM] Received status response, status is 0x00
[00:01:25.828][P][EM] <<< [E:7141r S:12984 M:129215794 (Ack:182033236)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:04 (IM:SubscribeResponse) (B:49)
[00:01:25.831][P][EM] ??1 [E:7141r S:12984 M:129215794] (S) Msg Retransmission to 1:000000003343F767 in 2384ms [State:Active II:500 AI:300 AT:4000]
[00:01:25.846][P][DMG] Registered a ReadHandler that will schedule a report between system Timestamp: 0x0000000000014F56 and system Timestamp 0x00000000001CC696.
[00:01:25.864][P][EM] >>> [E:7142r S:12984 M:182033237] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:06 (IM:WriteRequest) (B:89)
[00:01:25.905][P][EM] <<< [E:7142r S:12984 M:129215795 (Ack:182033237)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:07 (IM:WriteResponse) (B:84)
[00:01:25.906][P][EM] ??1 [E:7142r S:12984 M:129215795] (S) Msg Retransmission to 1:000000003343F767 in 2353ms [State:Active II:500 AI:300 AT:4000]
[00:01:25.926][P][EM] >>> [E:7143r S:12984 M:182033238] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:08 (IM:InvokeCommandRequest) (B:75)
[00:01:25.941][P][SWU] OTA Requestor received AnnounceOTAProvider
[00:01:25.955][P][EM] <<< [E:7143r S:12984 M:129215796 (Ack:182033238)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
[00:01:25.960][P][EM] ??1 [E:7143r S:12984 M:129215796] (S) Msg Retransmission to 1:000000003343F767 in 2341ms [State:Active II:500 AI:300 AT:4000]
[00:01:25.994][P][EM] <<< [E:32190i S:12984 M:129215797] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:84)
[00:01:25.996][P][EM] ??1 [E:32190i S:12984 M:129215797] (S) Msg Retransmission to 1:000000003343F767 in 2394ms [State:Active II:500 AI:300 AT:4000]
[00:01:26.015][P][SWU] Stopping the Periodic Query timer
[00:01:26.026][P][SWU] Starting the watchdog timer, timeout: 21600 seconds
[00:01:26.032][P][DIS] Found an existing secure session to [1:000000003343F767]!
[00:01:26.043][P][EM] <<< [E:32191i S:12984 M:129215798] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:88)
[00:01:26.046][P][EM] ??1 [E:32191i S:12984 M:129215798] (S) Msg Retransmission to 1:000000003343F767 in 2403ms [State:Active II:500 AI:300 AT:4000]
[00:01:26.092][P][EM] >>> [E:7141r S:12984 M:182033239 (Ack:129215794)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:26.118][P][EM] >>> [E:7142r S:12984 M:182033240 (Ack:129215795)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:26.140][P][EM] >>> [E:7143r S:12984 M:182033241 (Ack:129215796)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:26.266][P][EM] >>> [E:32190i S:12984 M:182033242 (Ack:129215797)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:26.267][P][IM] Received status response, status is 0x00
[00:01:26.272][P][EM] <<< [E:32190i S:12984 M:129215799 (Ack:182033242)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:26.304][P][EM] <<< [E:32192i S:12984 M:129215800] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:116)
[00:01:26.310][P][EM] ??1 [E:32192i S:12984 M:129215800] (S) Msg Retransmission to 1:000000003343F767 in 2346ms [State:Active II:500 AI:300 AT:4000]
[00:01:26.850][P][EM] >>> [E:32192i S:12984 M:182033243 (Ack:129215800)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:26.851][P][IM] Received status response, status is 0x00
[00:01:26.868][P][EM] <<< [E:32192i S:12984 M:129215801 (Ack:182033243)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:26.874][P][EM] >>> [E:32191i S:12984 M:182033244 (Ack:129215798)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:29.356][P][EM] >>> [E:32191i S:12984 M:182033245 (Ack:129215798)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
[00:01:29.358][P][DMG] Received Command Response Data, Endpoint=2 Cluster=0x0000_0029 Command=0x0000_0001
[00:01:29.364][P][SWU] Stopping the watchdog timer
[00:01:29.383][P][SWU] Starting the periodic query timer, timeout: 7200 seconds
[00:01:29.389][P][EM] <<< [E:32191i S:12984 M:129215802 (Ack:182033245)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:29.411][P][EM] <<< [E:32193i S:12984 M:129215803] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:116)
[00:01:29.416][P][EM] ??1 [E:32193i S:12984 M:129215803] (S) Msg Retransmission to 1:000000003343F767 in 2351ms [State:Active II:500 AI:300 AT:4000]
[00:01:29.849][P][EM] >>> [E:32193i S:12984 M:182033246 (Ack:129215803)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:29.850][P][IM] Received status response, status is 0x00
[00:01:29.867][P][EM] <<< [E:32193i S:12984 M:129215804 (Ack:182033246)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:01:44.802][P][-] ButtonEventHandler 21, 1
[00:01:44.803][P][-] ButtonEventHandler signal detected
[00:01:44.807][P][-] DoorWindow state changed to CLOSED
[00:01:44.810][P][-] UpdateClusterState StateValue::Set : 1
[00:01:48.346][P][-] ButtonEventHandler 21, 0
[00:01:48.347][P][-] ButtonEventHandler signal lost
[00:01:48.351][P][-] DoorWindow state changed to OPEN
[00:01:48.356][P][-] UpdateClusterState StateValue::Set : 0
[00:01:48.361][P][ZCL] Cluster BooleanState: attribute StateValue set to 0
[00:01:48.386][P][EM] <<< [E:32194i S:12984 M:129215805] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:69)
[00:01:48.388][P][EM] ??1 [E:32194i S:12984 M:129215805] (S) Msg Retransmission to 1:000000003343F767 in 2627ms [State:Idle II:500 AI:300 AT:4000]
[00:01:49.014][P][EM] >>> [E:32194i S:12984 M:182033247 (Ack:129215805)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:01:49.015][P][IM] Received status response, status is 0x00
[00:01:49.032][P][EM] <<< [E:32194i S:12984 M:129215806 (Ack:182033247)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:05:23.723][P][-] ButtonEventHandler 21, 1
[00:05:23.724][P][-] ButtonEventHandler signal detected
[00:05:23.728][P][-] DoorWindow state changed to CLOSED
[00:05:23.733][P][-] UpdateClusterState StateValue::Set : 1
[00:05:23.738][P][ZCL] Cluster BooleanState: attribute StateValue set to 1
[00:05:23.764][P][EM] <<< [E:32195i S:12984 M:129215807] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:69)
[00:05:23.765][P][EM] ??1 [E:32195i S:12984 M:129215807] (S) Msg Retransmission to 1:000000003343F767 in 2653ms [State:Idle II:500 AI:300 AT:4000]
[00:05:24.298][P][EM] >>> [E:32195i S:12984 M:182033248 (Ack:129215807)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:05:24.299][P][IM] Received status response, status is 0x00
[00:05:24.316][P][EM] <<< [E:32195i S:12984 M:129215808 (Ack:182033248)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
[00:05:24.876][P][-] ButtonEventHandler 21, 0
[00:05:24.877][P][-] ButtonEventHandler signal lost
[00:05:24.881][P][-] DoorWindow state changed to OPEN
[00:05:24.886][P][-] UpdateClusterState StateValue::Set : 0
[00:05:24.891][P][ZCL] Cluster BooleanState: attribute StateValue set to 0
[00:05:24.911][P][EM] <<< [E:32196i S:12984 M:129215809] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0001:05 (IM:ReportData) (B:69)
[00:05:24.913][P][EM] ??1 [E:32196i S:12984 M:129215809] (S) Msg Retransmission to 1:000000003343F767 in 2374ms [State:Active II:500 AI:300 AT:4000]
[00:05:25.288][P][EM] >>> [E:32196i S:12984 M:182033249 (Ack:129215809)] (S) Msg RX from 1:000000003343F767 [6DC4] to 0000000042D020D4 --- Type 0001:01 (IM:StatusResponse) (B:42)
[00:05:25.289][P][IM] Received status response, status is 0x00
[00:05:25.306][P][EM] <<< [E:32196i S:12984 M:129215810 (Ack:182033249)] (S) Msg TX from 0000000042D020D4 to 1:000000003343F767 [6DC4] [UDP:[fdda:acff:9a70:0:bb42:9982:181c:ef08]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)