I: Factory Reset Triggered. Release button within 3000ms to cancel. I: 58044 [TS]Pending Last Known Good Time: 2024-01-16T17:23:49 I: 58050 [TS]Previous Last Known Good Time: 2024-01-16T17:23:49 I: 58056 [TS]Reverted Last Known Good Time to previous value I: 58062 [DL]Performing factory reset uart:~$ *** Booting nRF Connect SDK v2.5.1 *** I: Init CHIP stack I: 152 [DL]BLE address: F0:CF:25:9C:46:12 OK D: 182 [DL]WiFiManager has been initialized I: 187 [SVR]Subscription persistence not supported I: 191 [SVR]Server initializing... I: 194 [TS]Last Known Good Time: [unknown] I: 198 [TS]Setting Last Known Good Time to firmware build time 2024-01-16T17:23:49 I: 207 [DMG]AccessControl: initializing I: 210 [DMG]Examples::AccessControlDelegate::Init I: 215 [DMG]AccessControl: setting I: 218 [DMG]DefaultAclStorage: initializing I: 222 [DMG]DefaultAclStorage: 0 entries loaded D: 226 [IN]UDP::Init bind&listen port=5540 E: 230 [IN]SO_REUSEPORT failed: 109 D: 233 [IN]UDP::Init bound to port=5540 D: 236 [IN]BLEBase::Init - setting/overriding transport D: 241 [IN]TransportMgr initialized I: 248 [ZCL]Using ZAP configuration... I: 253 [DMG]AccessControlCluster: initializing D: 258 [DL]Boot reason: 6 I: 260 [ZCL]Initiating Admin Commissioning cluster. I: 265 [ZCL]Endpoint 1 On/off already set to new value I: 270 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1 D: 277 [IN]SecureSession[0x200041e0]: Allocated Type:1 LSID:41640 D: 283 [DL]CHIPoBLE advertising set to on I: 287 [DIS]Updating services using commissioning mode 1 I: 292 [DIS]CHIP minimal mDNS started advertising. D: 297 [DL]Using wifi MAC for hostname I: 300 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 D: 309 [DIS]Responding with _matterc._udp.local D: 313 [DIS]Responding with 069A85E02BE0EE81._matterc._udp.local D: 319 [DIS]Responding with F4CE36001DFE.local D: 323 [DIS]Responding with F4CE36001DFE.local D: 328 [DIS]Responding with _V65521._sub._matterc._udp.local D: 333 [DIS]Responding with _S15._sub._matterc._udp.local D: 338 [DIS]Responding with _L3840._sub._matterc._udp.local D: 344 [DIS]Responding with _CM._sub._matterc._udp.local D: 349 [DIS]Responding with 069A85E02BE0EE81._matterc._udp.local I: 354 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 069A85E02BE0EE81. I: 364 [DIS]mDNS service published: _matterc._udp I: 368 [DIS]Updating services using commissioning mode 1 I: 373 [DIS]CHIP minimal mDNS started advertising. D: 378 [DL]Using wifi MAC for hostname I: 382 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 D: 391 [DIS]Responding with _matterc._udp.local D: 395 [DIS]Responding with 069A85E02BE0EE81._matterc._udp.local D: 401 [DIS]Responding with F4CE36001DFE.local D: 405 [DIS]Responding with F4CE36001DFE.local D: 409 [DIS]Responding with _V65521._sub._matterc._udp.local D: 415 [DIS]Responding with _S15._sub._matterc._udp.local D: 420 [DIS]Responding with _L3840._sub._matterc._udp.local D: 425 [DIS]Responding with _CM._sub._matterc._udp.local D: 430 [DIS]Responding with 069A85E02BE0EE81._matterc._udp.local I: 436 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 069A85E02BE0EE81. I: 445 [DIS]mDNS service published: _matterc._udp I: 450 [IN]CASE Server enabling CASE session setups D: 455 [IN]SecureSession[0x20004298]: Allocated Type:2 LSID:41641 I: 461 [SVR]Joining Multicast groups I: 464 [SVR]Server Listening... I: 467 [DL]Device Configuration: I: 470 [DL] Serial Number: 11223344556677889900 I: 474 [DL] Vendor Id: 65521 (0xFFF1) I: 478 [DL] Product Id: 32773 (0x8005) I: 481 [DL] Product Name: not-specified I: 485 [DL] Hardware Version: 0 I: 488 [DL] Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 I: 493 [DL] Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00) I: 500 [DL] Manufacturing Date: 2022-01-01 I: 503 [DL] Device Type: 65535 (0xFFFF) I: 507 [SVR]SetupQRCode: [MT:6FCJ142C00KA0648G00] I: 512 [SVR]Copy/paste the below URL in a browser to see the QR Code: I: 518 [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A6FCJ142C00KA0648G00 I: 527 [SVR]Manual pairing code: [34970112332] I: 531 [DL]CHIP task running I: 538 [DL]CHIPoBLE advertising started I: 542 [ZCL]Cluster OnOff: attribute OnOff set to 0 I: 547 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 I: 553 [DL]NFC Tag emulation started I: 12427 [DL]BLE connection established (ConnId: 0x00) I: 12432 [DL]Current number of connections: 1/1 I: 12436 [DL]CHIPoBLE advertising stopped I: 12440 [DL]NFC Tag emulation stopped D: 13371 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 13379 [BLE]local and remote recv window sizes = 5 I: 13383 [BLE]selected BTP version 4 I: 13387 [BLE]using BTP fragment sizes rx 128 / tx 128. D: 13392 [DL]ConnId: 0x00, New CCCD value: 0x0002 D: 13397 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6) D: 13404 [IN]BLE EndPoint 0x2000ae00 Connection Complete I: 13409 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 131) D: 13500 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 13680 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 13688 [EM]>>> [E:13189r S:0 M:54730544] (U) Msg RX from 0:E121A0177141FF62 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) I: 13701 [EM]<<< [E:13189r S:0 M:181269441] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) I: 13713 [IN](U) Sending msg 181269441 to IP address 'BLE' D: 13718 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 13815 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 13823 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 14) D: 13905 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 13913 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 13921 [EM]>>> [E:13189r S:0 M:54730545] (U) Msg RX from 0:E121A0177141FF62 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) I: 15290 [EM]<<< [E:13189r S:0 M:181269442] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) I: 15301 [IN](U) Sending msg 181269442 to IP address 'BLE' D: 15306 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) E: 15314 [DL]Long dispatch time: 1401 ms, for event type 16388 D: 15434 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 15441 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6) D: 15569 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 15577 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 15584 [EM]>>> [E:13189r S:0 M:54730546] (U) Msg RX from 0:E121A0177141FF62 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3) I: 15596 [EM]<<< [E:13189r S:0 M:181269443] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 15608 [IN](U) Sending msg 181269443 to IP address 'BLE' D: 15613 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 35) I: 15621 [SC]SecureSession[0x200041e0]: Moving from state 'kEstablishing' --> 'kActive' D: 15629 [IN]SecureSession[0x200041e0]: Activated - Type:1 LSID:41640 D: 15635 [IN]New secure session activated for device , LSID:41640 PSID:43697! I: 15644 [SVR]Commissioning completed session establishment step I: 15650 [DIS]Updating services using commissioning mode 0 I: 15655 [DIS]CHIP minimal mDNS started advertising. D: 15660 [DL]CHIPoBLE advertising set to off I: 15665 [SVR]Device completed Rendezvous process D: 15704 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 15712 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 15720 [EM]>>> [E:13190r S:41640 M:78121246] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 15731 [IM]Received Read request I: 15736 [EM]<<< [E:13190r S:41640 M:213702686] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 15746 [IN](S) Sending msg 213702686 on secure session with LSID: 41640 D: 15753 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 97) D: 15839 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 15847 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 15855 [EM]>>> [E:13191r S:41640 M:78121247] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 15866 [IM]Received Read request I: 15870 [EM]<<< [E:13191r S:41640 M:213702687] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 15881 [IN](S) Sending msg 213702687 on secure session with LSID: 41640 D: 15888 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 15974 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 15982 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 15990 [EM]>>> [E:13192r S:41640 M:78121248] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 16001 [IM]Received Read request I: 16005 [EM]<<< [E:13192r S:41640 M:213702688] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 16016 [IN](S) Sending msg 213702688 on secure session with LSID: 41640 D: 16022 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 16109 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 16117 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 16125 [EM]>>> [E:13193r S:41640 M:78121249] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 16136 [IM]Received Read request I: 16140 [EM]<<< [E:13193r S:41640 M:213702689] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 16151 [IN](S) Sending msg 213702689 on secure session with LSID: 41640 D: 16158 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 16244 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 16252 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 16260 [EM]>>> [E:13194r S:41640 M:78121250] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 16271 [IM]Received Read request I: 16275 [EM]<<< [E:13194r S:41640 M:213702690] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 16286 [IN](S) Sending msg 213702690 on secure session with LSID: 41640 D: 16293 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 16379 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 16387 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 16395 [EM]>>> [E:13195r S:41640 M:78121251] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 16406 [IM]Received Read request I: 16410 [EM]<<< [E:13195r S:41640 M:213702691] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 16421 [IN](S) Sending msg 213702691 on secure session with LSID: 41640 D: 16427 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 16514 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 16522 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 16530 [EM]>>> [E:13196r S:41640 M:78121252] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 16541 [IM]Received Read request I: 16546 [EM]<<< [E:13196r S:41640 M:213702692] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 16556 [IN](S) Sending msg 213702692 on secure session with LSID: 41640 D: 16563 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 97) D: 16649 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 16657 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 16665 [EM]>>> [E:13197r S:41640 M:78121253] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 16676 [IM]Received Read request I: 16680 [EM]<<< [E:13197r S:41640 M:213702693] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 16691 [IN](S) Sending msg 213702693 on secure session with LSID: 41640 D: 16698 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 16784 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 16792 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 16800 [EM]>>> [E:13198r S:41640 M:78121254] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 16811 [IM]Received Read request I: 16815 [EM]<<< [E:13198r S:41640 M:213702694] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 16826 [IN](S) Sending msg 213702694 on secure session with LSID: 41640 D: 16832 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 16919 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 17774 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 17782 [EM]>>> [E:13199r S:41640 M:78121255] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest) I: 17794 [EM]<<< [E:13199r S:41640 M:213702695] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:01 (IM:StatusResponse) I: 17805 [IN](S) Sending msg 213702695 on secure session with LSID: 41640 D: 17811 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43) D: 17909 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 17917 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 17925 [EM]>>> [E:13199r S:41640 M:78121256] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 17937 [FS]GeneralCommissioning: Received ArmFailSafe (120s) I: 17944 [EM]<<< [E:13199r S:41640 M:213702696] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 17955 [IN](S) Sending msg 213702696 on secure session with LSID: 41640 D: 17962 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 18044 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 18052 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 18060 [EM]>>> [E:13200r S:41640 M:78121257] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 18071 [IM]Received Read request I: 18075 [EM]<<< [E:13200r S:41640 M:213702697] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 18086 [IN](S) Sending msg 213702697 on secure session with LSID: 41640 D: 18093 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 18224 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 18232 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 18240 [EM]>>> [E:13201r S:41640 M:78121258] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest) I: 18251 [EM]<<< [E:13201r S:41640 M:213702698] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:01 (IM:StatusResponse) I: 18262 [IN](S) Sending msg 213702698 on secure session with LSID: 41640 D: 18269 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43) D: 18359 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 18367 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 18375 [EM]>>> [E:13201r S:41640 M:78121259] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 18390 [EM]<<< [E:13201r S:41640 M:213702699] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 18401 [IN](S) Sending msg 213702699 on secure session with LSID: 41640 D: 18408 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 18539 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 18547 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 18555 [EM]>>> [E:13202r S:41640 M:78121260] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 18567 [ZCL]OpCreds: Certificate Chain request received for DAC I: 18576 [EM]<<< [E:13202r S:41640 M:213702700] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 18588 [IN](S) Sending msg 213702700 on secure session with LSID: 41640 D: 18594 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 18674 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 18682 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 18764 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 18772 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 18854 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 18862 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 18989 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 18997 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 19004 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 57) D: 19079 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 19087 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 19095 [EM]>>> [E:13203r S:41640 M:78121261] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 19107 [ZCL]OpCreds: Certificate Chain request received for PAI I: 19116 [EM]<<< [E:13203r S:41640 M:213702701] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 19128 [IN](S) Sending msg 213702701 on secure session with LSID: 41640 D: 19134 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 19214 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 19222 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 19304 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 19312 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 19394 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 19402 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 19484 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 19492 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 19499 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 28) D: 19574 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 19582 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 19590 [EM]>>> [E:13204r S:41640 M:78121262] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 19602 [ZCL]OpCreds: Received an AttestationRequest command I: 19637 [ZCL]OpCreds: AttestationRequest successful. I: 19646 [EM]<<< [E:13204r S:41640 M:213702702] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 19658 [IN](S) Sending msg 213702702 on secure session with LSID: 41640 D: 19664 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 19754 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 19762 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 19844 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 19852 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 19934 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 19942 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 20024 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 20032 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 20039 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 20114 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 20122 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 92) D: 20204 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 20212 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 20220 [EM]>>> [E:13205r S:41640 M:78121263] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 20232 [ZCL]OpCreds: Received a CSRRequest command I: 20377 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded I: 20412 [ZCL]OpCreds: CSRRequest successful. I: 20419 [EM]<<< [E:13205r S:41640 M:213702703] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 20430 [IN](S) Sending msg 213702703 on secure session with LSID: 41640 D: 20437 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) E: 20444 [DL]Long dispatch time: 232 ms, for event type 16388 D: 20519 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 20527 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 20609 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 20617 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 20699 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 21510 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 21600 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 21690 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 21699 [EM]>>> [E:13206r S:41640 M:78121264] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 21711 [ZCL]OpCreds: Received an AddTrustedRootCertificate command I: 21770 [ZCL]OpCreds: AddTrustedRootCertificate successful. I: 21776 [EM]<<< [E:13206r S:41640 M:213702704] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 21788 [IN](S) Sending msg 213702704 on secure session with LSID: 41640 D: 21795 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 68) E: 21802 [DL]Long dispatch time: 112 ms, for event type 16388 D: 21869 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 21877 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 21960 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 22050 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 22139 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 22146 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) I: 22156 [EM]>>> [E:13207r S:41640 M:78121265] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 22168 [ZCL]OpCreds: Received an AddNOC command I: 22175 [FP]Validating NOC chain I: 22233 [FP]NOC chain validation successful I: 22237 [FP]Added new fabric at index: 0x1 I: 22241 [FP]Assigned compressed fabric ID: 0x959848D4ABD510E9, node ID: 0x697FA623206985AE I: 22249 [TS]Last Known Good Time: 2024-01-16T17:23:49 I: 22254 [TS]New proposed Last Known Good Time: 2024-01-09T00:00:00 I: 22260 [TS]Retaining current Last Known Good Time I: 22272 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0xFFFFFFFD00010001 D: 22281 [DL]Using wifi MAC for hostname I: 22285 [DIS]Advertise operational node 959848D4ABD510E9-697FA623206985AE D: 22291 [DIS]Responding with _matter._tcp.local D: 22296 [DIS]Responding with 959848D4ABD510E9-697FA623206985AE._matter._tcp.local D: 22303 [DIS]Responding with 959848D4ABD510E9-697FA623206985AE._matter._tcp.local D: 22310 [DIS]Responding with F4CE36001DFE.local D: 22315 [DIS]Responding with F4CE36001DFE.local D: 22319 [DIS]Responding with _I959848D4ABD510E9._sub._matter._tcp.local I: 22326 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: 959848D4ABD510E9-697FA623206985AE. I: 22336 [DIS]mDNS service published: _matter._tcp I: 22341 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC I: 22348 [EM]<<< [E:13207r S:41640 M:213702705] (S) Msg TX to 1:FFFFFFFB00000000 [10E9] --- Type 0001:09 (IM:InvokeCommandResponse) I: 22359 [IN](S) Sending msg 213702705 on secure session with LSID: 41640 E: 22366 [DL]Long dispatch time: 227 ms, for event type 16388 D: 22371 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 22379 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 70) D: 22454 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 22462 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 22470 [EM]>>> [E:13208r S:41640 M:78121266] (S) Msg RX from 1:FFFFFFFB00000000 [10E9] --- Type 0001:08 (IM:InvokeCommandRequest) I: 22483 [EM]<<< [E:13208r S:41640 M:213702706] (S) Msg TX to 1:FFFFFFFB00000000 [10E9] --- Type 0001:09 (IM:InvokeCommandResponse) I: 22495 [IN](S) Sending msg 213702706 on secure session with LSID: 41640 D: 22501 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 22634 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 22642 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 22650 [EM]>>> [E:13209r S:41640 M:78121267] (S) Msg RX from 1:FFFFFFFB00000000 [10E9] --- Type 0001:02 (IM:ReadRequest) D: 22661 [IM]Received Read request I: 22665 [EM]<<< [E:13209r S:41640 M:213702707] (S) Msg TX to 1:FFFFFFFB00000000 [10E9] --- Type 0001:05 (IM:ReportData) I: 22676 [IN](S) Sending msg 213702707 on secure session with LSID: 41640 D: 22682 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 22769 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 22777 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 22785 [EM]>>> [E:13210r S:41640 M:78121268] (S) Msg RX from 1:FFFFFFFB00000000 [10E9] --- Type 0001:08 (IM:InvokeCommandRequest) D: 22797 [DL]Connecting to WiFi network: LAB1 D: 22804 [DL]WiFi scanning started... D: 25284 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 25379 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) I: 27415 [DL]Wi-Fi scan done (0) OK OK OK OK OK E: 27427 [DL]Connection request failed -1 I: 27432 [EM]<<< [E:13210r S:41640 M:213702708] (S) Msg TX to 1:FFFFFFFB00000000 [10E9] --- Type 0001:09 (IM:InvokeCommandResponse) I: 27443 [IN](S) Sending msg 213702708 on secure session with LSID: 41640 D: 27450 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 70) D: 27584 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 27592 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 27600 [EM]>>> [E:13211r S:41640 M:78121269] (S) Msg RX from 1:FFFFFFFB00000000 [10E9] --- Type 0001:0a (IM:TimedRequest) I: 27611 [EM]<<< [E:13211r S:41640 M:213702709] (S) Msg TX to 1:FFFFFFFB00000000 [10E9] --- Type 0001:01 (IM:StatusResponse) I: 27622 [IN](S) Sending msg 213702709 on secure session with LSID: 41640 D: 27629 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43) D: 27719 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 27727 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 27735 [EM]>>> [E:13211r S:41640 M:78121270] (S) Msg RX from 1:FFFFFFFB00000000 [10E9] --- Type 0001:08 (IM:InvokeCommandRequest) I: 27747 [FS]GeneralCommissioning: Received ArmFailSafe (1s) I: 27754 [EM]<<< [E:13211r S:41640 M:213702710] (S) Msg TX to 1:FFFFFFFB00000000 [10E9] --- Type 0001:09 (IM:InvokeCommandResponse) I: 27765 [IN](S) Sending msg 213702710 on secure session with LSID: 41640 D: 27772 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 27854 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) I: 28752 [FS]Fail-safe timer expired E: 28755 [SVR]Failsafe timer expired D: 28758 [IN]SecureSession[0x200041e0]: MarkForEviction Type:1 LSID:41640 I: 28765 [SC]SecureSession[0x200041e0]: Moving from state 'kActive' --> 'kPendingEviction' D: 28773 [IN]SecureSession[0x200041e0]: Released - Type:1 LSID:41640 E: 28779 [SVR]Commissioning failed (attempt 1): 32 D: 28783 [IN]Clearing BLE pending packets. I: 28787 [BLE]Releasing end point's BLE connection back to application. I: 28794 [DL]Closing BLE GATT connection (ConnId 00) D: 28799 [IN]SecureSession[0x200041e0]: Allocated Type:1 LSID:41642 D: 28805 [DL]CHIPoBLE advertising set to on I: 28809 [DIS]Updating services using commissioning mode 1 I: 28815 [DIS]CHIP minimal mDNS started advertising. D: 28820 [DL]Using wifi MAC for hostname I: 28823 [DIS]Advertise operational node 959848D4ABD510E9-697FA623206985AE D: 28830 [DIS]Responding with _matter._tcp.local D: 28834 [DIS]Responding with 959848D4ABD510E9-697FA623206985AE._matter._tcp.local D: 28842 [DIS]Responding with 959848D4ABD510E9-697FA623206985AE._matter._tcp.local D: 28849 [DIS]Responding with F4CE36001DFE.local D: 28854 [DIS]Responding with F4CE36001DFE.local D: 28858 [DIS]Responding with _I959848D4ABD510E9._sub._matter._tcp.local I: 28864 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: 959848D4ABD510E9-697FA623206985AE. I: 28875 [DIS]mDNS service published: _matter._tcp D: 28879 [DL]Using wifi MAC for hostname I: 28883 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 D: 28892 [DIS]Responding with _matterc._udp.local D: 28897 [DIS]Responding with 069A85E02BE0EE81._matterc._udp.local D: 28903 [DIS]Responding with F4CE36001DFE.local D: 28907 [DIS]Responding with F4CE36001DFE.local D: 28912 [DIS]Responding with _V65521._sub._matterc._udp.local D: 28917 [DIS]Responding with _S15._sub._matterc._udp.local D: 28922 [DIS]Responding with _L3840._sub._matterc._udp.local D: 28928 [DIS]Responding with _CM._sub._matterc._udp.local D: 28933 [DIS]Responding with 069A85E02BE0EE81._matterc._udp.local I: 28939 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 069A85E02BE0EE81. I: 28949 [DIS]mDNS service published: _matterc._udp E: 28953 [ZCL]OpCreds: Got FailSafeTimerExpired E: 28958 [ZCL]OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry! D: 28964 [IN]Expiring all sessions for fabric 0x1!! E: 28969 [FP]Reverting pending fabric data for fabric 0x1 E: 28974 [FP]Warning: metadata not found during delete of fabric 0x1 I: 28984 [FP]Fabric (0x1) deleted. I: 28987 [ZCL]OpCreds: Fabric index 0x1 was removed I: 28991 [DIS]Updating services using commissioning mode 1 I: 28997 [DIS]CHIP minimal mDNS started advertising. D: 29002 [DL]Using wifi MAC for hostname I: 29006 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 D: 29015 [DIS]Responding with _matterc._udp.local D: 29019 [DIS]Responding with 069A85E02BE0EE81._matterc._udp.local D: 29025 [DIS]Responding with F4CE36001DFE.local D: 29030 [DIS]Responding with F4CE36001DFE.local D: 29034 [DIS]Responding with _V65521._sub._matterc._udp.local D: 29040 [DIS]Responding with _S15._sub._matterc._udp.local D: 29045 [DIS]Responding with _L3840._sub._matterc._udp.local D: 29051 [DIS]Responding with _CM._sub._matterc._udp.local D: 29056 [DIS]Responding with 069A85E02BE0EE81._matterc._udp.local I: 29062 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 069A85E02BE0EE81. I: 29071 [DIS]mDNS service published: _matterc._udp I: 29085 [TS]Pending Last Known Good Time: 2024-01-16T17:23:49 I: 29091 [TS]Previous Last Known Good Time: 2024-01-16T17:23:49 I: 29097 [TS]Reverted Last Known Good Time to previous value E: 29103 [FP]Warning: metadata not found during delete of fabric 0x1 E: 29110 [ZCL]OpCreds: failed to delete fabric at index 1: d8 E: 29116 [DL]Long dispatch time: 361 ms, for event type 32782 I: 29121 [FS]Fail-safe cleanly disarmed I: 29125 [DL]BLE GAP connection terminated (reason 0x16) I: 29130 [DL]Current number of connections: 0/1 I: 29137 [DL]CHIPoBLE advertising started I: 29141 [DL]NFC Tag emulation started I: 29987 [TS]Pending Last Known Good Time: 2024-01-16T17:23:49 I: 29993 [TS]Previous Last Known Good Time: 2024-01-16T17:23:49 I: 29999 [TS]Reverted Last Known Good Time to previous value I: 30004 [DL]Performing factory reset uart:~$ *** Booting nRF Connect SDK v2.5.1 *** I: Init CHIP stack I: 152 [DL]BLE address: C3:4C:E6:59:06:4E OK D: 182 [DL]WiFiManager has been initialized I: 187 [SVR]Subscription persistence not supported I: 191 [SVR]Server initializing... I: 194 [TS]Last Known Good Time: [unknown] I: 198 [TS]Setting Last Known Good Time to firmware build time 2024-01-16T17:23:49 I: 207 [DMG]AccessControl: initializing I: 210 [DMG]Examples::AccessControlDelegate::Init I: 215 [DMG]AccessControl: setting I: 218 [DMG]DefaultAclStorage: initializing I: 222 [DMG]DefaultAclStorage: 0 entries loaded D: 226 [IN]UDP::Init bind&listen port=5540 E: 230 [IN]SO_REUSEPORT failed: 109 D: 233 [IN]UDP::Init bound to port=5540 D: 236 [IN]BLEBase::Init - setting/overriding transport D: 241 [IN]TransportMgr initialized I: 248 [ZCL]Using ZAP configuration... I: 253 [DMG]AccessControlCluster: initializing D: 257 [DL]Boot reason: 6 I: 260 [ZCL]Initiating Admin Commissioning cluster. I: 265 [ZCL]Endpoint 1 On/off already set to new value I: 270 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1 D: 277 [IN]SecureSession[0x200041e0]: Allocated Type:1 LSID:9247 D: 283 [DL]CHIPoBLE advertising set to on I: 287 [DIS]Updating services using commissioning mode 1 I: 292 [DIS]CHIP minimal mDNS started advertising. D: 297 [DL]Using wifi MAC for hostname I: 300 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 D: 309 [DIS]Responding with _matterc._udp.local D: 313 [DIS]Responding with BC75E38739EBDE9E._matterc._udp.local D: 319 [DIS]Responding with F4CE36001DFE.local D: 323 [DIS]Responding with F4CE36001DFE.local D: 327 [DIS]Responding with _V65521._sub._matterc._udp.local D: 333 [DIS]Responding with _S15._sub._matterc._udp.local D: 338 [DIS]Responding with _L3840._sub._matterc._udp.local D: 343 [DIS]Responding with _CM._sub._matterc._udp.local D: 349 [DIS]Responding with BC75E38739EBDE9E._matterc._udp.local I: 354 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: BC75E38739EBDE9E. I: 364 [DIS]mDNS service published: _matterc._udp I: 368 [DIS]Updating services using commissioning mode 1 I: 373 [DIS]CHIP minimal mDNS started advertising. D: 378 [DL]Using wifi MAC for hostname I: 382 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 D: 391 [DIS]Responding with _matterc._udp.local D: 395 [DIS]Responding with BC75E38739EBDE9E._matterc._udp.local D: 401 [DIS]Responding with F4CE36001DFE.local D: 405 [DIS]Responding with F4CE36001DFE.local D: 409 [DIS]Responding with _V65521._sub._matterc._udp.local D: 415 [DIS]Responding with _S15._sub._matterc._udp.local D: 420 [DIS]Responding with _L3840._sub._matterc._udp.local D: 425 [DIS]Responding with _CM._sub._matterc._udp.local D: 430 [DIS]Responding with BC75E38739EBDE9E._matterc._udp.local I: 436 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: BC75E38739EBDE9E. I: 445 [DIS]mDNS service published: _matterc._udp I: 450 [IN]CASE Server enabling CASE session setups D: 455 [IN]SecureSession[0x20004298]: Allocated Type:2 LSID:9248 I: 460 [SVR]Joining Multicast groups I: 464 [SVR]Server Listening... I: 467 [DL]Device Configuration: I: 470 [DL] Serial Number: 11223344556677889900 I: 474 [DL] Vendor Id: 65521 (0xFFF1) I: 477 [DL] Product Id: 32773 (0x8005) I: 481 [DL] Product Name: not-specified I: 485 [DL] Hardware Version: 0 I: 488 [DL] Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 I: 493 [DL] Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00) I: 499 [DL] Manufacturing Date: 2022-01-01 I: 503 [DL] Device Type: 65535 (0xFFFF) I: 507 [SVR]SetupQRCode: [MT:6FCJ142C00KA0648G00] I: 511 [SVR]Copy/paste the below URL in a browser to see the QR Code: I: 518 [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A6FCJ142C00KA0648G00 I: 526 [SVR]Manual pairing code: [34970112332] I: 531 [DL]CHIP task running I: 537 [DL]CHIPoBLE advertising started I: 542 [ZCL]Cluster OnOff: attribute OnOff set to 0 I: 547 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 I: 553 [DL]NFC Tag emulation started I: 30541 [DL]CHIPoBLE advertising mode changed to slow I: 30549 [DL]CHIPoBLE advertising started