We successfully commissioned Matter lightbulb application (mostly based on lightbulb sample) on Google and Amazon Matter environment but it seems not working on Apple HomePod.
It seems that device (nRF5340) is commissioned but the Home application show an error occurred.
Please find the error logs from nRF5340.
uart:~$ I: nRF5 802154 radio initialized I: 4 Sectors of 4096 bytes I: alloc wra: 1, d00 I: data wra: 1, 218 *** Booting Zephyr OS build v3.2.99-ncs2 *** I: Init CHIP stack I: 176 [DL]BLE address: CC:21:C8:E2:F8:81 I: 200 [DL]OpenThread started: OK I: 203 [DL]Setting OpenThread device type to ROUTER I: 207 [SVR]Server initializing... I: 211 [TS]Last Known Good Time: 2023-05-15T10:36:50 I: 217 [DMG]AccessControl: initializing I: 221 [DMG]Examples::AccessControlDelegate::Init I: 225 [DMG]AccessControl: setting I: 228 [DMG]DefaultAclStorage: initializing I: 232 [DMG]DefaultAclStorage: 0 entries loaded D: 236 [IN]UDP::Init bind&listen port=5540 E: 240 [IN]SO_REUSEPORT failed: 109 D: 244 [IN]UDP::Init bound to port=5540 D: 247 [IN]BLEBase::Init - setting/overriding transport D: 252 [IN]TransportMgr initialized I: 259 [ZCL]Using ZAP configuration... I: 267 [DMG]AccessControlCluster: initializing I: 271 [ZCL]Initiating Admin Commissioning cluster. I: 276 [ZCL]On/Off set value: 1 0 I: 279 [ZCL]On/off already set to new value I: 283 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 D: 292 [IN]SecureSession[0x20004f28]: Allocated Type:1 LSID:1066 I: 298 [DIS]Updating services using commissioning mode 1 I: 304 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 313 [DIS]Failed to advertise commissionable node: 3 E: 318 [DIS]Failed to finalize service update: 1c I: 322 [DIS]Updating services using commissioning mode 1 I: 328 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 337 [DIS]Failed to advertise commissionable node: 3 E: 342 [DIS]Failed to finalize service update: 1c I: 347 [DIS]Delaying proxy of operational discovery: missing delegate I: 353 [IN]CASE Server enabling CASE session setups D: 358 [IN]SecureSession[0x20004fe0]: Allocated Type:2 LSID:1067 I: 364 [SVR]Joining Multicast groups I: 367 [SVR]Server Listening... I: 370 [DL]Device Configuration: I: 373 [DL] Serial Number: 11223344556677889900 I: 378 [DL] Vendor Id: 65521 (0xFFF1) I: 381 [DL] Product Id: 32773 (0x8005) I: 385 [DL] Hardware Version: 0 I: 389 [DL] Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 I: 395 [DL] Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00) I: 402 [DL] Manufacturing Date: (not set) I: 405 [DL] Device Type: 65535 (0xFFFF) I: 410 [SVR]SetupQRCode: [MT:6FCJ142C00KA0648G00] I: 415 [SVR]Copy/paste the below URL in a browser to see the QR Code: I: 421 [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A6FCJ142C00KA0648G00 I: 430 [SVR]Manual pairing code: [34970112332] I: 435 [DL]CHIP task running I: 442 [DL]CHIPoBLE advertising started I: 446 [ZCL]Cluster OnOff: attribute OnOff set to 0 I: 451 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 I: 459 [DL]NFC Tag emulation started I: 29800 [DL]BLE connection established (ConnId: 0x00) I: 29805 [DL]Current number of connections: 1/1 I: 29810 [DL]CHIPoBLE advertising stopped I: 29814 [DL]NFC Tag emulation stopped W: Ignoring data for unknown channel ID 0x003a I: 30755 [BLE]local and remote recv window sizes = 5 I: 30760 [BLE]selected BTP version 4 I: 30763 [BLE]using BTP fragment sizes rx 244 / tx 244. D: 30905 [IN]BLE EndPoint 0x2001bfe8 Connection Complete I: 30910 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 247) I: 30966 [EM]>>> [E:26685r M:233677739] (U) Msg RX from 0:A0014BBB8DD1A562 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) I: 30979 [EM]<<< [E:26685r M:189123480] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) I: 30991 [IN](U) Sending msg 189123480 to IP address 'BLE' I: 31116 [EM]>>> [E:26685r M:233677740] (U) Msg RX from 0:A0014BBB8DD1A562 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) I: 33110 [EM]<<< [E:26685r M:189123481] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) I: 33121 [IN](U) Sending msg 189123481 to IP address 'BLE' E: 33126 [DL]Long dispatch time: 2011 ms, for event type 16388 I: 33216 [EM]>>> [E:26685r M:233677741] (U) Msg RX from 0:A0014BBB8DD1A562 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3) I: 33228 [EM]<<< [E:26685r M:189123482] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 33239 [IN](U) Sending msg 189123482 to IP address 'BLE' I: 33246 [SC]SecureSession[0x20004f28]: Moving from state 'kEstablishing' --> 'kActive' D: 33254 [IN]SecureSession[0x20004f28]: Activated - Type:1 LSID:1066 D: 33260 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:1066 PSID:33303! I: 33269 [SVR]Commissioning completed session establishment step I: 33275 [DIS]Updating services using commissioning mode 0 I: 33282 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 E: 33291 [DIS]Failed to advertise extended commissionable node: 3 D: 33297 [DIS]Scheduling extended discovery timeout in 900s E: 33302 [DIS]Failed to finalize service update: 1c I: 33307 [SVR]Device completed Rendezvous process I: 33337 [EM]>>> [E:26686r M:14855563] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 33347 [IM]Received Read request I: 33352 [EM]<<< [E:26686r M:265618435] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 33362 [IN](S) Sending msg 265618435 on secure session with LSID: 1066 I: 33457 [EM]>>> [E:26687r M:14855564] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 33467 [IM]Received Read request I: 33475 [EM]<<< [E:26687r M:265618436] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 33485 [IN](S) Sending msg 265618436 on secure session with LSID: 1066 I: 33697 [EM]>>> [E:26688r M:14855565] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 33707 [IM]Received Read request I: 33712 [EM]<<< [E:26688r M:265618437] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 33722 [IN](S) Sending msg 265618437 on secure session with LSID: 1066 I: 33817 [EM]>>> [E:26689r M:14855566] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 33827 [IM]Received Read request I: 33832 [EM]<<< [E:26689r M:265618438] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 33842 [IN](S) Sending msg 265618438 on secure session with LSID: 1066 I: 33937 [EM]>>> [E:26690r M:14855567] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 33947 [IM]Received Read request I: 33955 [EM]<<< [E:26690r M:265618439] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 33965 [IN](S) Sending msg 265618439 on secure session with LSID: 1066 I: 34177 [EM]>>> [E:26691r M:14855568] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 34187 [IM]Received Read request I: 34192 [EM]<<< [E:26691r M:265618440] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 34202 [IN](S) Sending msg 265618440 on secure session with LSID: 1066 I: 34297 [EM]>>> [E:26692r M:14855569] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 34308 [IM]Received Read request I: 34320 [EM]<<< [E:26692r M:265618441] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 34330 [IN](S) Sending msg 265618441 on secure session with LSID: 1066 I: 34537 [EM]>>> [E:26693r M:14855570] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 34548 [FS]GeneralCommissioning: Received ArmFailSafe (60s) I: 34555 [EM]<<< [E:26693r M:265618442] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 34566 [IN](S) Sending msg 265618442 on secure session with LSID: 1066 I: 34657 [EM]>>> [E:26694r M:14855571] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 34672 [EM]<<< [E:26694r M:265618443] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 34683 [IN](S) Sending msg 265618443 on secure session with LSID: 1066 I: 34777 [EM]>>> [E:26695r M:14855572] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 34788 [ZCL]OpCreds: Certificate Chain request received for PAI I: 34798 [EM]<<< [E:26695r M:265618444] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 34809 [IN](S) Sending msg 265618444 on secure session with LSID: 1066 I: 35137 [EM]>>> [E:26696r M:14855573] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 35148 [ZCL]OpCreds: Certificate Chain request received for DAC I: 35158 [EM]<<< [E:26696r M:265618445] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 35169 [IN](S) Sending msg 265618445 on secure session with LSID: 1066 I: 35497 [EM]>>> [E:26697r M:14855574] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 35509 [ZCL]OpCreds: Received an AttestationRequest command I: 35559 [ZCL]OpCreds: AttestationRequest successful. I: 35568 [EM]<<< [E:26697r M:265618446] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 35579 [IN](S) Sending msg 265618446 on secure session with LSID: 1066 I: 35917 [EM]>>> [E:26698r M:14855575] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 35929 [FS]GeneralCommissioning: Received ArmFailSafe (60s) I: 35935 [EM]<<< [E:26698r M:265618447] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 35946 [IN](S) Sending msg 265618447 on secure session with LSID: 1066 I: 50797 [EM]>>> [E:26699r M:14855576] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 50808 [ZCL]OpCreds: Received a CSRRequest command I: 51022 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded I: 51072 [ZCL]OpCreds: CSRRequest successful. I: 51079 [EM]<<< [E:26699r M:265618448] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 51089 [IN](S) Sending msg 265618448 on secure session with LSID: 1066 E: 51096 [DL]Long dispatch time: 300 ms, for event type 16388 I: 51458 [EM]>>> [E:26700r M:14855577] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 51470 [ZCL]OpCreds: Received an AddTrustedRootCertificate command I: 51558 [ZCL]OpCreds: AddTrustedRootCertificate successful. I: 51565 [EM]<<< [E:26700r M:265618449] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 51576 [IN](S) Sending msg 265618449 on secure session with LSID: 1066 E: 51583 [DL]Long dispatch time: 127 ms, for event type 16388 I: 51819 [EM]>>> [E:26701r M:14855578] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 51830 [ZCL]OpCreds: Received an AddNOC command I: 51839 [FP]Validating NOC chain I: 51925 [FP]NOC chain validation successful I: 51929 [FP]Added new fabric at index: 0x1 I: 51933 [FP]Assigned compressed fabric ID: 0x5FA7D1D6AAC3422B, node ID: 0x00000000D307AC5E I: 51941 [TS]Last Known Good Time: 2023-05-15T10:36:50 I: 51946 [TS]New proposed Last Known Good Time: 2023-05-15T14:28:51 I: 51952 [TS]Updating pending Last Known Good Time to 2023-05-15T14:28:51 I: 51972 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x592567DF42218E24 I: 51981 [DIS]Advertise operational node 5FA7D1D6AAC3422B-00000000D307AC5E I: 51988 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC I: 51996 [EM]<<< [E:26701r M:265618450] (S) Msg TX to 1:FFFFFFFB00000000 [422B] --- Type 0001:09 (IM:InvokeCommandResponse) I: 52007 [IN](S) Sending msg 265618450 on secure session with LSID: 1066 E: 52013 [DL]Long dispatch time: 197 ms, for event type 16388 I: 52118 [EM]>>> [E:26702r M:14855579] (S) Msg RX from 1:FFFFFFFB00000000 [422B] --- Type 0001:08 (IM:InvokeCommandRequest) I: 52134 [EM]<<< [E:26702r M:265618451] (S) Msg TX to 1:FFFFFFFB00000000 [422B] --- Type 0001:09 (IM:InvokeCommandResponse) I: 52145 [IN](S) Sending msg 265618451 on secure session with LSID: 1066 I: 52237 [EM]>>> [E:26703r M:14855580] (S) Msg RX from 1:FFFFFFFB00000000 [422B] --- Type 0001:08 (IM:InvokeCommandRequest) I: Add IPv6 mcast address I: Add IPv6 address I: Add IPv6 address I: Add IPv6 mcast address I: Add IPv6 mcast address I: Add IPv6 mcast address I: Add IPv6 mcast address I: 53088 [DL]SRP Client was started, detected server: fd2f:ca14:2c89:0c6a:0000:00ff:fe00:fc11 E: iface_event_handler I: Network UP I: Join IPv6 mcast I: Delete IPv6 address I: Add IPv6 address I: Add IPv6 address I: Delete IPv6 mcast address I: Delete IPv6 mcast address I: Add IPv6 mcast address I: Add IPv6 mcast address I: Add IPv6 mcast address I: Add IPv6 mcast address I: 53420 [EM]<<< [E:26703r M:265618452] (S) Msg TX to 1:FFFFFFFB00000000 [422B] --- Type 0001:09 (IM:InvokeCommandResponse) I: 53432 [IN](S) Sending msg 265618452 on secure session with LSID: 1066 I: 53440 [DIS]Advertise operational node 5FA7D1D6AAC3422B-00000000D307AC5E I: 53448 [SVR]Operational advertising enabled I: 53537 [DIS]Setting operational delegate post init I: 53547 [SWU]Stopping the watchdog timer I: 53551 [SWU]Starting the periodic query timer, timeout: 86400 seconds I: 53558 [DIS]Updating services using commissioning mode 0 I: 53563 [DIS]Advertise operational node 5FA7D1D6AAC3422B-00000000D307AC5E I: 53570 [DL]advertising srp service: 5FA7D1D6AAC3422B-00000000D307AC5E._matter._tcp I: 53579 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 I: 53589 [DL]advertising srp service: 19E058DBA19BAAF8._matterc._udp I: 55352 [EM]>>> [E:26704r M:233677742] (U) Msg RX from 0:A448078ED1680DBD [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) I: 55364 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20006858 I: 55371 [IN]CASE Server disabling CASE session setups I: 55376 [EM]<<< [E:26704r M:189123483 (Ack:233677742)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 55389 [IN](U) Sending msg 189123483 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:62680' I: 55399 [SC]Received Sigma1 msg I: 55409 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x00000000D307AC5E I: 55668 [EM]<<< [E:26704r M:189123484 (Ack:233677742)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) I: 55681 [IN](U) Sending msg 189123484 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:62680' I: 55692 [SC]Sent Sigma2 msg I: 55976 [EM]>>> [E:26704r M:233677743 (Ack:189123484)] (U) Msg RX from 0:A448078ED1680DBD [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) I: 55989 [EM]<<< [E:26704r M:189123485 (Ack:233677743)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 56002 [IN](U) Sending msg 189123485 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:62680' I: 56012 [SC]Received Sigma3 msg E: 56019 [SC]The device does not support GetClock_RealTimeMS() API: 3. Falling back to Last Known Good UTC Time I: 56203 [EM]<<< [E:26704r M:189123486 (Ack:233677743)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 56216 [IN](U) Sending msg 189123486 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:62680' I: 56228 [SC]SecureSession[0x20004fe0]: Moving from state 'kEstablishing' --> 'kActive' D: 56238 [IN]SecureSession[0x20004fe0]: Activated - Type:2 LSID:1067 D: 56245 [IN]New secure session activated for device <592567DF42218E24, 1>, LSID:1067 PSID:33304! I: 56254 [IN]CASE Session established to peer: <592567DF42218E24, 1> I: 56261 [IN]CASE Server enabling CASE session setups D: 56267 [IN]SecureSession[0x20005098]: Allocated Type:2 LSID:1068 I: 56290 [EM]>>> [E:26705r M:145209208] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:08 (IM:InvokeCommandRequest) I: 56303 [FS]GeneralCommissioning: Received CommissioningComplete I: 56314 [FP]Metadata for Fabric 0x1 persisted to storage. I: 56341 [TS]Committing Last Known Good Time to storage: 2023-05-15T14:28:51 I: 56353 [ZCL]OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0x5FA7D1D6AAC3422B, FabricId 0000000000000003, NodeId 00000000D307AC5E, VendorId 0x1349 I: 56371 [FS]GeneralCommissioning: Successfully commited pending fabric data I: 56378 [FS]Fail-safe cleanly disarmed I: 56385 [EM]<<< [E:26705r M:172995309 (Ack:145209208)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:09 (IM:InvokeCommandResponse) I: 56399 [IN](S) Sending msg 172995309 on secure session with LSID: 1067 I: 56407 [SWU]Device commissioned, schedule a default provider query I: 56414 [SVR]Commissioning completed successfully I: 56420 [DIS]Updating services using commissioning mode 0 I: 56426 [DIS]Advertise operational node 5FA7D1D6AAC3422B-00000000D307AC5E I: 56435 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 D: 56445 [IN]Expiring all PASE sessions D: 56449 [IN]SecureSession[0x20004f28]: MarkForEviction Type:1 LSID:1066 I: 56455 [SC]SecureSession[0x20004f28]: Moving from state 'kActive' --> 'kPendingEviction' D: 56463 [IN]SecureSession[0x20004f28]: Released - Type:1 LSID:1066 D: 56469 [IN]Clearing BLE pending packets. I: 56473 [BLE]Releasing end point's BLE connection back to application. I: 56479 [DL]Closing BLE GATT connection (ConnId 00) I: 56487 [EM]>>> [E:26704r M:233677744 (Ack:189123486)] (U) Msg RX from 0:A448078ED1680DBD [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 56555 [DL]BLE GAP connection terminated (reason 0x16) I: 56560 [DL]Current number of connections: 0/1 I: 56688 [EM]>>> [E:26705r M:145209209 (Ack:172995309)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 56704 [EM]>>> [E:26706r M:145209210] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:02 (IM:ReadRequest) D: 56714 [IM]Received Read request I: 56721 [EM]<<< [E:26706r M:172995310 (Ack:145209210)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:05 (IM:ReportData) I: 56732 [IN](S) Sending msg 172995310 on secure session with LSID: 1067 I: 56847 [EM]>>> [E:26706r M:145209211 (Ack:172995310)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 56863 [EM]>>> [E:26707r M:145209212] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:02 (IM:ReadRequest) D: 56873 [IM]Received Read request I: 56879 [EM]<<< [E:26707r M:172995311 (Ack:145209212)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:05 (IM:ReportData) I: 56890 [IN](S) Sending msg 172995311 on secure session with LSID: 1067 I: 57006 [EM]>>> [E:26707r M:145209213 (Ack:172995311)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 57022 [EM]>>> [E:26708r M:145209214] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:02 (IM:ReadRequest) D: 57032 [IM]Received Read request I: 57038 [EM]<<< [E:26708r M:172995312 (Ack:145209214)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:05 (IM:ReportData) I: 57050 [IN](S) Sending msg 172995312 on secure session with LSID: 1067 I: 57229 [EM]>>> [E:26708r M:145209215 (Ack:172995312)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 57246 [EM]>>> [E:26709r M:145209216] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:02 (IM:ReadRequest) D: 57256 [IM]Received Read request I: 57261 [EM]<<< [E:26709r M:172995313 (Ack:145209216)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:05 (IM:ReportData) I: 57273 [IN](S) Sending msg 172995313 on secure session with LSID: 1067 I: 57531 [EM]>>> [E:26709r M:145209217 (Ack:172995313)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 57548 [EM]>>> [E:26710r M:145209218] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:02 (IM:ReadRequest) D: 57558 [IM]Received Read request I: 57565 [EM]<<< [E:26710r M:172995314 (Ack:145209218)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:05 (IM:ReportData) I: 57576 [IN](S) Sending msg 172995314 on secure session with LSID: 1067 I: 57746 [EM]>>> [E:26710r M:145209219 (Ack:172995314)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 57763 [EM]>>> [E:26711r M:145209220] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:02 (IM:ReadRequest) D: 57773 [IM]Received Read request I: 57779 [EM]<<< [E:26711r M:172995315 (Ack:145209220)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:05 (IM:ReportData) I: 57790 [IN](S) Sending msg 172995315 on secure session with LSID: 1067 I: 57971 [EM]>>> [E:26711r M:145209221 (Ack:172995315)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 57987 [EM]>>> [E:26712r M:145209222] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:02 (IM:ReadRequest) D: 57998 [IM]Received Read request I: 58003 [EM]<<< [E:26712r M:172995316 (Ack:145209222)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:05 (IM:ReportData) I: 58015 [IN](S) Sending msg 172995316 on secure session with LSID: 1067 I: 58124 [EM]>>> [E:26712r M:145209223 (Ack:172995316)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 58141 [EM]>>> [E:26713r M:145209224] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:02 (IM:ReadRequest) D: 58151 [IM]Received Read request I: 58157 [EM]<<< [E:26713r M:172995317 (Ack:145209224)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:05 (IM:ReportData) I: 58169 [IN](S) Sending msg 172995317 on secure session with LSID: 1067 I: 58356 [EM]>>> [E:26713r M:145209225 (Ack:172995317)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 58432 [EM]>>> [E:26714r M:145209226] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:0a (IM:TimedRequest) I: 58443 [EM]<<< [E:26714r M:172995318 (Ack:145209226)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:01 (IM:StatusResponse) I: 58455 [IN](S) Sending msg 172995318 on secure session with LSID: 1067 I: 58646 [EM]>>> [E:26714r M:145209227 (Ack:172995318)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0001:08 (IM:InvokeCommandRequest) I: 58659 [ZCL]Received command to open commissioning window D: 58665 [IN]SecureSession[0x20004f28]: Allocated Type:1 LSID:1069 I: 58671 [DIS]Updating services using commissioning mode 2 I: 58677 [DIS]Advertise operational node 5FA7D1D6AAC3422B-00000000D307AC5E I: 58685 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3629/14 cm=2 I: 58695 [DL]advertising srp service: 21C1C0E15CFA0CB4._matterc._udp I: 58701 [DL]removing srp service: 19E058DBA19BAAF8._matterc._udp I: 58707 [ZCL]Commissioning window is now open I: 58712 [EM]<<< [E:26714r M:172995319 (Ack:145209227)] (S) Msg TX to 1:592567DF42218E24 [422B] --- Type 0001:09 (IM:InvokeCommandResponse) I: 58725 [IN](S) Sending msg 172995319 on secure session with LSID: 1067 I: 58880 [EM]>>> [E:26714r M:145209228 (Ack:172995319)] (S) Msg RX from 1:592567DF42218E24 [422B] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 60378 [EM]>>> [E:29510r M:146243168] (U) Msg RX from 0:D896225FF099D1F4 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) I: 60390 [EM]<<< [E:29510r M:189123487 (Ack:146243168)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 60403 [IN](U) Sending msg 189123487 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:55501' I: 60415 [EM]<<< [E:29510r M:189123488 (Ack:146243168)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) I: 60429 [IN](U) Sending msg 189123488 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:55501' I: 60708 [EM]>>> [E:29510r M:146243169 (Ack:189123488)] (U) Msg RX from 0:D896225FF099D1F4 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) I: 60721 [EM]<<< [E:29510r M:189123489 (Ack:146243169)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 60734 [IN](U) Sending msg 189123489 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:55501' I: 61143 [EM]<<< [E:29510r M:189123490 (Ack:146243169)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 61156 [IN](U) Sending msg 189123490 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:55501' D: 61167 [IN]SecureSession[0x20004f28]: Released - Type:1 LSID:1069 E: 61174 [SC]Failed during PASE session setup: ac E: 61179 [SVR]Commissioning failed (attempt 1): ac D: 61184 [IN]SecureSession[0x20004f28]: Allocated Type:1 LSID:1070 I: 61190 [DIS]Updating services using commissioning mode 2 I: 61195 [DIS]Advertise operational node 5FA7D1D6AAC3422B-00000000D307AC5E I: 61204 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3629/14 cm=2 I: 61239 [EM]>>> [E:29510r M:146243170 (Ack:189123490)] (U) Msg RX from 0:D896225FF099D1F4 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) E: 86414 [SWU]No suitable OTA Provider candidate found I: 86419 [SWU]No provider available I: 244041 [EM]>>> [E:50648r M:216486127] (U) Msg RX from 0:B46FE03CD9DD45DB [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) I: 244055 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20006858 I: 244063 [IN]CASE Server disabling CASE session setups I: 244068 [EM]<<< [E:50648r M:189123491 (Ack:216486127)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 244081 [IN](U) Sending msg 189123491 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:53607' I: 244091 [SC]Received Sigma1 msg I: 244100 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x00000000D307AC5E I: 244359 [EM]<<< [E:50648r M:189123492 (Ack:216486127)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) I: 244371 [IN](U) Sending msg 189123492 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:53607' I: 244383 [SC]Sent Sigma2 msg I: 244680 [EM]>>> [E:50648r M:216486128 (Ack:189123492)] (U) Msg RX from 0:B46FE03CD9DD45DB [0000] --- Type 0000:40 (SecureChannel:StatusReport) E: 244693 [SC]Received error (protocol code 2) during pairing process: 54 E: 244700 [IN]CASE Session establishment failed: 54 I: 244704 [IN]CASE Server enabling CASE session setups D: 244709 [IN]SecureSession[0x20005098]: Released - Type:2 LSID:1068 D: 244716 [IN]SecureSession[0x20005098]: Allocated Type:2 LSID:1071 I: 244722 [EM]<<< [E:50648r M:189123493 (Ack:216486128)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I: 244735 [IN](U) Sending msg 189123493 to IP address 'UDP:[fd59:9aa0:73ed:1:1c58:6215:6753:e407]:53607' I: 358665 [SVR]Closing pairing window D: 358669 [IN]SecureSession[0x20004f28]: Released - Type:1 LSID:1070 I: 358675 [DIS]Updating services using commissioning mode 0 I: 358680 [DIS]Advertise operational node 5FA7D1D6AAC3422B-00000000D307AC5E I: 358689 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 I: 358698 [DL]advertising srp service: 21C1C0E15CFA0CB4._matterc._udp D: 358705 [DIS]Scheduling extended discovery timeout in 900s I: Factory Reset Triggered. Release button within 3000ms to cancel. I: 911490 [TS]Pending Last Known Good Time: 2023-05-15T14:28:51 I: 911497 [TS]Previous Last Known Good Time: 2023-05-15T14:28:51 I: 911503 [TS]Reverted Last Known Good Time to previous value I: 911514 [FP]Fabric (0x1) deleted. I: 911517 [ZCL]OpCreds: Fabric index 0x1 was removed I: 911522 [DIS]Updating services using commissioning mode 0 I: 911529 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 I: 911538 [DL]advertising srp service: 21C1C0E15CFA0CB4._matterc._udp I: 911545 [DL]removing srp service: 5FA7D1D6AAC3422B-00000000D307AC5E._matter._tcp I: 911575 [DL]Performing factory reset I: 911597 [DL]Erasing Thread persistent info...
E: 61174 [SC]Failed during PASE session setup: ac
E: 61179 [SVR]Commissioning failed (attempt 1): ac
Do you have an idea or already reproduced that issue?
Thanks for your help
