chip-tool pairing ble-wifi 给matter设备配网异常

[paul6688]

在使用chip-tool pairing ble-wifi 0x7283 SSID PASSCODE 20202021 3840给matter 设备配网的时候，
出现如下错误，wifi名字和密码已经设置到matter设备的，看着是后面还有些配置没有成功


[1688103497.794157][22124:22126] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1688103498.919658][22124:22126] CHIP:DIS: Checking node lookup status after 45001 ms
[1688103498.919684][22124:22126] CHIP:DIS: OperationalSessionSetup[1:0000000000007283]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout
[1688103498.919689][22124:22126] CHIP:CTL: Device connection failed. Error src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout
[1688103498.919700][22124:22126] CHIP:CTL: Error on commissioning step 'FindOperational': 'src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout'
[1688103498.919704][22124:22126] CHIP:CTL: Failed to perform commissioning step 20
[1688103498.919708][22124:22126] CHIP:CTL: Going from commissioning step 'FindOperational' with lastErr = 'src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout' -> 'Cleanup'
[1688103498.919715][22124:22126] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = 'src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout'
[1688103498.919719][22124:22126] CHIP:CTL: Successfully finished commissioning step 'Cleanup'
[1688103498.919726][22124:22126] CHIP:TOO: Device commissioning Failure: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout
[1688103498.920293][22124:22124] CHIP:CTL: Shutting down the commissioner
[1688103498.920318][22124:22124] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1688103498.920340][22124:22124] CHIP:DIS: Closing all BLE connections
[1688103498.920346][22124:22124] CHIP:IN: Clearing BLE pending packets.
[1688103498.921023][22124:22124] CHIP:BLE: Auto-closing end point's BLE connection.
[1688103498.921031][22124:22124] CHIP:DL: Closing BLE GATT connection (con 0x7effac0ec930)
[1688103498.921135][22124:22125] CHIP:DL: BluezDisconnect peer=D2:87:93:87:CD:A7
[1688103501.596697][22124:22124] CHIP:IN: SecureSession[0x7effa40dd600]: MarkForEviction Type:1 LSID:38471
[1688103501.596760][22124:22124] CHIP:SC: SecureSession[0x7effa40dd600]: Moving from state 'kActive' --> 'kPendingEviction'
[1688103501.596805][22124:22124] CHIP:IN: SecureSession[0x7effa40dd600]: Released - Type:1 LSID:38471
[1688103501.596822][22124:22124] CHIP:CTL: Shutting down the controller
[1688103501.596833][22124:22124] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1688103501.596841][22124:22124] CHIP:FP: Forgetting fabric 0x1
[1688103501.596909][22124:22124] CHIP:TS: Pending Last Known Good Time: 2023-06-21T21:51:50
[1688103501.596990][22124:22124] CHIP:TS: Previous Last Known Good Time: 2023-06-21T21:51:50
[1688103501.597044][22124:22124] CHIP:TS: Reverted Last Known Good Time to previous value
[1688103501.597074][22124:22124] CHIP:CTL: Shutting down the commissioner
[1688103501.597082][22124:22124] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1688103501.597091][22124:22124] CHIP:CTL: Shutting down the controller
[1688103501.597097][22124:22124] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack
[1688103501.597174][22124:22124] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet
[1688103501.597393][22124:22124] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented.
[1688103501.597422][22124:22124] CHIP:FP: Shutting down FabricTable
[1688103501.597427][22124:22124] CHIP:TS: Pending Last Known Good Time: 2023-06-21T21:51:50
[1688103501.597446][22124:22124] CHIP:TS: Previous Last Known Good Time: 2023-06-21T21:51:50
[1688103501.597449][22124:22124] CHIP:TS: Reverted Last Known Good Time to previous value
[1688103501.597578][22124:22124] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-m3hrN6)
[1688103501.597881][22124:22124] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1688103501.597910][22124:22124] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1688103501.597945][22124:22124] CHIP:DL: Inet Layer shutdown
[1688103501.597950][22124:22124] CHIP:DL: BLE shutdown
[1688103501.609307][22124:22124] CHIP:DL: System Layer shutdown
[1688103501.610224][22124:22124] CHIP:TOO: Run command failure: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout

[paul6688]

补上matter设备监控的最后的部分log


I (110216) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (112716) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (115226) NimBLE: GATT procedure initiated: indicate;
I (115226) NimBLE: att_handle=14

