Matter commissioning issue in ThreadNetworkSetup step

[Shun Tanaka]

Hi,

After commissioning with a smart speaker such as Apple HomePod,  if the device is deleted and re-commissioned with a Test-harness, it results in an error with networkingStatus=2 in 'ThreadNetworkSetup'.

networkingStatus=2 means that adding the network configuration would exceed the limit. Should networkingStatus be set to 0 on the node when removing it from the network, or is it expected behavior for the controller side to change it?

[1721090298.852644][2768:2770] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005
[1721090298.852720][2768:2770] CHIP:CTL: Received NetworkConfig response, networkingStatus=2
[1721090298.852807][2768:2770] CHIP:CTL: Error on commissioning step 'ThreadNetworkSetup': 'src/controller/CHIPDeviceController.cpp:2285: CHIP Error 0x000000AC: Internal error'
[1721090298.852861][2768:2770] CHIP:CTL: Failed to perform commissioning step 21
[1721090298.852947][2768:2770] CHIP:CTL: Going from commissioning step 'ThreadNetworkSetup' with lastErr = 'src/controller/CHIPDeviceController.cpp:2285: CHIP Error 0x000000AC: Internal error' -> 'Cleanup'
[1721090298.853020][2768:2770] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = 'src/controller/CHIPDeviceController.cpp:2285: CHIP Error 0x000000AC: Internal error'
[1721090298.853119][2768:2770] CHIP:CTL: Successfully finished commissioning step 'Cleanup'
[1721090298.853192][2768:2770] CHIP:TOO: Device commissioning Failure: src/controller/CHIPDeviceController.cpp:2285: CHIP Error 0x000000AC: Internal error
[1721090298.853351][2768:2770] CHIP:DMG: ICR moving to [AwaitingDe]
[1721090298.853865][2768:2768] CHIP:CTL: Shutting down the commissioner
[1721090298.854065][2768:2768] CHIP:DIS: Closing all BLE connections
[1721090298.854130][2768:2768] CHIP:IN: Clearing BLE pending packets.
[1721090298.854743][2768:2768] CHIP:BLE: Auto-closing end point's BLE connection.
[1721090298.854802][2768:2768] CHIP:DL: Closing BLE GATT connection (con 0xffffac06d050)
[1721090298.854975][2768:2769] CHIP:DL: BluezDisconnect peer=E2:D2:F1:12:30:CE
[1721090299.287815][2768:2768] CHIP:IN: SecureSession[0xffffa400ae70]: MarkForEviction Type:1 LSID:32480
[1721090299.287911][2768:2768] CHIP:SC: SecureSession[0xffffa400ae70, LSID:32480]: State change 'kActive' --> 'kPendingEviction'
[1721090299.287997][2768:2768] CHIP:IN: SecureSession[0xffffa400ae70]: Released - Type:1 LSID:32480
[1721090299.288058][2768:2768] CHIP:CTL: Shutting down the controller
[1721090299.288109][2768:2768] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1721090299.288152][2768:2768] CHIP:FP: Forgetting fabric 0x1
[1721090299.288211][2768:2768] CHIP:TS: Pending Last Known Good Time: 2023-10-03T16:51:18
[1721090299.288177][2768:2769] CHIP:DL: Bluez disconnected
[1721090299.288288][2768:2769] CHIP:DL: Bluez notify CHIPoBluez connection disconnected
[1721090299.288381][2768:2768] CHIP:TS: Previous Last Known Good Time: 2023-10-03T16:51:18
[1721090299.288401][2768:2768] CHIP:TS: Reverted Last Known Good Time to previous value
[1721090299.288444][2768:2768] CHIP:CTL: Shutting down the commissioner
[1721090299.288526][2768:2768] CHIP:CTL: Shutting down the controller
[1721090299.288546][2768:2768] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack
[1721090299.288690][2768:2768] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet
[1721090299.288820][2768:2768] CHIP:FP: Shutting down FabricTable
[1721090299.288891][2768:2768] CHIP:TS: Pending Last Known Good Time: 2023-10-03T16:51:18
[1721090299.289078][2768:2768] CHIP:TS: Previous Last Known Good Time: 2023-10-03T16:51:18
[1721090299.289132][2768:2768] CHIP:TS: Reverted Last Known Good Time to previous value
[1721090299.289403][2768:2768] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-17I1ld)
[1721090299.290022][2768:2768] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1721090299.290091][2768:2768] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1721090299.290112][2768:2768] CHIP:DL: Inet Layer shutdown
[1721090299.290130][2768:2768] CHIP:DL: BLE shutdown
[1721090299.292226][2768:2768] CHIP:DL: System Layer shutdown
[1721090299.292669][2768:2768] CHIP:TOO: Run command failure: src/controller/CHIPDeviceController.cpp:2285: CHIP Error 0x000000AC: Internal error

[Maria Gilje]

Hello,

I have picked up this case and will return with my initial findings tomorrow.

Best regards,

Maria

[Maria Gilje]

Thank you for your patience so far.

I have not reached any conclusions, but I want to share that my current hypothesis is that your Matter device still has the operational dataset from the Apple HomePod fabric/network saved after being deleted. I have contacted our Matter experts to ask for comments and further support.

Best regards,

Maria

[Maria Gilje]

Our Matter experts inform that the issue you see can happen when only one fabric is deleted after commissioning to an Apple hub. The reason is that two fabrics are created and the Matter device is added to both during commissioning. When removing the device, both fabrics should be deleted and the Matter device should perform a factory reset after the second fabric is deleted.

For further support and debugging help, please share the whole device log with us.

Best regards,

Maria