search

ivmarkov / esp-idf-matter

Run rs-matter on Espressif chips with ESP IDF
https://github.com/ivmarkov/esp-idf-matter
Apache License 2.0
18 stars 1 forks source link

Missing BT related fields when trying to compile against riscv32imac-esp-espidf (esp32c6) #5

Open oddlama opened 5 days ago

oddlama commented 5 days ago

Transfer of esp-rs/esp-idf-svc#442; Summary:

When I'm trying to compile this for the esp32c6 using riscv32imc-esp-espidf, I run into the following issue:

   Compiling esp-idf-svc v0.49.0
error[E0425]: cannot find value `ESP_BT_CTRL_CONFIG_MAGIC_VAL` in module `crate::sys`
   --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:497:32
    |
497 |             magic: crate::sys::ESP_BT_CTRL_CONFIG_MAGIC_VAL,
    |                                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys`

It happens on esp-idf v5.2 and v5.2.2. EDIT: As we found out later it seems to be related to the architecture.

Full cargo build error ``` Compiling esp-idf-svc v0.49.0 error[E0425]: cannot find value `ESP_BT_CTRL_CONFIG_MAGIC_VAL` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:497:32 | 497 | magic: crate::sys::ESP_BT_CTRL_CONFIG_MAGIC_VAL, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `ESP_BT_CTRL_CONFIG_VERSION` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:498:34 | 498 | version: crate::sys::ESP_BT_CTRL_CONFIG_VERSION, | ^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_PINNED_TO_CORE` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:501:50 | 501 | controller_task_run_cpu: crate::sys::CONFIG_BT_CTRL_PINNED_TO_CORE as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ help: a constant with a similar name exists: `CONFIG_BLUEDROID_PINNED_TO_CORE` | ::: /home/nixuser/projects/embedded/esp-idf-matter/target/riscv32imac-esp-espidf/debug/build/esp-idf-sys-c8d14348dff4d490/out/bindings.rs:1167:1 | 1167 | pub const CONFIG_BLUEDROID_PINNED_TO_CORE: u32 = 0; | ---------------------------------------------- similarly named constant `CONFIG_BLUEDROID_PINNED_TO_CORE` defined here error[E0425]: cannot find value `CONFIG_BT_CTRL_MODE_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:502:41 | 502 | bluetooth_mode: crate::sys::CONFIG_BT_CTRL_MODE_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_BLE_MAX_ACT_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:503:38 | 503 | ble_max_act: crate::sys::CONFIG_BT_CTRL_BLE_MAX_ACT_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_SLEEP_MODE_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:504:37 | 504 | sleep_mode: crate::sys::CONFIG_BT_CTRL_SLEEP_MODE_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_SLEEP_CLOCK_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:505:38 | 505 | sleep_clock: crate::sys::CONFIG_BT_CTRL_SLEEP_CLOCK_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_BLE_STATIC_ACL_TX_BUF_NB` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:506:47 | 506 | ble_st_acl_tx_buf_nb: crate::sys::CONFIG_BT_CTRL_BLE_STATIC_ACL_TX_BUF_NB as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_HW_CCA_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:507:43 | 507 | ble_hw_cca_check: crate::sys::CONFIG_BT_CTRL_HW_CCA_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_ADV_DUP_FILT_MAX` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:508:47 | 508 | ble_adv_dup_filt_max: crate::sys::CONFIG_BT_CTRL_ADV_DUP_FILT_MAX as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_CE_LENGTH_TYPE_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:509:38 | 509 | ce_len_type: crate::sys::CONFIG_BT_CTRL_CE_LENGTH_TYPE_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_HCI_TL_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:510:38 | 510 | hci_tl_type: crate::sys::CONFIG_BT_CTRL_HCI_TL_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_TX_ANTENNA_INDEX_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:512:36 | 512 | txant_dft: crate::sys::CONFIG_BT_CTRL_TX_ANTENNA_INDEX_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_RX_ANTENNA_INDEX_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:513:36 | 513 | rxant_dft: crate::sys::CONFIG_BT_CTRL_RX_ANTENNA_INDEX_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_DFT_TX_POWER_LEVEL_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:514:36 | 514 | txpwr_dft: crate::sys::CONFIG_BT_CTRL_DFT_TX_POWER_LEVEL_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CFG_MASK` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:515:35 | 515 | cfg_mask: crate::sys::CFG_MASK, | ^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `SCAN_DUPLICATE_MODE` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:516:46 | 516 | scan_duplicate_mode: crate::sys::SCAN_DUPLICATE_MODE as _, | ^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `SCAN_DUPLICATE_TYPE_VALUE` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:517:46 | 517 | scan_duplicate_type: crate::sys::SCAN_DUPLICATE_TYPE_VALUE as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `NORMAL_SCAN_DUPLICATE_CACHE_SIZE` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:518:42 | 518 | normal_adv_size: crate::sys::NORMAL_SCAN_DUPLICATE_CACHE_SIZE as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `MESH_DUPLICATE_SCAN_CACHE_SIZE` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:519:40 | 519 | mesh_adv_size: crate::sys::MESH_DUPLICATE_SCAN_CACHE_SIZE as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_COEX_PHY_CODED_TX_RX_TLIM_EFF` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:521:29 | 521 | crate::sys::CONFIG_BT_CTRL_COEX_PHY_CODED_TX_RX_TLIM_EFF as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ help: a constant with a similar name exists: `CONFIG_BT_LE_COEX_PHY_CODED_TX_RX_TLIM_EFF` | ::: /home/nixuser/projects/embedded/esp-idf-matter/target/riscv32imac-esp-espidf/debug/build/esp-idf-sys-c8d14348dff4d490/out/bindings.rs:754:1 | 754 | pub const CONFIG_BT_LE_COEX_PHY_CODED_TX_RX_TLIM_EFF: u32 = 0; | --------------------------------------------------------- similarly named constant `CONFIG_BT_LE_COEX_PHY_CODED_TX_RX_TLIM_EFF` defined here error[E0425]: cannot find value `BLE_HW_TARGET_CODE_CHIP_ECO0` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:522:41 | 522 | hw_target_code: crate::sys::BLE_HW_TARGET_CODE_CHIP_ECO0 as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `SLAVE_CE_LEN_MIN_DEFAULT` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:523:43 | 523 | slave_ce_len_min: crate::sys::SLAVE_CE_LEN_MIN_DEFAULT as _, | ^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `AGC_RECORRECT_EN` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:524:42 | 524 | hw_recorrect_en: crate::sys::AGC_RECORRECT_EN as _, | ^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `CONFIG_BT_CTRL_HW_CCA_VAL` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:525:37 | 525 | cca_thresh: crate::sys::CONFIG_BT_CTRL_HW_CCA_VAL as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `BT_CTRL_50_FEATURE_SUPPORT` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:528:43 | 528 | ble_50_feat_supp: crate::sys::BT_CTRL_50_FEATURE_SUPPORT != 0, | ^^^^^^^^^^^^^^^^^^^^^^^^^^ help: a constant with a similar name exists: `EXT_CSD_SEC_FEATURE_SUPPORT` | ::: /home/nixuser/projects/embedded/esp-idf-matter/target/riscv32imac-esp-espidf/debug/build/esp-idf-sys-c8d14348dff4d490/out/bindings.rs:4935:1 | 4935 | pub const EXT_CSD_SEC_FEATURE_SUPPORT: u32 = 231; | ------------------------------------------ similarly named constant `EXT_CSD_SEC_FEATURE_SUPPORT` defined here error[E0425]: cannot find value `DUPL_SCAN_CACHE_REFRESH_PERIOD` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:529:50 | 529 | dup_list_refresh_period: crate::sys::DUPL_SCAN_CACHE_REFRESH_PERIOD as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0425]: cannot find value `BT_CTRL_SCAN_BACKOFF_UPPERLIMITMAX` in module `crate::sys` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:530:53 | 530 | scan_backoff_upperlimitmax: crate::sys::BT_CTRL_SCAN_BACKOFF_UPPERLIMITMAX as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ not found in `crate::sys` error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `magic` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:497:13 | 497 | magic: crate::sys::ESP_BT_CTRL_CONFIG_MAGIC_VAL, | ^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `version` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:498:13 | 498 | version: crate::sys::ESP_BT_CTRL_CONFIG_VERSION, | ^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `controller_task_run_cpu` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:501:13 | 501 | controller_task_run_cpu: crate::sys::CONFIG_BT_CTRL_PINNED_TO_CORE as _, | ^^^^^^^^^^^^^^^^^^^^^^^ unknown field | help: a field with a similar name exists | 501 | controller_run_cpu: crate::sys::CONFIG_BT_CTRL_PINNED_TO_CORE as _, | ~~~~~~~~~~~~~~~~~~ error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `bluetooth_mode` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:502:13 | 502 | bluetooth_mode: crate::sys::CONFIG_BT_CTRL_MODE_EFF as _, | ^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `ble_max_act` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:503:13 | 503 | ble_max_act: crate::sys::CONFIG_BT_CTRL_BLE_MAX_ACT_EFF as _, | ^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `sleep_mode` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:504:13 | 504 | sleep_mode: crate::sys::CONFIG_BT_CTRL_SLEEP_MODE_EFF as _, | ^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `sleep_clock` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:505:13 | 505 | sleep_clock: crate::sys::CONFIG_BT_CTRL_SLEEP_CLOCK_EFF as _, | ^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `ble_st_acl_tx_buf_nb` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:506:13 | 506 | ble_st_acl_tx_buf_nb: crate::sys::CONFIG_BT_CTRL_BLE_STATIC_ACL_TX_BUF_NB as _, | ^^^^^^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `ble_hw_cca_check` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:507:13 | 507 | ble_hw_cca_check: crate::sys::CONFIG_BT_CTRL_HW_CCA_EFF as _, | ^^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `ble_adv_dup_filt_max` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:508:13 | 508 | ble_adv_dup_filt_max: crate::sys::CONFIG_BT_CTRL_ADV_DUP_FILT_MAX as _, | ^^^^^^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `ce_len_type` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:509:13 | 509 | ce_len_type: crate::sys::CONFIG_BT_CTRL_CE_LENGTH_TYPE_EFF as _, | ^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `hci_tl_type` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:510:13 | 510 | hci_tl_type: crate::sys::CONFIG_BT_CTRL_HCI_TL_EFF as _, | ^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `hci_tl_funcs` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:511:13 | 511 | hci_tl_funcs: core::ptr::null_mut(), | ^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `txant_dft` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:512:13 | 512 | txant_dft: crate::sys::CONFIG_BT_CTRL_TX_ANTENNA_INDEX_EFF as _, | ^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `rxant_dft` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:513:13 | 513 | rxant_dft: crate::sys::CONFIG_BT_CTRL_RX_ANTENNA_INDEX_EFF as _, | ^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `txpwr_dft` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:514:13 | 514 | txpwr_dft: crate::sys::CONFIG_BT_CTRL_DFT_TX_POWER_LEVEL_EFF as _, | ^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `cfg_mask` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:515:13 | 515 | cfg_mask: crate::sys::CFG_MASK, | ^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `scan_duplicate_mode` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:516:13 | 516 | scan_duplicate_mode: crate::sys::SCAN_DUPLICATE_MODE as _, | ^^^^^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `scan_duplicate_type` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:517:13 | 517 | scan_duplicate_type: crate::sys::SCAN_DUPLICATE_TYPE_VALUE as _, | ^^^^^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `normal_adv_size` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:518:13 | 518 | normal_adv_size: crate::sys::NORMAL_SCAN_DUPLICATE_CACHE_SIZE as _, | ^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `mesh_adv_size` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:519:13 | 519 | mesh_adv_size: crate::sys::MESH_DUPLICATE_SCAN_CACHE_SIZE as _, | ^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `hw_target_code` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:522:13 | 522 | hw_target_code: crate::sys::BLE_HW_TARGET_CODE_CHIP_ECO0 as _, | ^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `slave_ce_len_min` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:523:13 | 523 | slave_ce_len_min: crate::sys::SLAVE_CE_LEN_MIN_DEFAULT as _, | ^^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `hw_recorrect_en` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:524:13 | 524 | hw_recorrect_en: crate::sys::AGC_RECORRECT_EN as _, | ^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `cca_thresh` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:525:13 | 525 | cca_thresh: crate::sys::CONFIG_BT_CTRL_HW_CCA_VAL as _, | ^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `coex_param_en` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:526:13 | 526 | coex_param_en: false, | ^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `coex_use_hooks` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:527:13 | 527 | coex_use_hooks: false, | ^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `ble_50_feat_supp` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:528:13 | 528 | ble_50_feat_supp: crate::sys::BT_CTRL_50_FEATURE_SUPPORT != 0, | ^^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `dup_list_refresh_period` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:529:13 | 529 | dup_list_refresh_period: crate::sys::DUPL_SCAN_CACHE_REFRESH_PERIOD as _, | ^^^^^^^^^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others error[E0560]: struct `esp_idf_hal::sys::esp_bt_controller_config_t` has no field named `scan_backoff_upperlimitmax` --> /home/nixuser/.local/share/cargo/registry/src/index.crates.io-6f17d22bba15001f/esp-idf-svc-0.49.0/src/bt.rs:530:13 | 530 | scan_backoff_upperlimitmax: crate::sys::BT_CTRL_SCAN_BACKOFF_UPPERLIMITMAX as _, | ^^^^^^^^^^^^^^^^^^^^^^^^^^ `esp_idf_hal::sys::esp_bt_controller_config_t` does not have this field | = note: available fields are: `config_version`, `ble_ll_resolv_list_size`, `ble_hci_evt_hi_buf_count`, `ble_hci_evt_lo_buf_count`, `ble_ll_sync_list_cnt` ... and 46 others Some errors have detailed explanations: E0425, E0560. For more information about an error, try `rustc --explain E0425`. error: could not compile `esp-idf-svc` (lib) due to 58 previous errors ```
oddlama commented 5 days ago

I can offer a reproducible environment leading to this error in my fork, in case you are willing to install Nix. You can then enter a shell with the full environment by running nix --extra-experimental-features 'nix-command flakes' develop when you are in the main directory of my fork.

ivmarkov commented 5 days ago

One more question: when compiling for one of the targets which are part of the CI - like riscv32imc-esp-espidf - does it compile OK?

oddlama commented 5 days ago

Yes, riscv32imc-esp-espidf works

ivmarkov commented 5 days ago

Ok. Then it is not *nix specific and I'll look into it. Might take a day or two though.

ivmarkov commented 1 day ago

You'll need this PR to be merged in esp-idf-svc. As it turns out, we currently don't actually support proper Bluedroid initialization on anything but esp32/esp32s3/esp32c3.

In the meantime, I've added the branch supporting this PR to esp-idf-matter.

I can't test the project with the esp32c6 right now, as I'm not at home and don't have a c6 chip with me. If you can try it out on the c6, I would appreciate it!

oddlama commented 1 day ago

Thanks a ton for the quick fix! Works flawlessly on my C6 now!

ivmarkov commented 1 day ago

Thanks a ton for the quick fix! Works flawlessly on my C6 now!

The question is if esp-idf-matter examples work for you.

oddlama commented 16 hours ago

Sorry I only had time to test it thoroughly today. I tested the "light" example now.

There are no obvious errors reported by the device from what I can tell, everything looks like it is working. I just cannot get the device to actually finish commissioning in Home Assistant. But I can see that the Wifi credentials are correctly sent to the device, and it acquires an IP address. The phone just fails to find it afterwards, but this may very well be due to the HA matter addon being experimental.

I think you can probably directly tell whether this is device related or HA related issue from the logs:

ESP output log ``` I (23) boot: ESP-IDF 0572f11 2nd stage bootloader I (24) boot: compile time Jul 7 2024 18:01:57 I (24) boot: chip revision: v0.0 I (26) boot.esp32c6: SPI Speed : 80MHz I (31) boot.esp32c6: SPI Mode : DIO I (36) boot.esp32c6: SPI Flash Size : 4MB I (41) boot: Enabling RNG early entropy source... I (46) boot: Partition Table: I (50) boot: ## Label Usage Type ST Offset Length I (57) boot: 0 nvs WiFi data 01 02 00009000 00006000 I (64) boot: 1 phy_init RF data 01 01 0000f000 00001000 I (72) boot: 2 factory factory app 00 00 00010000 00300000 I (79) boot: End of partition table I (84) esp_image: segment 0: paddr=00010020 vaddr=42000020 size=1c1240h (1839680) map I (471) esp_image: segment 1: paddr=001d1268 vaddr=40800000 size=06db0h ( 28080) load I (479) esp_image: segment 2: paddr=001d8020 vaddr=421c8020 size=7af28h (503592) map I (583) esp_image: segment 3: paddr=00252f50 vaddr=40806db0 size=12818h ( 75800) load I (602) esp_image: segment 4: paddr=00265770 vaddr=408195d0 size=1072ch ( 67372) load I (627) boot: Loaded app from partition at offset 0x10000 I (627) boot: Disabling RNG early entropy source... I (638) cpu_start: Unicore app W (647) clk: esp_perip_clk_init() has not been implemented yet I (654) cpu_start: Pro cpu start user code I (654) cpu_start: cpu freq: 160000000 Hz I (654) cpu_start: Application information: I (657) cpu_start: Project name: libespidf I (662) cpu_start: App version: c9297bc I (667) cpu_start: Compile time: Jul 7 2024 18:01:54 I (673) cpu_start: ELF file SHA256: 000000000... I (678) cpu_start: ESP-IDF: 0572f11 I (683) cpu_start: Min chip rev: v0.0 I (688) cpu_start: Max chip rev: v0.99 I (693) cpu_start: Chip rev: v0.0 I (698) heap_init: Initializing. RAM available for dynamic allocation: I (705) heap_init: At 4082F520 len 0004D0F0 (308 KiB): RAM I (711) heap_init: At 4087C610 len 00002F54 (11 KiB): RAM I (717) heap_init: At 50000000 len 00003FE8 (15 KiB): RTCRAM I (724) spi_flash: detected chip: generic I (728) spi_flash: flash io: dio W (732) spi_flash: Detected size(8192k) larger than the size in the binary image header(4096k). Using the size in the binary image header. W (745) pcnt(legacy): legacy driver is deprecated, please migrate to `driver/pulse_cnt.h` W (754) timer_group: legacy driver is deprecated, please migrate to `driver/gptimer.h` I (763) sleep: Configure to isolate all GPIO pins in sleep state I (769) sleep: Enable automatic switching of GPIO sleep configuration I (776) coexist: coex firmware version: d96c1e51f I (782) coexist: coexist rom version 5b8dcfa I (787) main_task: Started on CPU0 I (787) main_task: Calling app_main() I (787) light: Starting... I (797) esp_idf_matter::stack: Async IO initialized; using `async-io-mini` I (817) rs_matter_stack::wifible: Matter Stack memory: 52328B I (817) esp_idf_svc::bt: Init bluetooth controller I (817) BLE_INIT: Using main XTAL as clock source I (827) BLE_INIT: ble controller commit:[39c6e05] I (827) esp_idf_svc::bt: Enable bluetooth controller I (837) phy_init: phy_version 290,81efd96,May 8 2024,10:42:13 I (897) phy: libbtbb version: f97b181, May 8 2024, 10:42:29 I (897) esp_idf_svc::bt: Init bluedroid I (897) esp_idf_svc::bt: Enable bluedroid I (907) rs_matter_stack::wifible: BLE driver initialized I (907) rs_matter_stack::wifible: Running Matter in commissioning mode (BLE) I (917) esp_idf_matter::ble: BLE Gap and Gatts initialized I (917) esp_idf_matter::ble: BLE Gap and Gatts subscriptions initialized I (937) esp_idf_matter::ble: Gatts BTP app registered I (937) rs_matter_stack: Responder memory: Responder=176B, Runner=10664B I (937) rs_matter::respond: Responder: Creating 4 handlers I (947) rs_matter::respond: Responder: Handlers size: 8040B I (947) rs_matter::respond: Busy Responder: Creating 2 handlers I (957) rs_matter::respond: Busy Responder: Handlers size: 712B I (967) rs_matter::pairing::code: Pairing Code: 0087-6800-071 I (967) rs_matter::pairing::qr: QR Code Text: MT:Y.K90KQS02Z6Q66D33P084L90Z.I332SQ43I15T0 I (1027) rs_matter::pairing::qr: █████████████████████████████████████ I (1027) rs_matter::pairing::qr: █████████████████████████████████████ I (1037) rs_matter::pairing::qr: ████ ▄▄▄▄▄ █ ▄▄▄ ██▄ █ ▄ █ ▄▄▄▄▄ ████ I (1057) rs_matter::pairing::qr: ████ █ █ █▄▄▀▀▀██▄ ▄ █▄█ █ █ ████ I (1067) rs_matter::pairing::qr: ████ █▄▄▄█ █▄▀▀▀▄█▄ █▀▀ █ █▄▄▄█ ████ I (1077) rs_matter::pairing::qr: ████▄▄▄▄▄▄▄█▄█ █ ▀▄▀ █ ▀▄█▄▄▄▄▄▄▄████ I (1087) rs_matter::pairing::qr: ████ ██ ▄ ▄▄ ▀██▄ ██▀▀ ▄█▄▀▀ ▀▄ ████ I (1107) rs_matter::pairing::qr: ████▀ ▀ █▄▄█▄▀▄▄ █▄ ▀███▀▀ ▄ ▀ ████ I (1117) rs_matter::pairing::qr: ████ ▀▀▀▄▄▄█▀▀ ▄ ▀▄ ▀ █▀ ██ ▄▄▀▀████ I (1127) rs_matter::pairing::qr: ████ ▀█ ▄▄ ▀ ██▀▄█▀▄▀█▀▀▄▀▀ ▄█ █████ I (1137) rs_matter::pairing::qr: ████ ▀█▄▀▄▀▀▀▀█▄▀ █ ▄█▀█▄█▀▄█▀▀▄████ I (1157) rs_matter::pairing::qr: ████ █▀ ▄ ▀▀ █▄█▀▄█▄▄ ▀▄█ ▀█ ▀████ I (1167) rs_matter::pairing::qr: ████▄▄▄███▄█▀▀▀ █▄ ▄▄▀██ ▄▄▄ ▀█▀█████ I (1177) rs_matter::pairing::qr: ████ ▄▄▄▄▄ █ ▄ ▄ █ ▀█▄ ▀ █▄█ █▀ ████ I (1197) rs_matter::pairing::qr: ████ █ █ █ █ █▄▄▄█▄▀█▀ ▄▄ ▄█▄▀▄████ I (1207) rs_matter::pairing::qr: ████ █▄▄▄█ ██▀▀▄▀▄█▄ ▄▀▄▀▄ ▀██▄▄████ I (1217) rs_matter::pairing::qr: ████▄▄▄▄▄▄▄█▄██▄█▄█▄█▄▄▄▄▄█▄▄█▄▄█████ I (1227) rs_matter::pairing::qr: █████████████████████████████████████ I (1247) rs_matter::pairing::qr: █████████████████████████████████████ I (1257) rs_matter::transport::core: Running Matter transport I (1267) rs_matter::transport::core: Waiting for orphaned RX packets I (6277) light: Light toggled I (11277) light: Light toggled I (16277) light: Light toggled I (21277) light: Light toggled I (26277) light: Light toggled I (31277) light: Light toggled I (31517) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [H|M|B|E,OP:6c] HANDSHAKE REQ HandshakeReq { versions: 4, mtu: 131, window_size: 5 } Selected version: 4, MTU: 128, window size: 5 I (31547) rs_matter::transport::network::btp::context: Subscribe request from 4B:05:75:42:C4:FD I (31547) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [H|M|B|E,OP:6c] HANDSHAKE RESP HandshakeResp { version: 4, mtu: 128, window_size: 5 } I (31607) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:0,CTR:0,LEN:62] READ 98B I (31617) rs_matter::transport::session: New exchange: 0::0 [SID:0,RSID:0,EID:e233] :: Responder(AcceptPending) I (31617) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [S,SID:0,CTR:fe41cf1,SRC:9a804cb7833d63c2][I,EID:e233,PROTO:0,OP:20] SC::PBKDFParamRequest => Processing (new exchange) I (31637) rs_matter::transport::core: Exchange 0::0 [SID:0,RSID:0,EID:e233]: Accepted I (31647) rs_matter::respond: Responder: Handler 0 / exchange 0::0: Starting I (31657) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [U,SID:0,CTR:1583f86,DST:9a804cb7833d63c2][EID:e233,PROTO:0,OP:21] SC::PBKDFParamResponse => Sending I (31667) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B,ACTR:0,CTR:1,LEN:8b] WRITE 123B I (31687) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C|E,CTR:2] WRITE 16B I (31787) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:2,CTR:1,LEN:5c] READ 92B I (31797) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [S,SID:0,CTR:fe41cf2,SRC:9a804cb7833d63c2][I,EID:e233,PROTO:0,OP:22] SC::PASEPake1 => Processing I (33677) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [U,SID:0,CTR:1583f87,DST:9a804cb7833d63c2][EID:e233,PROTO:0,OP:23] SC::PASEPake2 => Sending I (33687) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B,ACTR:1,CTR:3,LEN:7f] WRITE 123B I (33697) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C|E,CTR:4] WRITE 4B I (33847) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:4,CTR:2,LEN:3b] READ 59B I (33857) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [S,SID:0,CTR:fe41cf3,SRC:9a804cb7833d63c2][I,EID:e233,PROTO:0,OP:24] SC::PASEPake3 => Processing I (33867) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [U,SID:0,CTR:1583f88,DST:9a804cb7833d63c2][EID:e233,PROTO:0,OP:40] SC::StatusReport => Sending I (33877) rs_matter::respond: Responder: Handler 0 / exchange 0::0: Completed I (33887) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:2,CTR:5,LEN:1e] WRITE 30B I (33967) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:5,CTR:3,LEN:33] READ 51B I (33977) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e234] :: Responder(AcceptPending) I (33977) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13acb][I,EID:e234,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (33997) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e234]: Accepted I (34007) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (34017) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab5a][EID:e234,PROTO:1,OP:5] IM::ReportData => Sending I (34027) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (34037) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:3,CTR:6,LEN:53] WRITE 83B I (34107) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:6,CTR:4,LEN:34] READ 52B I (34117) rs_matter::transport::session: New exchange: 1::1 [SID:1,RSID:2bf2,EID:e235] :: Responder(AcceptPending) I (34117) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13acc][I,EID:e235,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (34137) rs_matter::transport::core: Exchange 1::1 [SID:1,RSID:2bf2,EID:e235]: Accepted I (34147) rs_matter::respond: Responder: Handler 0 / exchange 1::1: Starting I (34157) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab5b][EID:e235,PROTO:1,OP:5] IM::ReportData => Sending I (34167) rs_matter::respond: Responder: Handler 0 / exchange 1::1: Completed I (34177) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:4,CTR:7,LEN:40] WRITE 64B I (34247) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:7,CTR:5,LEN:33] READ 51B I (34257) rs_matter::transport::session: New exchange: 1::2 [SID:1,RSID:2bf2,EID:e236] :: Responder(AcceptPending) I (34257) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13acd][I,EID:e236,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (34277) rs_matter::transport::core: Exchange 1::2 [SID:1,RSID:2bf2,EID:e236]: Accepted I (34287) rs_matter::respond: Responder: Handler 0 / exchange 1::2: Starting I (34297) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab5c][EID:e236,PROTO:1,OP:5] IM::ReportData => Sending I (34307) rs_matter::respond: Responder: Handler 0 / exchange 1::2: Completed I (34317) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:5,CTR:8,LEN:40] WRITE 64B I (34367) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:8,CTR:6,LEN:33] READ 51B I (34377) rs_matter::transport::session: New exchange: 1::3 [SID:1,RSID:2bf2,EID:e237] :: Responder(AcceptPending) I (34377) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ace][I,EID:e237,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (34397) rs_matter::transport::core: Exchange 1::3 [SID:1,RSID:2bf2,EID:e237]: Accepted I (34407) rs_matter::respond: Responder: Handler 0 / exchange 1::3: Starting I (34417) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab5d][EID:e237,PROTO:1,OP:5] IM::ReportData => Sending I (34427) rs_matter::respond: Responder: Handler 0 / exchange 1::3: Completed I (34437) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:6,CTR:9,LEN:40] WRITE 64B I (34487) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:9,CTR:7,LEN:33] READ 51B I (34497) rs_matter::transport::session: New exchange: 1::4 [SID:1,RSID:2bf2,EID:e238] :: Responder(AcceptPending) I (34497) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13acf][I,EID:e238,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (34517) rs_matter::transport::core: Exchange 1::4 [SID:1,RSID:2bf2,EID:e238]: Accepted I (34527) rs_matter::respond: Responder: Handler 0 / exchange 1::4: Starting I (34537) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab5e][EID:e238,PROTO:1,OP:5] IM::ReportData => Sending I (34547) rs_matter::respond: Responder: Handler 0 / exchange 1::4: Completed I (34557) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:7,CTR:a,LEN:3f] WRITE 63B I (34627) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:a,CTR:8,LEN:33] READ 51B I (34637) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e239] :: Responder(AcceptPending) I (34637) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ad0][I,EID:e239,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (34657) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e239]: Accepted I (34667) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (34677) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab5f][EID:e239,PROTO:1,OP:5] IM::ReportData => Sending I (34687) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (34697) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:8,CTR:b,LEN:3f] WRITE 63B I (34747) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:b,CTR:9,LEN:33] READ 51B I (34757) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e23a] :: Responder(AcceptPending) I (34757) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ad1][I,EID:e23a,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (34777) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e23a]: Accepted I (34787) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (34797) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab60][EID:e23a,PROTO:1,OP:5] IM::ReportData => Sending I (34807) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (34817) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:9,CTR:c,LEN:3f] WRITE 63B I (35207) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:c,CTR:a,LEN:3e] READ 62B I (35217) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e23b] :: Responder(AcceptPending) I (35217) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ad2][I,EID:e23b,PROTO:1,OP:8] IM::InvokeRequest => Processing (new exchange) I (35237) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e23b]: Accepted I (35247) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (35257) rs_matter::data_model::sdm::noc: Handling command CertChainRequest I (35257) rs_matter::data_model::sdm::noc: Received data: 1: { I (35267) rs_matter::data_model::sdm::noc: Received Cert Type:1 I (35277) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab61][EID:e23b,PROTO:1,OP:9] IM::InvokeResponse => Sending I (35297) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (35297) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B,ACTR:a,CTR:d,LEN:229] WRITE 123B I (35307) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C,CTR:e] WRITE 126B I (35327) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C,CTR:f] WRITE 126B I (35347) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C,CTR:10] WRITE 126B I (35467) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A,ACTR:10,CTR:b] READ 0B I (35467) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|C|E,ACTR:b,CTR:11] WRITE 52B I (35527) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:11,CTR:c,LEN:33] READ 51B I (35537) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e23c] :: Responder(AcceptPending) I (35537) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ad3][I,EID:e23c,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (35557) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e23c]: Accepted I (35567) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (35577) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab62][EID:e23c,PROTO:1,OP:5] IM::ReportData => Sending I (35587) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (35597) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:c,CTR:12,LEN:53] WRITE 83B I (35647) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:12,CTR:d,LEN:34] READ 52B I (35657) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e23d] :: Responder(AcceptPending) I (35657) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ad4][I,EID:e23d,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (35677) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e23d]: Accepted I (35687) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (35697) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab63][EID:e23d,PROTO:1,OP:5] IM::ReportData => Sending I (35707) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (35717) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:d,CTR:13,LEN:40] WRITE 64B I (35847) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:13,CTR:e,LEN:27] READ 39B I (35857) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e23e] :: Responder(AcceptPending) I (35857) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ad5][I,EID:e23e,PROTO:1,OP:a] IM::TimedRequest => Processing (new exchange) I (35877) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e23e]: Accepted I (35887) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (35897) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab64][EID:e23e,PROTO:1,OP:1] IM::StatusResponse => Sending I (35907) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:e,CTR:14,LEN:23] WRITE 35B I (35947) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:14,CTR:f,LEN:41] READ 65B I (35957) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ad6][I,EID:e23e,PROTO:1,OP:8] IM::InvokeRequest => Processing I (35967) rs_matter::data_model::sdm::general_commissioning: Handling command ARM Fail Safe I (35977) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab65][EID:e23e,PROTO:1,OP:9] IM::InvokeResponse => Sending I (35987) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (35997) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:f,CTR:15,LEN:3f] WRITE 63B I (36067) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:15,CTR:10,LEN:33] READ 51B I (36077) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e23f] :: Responder(AcceptPending) I (36077) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ad7][I,EID:e23f,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (36097) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e23f]: Accepted I (36107) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (36117) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab66][EID:e23f,PROTO:1,OP:5] IM::ReportData => Sending I (36127) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (36137) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:10,CTR:16,LEN:3f] WRITE 63B I (36187) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:16,CTR:11,LEN:27] READ 39B I (36197) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e240] :: Responder(AcceptPending) I (36197) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ad8][I,EID:e240,PROTO:1,OP:a] IM::TimedRequest => Processing (new exchange) I (36217) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e240]: Accepted I (36227) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (36237) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab67][EID:e240,PROTO:1,OP:1] IM::StatusResponse => Sending I (36247) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:11,CTR:17,LEN:23] WRITE 35B I (36287) light: Light toggled I (36287) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:17,CTR:12,LEN:46] READ 70B I (36297) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ad9][I,EID:e240,PROTO:1,OP:8] IM::InvokeRequest => Processing I (36307) rs_matter::data_model::sdm::general_commissioning: Handling command Set Regulatory Config I (36317) rs_matter::data_model::sdm::general_commissioning: Received country code: [68, 69] I (36327) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab68][EID:e240,PROTO:1,OP:9] IM::InvokeResponse => Sending I (36337) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (36347) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:12,CTR:18,LEN:3f] WRITE 63B I (36387) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:18,CTR:13,LEN:3e] READ 62B I (36397) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e241] :: Responder(AcceptPending) I (36397) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ada][I,EID:e241,PROTO:1,OP:8] IM::InvokeRequest => Processing (new exchange) I (36417) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e241]: Accepted I (36427) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (36437) rs_matter::data_model::sdm::noc: Handling command CertChainRequest I (36437) rs_matter::data_model::sdm::noc: Received data: 1: { I (36447) rs_matter::data_model::sdm::noc: Received Cert Type:2 I (36457) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab69][EID:e241,PROTO:1,OP:9] IM::InvokeResponse => Sending I (36477) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (36477) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B,ACTR:13,CTR:19,LEN:20c] WRITE 123B I (36487) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C,CTR:1a] WRITE 126B I (36507) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C,CTR:1b] WRITE 126B I (36547) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C,CTR:1c] WRITE 126B I (36667) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A,ACTR:1c,CTR:14] READ 0B I (36667) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|C|E,ACTR:14,CTR:1d] WRITE 23B I (36727) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:1d,CTR:15,LEN:5e] READ 94B I (36737) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e242] :: Responder(AcceptPending) I (36737) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13adb][I,EID:e242,PROTO:1,OP:8] IM::InvokeRequest => Processing (new exchange) I (36757) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e242]: Accepted I (36767) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (36777) rs_matter::data_model::sdm::noc: Handling command AttestationRequest I (36777) rs_matter::data_model::sdm::noc: Received Attestation Nonce:OctetStr([187, 84, 175, 87, 119, 197, 111, 183, 0, 0, 1, 144, 147, 176, 128, 14, 1, 158, 171, 14, 3, 180, 216, 190, 87, 229, 85, 69, 191, 86, 118, 73]) I (37127) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab6a][EID:e242,PROTO:1,OP:9] IM::InvokeResponse => Sending I (37147) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (37147) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B,ACTR:15,CTR:1e,LEN:2c9] WRITE 123B I (37157) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C,CTR:1f] WRITE 126B I (37167) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C,CTR:20] WRITE 126B I (37247) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C,CTR:21] WRITE 126B I (37367) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A,ACTR:21,CTR:16] READ 0B I (37367) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|C,ACTR:16,CTR:22] WRITE 125B I (37377) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C|E,CTR:23] WRITE 87B I (37467) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:23,CTR:17,LEN:60] READ 96B I (37477) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e243] :: Responder(AcceptPending) I (37477) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13adc][I,EID:e243,PROTO:1,OP:8] IM::InvokeRequest => Processing (new exchange) I (37497) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e243]: Accepted I (37507) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (37517) rs_matter::data_model::sdm::noc: Handling command CSRRequest I (37517) rs_matter::data_model::sdm::noc: Received CSR Nonce:OctetStr([178, 28, 40, 231, 91, 96, 125, 104, 0, 0, 1, 144, 147, 176, 128, 14, 1, 158, 171, 14, 3, 94, 59, 104, 176, 125, 5, 236, 109, 76, 38, 217]) I (38197) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab6b][EID:e243,PROTO:1,OP:9] IM::InvokeResponse => Sending I (38207) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (38207) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B,ACTR:17,CTR:24,LEN:172] WRITE 123B I (38217) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C,CTR:25] WRITE 126B I (38237) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [C|E,CTR:26] WRITE 121B I (38827) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B,ACTR:26,CTR:18,LEN:133] READ 123B I (38867) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [C,CTR:19] READ 126B I (38907) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [C|E,CTR:1a] READ 58B I (38917) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e244] :: Responder(AcceptPending) I (38917) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13add][I,EID:e244,PROTO:1,OP:8] IM::InvokeRequest => Processing (new exchange) I (38937) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e244]: Accepted I (38947) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (38957) rs_matter::data_model::sdm::noc: Handling command AddTrustedRootCert I (38957) rs_matter::data_model::sdm::noc: Received Trusted Cert:OctetStr([15, 30, 1, 1, 1, 24, 2, 1, 37, 3, 27, 14, 4e, 7e, 2c, a5, 6, 96, d6, 89, 18, 26, 4, 0, ae, 14, 2e, 26, 5, 0, b4, ac, 53, 37, 6, 27, 14, 4e, 7e, 2c, a5, 6, 96, d6, 89, 18, 24, 7, 1, 24, 8, 1, 30, 9, 41, 4, f, 96, a5, 61, ee, 4f, f9, 12, f7, 62, 38, 85, b0, de, 44, e7, 5b, 2b, ba, 54, 6b, b2, 63, 1e, b9, ac, b3, 33, 94, a3, 80, 85, 82, c9, d0, d4, 45, e9, d3, 6c, 82, e3, d7, a4, 54, 3d, 8e, 92, d8, e0, 50, c2, d0, 5f, 35, 79, 57, ac, f6, 9c, 37, 80, 87, 3e, 37, a, 35, 1, 29, 1, 18, 24, 2, 60, 30, 4, 14, 6b, 12, 9d, fe, f3, fe, ae, 95, 5f, 73, c8, 5d, 13, 0, 99, 5, fa, 47, 3d, 2f, 30, 5, 14, 6b, 12, 9d, fe, f3, fe, ae, 95, 5f, 73, c8, 5d, 13, 0, 99, 5, fa, 47, 3d, 2f, 18, 30, b, 40, c1, 75, 14, 38, ba, 25, b1, d4, 30, 9d, 27, de, 6a, 53, e4, e4, 19, da, 7f, f1, 41, c2, 17, 74, 70, af, 79, d2, 31, 5c, af, 77, 3d, 87, ba, 9b, af, 4f, 30, bf, de, e0, 52, c2, 26, a3, d3, 4a, e7, 51, 95, 1a, 9b, 37, b8, 5b, c8, b2, 41, 90, 86, dc, 55, 8f, 18]) I (39047) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab6c][EID:e244,PROTO:1,OP:9] IM::InvokeResponse => Sending I (39067) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (39077) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:1a,CTR:27,LEN:3f] WRITE 63B I (39167) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B,ACTR:27,CTR:1b,LEN:18a] READ 123B I (39207) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [C,CTR:1c] READ 126B I (39247) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [C,CTR:1d] READ 126B I (39287) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [C|E,CTR:1e] READ 19B I (39297) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e245] :: Responder(AcceptPending) I (39297) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ade][I,EID:e245,PROTO:1,OP:8] IM::InvokeRequest => Processing (new exchange) I (39317) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e245]: Accepted I (39317) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (39327) rs_matter::data_model::sdm::noc: Handling command AddNOC I (39337) rs_matter::data_model::sdm::noc: Received NOC as: Version: [0] [2] Serial Num: [77, f6, a0, a3, df, b7, 2a, a5] Signature Algorithm: ECDSA with SHA256 Issuer: Chip Root CA Id: "89D69606A52C7E4E" Validity: Not Before: 2024-07-01 0:00:00.0 +00:00:00 Not After: 2044-06-26 0:00:00.0 +00:00:00 Subject: Chip Fabric Id: "01EA7FD730E6B3B8" Chip Node Id: "3150C5EDF49CB8BC" Chip NOC CAT Id: "00010001" Common Name: "Android Local Fabric" Public Key Algorithm ECPubKey Prime256v1 Public-Key: [4, 12, 1f, 13, 1b, 71, 4e, f4, 89, de, 15, f6, 81, bd, 6a, 63, a5, a6, d2, 6f, 40, 4e, bb, fe, b6, 9b, 4, a0, 71, 1b, 3, 9f, b0, d5, 75, 13, 17, fe, 12, 7e, 49, a2, f2, 72, b7, 19, d8, 93, 23, 1b, 6b, f6, 46, 5, b0, ca, 70, 56, 67, 98, b4, e7, 5c, 3d, 2e] X509v3 extensions: [3] X509v3 Basic Constraints critical: true value: X509v3 Key Usage critical: true value: digitalSignature [80, 0] X509v3 Extended Key Usage critical: true value: ClientAuth ServerAuth Subject Key ID value: [92, f6, 87, b9, bb, ba, 90, 8e, 5e, 12, 43, ae, 55, 54, d0, d2, 54, 49, c6, a0] Auth Key ID value: [0][6b, 12, 9d, fe, f3, fe, ae, 95, 5f, 73, c8, 5d, 13, 0, 99, 5, fa, 47, 3d, 2f] Signature: [bd, 7, ea, 61, a2, f7, 5a, d6, e3, d, df, fc, 7d, c2, ec, 38, 57, 44, 4d, 55, 76, 51, b2, 25, 38, fc, c, cd, 29, e5, 9d, 9, b6, 57, d0, 8f, d1, b1, 52, 4a, f4, 6b, d8, 44, c5, ae, 9b, 1d, 95, cc, f4, 62, 53, 19, 1a, 5f, 77, b1, 83, b4, ea, 8f, d, 7c] I (39507) rs_matter::fabric: MDNS Service Name: 62588B9380516AB4-3150C5EDF49CB8BC I (39517) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab6d][EID:e245,PROTO:1,OP:9] IM::InvokeResponse => Sending I (39527) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (39537) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:1e,CTR:28,LEN:42] WRITE 66B I (39667) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B,ACTR:28,CTR:1f,LEN:87] READ 123B I (39707) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [C|E,CTR:20] READ 12B I (39717) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e246] :: Responder(AcceptPending) I (39717) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13adf][I,EID:e246,PROTO:1,OP:8] IM::InvokeRequest => Processing (new exchange) I (39737) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e246]: Accepted I (39747) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (39757) rs_matter_stack::wifi::comm: AddOrUpdateWifiNetwork I (39757) rs_matter_stack::wifi::comm: Added network with SSID IOT_Test_Wifi_2 I (39767) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab6e][EID:e246,PROTO:1,OP:9] IM::InvokeResponse => Sending I (39777) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (39787) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:20,CTR:29,LEN:3f] WRITE 63B I (39847) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:29,CTR:21,LEN:33] READ 51B I (39857) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e247] :: Responder(AcceptPending) I (39857) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ae0][I,EID:e247,PROTO:1,OP:2] IM::ReadRequest => Processing (new exchange) I (39877) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e247]: Accepted I (39887) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (39897) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab6f][EID:e247,PROTO:1,OP:5] IM::ReportData => Sending I (39907) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (39917) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:21,CTR:2a,LEN:3f] WRITE 63B I (39967) rs_matter::transport::network::btp::session: >>>>> (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:2a,CTR:22,LEN:45] READ 69B I (39977) rs_matter::transport::session: New exchange: 1::0 [SID:1,RSID:2bf2,EID:e248] :: Responder(AcceptPending) I (39977) rs_matter::transport::core: >>>>> BTP 4B:05:75:42:C4:FD [SID:1,CTR:fe13ae1][I,EID:e248,PROTO:1,OP:8] IM::InvokeRequest => Processing (new exchange) I (39997) rs_matter::transport::core: Exchange 1::0 [SID:1,RSID:2bf2,EID:e248]: Accepted I (40007) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Starting I (40017) rs_matter_stack::wifi::comm: ConnectNetwork I (40017) rs_matter_stack::wifi::comm: Request to connect to network with SSID IOT_Test_Wifi_2 received I (40027) rs_matter::transport::exchange: <<<<< BTP 4B:05:75:42:C4:FD [SID:2bf2,CTR:fbdab70][EID:e248,PROTO:1,OP:9] IM::InvokeResponse => Sending I (40047) rs_matter::respond: Responder: Handler 0 / exchange 1::0: Completed I (40047) rs_matter::transport::network::btp::session: <<<<< (BTP IO) 4B:05:75:42:C4:FD [A|B|E,ACTR:22,CTR:2b,LEN:3f] WRITE 63B W (40067) rs_matter_stack::wifi: Giving BLE/BTP extra 4 seconds for any outstanding messages before switching to Wifi W (41147) BT_HCI: hcif disc complete: hdl 0x1, rsn 0x13 I (41147) rs_matter::transport::network::btp::context: Unsubscribe request from 4B:05:75:42:C4:FD I (41147) rs_matter::transport::network::btp::context: Session 4B:05:75:42:C4:FD removed I (41287) light: Light toggled W (44077) BT_APPL: bta_dm_disable BTA_DISABLE_DELAY set to 200 ms I (44277) pp: pp rom version: 5b8dcfa I (44277) net80211: net80211 rom version: 5b8dcfa I (44287) wifi:wifi driver task: 4085b180, prio:23, stack:6656, core=0 I (44287) wifi:wifi firmware version: 3e0076f I (44287) wifi:wifi certification version: v7.0 I (44297) wifi:config NVS flash: enabled I (44297) wifi:config nano formating: disabled I (44297) wifi:mac_version:HAL_MAC_ESP32AX_761,ut_version:N I (44307) wifi:Init data frame dynamic rx buffer num: 32 I (44307) wifi:Init static rx mgmt buffer num: 10 I (44317) wifi:Init management short buffer num: 32 I (44317) wifi:Init dynamic tx buffer num: 32 I (44327) wifi:Init static tx FG buffer num: 2 I (44327) wifi:Init static rx buffer size: 1700 I (44337) wifi:Init static rx buffer num: 10 I (44337) wifi:Init dynamic rx buffer num: 32 I (44337) wifi_init: rx ba win: 6 I (44347) wifi_init: tcpip mbox: 32 I (44347) wifi_init: udp mbox: 6 I (44357) wifi_init: tcp mbox: 6 I (44357) wifi_init: tcp tx win: 5760 I (44357) wifi_init: tcp rx win: 5760 I (44367) wifi_init: tcp mss: 1440 I (44367) wifi_init: WiFi IRAM OP enabled I (44377) wifi_init: WiFi RX IRAM OP enabled I (44387) rs_matter_stack::wifible: Wifi driver initialized I (44387) rs_matter_stack::wifible: Running Matter in operating mode (Wifi) I (44397) rs_matter_stack::wifi: Trying with requested network first - SSID: IOT_Test_Wifi_2 I (44407) rs_matter_stack::wifi::mgmt: Connecting to SSID IOT_Test_Wifi_2 I (44407) rs_matter_stack::wifi::mgmt: Connecting with Client(ClientConfiguration { ssid: "IOT_Test_Wifi_2", bssid: None, auth_method: WPAWPA2Personal, channel: None, scan_method: CompleteScan(Signal), pmf_cfg: NotCapable }) W (44447) wifi:ACK_TAB0 :0x 90a0b, QAM16:0x9 (24Mbps), QPSK:0xa (12Mbps), BPSK:0xb (6Mbps) W (44447) wifi:CTS_TAB0 :0x 90a0b, QAM16:0x9 (24Mbps), QPSK:0xa (12Mbps), BPSK:0xb (6Mbps) W (44457) wifi:(agc)0x600a7128:0xd200aa0e, min.avgNF:0xce->0xd2(dB), RCalCount:0xa, min.RRssi:0xa0e(-95.12) W (44467) wifi:(TB)WDEV_PWR_TB_MCS0:19 W (44467) wifi:(TB)WDEV_PWR_TB_MCS1:19 W (44467) wifi:(TB)WDEV_PWR_TB_MCS2:19 W (44477) wifi:(TB)WDEV_PWR_TB_MCS3:19 W (44477) wifi:(TB)WDEV_PWR_TB_MCS4:19 W (44477) wifi:(TB)WDEV_PWR_TB_MCS5:19 W (44487) wifi:(TB)WDEV_PWR_TB_MCS6:18 W (44487) wifi:(TB)WDEV_PWR_TB_MCS7:18 W (44487) wifi:(TB)WDEV_PWR_TB_MCS8:17 W (44497) wifi:(TB)WDEV_PWR_TB_MCS9:15 W (44497) wifi:(TB)WDEV_PWR_TB_MCS10:15 W (44497) wifi:(TB)WDEV_PWR_TB_MCS11:15 I (44507) wifi:11ax coex: WDEVAX_PTI0(0x55777555), WDEVAX_PTI1(0x00003377). I (44507) wifi:mode : sta (40:4c:ca:11:22:33) I (44517) wifi:enable tsf I (44517) rs_matter_stack: Waiting for the network to come up... I (46287) light: Light toggled I (47257) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (47257) wifi:(connect)dot11_authmode:0x3, pairwise_cipher:0x3, group_cipher:0x3 I (47257) wifi:state: init -> auth (b0) I (47267) wifi:state: auth -> assoc (0) I (47267) wifi:Association refused temporarily, comeback time 1200 (TUs) I (48497) wifi:state: assoc -> assoc (0) I (48507) wifi:state: assoc -> init (9c0) I (48507) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (51287) light: Light toggled I (56287) light: Light toggled I (59517) rs_matter_stack::wifi::mgmt: Connecting with Client(ClientConfiguration { ssid: "IOT_Test_Wifi_2", bssid: None, auth_method: WPA2WPA3Personal, channel: None, scan_method: CompleteScan(Signal), pmf_cfg: NotCapable }) W (59527) wifi:(itwt)itwt_stop_process! I (59537) wifi:flush txq I (59537) wifi:stop sw txq I (59537) wifi:lmac stop hw txq W (59567) wifi:ACK_TAB0 :0x 90a0b, QAM16:0x9 (24Mbps), QPSK:0xa (12Mbps), BPSK:0xb (6Mbps) W (59567) wifi:CTS_TAB0 :0x 90a0b, QAM16:0x9 (24Mbps), QPSK:0xa (12Mbps), BPSK:0xb (6Mbps) W (59567) wifi:(agc)0x600a7128:0xd20f4a14, min.avgNF:0xce->0xd2(dB), RCalCount:0xf4, min.RRssi:0xa14(-94.75) W (59577) wifi:(TB)WDEV_PWR_TB_MCS0:19 W (59577) wifi:(TB)WDEV_PWR_TB_MCS1:19 W (59587) wifi:(TB)WDEV_PWR_TB_MCS2:19 W (59587) wifi:(TB)WDEV_PWR_TB_MCS3:19 W (59597) wifi:(TB)WDEV_PWR_TB_MCS4:19 W (59597) wifi:(TB)WDEV_PWR_TB_MCS5:19 W (59597) wifi:(TB)WDEV_PWR_TB_MCS6:18 W (59607) wifi:(TB)WDEV_PWR_TB_MCS7:18 W (59607) wifi:(TB)WDEV_PWR_TB_MCS8:17 W (59607) wifi:(TB)WDEV_PWR_TB_MCS9:15 W (59617) wifi:(TB)WDEV_PWR_TB_MCS10:15 W (59617) wifi:(TB)WDEV_PWR_TB_MCS11:15 I (59617) wifi:11ax coex: WDEVAX_PTI0(0x55777555), WDEVAX_PTI1(0x00003377). I (59627) wifi:mode : sta (40:4c:ca:11:22:33) I (59627) wifi:enable tsf I (61287) light: Light toggled I (66287) light: Light toggled I (71287) light: Light toggled I (74637) rs_matter_stack::wifi::mgmt: Connecting with Client(ClientConfiguration { ssid: "IOT_Test_Wifi_2", bssid: None, auth_method: WEP, channel: None, scan_method: CompleteScan(Signal), pmf_cfg: NotCapable }) W (74647) wifi:(itwt)itwt_stop_process! I (74647) wifi:flush txq I (74647) wifi:stop sw txq I (74657) wifi:lmac stop hw txq W (74677) wifi:ACK_TAB0 :0x 90a0b, QAM16:0x9 (24Mbps), QPSK:0xa (12Mbps), BPSK:0xb (6Mbps) W (74677) wifi:CTS_TAB0 :0x 90a0b, QAM16:0x9 (24Mbps), QPSK:0xa (12Mbps), BPSK:0xb (6Mbps) W (74677) wifi:(agc)0x600a7128:0xd21cea18, min.avgNF:0xce->0xd2(dB), RCalCount:0x1ce, min.RRssi:0xa18(-94.50) W (74687) wifi:(TB)WDEV_PWR_TB_MCS0:19 W (74697) wifi:(TB)WDEV_PWR_TB_MCS1:19 W (74697) wifi:(TB)WDEV_PWR_TB_MCS2:19 W (74697) wifi:(TB)WDEV_PWR_TB_MCS3:19 W (74707) wifi:(TB)WDEV_PWR_TB_MCS4:19 W (74707) wifi:(TB)WDEV_PWR_TB_MCS5:19 W (74707) wifi:(TB)WDEV_PWR_TB_MCS6:18 W (74717) wifi:(TB)WDEV_PWR_TB_MCS7:18 W (74717) wifi:(TB)WDEV_PWR_TB_MCS8:17 W (74717) wifi:(TB)WDEV_PWR_TB_MCS9:15 W (74727) wifi:(TB)WDEV_PWR_TB_MCS10:15 W (74727) wifi:(TB)WDEV_PWR_TB_MCS11:15 I (74727) wifi:11ax coex: WDEVAX_PTI0(0x55777555), WDEVAX_PTI1(0x00003377). I (74737) wifi:mode : sta (40:4c:ca:11:22:33) I (74737) wifi:enable tsf I (76287) light: Light toggled I (77487) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (77487) wifi:(connect)dot11_authmode:0x3, pairwise_cipher:0x3, group_cipher:0x3 I (77487) wifi:state: init -> auth (b0) I (77497) wifi:state: auth -> assoc (0) I (77497) wifi:state: assoc -> run (10) I (77497) wifi:(trc)phytype:CBW20-SGI, snr:29, maxRate:144, highestRateIdx:0 I (77507) wifi:(trc)rate(L-MCS5, schedIdx:3), ampdu(rate:L-MCS5, schedIdx(3, stop:8)), snr:29, ampduState:wait operational I (77517) wifi:ifidx:0, rssi:-67, nf:-96, phytype(0x3, CBW20-SGI), phymode(0x3, 11bgn), max_rate:1440, he:0 I (77527) wifi:max ampdu length exponent:3(65535 bytes), mmss:6(8 us) I (77537) wifi:connected with IOT_Test_Wifi_2, aid = 5, channel 1, BW20, bssid = f8:a1:1a:16:43:a3 I (77537) wifi:cipher(pairwise:0x3, group:0x3), pmf:1, security:WPA2-PSK, phy:11bgn, rssi:-67 I (77557) wifi:pm start, type: 1, twt_start:0 I (77557) wifi:pm start, type:1, aid:0x5, trans-BSSID:f8:a1:1a:16:43:a3, BSSID[5]:0x38, mbssid(max-indicator:0, index:0), he:0 I (77567) wifi:dp: 1, bi: 102400, li: 3, scale listen interval from 307200 us to 307200 us I (77577) wifi:set rx beacon pti, rx_bcn_pti: 10, bcn_timeout: 25000, mt_pti: 10, mt_time: 10000 I (77577) wifi:[ADDBA]TX addba request, tid:0, dialogtoken:1, bufsize:64, A-MSDU:0(not supported), policy:1(IMR), ssn:0(0x0) I (77587) wifi:[ADDBA]TX addba request, tid:7, dialogtoken:2, bufsize:64, A-MSDU:0(not supported), policy:1(IMR), ssn:0(0x20) I (77607) wifi:[ADDBA]TX addba request, tid:5, dialogtoken:3, bufsize:64, A-MSDU:0(not supported), policy:1(IMR), ssn:0(0x0) I (77617) wifi:[ADDBA]RX addba response, status:0, tid:0/tb:1(0xa1), bufsize:64, batimeout:0, txa_wnd:64 I (77627) wifi:[ADDBA]RX addba response, status:0, tid:7/tb:1(0xa1), bufsize:64, batimeout:0, txa_wnd:64 I (77637) wifi:[ADDBA]RX addba response, status:0, tid:5/tb:1(0xa1), bufsize:64, batimeout:0, txa_wnd:64 I (77637) wifi:AP's beacon interval = 102400 us, DTIM period = 1 I (77647) rs_matter_stack::wifi::mgmt: Successfully connected with Client(ClientConfiguration { ssid: "IOT_Test_Wifi_2", bssid: None, auth_method: WEP, channel: None, scan_method: CompleteScan(Signal), pmf_cfg: NotCapable }) W (77657) wifi:idx:0, ifx:0, tid:0, TAHI:0x1003834, TALO:0x291ee7f8, (ssn:0, win:64, cur_ssn:0), CONF:0xc0000005 I (77677) rs_matter_stack::wifi::mgmt: Connected to SSID IOT_Test_Wifi_2 I (78647) esp_netif_handlers: sta ip: 192.168.1.86, mask: 255.255.255.0, gw: 192.168.1.1 I (79377) rs_matter_stack: Got IP network: IPv4: 192.168.1.86, IPv6: fe80::424c:caff:fe50:912c, Interface: 4, MAC: 40:4c:ca:11:22:33 I (79377) rs_matter_stack: Responder memory: Responder=176B, Runner=10664B I (79387) rs_matter::respond: Responder: Creating 4 handlers I (79387) rs_matter::respond: Responder: Handlers size: 8040B I (79397) rs_matter::respond: Busy Responder: Creating 2 handlers I (79407) rs_matter::respond: Busy Responder: Handlers size: 712B I (79407) rs_matter::transport::core: Running Matter transport I (79417) rs_matter::transport::core: Waiting for orphaned RX packets I (79427) rs_matter::transport::core: Running Matter built-in mDNS service I (79437) rs_matter::mdns::builtin: Broadcasting mDNS entry to 224.0.0.251:5353 I (79447) rs_matter::mdns::builtin: Broadcasting mDNS entry to [ff02::fb%4]:5353 I (81287) light: Light toggled I (86297) light: Light toggled I (87247) rs_matter::mdns::builtin: Replying to mDNS query from UDP [::ffff:192.168.1.100]:5353 on [ff02::fb%4]:5353 I (91297) light: Light toggled I (96297) light: Light toggled I (101297) light: Light toggled I (106297) light: Light toggled I (108247) rs_matter::mdns::builtin: Replying to mDNS query from UDP [::ffff:192.168.1.100]:5353 on [ff02::fb%4]:5353 I (108257) rs_matter::mdns::builtin: Replying to mDNS query from UDP [fe80::1ae7:efa9:632f:2cf6%4]:5353 on [ff02::fb%4]:5353 I (109447) rs_matter::mdns::builtin: Broadcasting mDNS entry to 224.0.0.251:5353 I (109457) rs_matter::mdns::builtin: Broadcasting mDNS entry to [ff02::fb%4]:5353 I (111307) light: Light toggled I (116307) light: Light toggled I (121307) light: Light toggled I (126307) light: Light toggled I (131307) light: Light toggled I (136317) light: Light toggled I (139467) rs_matter::mdns::builtin: Broadcasting mDNS entry to 224.0.0.251:5353 I (139467) rs_matter::mdns::builtin: Broadcasting mDNS entry to [ff02::fb%4]:5353 I (141317) light: Light toggled I (146317) light: Light toggled I (151317) light: Light toggled I (155347) rs_matter::mdns::builtin: Replying to mDNS query from UDP [::ffff:192.168.1.100]:5353 on [ff02::fb%4]:5353 I (156317) light: Light toggled I (161327) light: Light toggled I (166327) light: Light toggled I (169477) rs_matter::mdns::builtin: Broadcasting mDNS entry to 224.0.0.251:5353 I (169477) rs_matter::mdns::builtin: Broadcasting mDNS entry to [ff02::fb%4]:5353 I (171327) light: Light toggled I (176327) light: Light toggled I (178387) rs_matter::mdns::builtin: Replying to mDNS query from UDP [::ffff:192.168.1.100]:5353 on [ff02::fb%4]:5353 I (181327) light: Light toggled I (186327) light: Light toggled I (191337) light: Light toggled I (196337) light: Light toggled I (199487) rs_matter::mdns::builtin: Broadcasting mDNS entry to 224.0.0.251:5353 I (199487) rs_matter::mdns::builtin: Broadcasting mDNS entry to [ff02::fb%4]:5353 I (201337) light: Light toggled I (206337) light: Light toggled I (211337) light: Light toggled I (216347) light: Light toggled I (221347) light: Light toggled I (226347) light: Light toggled I (229497) rs_matter::mdns::builtin: Broadcasting mDNS entry to 224.0.0.251:5353 I (229497) rs_matter::mdns::builtin: Broadcasting mDNS entry to [ff02::fb%4]:5353 I (231347) light: Light toggled I (236347) light: Light toggled I (241357) light: Light toggled I (246357) light: Light toggled I (251357) light: Light toggled I (256357) light: Light toggled ```
ivmarkov commented 4 hours ago

