Hi, when i use homepod mini to pairing the thread device using the matter light sample, it will be error. what is the problem?
>▒I: nRF5 802154 radio initialized I: 4 Sectors of 4096 bytes I: alloc wra: 0, d18 I: data wra: 0, 480 ooting Zephyr OS build v3.1.99-ncs1 *** I: Init CHIP stack 32muart:~$ : 229 [DL]BLE address: DF:FA:4F:C1:19:9F mI: SoftDevice Controller build revision: I: f2 e7 5f 6f 23 a2 f3 e8 |.._o#... I: 10 2f c3 35 9e d7 1d fe |./.5.... I: 8f 80 42 f9 |..B. I: 254 [DL]OpenThread started: OK I: 257 [DL]Setting OpenThread device type to ROUTER I: 262 [SVR]Server initializing... D: 266 [FP]Initializing FabricTable from persistent storage I: 272 [TS]Last Known Good Time: 2022-10-15T15:29:36 I: 278 [DMG]AccessControl: initializing I: 281 [DMG]Examples::AccessControlDelegate::Init I: 286 [DMG]AccessControl: setting I: 289 [DMG]DefaultAclStorage: initializing I: 293 [DMG]DefaultAclStorage: 0 entries loaded D: 297 [IN]UDP::Init bind&listen port=5540 E: 301 [IN]SO_REUSEPORT failed: 109 D: 304 [IN]UDP::Init bound to port=5540 D: 308 [IN]BLEBase::Init - setting/overriding transport D: 313 [IN]TransportMgr initialized D: 320 [DL]Using Thread extended MAC for hostname. I: 327 [ZCL]Using ZAP configuration... D: 332 [DMG]Failed to read stored attribute (0, 0x0000_0028, 0x0000_0005: a0 D: 339 [DMG]Failed to read stored attribute (0, 0x0000_0028, 0x0000_0010: a0 D: 348 [DMG]Failed to read stored attribute (1, 0x0000_0006, 0x0000_4003: a0 D: 357 [DMG]Failed to read stored attribute (1, 0x0000_0008, 0x0000_4000: a0 I: 363 [DMG]AccessControlCluster: initializing D: 368 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to 847332ee D: 374 [DL]Boot reason: 1 D: 376 [ZCL]GeneralDiagnostics: OnDeviceReboot D: 381 [DMG]Endpoint 0, Cluster 0x0000_0033 update version to aab75c34 D: 387 [EVL]LogEvent event number: 0x0000000000060000 priority: 2, endpoint id: 0x0 cluster id: 0x0000_0033 event id: 0x3 Sys timestamp: 0x0000000000000183 I: 401 [ZCL]Initiating Admin Commissioning cluster. D: 406 [DMG]Endpoint 0, Cluster 0x0000_0004 update version to aa9bd4ce D: 412 [DMG]Endpoint 0, Cluster 0x0000_0004 update version to aa9bd4cf D: 419 [DMG]Endpoint 1, Cluster 0x0000_0004 update version to 20c356b6 D: 425 [DMG]Endpoint 1, Cluster 0x0000_0004 update version to 20c356b7 D: 432 [DMG]Endpoint 1, Cluster 0x0000_0006 update version to 38573aba I: 439 [ZCL]Cluster OnOff: attribute OnOff set to 0 D: 444 [DMG]Endpoint 1, Cluster 0x0000_0008 update version to cd556d42 I: 450 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 I: 456 [ZCL]On/Off set value: 1 0 I: 459 [ZCL]On/off already set to new value D: 464 [DMG]Endpoint 1, Cluster 0x0000_0008 update version to cd556d43 I: 471 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 D: 479 [IN]SecureSession[0x200034f0]: Allocated Type:1 LSID:41701 D: 485 [SC]Assigned local session key ID 41701 D: 489 [SC]Waiting for PBKDF param request D: 493 [DL]CHIPoBLE advertising set to on I: 497 [DIS]Updating services using commissioning mode 1 D: 502 [DL]Using Thread extended MAC for hostname. D: 507 [DL]Using Thread extended MAC for hostname. I: 512 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 521 [DIS]Failed to advertise commissionable node: 3 E: 526 [DIS]Failed to finalize service update: 1c I: 531 [DIS]Updating services using commissioning mode 1 D: 536 [DL]Using Thread extended MAC for hostname. D: 541 [DL]Using Thread extended MAC for hostname. I: 546 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 555 [DIS]Failed to advertise commissionable node: 3 E: 560 [DIS]Failed to finalize service update: 1c I: 564 [DIS]Delaying proxy of operational discovery: missing delegate I: 571 [IN]CASE Server enabling CASE session setups D: 576 [IN]SecureSession[0x200035a8]: Allocated Type:2 LSID:41702 D: 581 [SC]Allocated SecureSession (0x200035a8) - waiting for Sigma1 msg I: 588 [SVR]Joining Multicast groups D: 591 [ZCL]Emitting StartUp event D: 595 [EVL]LogEvent event number: 0x0000000000060001 priority: 2, endpoint id: 0x0 cluster id: 0x0000_0028 event id: 0x0 Sys timestamp: 0x0000000000000252 I: 609 [SVR]Server Listening... I: 611 [DL]Device Configuration: I: 615 [DL] Serial Number: 11223344556677889900 I: 619 [DL] Vendor Id: 65521 (0xFFF1) I: 623 [DL] Product Id: 32773 (0x8005) I: 627 [DL] Hardware Version: 0 I: 631 [DL] Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 I: 637 [DL] Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00) I: 644 [DL] Manufacturing Date: (not set) I: 648 [DL] Device Type: 65535 (0xFFFF) I: 653 [SVR]SetupQRCode: [MT:6FCJ142C00KA0648G00] I: 657 [SVR]Copy/paste the below URL in a browser to see the QR Code: I: 663 [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A6FCJ142C00KA0648G00 I: 672 [SVR]Manual pairing code: [34970112332] I: 678 [DL]CHIP task running I: 683 [DL]CHIPoBLE advertising started I: 688 [DL]NFC Tag emulation started I: BLE advertising is already enabled I: 25729 [DL]BLE connection established (ConnId: 0x00) I: 25734 [DL]Current number of connections: 1/1 I: 25738 [DL]CHIPoBLE advertising stopped I: 25742 [DL]NFC Tag emulation stopped W: Ignoring data for unknown channel ID 0x003a D: 26730 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 26737 [BLE]local and remote recv window sizes = 5 I: 26742 [BLE]selected BTP version 4 I: 26745 [BLE]using BTP fragment sizes rx 244 / tx 244. D: 26850 [DL]ConnId: 0x00, New CCCD value: 0x0002 D: 26854 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6) D: 26862 [IN]BLE EndPoint 0x20011b80 Connection Complete I: 26867 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 247) D: 26909 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 26918 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 26925 [EM]Received message of type 0x20 with protocolId (0, 0) and MessageCounter:18737569 on exchange 41299r D: 26935 [EM]Handling via exchange: 41299r, Delegate: 0x200056d4 D: 26941 [SC]Received PBKDF param request D: 26945 [SC]Peer assigned session ID 26224 D: 26949 [SC]Found MRP parameters in the message D: 26954 [SC]Including MRP parameters in PBKDF param response I: 26960 [IN]Prepared unauthenticated message 0x2002e5a0 to 0x0000000000000000 (0) of type 0x21 and protocolId (0, 0) on exchange 41299r with MessageCounter:34100068. I: 26975 [IN]Sending unauthenticated msg 0x2002e5a0 with MessageCounter:34100068 to 0x0000000000000000 at monotonic time: 000000000000695F msec D: 26988 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 140) D: 26996 [SC]Sent PBKDF param response D: 27030 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 27090 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 27097 [EM]Received message of type 0x22 with protocolId (0, 0) and MessageCounter:18737570 on exchange 41299r D: 27107 [EM]Found matching exchange: 41299r, Delegate: 0x200056d4 D: 27113 [SC]Received spake2p msg1 I: 29124 [IN]Prepared unauthenticated message 0x2002e530 to 0x0000000000000000 (0) of type 0x23 and protocolId (0, 0) on exchange 41299r with MessageCounter:34100069. I: 29139 [IN]Sending unauthenticated msg 0x2002e530 with MessageCounter:34100069 to 0x0000000000000000 at monotonic time: 00000000000071D3 msec D: 29152 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 132) D: 29160 [SC]Sent spake2p msg2 E: 29163 [DL]Long dispatch time: 2072 ms, for event type 16388 D: 29220 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 29250 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 29257 [EM]Received message of type 0x24 with protocolId (0, 0) and MessageCounter:18737571 on exchange 41299r D: 29267 [EM]Found matching exchange: 41299r, Delegate: 0x200056d4 D: 29273 [SC]Received spake2p msg3 D: 29277 [SC]Sending status report. Protocol code 0, exchange 41299 I: 29283 [IN]Prepared unauthenticated message 0x2002e5b8 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 41299r with MessageCounter:34100070. I: 29298 [IN]Sending unauthenticated msg 0x2002e5b8 with MessageCounter:34100070 to 0x0000000000000000 at monotonic time: 0000000000007272 msec D: 29312 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 35) I: 29320 [SC]SecureSession[0x200034f0]: Moving from state 'kEstablishing' --> 'kActive' D: 29328 [IN]SecureSession[0x200034f0]: Activated - Type:1 LSID:41701 D: 29334 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:41701 PSID:26224! I: 29343 [SVR]Commissioning completed session establishment step I: 29349 [DIS]Updating services using commissioning mode 0 D: 29355 [DL]Using Thread extended MAC for hostname. D: 29360 [DL]Using Thread extended MAC for hostname. I: 29365 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 E: 29375 [DIS]Failed to advertise extended commissionable node: 3 D: 29381 [DIS]Scheduling extended discovery timeout in 900s E: 29386 [DIS]Failed to finalize service update: 1c D: 29391 [DL]CHIPoBLE advertising set to off I: 29395 [SVR]Device completed Rendezvous process E: 29400 [DL]Long dispatch time: 150 ms, for event type 16388 D: 29406 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 29414 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 29421 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:180494886 on exchange 41300r D: 29432 [EM]Handling via exchange: 41300r, Delegate: 0x2000f54c D: 29437 [IM]Received Read request D: 29441 [DMG]IM RH moving to [GeneratingReports] D: 29446 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 29455 [DMG]<RE:Run> Cluster 1d, Attribute 3 is dirty D: 29460 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0003 (expanded=0) D: 29469 [DMG]<RE> Sending report (payload has 64 bytes)... I: 29476 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 41300r with MessageCounter:187542539. I: 29490 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:187542539 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000007332 msec D: 29503 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 99) D: 29510 [DMG]<RE> OnReportConfirm: NumReports = 0 D: 29515 [DMG]<RE> ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 29522 [DMG]IM RH moving to [AwaitingDestruction] D: 29527 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 29550 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 29558 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 29566 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:180494887 on exchange 41301r D: 29576 [EM]Handling via exchange: 41301r, Delegate: 0x2000f54c D: 29582 [IM]Received Read request D: 29586 [DMG]IM RH moving to [GeneratingReports] D: 29590 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 29599 [DMG]<RE:Run> Cluster 1d, Attribute 1 is dirty D: 29604 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0001 (expanded=0) D: 29614 [DMG]<RE> Sending report (payload has 400 bytes)... I: 29622 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 41301r with MessageCounter:187542540. I: 29636 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:187542540 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000000073C4 msec D: 29649 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 29657 [DMG]<RE> OnReportConfirm: NumReports = 0 D: 29661 [DMG]<RE> ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 29669 [DMG]IM RH moving to [AwaitingDestruction] D: 29673 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 29699 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 29707 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 193) D: 29760 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 29768 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 29776 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:180494888 on exchange 41302r D: 29786 [EM]Handling via exchange: 41302r, Delegate: 0x2000f54c D: 29792 [IM]Received Read request D: 29796 [DMG]IM RH moving to [GeneratingReports] D: 29800 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 29809 [DMG]<RE:Run> Cluster 31, Attribute fffc is dirty D: 29814 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFC (expanded=0) D: 29824 [DMG]<RE> Sending report (payload has 37 bytes)... I: 29830 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 41302r with MessageCounter:187542541. I: 29844 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:187542541 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000007494 msec D: 29857 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 29865 [DMG]<RE> OnReportConfirm: NumReports = 0 D: 29869 [DMG]<RE> ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 29876 [DMG]IM RH moving to [AwaitingDestruction] D: 29881 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 29909 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 32100 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 32108 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:180494889 on exchange 41303r D: 32118 [EM]Handling via exchange: 41303r, Delegate: 0x2000f54c D: 32124 [IM]Received Read request D: 32130 [DMG]IM RH moving to [GeneratingReports] D: 32135 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 32144 [DMG]<RE:Run> Cluster 31, Attribute 3 is dirty D: 32149 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 32158 [DMG]<RE:Run> Cluster 28, Attribute 4 is dirty D: 32163 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0004 (expanded=0) D: 32172 [DMG]<RE:Run> Cluster 28, Attribute 2 is dirty D: 32177 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0002 (expanded=0) D: 32186 [DMG]<RE:Run> Cluster 30, Attribute 3 is dirty D: 32191 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0003 (expanded=0) D: 32200 [DMG]<RE:Run> Cluster 30, Attribute 2 is dirty D: 32205 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0002 (expanded=0) D: 32214 [DMG]<RE:Run> Cluster 30, Attribute 1 is dirty D: 32220 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0001 (expanded=0) D: 32229 [DMG]<RE:Run> Cluster 30, Attribute 0 is dirty D: 32234 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0000 (expanded=0) D: 32243 [DMG]<RE:Run> Cluster 31, Attribute fffc is dirty D: 32249 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 32258 [DMG]<RE> Sending report (payload has 228 bytes)... I: 32265 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 41303r with MessageCounter:187542542. I: 32279 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:187542542 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000007E17 msec D: 32292 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 32300 [DMG]<RE> OnReportConfirm: NumReports = 0 D: 32304 [DMG]<RE> ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 32312 [DMG]IM RH moving to [AwaitingDestruction] D: 32316 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 32399 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 32407 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 21) D: 32520 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 32528 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 32536 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494890 on exchange 41304r D: 32546 [EM]Handling via exchange: 41304r, Delegate: 0x2000f54c D: 32552 [DMG]Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0000 I: 32560 [FS]GeneralCommissioning: Received ArmFailSafe (60s) D: 32565 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to 847332ef D: 32572 [DMG]Command handler moving to [ Preparing] D: 32577 [DMG]Command handler moving to [AddingComm] D: 32582 [DMG]Command handler moving to [AddedComma] D: 32586 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 32594 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 41304r with MessageCounter:187542543. I: 32608 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:187542543 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000007F60 msec D: 32621 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 32628 [DMG]Command handler moving to [CommandSen] D: 32633 [DMG]Command handler moving to [AwaitingDe] E: 32638 [DL]Long dispatch time: 110 ms, for event type 16388 D: 32699 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 32708 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 32716 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494891 on exchange 41305r D: 32726 [EM]Handling via exchange: 41305r, Delegate: 0x2000f54c D: 32732 [DMG]Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0002 D: 32741 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to 847332f0 D: 32747 [DMG]Command handler moving to [ Preparing] D: 32752 [DMG]Command handler moving to [AddingComm] D: 32757 [DMG]Command handler moving to [AddedComma] D: 32762 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 32769 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 41305r with MessageCounter:187542544. I: 32783 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:187542544 to 0xFFFFFFFB00000000 (0) at monotonic time: 000000000000800F msec D: 32796 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 32804 [DMG]Command handler moving to [CommandSen] D: 32808 [DMG]Command handler moving to [AwaitingDe] E: 32813 [DL]Long dispatch time: 105 ms, for event type 16388 D: 32880 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 32888 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 32896 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494892 on exchange 41306r D: 32906 [EM]Handling via exchange: 41306r, Delegate: 0x2000f54c D: 32912 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002 I: 32920 [ZCL]OpCreds: Certificate Chain request received for PAI D: 32926 [DMG]Command handler moving to [ Preparing] D: 32930 [DMG]Command handler moving to [AddingComm] D: 32935 [DMG]Command handler moving to [AddedComma] D: 32940 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 32950 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 41306r with MessageCounter:187542545. I: 32964 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:187542545 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000000080C4 msec D: 32977 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 32984 [DMG]Command handler moving to [CommandSen] D: 32989 [DMG]Command handler moving to [AwaitingDe] E: 32994 [DL]Long dispatch time: 106 ms, for event type 16388 D: 33059 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 33067 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 33179 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 33187 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 48) D: 33300 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 33308 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 33316 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494893 on exchange 41307r D: 33326 [EM]Handling via exchange: 41307r, Delegate: 0x2000f54c D: 33332 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002 I: 33340 [ZCL]OpCreds: Certificate Chain request received for DAC D: 33346 [DMG]Command handler moving to [ Preparing] D: 33350 [DMG]Command handler moving to [AddingComm] D: 33355 [DMG]Command handler moving to [AddedComma] D: 33360 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 33370 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 41307r with MessageCounter:187542546. I: 33384 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:187542546 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000008268 msec D: 33397 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 33404 [DMG]Command handler moving to [CommandSen] D: 33409 [DMG]Command handler moving to [AwaitingDe] E: 33414 [DL]Long dispatch time: 106 ms, for event type 16388 D: 33479 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 33487 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 33599 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 33607 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 77) D: 33719 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 33728 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 33736 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494894 on exchange 41308r D: 33746 [EM]Handling via exchange: 41308r, Delegate: 0x2000f54c D: 33752 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0000 I: 33760 [ZCL]OpCreds: Received an AttestationRequest command I: 33796 [ZCL]OpCreds: AttestationRequest successful. D: 33801 [DMG]Command handler moving to [ Preparing] D: 33806 [DMG]Command handler moving to [AddingComm] D: 33810 [DMG]Command handler moving to [AddedComma] D: 33816 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 33826 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 41308r with MessageCounter:187542547. I: 33840 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:187542547 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000008430 msec D: 33853 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 33861 [DMG]Command handler moving to [CommandSen] D: 33865 [DMG]Command handler moving to [AwaitingDe] E: 33870 [DL]Long dispatch time: 143 ms, for event type 16388 D: 33959 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 33967 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 34079 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 34087 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 237) D: 34200 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 34208 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 34216 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494895 on exchange 41309r D: 34226 [EM]Handling via exchange: 41309r, Delegate: 0x2000f54c D: 34232 [DMG]Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0000 I: 34240 [FS]GeneralCommissioning: Received ArmFailSafe (60s) D: 34245 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to 847332f1 D: 34252 [DMG]Command handler moving to [ Preparing] D: 34257 [DMG]Command handler moving to [AddingComm] D: 34262 [DMG]Command handler moving to [AddedComma] D: 34267 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 34274 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 41309r with MessageCounter:187542548. I: 34288 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:187542548 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000000085F0 msec D: 34301 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 34308 [DMG]Command handler moving to [CommandSen] D: 34313 [DMG]Command handler moving to [AwaitingDe] E: 34318 [DL]Long dispatch time: 110 ms, for event type 16388 D: 34379 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 35880 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 35888 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494896 on exchange 41310r D: 35898 [EM]Handling via exchange: 41310r, Delegate: 0x2000f54c D: 35904 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0004 I: 35912 [ZCL]OpCreds: Received a CSRRequest command D: 35917 [ZCL]OpCreds: Finding fabric with fabricIndex 0x0 I: 36120 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded I: 36156 [ZCL]OpCreds: CSRRequest successful. D: 36160 [DMG]Command handler moving to [ Preparing] D: 36165 [DMG]Command handler moving to [AddingComm] D: 36169 [DMG]Command handler moving to [AddedComma] D: 36174 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 36183 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 41310r with MessageCounter:187542549. I: 36197 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:187542549 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000008D65 msec D: 36210 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 36218 [DMG]Command handler moving to [CommandSen] D: 36222 [DMG]Command handler moving to [AwaitingDe] E: 36227 [DL]Long dispatch time: 347 ms, for event type 16388 D: 36299 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 36307 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 139) D: 36419 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 36428 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 36540 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 36549 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494897 on exchange 41311r D: 36559 [EM]Handling via exchange: 41311r, Delegate: 0x2000f54c D: 36565 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B I: 36573 [ZCL]OpCreds: Received an AddTrustedRootCertificate command I: 36636 [ZCL]OpCreds: AddTrustedRootCertificate successful. D: 36642 [DMG]Command handler moving to [ Preparing] D: 36646 [DMG]Command handler moving to [AddingComm] D: 36651 [DMG]Command handler moving to [AddedComma] D: 36656 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 36664 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 41311r with MessageCounter:187542550. I: 36678 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:187542550 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000008F46 msec D: 36691 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 68) D: 36698 [DMG]Command handler moving to [CommandSen] D: 36703 [DMG]Command handler moving to [AwaitingDe] E: 36708 [DL]Long dispatch time: 168 ms, for event type 16388 D: 36779 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 36788 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 36900 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 36909 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494898 on exchange 41312r D: 36919 [EM]Handling via exchange: 41312r, Delegate: 0x2000f54c D: 36926 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0006 I: 36933 [ZCL]OpCreds: Received an AddNOC command I: 36941 [FP]Validating NOC chain I: 37002 [FP]NOC chain validation successful I: 37006 [FP]Added new fabric at index: 0x1 I: 37010 [FP]Assigned compressed fabric ID: 0x97C74B46E6F0035E, node ID: 0x00000000DB9DEF91 I: 37018 [TS]Last Known Good Time: 2022-10-15T15:29:36 I: 37024 [TS]New proposed Last Known Good Time: 2022-10-15T14:48:22 I: 37030 [TS]Retaining current Last Known Good Time D: 37035 [DMG]Endpoint 0, Cluster 0x0000_003E update version to 4184ba5f D: 37041 [DMG]Endpoint 0, Cluster 0x0000_003E update version to 4184ba60 D: 37057 [EVL]LogEvent event number: 0x0000000000060002 priority: 1, endpoint id: 0x0 cluster id: 0x0000_001F event id: 0x0 Sys timestamp: 0x00000000000090C1 I: 37072 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x88D90B0E4A8E5D9A D: 37080 [DL]Using Thread extended MAC for hostname. I: 37085 [DIS]Advertise operational node 97C74B46E6F0035E-00000000DB9DEF91 D: 37092 [DMG]Endpoint 0, Cluster 0x0000_003E update version to 4184ba61 D: 37099 [DMG]Endpoint 0, Cluster 0x0000_003E update version to 4184ba62 D: 37105 [DMG]Command handler moving to [ Preparing] D: 37110 [DMG]Command handler moving to [AddingComm] D: 37115 [DMG]Command handler moving to [AddedComma] I: 37119 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC D: 37126 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 37133 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (1) of type 0x9 and protocolId (0, 1) on exchange 41312r with MessageCounter:187542551. I: 37148 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:187542551 to 0xFFFFFFFB00000000 (1) at monotonic time: 000000000000911C msec D: 37160 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 37168 [DMG]Command handler moving to [CommandSen] D: 37173 [DMG]Command handler moving to [AwaitingDe] E: 37178 [DL]Long dispatch time: 278 ms, for event type 16388 D: 37259 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 37267 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 37276 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494899 on exchange 41313r D: 37286 [EM]Handling via exchange: 41313r, Delegate: 0x2000f54c D: 37292 [DMG]Received command for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0003 D: 37304 [DMG]Command handler moving to [ Preparing] D: 37309 [DMG]Command handler moving to [AddingComm] D: 37314 [DMG]Command handler moving to [AddedComma] D: 37319 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to 847332f2 D: 37326 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 37333 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (1) of type 0x9 and protocolId (0, 1) on exchange 41313r with MessageCounter:187542552. I: 37347 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:187542552 to 0xFFFFFFFB00000000 (1) at monotonic time: 00000000000091E3 msec D: 37360 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 37368 [DMG]Command handler moving to [CommandSen] D: 37372 [DMG]Command handler moving to [AwaitingDe] E: 37377 [DL]Long dispatch time: 110 ms, for event type 16388 D: 37439 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 37447 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 37455 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:180494900 on exchange 41314r D: 37466 [EM]Handling via exchange: 41314r, Delegate: 0x2000f54c D: 37472 [DMG]Received command for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0006 D: 37515 [DMG]Decreasing reference count for CommandHandler, remaining 1 D: 37522 [DL]OpenThread State Changed (Flags: 0x100fc100) D: 37527 [DL] Network Name: MyHome1873482962 D: 37531 [DL] PAN Id: 0xDC46 D: 37534 [DL] Extended PAN Id: 0xC96C74D73EE34C44 D: 37539 [DL] Channel: 25 D: 37541 [DL] Mesh Prefix: fdde:ad00:beef::/64 D: 37547 [DL]OpenThread State Changed (Flags: 0x0100103d) D: 37552 [DL] Device Role: DETACHED D: 37555 [DL] Thread Unicast Addresses: D: 37560 [DL] fdde:ad00:beef::ff:fe00:6000/64 valid rloc D: 37566 [DL] fdde:ad00:beef:0:f533:f413:742c:197e/64 valid D: 37572 [DL] fe80::6484:9a0c:7119:4cd8/64 valid preferred D: 39109 [DL]OpenThread State Changed (Flags: 0x10000040) D: 39955 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 40079 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) I: 40094 [DL]SRP Client was started, detected server: fdd4:4ac1:649a:0000:fbd6:a9c0:5752:00c6 D: 40103 [DL]OpenThread State Changed (Flags: 0x301032b7) D: 40144 [DL] Device Role: CHILD D: 40148 [DL] Partition Id: 0x3BB7E9A5 D: 40152 [DL] Thread Unicast Addresses: D: 40156 [DL] fdd4:4ac1:649a:0:237f:a8b6:31f2:bcd5/64 valid preferred D: 40163 [DL] fdc9:6c74:d73e::ff:fe00:7002/64 valid rloc D: 40171 [DL] fdc9:6c74:d73e:0:f533:f413:742c:197e/64 valid D: 40177 [DL] fe80::6484:9a0c:7119:4cd8/64 valid preferred D: 40185 [DL]OpenThread State Changed (Flags: 0x00000001) D: 40191 [DL] Thread Unicast Addresses: D: 40195 [DL] fdd4:4ac1:649a:0:237f:a8b6:31f2:bcd5/64 valid preferred D: 40204 [DL] fdc9:6c74:d73e::ff:fe00:7002/64 valid rloc D: 40210 [DL] fdc9:6c74:d73e:0:f533:f413:742c:197e/64 valid D: 40216 [DL] fe80::6484:9a0c:7119:4cd8/64 valid preferred D: 40224 [DMG]Command handler moving to [ Preparing] D: 40228 [DMG]Command handler moving to [AddingComm] D: 40233 [DMG]Command handler moving to [AddedComma] D: 40238 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to 847332f3 D: 40244 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 40252 [IN]Prepared secure message 0x2002e808 to 0xFFFFFFFB00000000 (1) of type 0x9 and protocolId (0, 1) on exchange 41314r with MessageCounter:187542553. I: 40266 [IN]Sending encrypted msg 0x2002e808 with MessageCounter:187542553 to 0xFFFFFFFB00000000 (1) at monotonic time: 0000000000009D4A msec D: 40280 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 69) D: 40288 [DL]SRP update succeeded I: 40291 [DIS]Setting operational delegate post init D: 40297 [DMG]Command handler moving to [CommandSen] D: 40301 [DMG]Command handler moving to [AwaitingDe] D: 40306 [DL]Using Thread extended MAC for hostname. I: 40311 [DIS]Advertise operational node 97C74B46E6F0035E-00000000DB9DEF91 I: 40318 [DL]advertising srp service: 97C74B46E6F0035E-00000000DB9DEF91._matter._tcp I: 40326 [SVR]Operational advertising enabled I: 40333 [SWU]Stopping the watchdog timer I: 40373 [SWU]Starting the periodic query timer, timeout: 86400 seconds I: 40381 [DIS]Updating services using commissioning mode 0 D: 40386 [DL]Using Thread extended MAC for hostname. I: 40392 [DIS]Advertise operational node 97C74B46E6F0035E-00000000DB9DEF91 D: 40399 [DL]Using Thread extended MAC for hostname. I: 40406 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 I: 40416 [DL]advertising srp service: B540ED28DE4C01D8._matterc._udp D: 40423 [DMG]Endpoint 0, Cluster 0x0000_002A update version to f85f0379 D: 40430 [DMG]Endpoint 0, Cluster 0x0000_002A update version to f85f037a D: 40474 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) I: 41483 [EM]Received message of type 0x30 with protocolId (0, 0) and MessageCounter:18737572 on exchange 41315r D: 41493 [EM]Handling via exchange: 41315r, Delegate: 0x20004160 I: 41499 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20004e70 I: 41506 [IN]CASE Server disabling CASE session setups D: 41511 [EM]Sending Standalone Ack for MessageCounter:18737572 on exchange 41315r I: 41519 [IN]Prepared unauthenticated message 0x2002e4d8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 41315r with MessageCounter:34100071. I: 41534 [IN]Sending unauthenticated msg 0x2002e4d8 with MessageCounter:34100071 to 0x0000000000000000 at monotonic time: 000000000000A23E msec D: 41547 [EM]Flushed pending ack for MessageCounter:18737572 on exchange 41315r I: 41555 [SC]Received Sigma1 msg D: 41558 [SC]Found MRP parameters in the message D: 41563 [SC]Peer assigned session key ID 26225 I: 41570 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x00000000DB9DEF91 D: 41801 [SC]Including MRP parameters I: 41805 [IN]Prepared unauthenticated message 0x20005124 to 0x0000000000000000 (0) of type 0x31 and protocolId (0, 0) on exchange 41315r with MessageCounter:34100072. I: 41820 [IN]Sending unauthenticated msg 0x20005124 with MessageCounter:34100072 to 0x0000000000000000 at monotonic time: 000000000000A35C msec I: 41834 [SC]Sent Sigma2 msg I: 42075 [EM]Received message of type 0x40 with protocolId (0, 0) and MessageCounter:18737573 on exchange 41315r D: 42085 [EM]Found matching exchange: 41315r, Delegate: 0x2000417c D: 42091 [EM]Rxd Ack; Removing MessageCounter:34100072 from Retrans Table on exchange 41315r D: 42099 [EM]Removed CHIP MessageCounter:34100072 from RetransTable on exchange 41315r E: 42107 [SC]Received error (protocol code 2) during pairing process: 54 E: 42114 [IN]CASE Session establishment failed: 54 D: 42119 [EM]Sending Standalone Ack for MessageCounter:18737573 on exchange 41315r I: 42126 [IN]Prepared unauthenticated message 0x2002e4c0 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 41315r with MessageCounter:34100073. I: 42141 [IN]Sending unauthenticated msg 0x2002e4c0 with MessageCounter:34100073 to 0x0000000000000000 at monotonic time: 000000000000A49D msec D: 42155 [EM]Flushed pending ack for MessageCounter:18737573 on exchange 41315r I: 42162 [IN]CASE Server enabling CASE session setups D: 42168 [IN]SecureSession[0x200035a8]: Released - Type:2 LSID:41702 D: 42174 [IN]SecureSession[0x200035a8]: Allocated Type:2 LSID:41703 D: 42180 [SC]Allocated SecureSession (0x200035a8) - waiting for Sigma1 msg E: 42273 [DL]SRP update error: timed out waiting on server response I: 42415 [EM]Received message of type 0x30 with protocolId (0, 0) and MessageCounter:18737574 on exchange 41316r D: 42425 [EM]Handling via exchange: 41316r, Delegate: 0x20004160 I: 42431 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20004e70 I: 42439 [IN]CASE Server disabling CASE session setups D: 42444 [EM]Sending Standalone Ack for MessageCounter:18737574 on exchange 41316r I: 42451 [IN]Prepared unauthenticated message 0x2002e4d8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 41316r with MessageCounter:34100074. I: 42466 [IN]Sending unauthenticated msg 0x2002e4d8 with MessageCounter:34100074 to 0x0000000000000000 at monotonic time: 000000000000A5E2 msec D: 42480 [EM]Flushed pending ack for MessageCounter:18737574 on exchange 41316r I: 42487 [SC]Received Sigma1 msg D: 42491 [SC]Found MRP parameters in the message D: 42495 [SC]Peer assigned session key ID 26226 I: 42503 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x00000000DB9DEF91 D: 42735 [SC]Including MRP parameters I: 42739 [IN]Prepared unauthenticated message 0x20005124 to 0x0000000000000000 (0) of type 0x31 and protocolId (0, 0) on exchange 41316r with MessageCounter:34100075. I: 42754 [IN]Sending unauthenticated msg 0x20005124 with MessageCounter:34100075 to 0x0000000000000000 at monotonic time: 000000000000A702 msec I: 42768 [SC]Sent Sigma2 msg D: 42771 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 43074 [DL]SRP update succeeded I: 43079 [DL]BLE GAP connection terminated (reason 0x13) I: 43085 [DL]Current number of connections: 0/1 I: 43131 [EM]Received message of type 0x40 with protocolId (0, 0) and MessageCounter:18737575 on exchange 41316r D: 43140 [EM]Found matching exchange: 41316r, Delegate: 0x2000417c D: 43146 [EM]Rxd Ack; Removing MessageCounter:34100075 from Retrans Table on exchange 41316r D: 43155 [EM]Removed CHIP MessageCounter:34100075 from RetransTable on exchange 41316r E: 43163 [SC]Received error (protocol code 2) during pairing process: 54 E: 43169 [IN]CASE Session establishment failed: 54 D: 43174 [EM]Sending Standalone Ack for MessageCounter:18737575 on exchange 41316r I: 43181 [IN]Prepared unauthenticated message 0x2002e4c0 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 41316r with MessageCounter:34100076. I: 43196 [IN]Sending unauthenticated msg 0x2002e4c0 with MessageCounter:34100076 to 0x0000000000000000 at monotonic time: 000000000000A8BC msec D: 43210 [EM]Flushed pending ack for MessageCounter:18737575 on exchange 41316r I: 43217 [IN]CASE Server enabling CASE session setups D: 43222 [IN]SecureSession[0x200035a8]: Released - Type:2 LSID:41703 D: 43229 [IN]SecureSession[0x200035a8]: Allocated Type:2 LSID:41704 D: 43235 [SC]Allocated SecureSession (0x200035a8) - waiting for Sigma1 msg E: 45280 [DL]BLEManagerImpl::SendIndication() failed: 2f D: 45285 [IN]Clearing BLE pending packets. I: 45289 [BLE]Releasing end point's BLE connection back to application. I: 45295 [DL]Closing BLE GATT connection (ConnId 00) I: 94245 [FS]Fail-safe timer expired E: 94248 [SVR]Failsafe timer expired D: 94252 [IN]SecureSession[0x200034f0]: MarkForEviction Type:1 LSID:41701 I: 94258 [SC]SecureSession[0x200034f0]: Moving from state 'kActive' --> 'kPendingEviction' D: 94266 [IN]SecureSession[0x200034f0]: Released - Type:1 LSID:41701 E: 94272 [SVR]Commissioning failed (attempt 1): 32 D: 94279 [IN]SecureSession[0x200034f0]: Allocated Type:1 LSID:41705 D: 94285 [SC]Assigned local session key ID 41705 D: 94290 [SC]Waiting for PBKDF param request D: 94293 [DL]CHIPoBLE advertising set to on I: 94297 [DIS]Updating services using commissioning mode 1 D: 94303 [DL]Using Thread extended MAC for hostname. I: 94307 [DIS]Advertise operational node 97C74B46E6F0035E-00000000DB9DEF91 D: 94314 [DL]Using Thread extended MAC for hostname. I: 94320 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 I: 94330 [DL]advertising srp service: B540ED28DE4C01D8._matterc._udp E: 94336 [ZCL]OpCreds: Got FailSafeTimerExpired E: 94340 [ZCL]OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry! D: 94383 [IN]Expiring all sessions for fabric 0x1!! E: 94387 [FP]Reverting pending fabric data for fabric 0x1 D: 94394 [EVL]LogEvent event number: 0x0000000000060003 priority: 1, endpoint id: 0x0 cluster id: 0x0000_0028 event id: 0x2 Sys timestamp: 0x00000000000170B9 D: 94408 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet E: 94415 [FP]Warning: metadata not found during delete of fabric 0x1 I: 94501 [FP]Fabric (0x1) deleted. I: 94504 [ZCL]OpCreds: Fabric index 0x1 was removed D: 94510 [DMG]Endpoint 0, Cluster 0x0000_003E update version to 4184ba63 D: 94516 [DMG]Endpoint 0, Cluster 0x0000_003E update version to 4184ba64 I: 94636 [TS]Pending Last Known Good Time: 2022-10-15T15:29:36 I: 94642 [TS]Previous Last Known Good Time: 2022-10-15T15:29:36 I: 94648 [TS]Reverted Last Known Good Time to previous value D: 94654 [EVL]LogEvent event number: 0x0000000000060004 priority: 1, endpoint id: 0x0 cluster id: 0x0000_0028 event id: 0x2 Sys timestamp: 0x00000000000171BD D: 94668 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet E: 94674 [FP]Warning: metadata not found during delete of fabric 0x1 E: 94683 [ZCL]OpCreds: failed to delete fabric at index 1: d8 D: 94688 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to 847332f4 D: 94695 [ZCL]Failsafe timeout, tell platform driver to revert network credentials. I: 94702 [NP]Reverting Thread operational dataset E: 94715 [DL]Long dispatch time: 467 ms, for event type 32782 I: 94720 [FS]Fail-safe cleanly disarmed I: 94726 [DL]CHIPoBLE advertising started D: 94730 [DL]OpenThread State Changed (Flags: 0x11002046) D: 94735 [DL] Device Role: DISABLED D: 94738 [DL] Thread Unicast Addresses: D: 94742 [DL] fdd4:4ac1:649a:0:237f:a8b6:31f2:bcd5/64 valid preferred D: 94750 [DL]OpenThread State Changed (Flags: 0x10000000) I: 94757 [DL]NFC Tag emulation started