I (115366) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (117866) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (120376) NimBLE: GATT procedure initiated: indicate;
I (120376) NimBLE: att_handle=14

I (120516) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (122966) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (125476) NimBLE: GATT procedure initiated: indicate;
I (125476) NimBLE: att_handle=14

I (125616) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (128066) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (129316) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 0
I (129326) chip[DL]: CHIPoBLE unsubscribe received
I (129326) chip[BLE]: Releasing end point's BLE connection back to application.
I (131966) chip[DL]: BLE GAP connection terminated (con 1 reason 0x213)
I (131976) NimBLE: GAP procedure initiated: stop advertising.

W (131986) Timer: Timer not stopped
W (131986) Timer: Timer not stopped
W (131986) BTDM_INIT: esp_bt_mem_release not implemented, return OK
I (131996) esp_matter_core: BLE deinit successful and memory reclaimed
I (151356) chip[FS]: Fail-safe timer expired
E (151356) chip[SVR]: Failsafe timer expired
I (151356) chip[SC]: SecureSession[0x3fc97c70]: Moving from state 'kActive' --> 'kPendingEviction'
E (151366) chip[SVR]: Commissioning failed (attempt 1): 32
I (151376) chip[DIS]: Updating services using commissioning mode 1
I (151376) chip[DIS]: CHIP minimal mDNS started advertising.
I (151386) chip[DIS]: Advertise operational node 2F662859897ECDF6-0000000000007283
I (151386) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 2F662859897ECDF6-0000000000007283.
I (151406) chip[DIS]: mDNS service published: _matter._tcp
I (151406) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1
I (151426) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: F68FE24248E0DDE9.
I (151446) chip[DIS]: mDNS service published: _matterc._udp
E (151446) chip[ZCL]: OpCreds: Got FailSafeTimerExpired
E (151446) chip[ZCL]: OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry!
E (151456) chip[FP]: Reverting pending fabric data for fabric 0x1
E (151476) chip[FP]: Warning: metadata not found during delete of fabric 0x1
I (151496) chip[FP]: Fabric (0x1) deleted.
I (151496) chip[ZCL]: OpCreds: Fabric index 0x1 was removed
I (151496) chip[DIS]: Updating services using commissioning mode 1
I (151506) chip[DIS]: CHIP minimal mDNS started advertising.
I (151536) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1
I (151536) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: F68FE24248E0DDE9.
I (151556) chip[DIS]: mDNS service published: _matterc._udp
I (151576) chip[TS]: Pending Last Known Good Time: 2023-06-25T14:16:16
I (151586) chip[TS]: Previous Last Known Good Time: 2023-06-25T14:16:16
I (151596) chip[TS]: Reverted Last Known Good Time to previous value
E (151596) chip[FP]: Warning: metadata not found during delete of fabric 0x1
E (151606) chip[ZCL]: OpCreds: failed to delete fabric at index 1: d8
I (151616) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (151626) app_main: Commissioning failed, fail safe timer expired
I (151636) chip[FS]: Fail-safe cleanly disarmed
E (151636) chip[DL]: ble_gap_adv_set_data failed: Error ESP32:0x05B0001E 3840
E (151646) chip[DL]: Configure Adv Data failed: Error ESP32:0x05B0001E
E (151646) chip[DL]: Disabling CHIPoBLE service due to error: Error ESP32:0x05B0001E
I (151656) app_main: Commissioning window opened
I (151666) app_main: Fabric will be removed
I (151676) app_main: Fabric removed successfully
I (151676) app_main: Fabric will be removed
I (160476) ROUTE_HOOK: Ignore invalid ICMP packet
I (173666) ROUTE_HOOK: Ignore invalid ICMP packet
I (173666) ROUTE_HOOK: Ignore invalid ICMP packet
I (214186) ROUTE_HOOK: Ignore invalid ICMP packet
I (214186) ROUTE_HOOK: Ignore invalid ICMP packet
I (282086) wifi:bcn_timout,ap_probe_send_start
I (437526) wifi:bcn_timout,ap_probe_send_start

[ESP_@In逍遥子]

请补充下你的测试环境
1.电脑
2.蓝牙适配器
3.芯片/设备
4.使用 SDK 版本

建议使用 linux 主机，搭配 BLE 4.0 以上的蓝牙适配器，使用esp-matter 默认的示例，不做任何修改，先测试 OK，再做开发。