With nrfconnect SDK V3.3.0 seeing commissioning issues with light bulb sample with nrf52840dk and google home. The logs are given below. Please help with resolution
Thanks,
Mahesh
uart:~$ *** Booting My Application v3.3.0-ba167d9f3db4 ***
*** Using nRF Connect SDK v3.3.0-ba167d9f3db4 ***
*** Using Zephyr OS v4.3.99-fd9204a02d52 ***
I: 64 [DL]BLE address: F7:1A:9D:CD:46:F8
I: 74 [DL]CHIP task running
I: Init CHIP stack
I: 79 [DL]OpenThread started: OK
I: 82 [DL]Setting OpenThread device type to ROUTER
I: 87 [DL]Found DAC Private Key in factory data set. Copying to secure storage...
D: Registered new test event: 0xffffffff00000000
D: Registered new test event: 0xffffffff10000000
I: 118 [SVR]Initializing subscription resumption storage...
I: 134 [SVR]Server initializing...
I: 137 [TS]Last Known Good Time: [unknown]
I: 141 [TS]Setting Last Known Good Time to firmware build time 2023-10-14T01:16:48
I: 160 [DMG]AccessControl: initializing
I: 164 [DMG]Examples::AccessControlDelegate::Init
I: 168 [DMG]AccessControl: setting
I: 171 [DMG]DefaultAclStorage: initializing
I: 175 [DMG]DefaultAclStorage: 0 entries loaded
D: 179 [IN]UDP::Init bind&listen port=5540
D: 183 [IN]UDP::Init bound to port=5540
D: 187 [IN]BLEBase::Init - setting/overriding transport
D: 192 [IN]TransportMgr initialized
D: 217 [DL]Using Thread extended MAC for hostname.
I: 234 [ZCL]Using ZAP configuration...
D: 239 [DL]Boot reason: 6
I: 243 [ZCL]Endpoint 1 On/off already set to new value
I: 251 [DMG]AccessControlCluster: initializing
D: 256 [IN]SecureSession[0x20013468]: Allocated Type:1 LSID:21078
D: 262 [DL]CHIPoBLE advertising set to on
I: 266 [DIS]Updating services using commissioning mode 1
E: 271 [DIS]Failed to remove advertised services: 3
D: 275 [DL]Using Thread extended MAC for hostname.
D: 280 [DIS]DNS-SD Pairing Instruction not set
I: 284 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 cp=0 jf=0
E: 294 [DIS]Failed to advertise commissionable node: 3
E: 299 [DIS]Failed to finalize service update: 3
I: 303 [DIS]Updating services using commissioning mode 1
E: 308 [DIS]Failed to remove advertised services: 3
D: 313 [DL]Using Thread extended MAC for hostname.
D: 317 [DIS]DNS-SD Pairing Instruction not set
I: 321 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 cp=0 jf=0
E: 331 [DIS]Failed to advertise commissionable node: 3
E: 336 [DIS]Failed to finalize service update: 3
I: 340 [IN]CASE Server enabling CASE session setups
D: 345 [IN]SecureSession[0x20013540]: Allocated Type:2 LSID:21079
I: 351 [SVR]Server Listening...
I: 354 [DL]Device Configuration:
I: 357 [DL] Serial Number: 11223344556677889900
I: 361 [DL] Vendor Id: 65521 (0xFFF1)
I: 365 [DL] Product Id: 32773 (0x8005)
I: 368 [DL] Product Name: Matter Light Bulb
I: 372 [DL] Hardware Version: 0
I: 375 [DL] Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
I: 380 [DL] Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
I: 387 [DL] Manufacturing Date: 2022-01-01
I: 391 [DL] Device Type: 65535 (0xFFFF)
I: 394 [SVR]SetupQRCode: [MT:6FCJ142C00KA0648G00]
I: 399 [SVR]Copy/paste the below URL in a browser to see the QR Code:
I: 405 [SVR]project-chip.github.io/.../qrcode.html
I: 414 [SVR]Manual pairing code: [34970112332]
E: 418 [DL]Long dispatch time: 342 ms, for event type 2
I: 425 [DL]CHIPoBLE advertising started
I: 429 [DL]NFC Tag emulation started
I: 30428 [DL]CHIPoBLE advertising mode changed to slow
I: 30435 [DL]CHIPoBLE advertising started
I: 90825 [DL]Current number of connections: 1/1
I: 90830 [DL]BLE connection established (ConnId: 0x00)
I: 90835 [DL]CHIPoBLE advertising stopped
I: 90838 [DL]NFC Tag emulation stopped
D: 91689 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 91696 [BLE]local and remote recv window sizes = 5
I: 91701 [BLE]selected BTP version 4
I: 91705 [BLE]using BTP fragment sizes rx 244 / tx 244.
D: 91712 [DL]ConnId: 0x00, New CCCD value: 0x0002
D: 91716 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6)
D: 91724 [IN]BLE EndPoint 0x2001dd14 Connection Complete
I: 91729 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 247)
D: 91794 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 91885 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 91892 [EM]>>> [E:15021r S:0 M:204189502] (U) Msg RX from 0:42448B12FFF0778C [0000] to 0000000000000000 --- Type 0000:20 (SecureChannel:PBKDFParamRequest) (B:98)
I: 91907 [EM]<<< [E:15021r S:0 M:199243272] (U) Msg TX from 0000000000000000 to 0:42448B12FFF0778C [0000] [BLE] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) (B:154)
D: 91923 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 159)
I: 91931 [SVR]Commissioning session establishment step started
D: 91974 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 91982 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 91990 [EM]>>> [E:15021r S:0 M:204189503] (U) Msg RX from 0:42448B12FFF0778C [0000] to 0000000000000000 --- Type 0000:22 (SecureChannel:PASE_Pake1) (B:92)
I: 92132 [EM]<<< [E:15021r S:0 M:199243273] (U) Msg TX from 0000000000000000 to 0:42448B12FFF0778C [0000] [BLE] --- Type 0000:23 (SecureChannel:PASE_Pake2) (B:127)
D: 92147 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 132)
E: 92155 [DL]Long dispatch time: 173 ms, for event type 16388
D: 92244 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 92252 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 92260 [EM]>>> [E:15021r S:0 M:204189504] (U) Msg RX from 0:42448B12FFF0778C [0000] to 0000000000000000 --- Type 0000:24 (SecureChannel:PASE_Pake3) (B:59)
I: 92274 [EM]<<< [E:15021r S:0 M:199243274] (U) Msg TX from 0000000000000000 to 0:42448B12FFF0778C [0000] [BLE] --- Type 0000:40 (SecureChannel:StatusReport) (B:30)
D: 92289 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 35)
I: 92298 [SC]SecureSession[0x20013468, LSID:21078]: State change 'kEstablishing' --> 'kActive'
D: 92306 [IN]SecureSession[0x20013468]: Activated - Type:1 LSID:21078
D: 92313 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:21078 PSID:64765!
I: 92321 [SVR]Commissioning completed session establishment step
I: 92327 [DIS]Updating services using commissioning mode 0
E: 92332 [DIS]Failed to remove advertised services: 3
E: 92337 [DIS]Failed to finalize service update: 3
D: 92342 [DL]CHIPoBLE advertising set to off
I: 92346 [SVR]Device completed Rendezvous process
D: 92379 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 92387 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 92395 [EM]>>> [E:15022r S:21078 M:250416044] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 92408 [IM]Received Read request
I: 92416 [EM]<<< [E:15022r S:21078 M:83365295] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:94)
D: 92429 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 99)
D: 92514 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 92522 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 92530 [EM]>>> [E:15023r S:21078 M:250416045] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:52)
D: 92543 [IM]Received Read request
I: 92550 [EM]<<< [E:15023r S:21078 M:83365296] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
D: 92564 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
D: 92649 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 92657 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 92665 [EM]>>> [E:15024r S:21078 M:250416046] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 92678 [IM]Received Read request
I: 92685 [EM]<<< [E:15024r S:21078 M:83365297] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
D: 92699 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
D: 92784 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 92792 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 92800 [EM]>>> [E:15025r S:21078 M:250416047] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 92813 [IM]Received Read request
I: 92820 [EM]<<< [E:15025r S:21078 M:83365298] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
D: 92834 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
D: 92919 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 92927 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 92935 [EM]>>> [E:15026r S:21078 M:250416048] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 92948 [IM]Received Read request
I: 92955 [EM]<<< [E:15026r S:21078 M:83365299] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
D: 92969 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 93054 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 93062 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 93070 [EM]>>> [E:15027r S:21078 M:250416049] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 93083 [IM]Received Read request
I: 93090 [EM]<<< [E:15027r S:21078 M:83365300] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
D: 93104 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 93189 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 93197 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 93205 [EM]>>> [E:15028r S:21078 M:250416050] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 93218 [IM]Received Read request
I: 93225 [EM]<<< [E:15028r S:21078 M:83365301] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
D: 93239 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 93324 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 93332 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 93340 [EM]>>> [E:15029r S:21078 M:250416051] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
I: 93355 [ZCL]OpCreds: Certificate Chain request received for DAC
I: 93364 [EM]<<< [E:15029r S:21078 M:83365302] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:556)
D: 93379 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 93504 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 93512 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 93594 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 93602 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 77)
D: 93684 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 93692 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 93700 [EM]>>> [E:15030r S:21078 M:250416052] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 93713 [IM]Received Read request
I: 93721 [EM]<<< [E:15030r S:21078 M:83365303] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:94)
D: 93734 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 99)
D: 93819 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 93827 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 93835 [EM]>>> [E:15031r S:21078 M:250416053] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:52)
D: 93848 [IM]Received Read request
I: 93855 [EM]<<< [E:15031r S:21078 M:83365304] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:67)
D: 93869 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
D: 93954 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 96474 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 98981 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 99039 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 101559 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 104066 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 104124 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 106644 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 109151 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 109209 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 111729 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 114236 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 114293 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 116814 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 119064 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 119072 [EM]>>> [E:15032r S:21078 M:250416054] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:0A (IM:TimedRequest) (B:39)
I: 119086 [EM]<<< [E:15032r S:21078 M:83365305] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse) (B:38)
D: 119100 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43)
D: 119153 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 119162 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 119170 [EM]>>> [E:15032r S:21078 M:250416055] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:65)
I: 119185 [FS]GeneralCommissioning: Received ArmFailSafe (120s)
I: 119192 [EM]<<< [E:15032r S:21078 M:83365306] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
D: 119207 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 119288 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 119297 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 119305 [EM]>>> [E:15033r S:21078 M:250416056] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 119318 [IM]Received Read request
I: 119325 [EM]<<< [E:15033r S:21078 M:83365307] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
D: 119338 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 119423 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 119432 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 119440 [EM]>>> [E:15034r S:21078 M:250416057] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:0A (IM:TimedRequest) (B:39)
I: 119454 [EM]<<< [E:15034r S:21078 M:83365308] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse) (B:38)
D: 119468 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43)
D: 119558 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 119567 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 119575 [EM]>>> [E:15034r S:21078 M:250416058] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:70)
I: 119623 [EM]<<< [E:15034r S:21078 M:83365309] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
D: 119638 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 119693 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 119702 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 119710 [EM]>>> [E:15035r S:21078 M:250416059] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
I: 119725 [ZCL]OpCreds: Certificate Chain request received for PAI
I: 119734 [EM]<<< [E:15035r S:21078 M:83365310] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:527)
D: 119749 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 119828 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 119836 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 119918 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 119926 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 48)
D: 120008 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 120055 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 120063 [EM]>>> [E:15036r S:21078 M:250416060] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:94)
I: 120078 [ZCL]OpCreds: Received an AttestationRequest command
I: 120115 [ZCL]OpCreds: AttestationRequest successful.
I: 120123 [EM]<<< [E:15036r S:21078 M:83365311] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:716)
D: 120138 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 120233 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 120241 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 120323 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 120331 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 237)
D: 120413 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 120422 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 120430 [EM]>>> [E:15037r S:21078 M:250416061] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:96)
I: 120445 [ZCL]OpCreds: Received a CSRRequest command
I: 120506 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded
I: 120542 [ZCL]OpCreds: CSRRequest successful.
I: 120549 [EM]<<< [E:15037r S:21078 M:83365312] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:393)
D: 120564 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
E: 120572 [DL]Long dispatch time: 150 ms, for event type 16388
D: 120683 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 120691 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 156)
D: 120773 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 121811 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 121900 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 121909 [EM]>>> [E:15038r S:21078 M:250416062] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:367)
I: 121924 [ZCL]OpCreds: Received an AddTrustedRootCertificate command
I: 121989 [ZCL]OpCreds: AddTrustedRootCertificate successful.
I: 121995 [EM]<<< [E:15038r S:21078 M:83365313] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:63)
D: 122010 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 68)
E: 122018 [DL]Long dispatch time: 118 ms, for event type 16388
D: 122078 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 122087 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 122171 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 122261 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 122349 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 122356 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
I: 122367 [EM]>>> [E:15039r S:21078 M:250416063] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:727)
I: 122382 [ZCL]OpCreds: Received an AddNOC command
I: 122389 [FP]Validating NOC chain
I: 122510 [FP]NOC chain validation successful
I: 122514 [FP]Added new fabric at index: 0x1
I: 122518 [FP]Assigned compressed fabric ID: 0x2DB644C9709A0F68, node ID: 0x00000000C5B85182
I: 122526 [TS]Last Known Good Time: 2023-10-14T01:16:48
I: 122531 [TS]New proposed Last Known Good Time: 2026-05-03T13:02:52
I: 122538 [TS]Updating pending Last Known Good Time to 2026-05-03T13:02:52
I: 122608 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0xFFFFFFFD00010001
D: 122617 [DL]Using Thread extended MAC for hostname.
I: 122622 [DIS]Advertise operational node 2DB644C9709A0F68-00000000C5B85182
E: 122629 [SVR]Operational advertising failed: 3
I: 122633 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC
I: 122641 [EM]<<< [E:15039r S:21078 M:83365314] (S) Msg TX from 0000000000000000 to 1:FFFFFFFB00000000 [0F68] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
E: 122655 [DL]Long dispatch time: 306 ms, for event type 16388
D: 122661 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 122669 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 70)
D: 122753 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 122762 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 122771 [EM]>>> [E:15040r S:21078 M:250416064] (S) Msg RX from 1:FFFFFFFB00000000 [0F68] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:169)
I: 122809 [EM]<<< [E:15040r S:21078 M:83365315] (S) Msg TX from 0000000000000000 to 1:FFFFFFFB00000000 [0F68] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:66)
D: 122824 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 122933 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 122942 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 122950 [EM]>>> [E:15041r S:21078 M:250416065] (S) Msg RX from 1:FFFFFFFB00000000 [0F68] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 122963 [IM]Received Read request
I: 122970 [EM]<<< [E:15041r S:21078 M:83365316] (S) Msg TX from 0000000000000000 to 1:FFFFFFFB00000000 [0F68] [BLE] --- Type 0001:05 (IM:ReportData) (B:66)
D: 122984 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 123068 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 123077 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 123085 [EM]>>> [E:15042r S:21078 M:250416066] (S) Msg RX from 1:FFFFFFFB00000000 [0F68] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:73)
E: 123189 [DL]Long dispatch time: 112 ms, for event type 16388
D: 123195 [DL]OpenThread State Changed (Flags: 0x101fc110)
D: 123200 [DL] Network Name: NEST-PAN-F1DB
D: 123205 [DL] PAN Id: 0xF1DB
D: 123207 [DL] Extended PAN Id: 0x10DDC2F5E438C68A
D: 123212 [DL] Channel: 23
D: 123215 [DL] Mesh Prefix: fd77:6ab4:f0a8:0:0:0:0:0/64
D: 123221 [DL]OpenThread State Changed (Flags: 0x1100100d)
D: 123227 [DL] Device Role: DETACHED
D: 123230 [DL] Thread Unicast Addresses:
D: 123234 [DL] fd77:6ab4:f0a8:0:70f9:5eb4:669f:3ae0/64 valid preferred
D: 123241 [DL] fe80:0:0:0:38a3:9895:1471:8a47/64 valid preferred
D: 125584 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 125678 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 128199 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 130238 [ZCL]ThreadDiagnosticsDelegate: OnConnectionStatusChanged
D: 130245 [DL]OpenThread State Changed (Flags: 0x100012a5)
D: 130250 [DL] Device Role: LEADER
D: 130253 [DL] Partition Id: 0x2FEAB95A
D: 130257 [DL] Thread Unicast Addresses:
D: 130261 [DL] fd77:6ab4:f0a8:0:0:ff:fe00:fc00/64 valid preferred
D: 130268 [DL] fd77:6ab4:f0a8:0:0:ff:fe00:2800/64 valid preferred rloc
D: 130275 [DL] fd77:6ab4:f0a8:0:70f9:5eb4:669f:3ae0/64 valid preferred
D: 130282 [DL] fe80:0:0:0:38a3:9895:1471:8a47/64 valid preferred
D: 130289 [DL]Thread Attached updating Multicast address
I: 130294 [SVR]Joining Multicast groups
I: 130299 [EM]<<< [E:15042r S:21078 M:83365317] (S) Msg TX from 0000000000000000 to 1:FFFFFFFB00000000 [0F68] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) (B:65)
D: 130314 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 70)
D: 130322 [DL]Using Thread extended MAC for hostname.
I: 130327 [DIS]Advertise operational node 2DB644C9709A0F68-00000000C5B85182
E: 130334 [SVR]Operational advertising failed: 3
D: 130403 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
I: 131438 [DL]Current number of connections: 0/1
I: 131443 [DL]BLE GAP connection terminated (reason 0x13)
E: 145322 [BLE]ack recv timeout, closing ep 0x2001dd14
D: 145327 [IN]Clearing BLE pending packets.
I: 145331 [BLE]Releasing end point's BLE connection back to application.
I: 145338 [DL]Closing BLE GATT connection (ConnId 00)