There are no obvious errors reported by the device from what I can tell, everything looks like it is working. I just cannot get the device to actually finish commissioning in Home Assistant. But I can see that the Wifi credentials are correctly sent to the device, and it acquires an IP address.

What happens is that the first part of the commissioning process, which is running over BLE completes successfully. The second part of the commissioning, which is running over IPv6 however fails, as we are never contacted on the Matter UDP port.

99% this is due to some sort of mDNS issue, because:

I would check the following:

The reason for the above is that Matter runs over Ipv6. Moreover, more often than not it runs over link-local ipv6 addresses (again, google it, but these are - roughly speaking - something like the 169.254.0.0/16 range that Macs sometimes use when they can't find a DHCP server, except ipv6 link-local addresses are (a) much more popular - basically there is no DHCP in the ipv6 world (b) should almost always be present on your ipv6 NIC even if there are no other ipv6 addresses because - say - your internet provider does not offer ipv6 connectivity). But as I said, link-local only works on a single (or L2/bridged) LAN, as there is no routing involved.

The phone just fails to find it afterwards, but this may very well be due to the HA matter addon being experimental.

I would not sit on this. HA likely uses the C++ Matter SDK which is the most stable one and is used by basically all the big players (Apple/Samsung/Google/AWS) in production, as they basically created it. With other companies like Espressif participating too.

Would it be possible to paste the HA Matter logs here? Also your phone logs? This would:

Another option: Can you try the light_eth example too? It only goes via mDNS + ipv6 so if there is an mDNS discoverability issue, we'll know right away...