[X] I have updated my IDF branch (release/vX.Y) to the latest version and checked that the issue is present there. This is not applicable if you are using Rainmaker with Arduino.
[X] I have searched the Rainmaker forum and issue tracker for a similar issue and not found a similar issue.
IDF / ESP32-Arduino version.
V5.0.6
Operating System used.
Linux
How did you build your project?
Command line with idf.py
Development Kit.
ESP32-C3-DevKit
What is the expected behavior?
I expect I can run the matter+rainmaker switch example，but it failed.
What is the actual behavior?
I follow the rainmaker docs step by step, after downloading and run, the chip always reboot ,
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fcd5820,len:0x16a8
load:0x403cc710,len:0x95c
load:0x403ce710,len:0x2eac
entry 0x403cc710
I (35) boot: ESP-IDF v5.0.6-dirty 2nd stage bootloader
I (35) boot: compile time 23:47:42
I (35) boot: chip revision: v0.4
I (37) boot.esp32c3: SPI Speed : 80MHz
I (42) boot.esp32c3: SPI Mode : DIO
I (47) boot.esp32c3: SPI Flash Size : 4MB
I (52) boot: Enabling RNG early entropy source...
I (57) boot: Partition Table:
I (61) boot: ## Label Usage Type ST Offset Length
I (68) boot: 0 esp_secure_cert unknown 3f 06 0000d000 00002000
I (75) boot: 1 nvs WiFi data 01 02 00010000 0000c000
I (83) boot: 2 nvs_keys NVS keys 01 04 0001c000 00001000
I (90) boot: 3 otadata OTA data 01 00 0001d000 00002000
I (98) boot: 4 phy_init RF data 01 01 0001f000 00001000
I (105) boot: 5 ota_0 OTA app 00 10 00020000 001e0000
I (113) boot: 6 ota_1 OTA app 00 11 00200000 001e0000
I (120) boot: 7 fctry WiFi data 01 02 003e0000 00006000
I (128) boot: End of partition table
I (132) esp_image: segment 0: paddr=00020020 vaddr=3c140020 size=5d978h (383352) map
I (201) esp_image: segment 1: paddr=0007d9a0 vaddr=3fc94c00 size=02678h ( 9848) load
I (203) esp_image: segment 2: paddr=00080020 vaddr=42000020 size=13de38h (1302072) map
I (410) esp_image: segment 3: paddr=001bde60 vaddr=3fc97278 size=00c64h ( 3172) load
I (411) esp_image: segment 4: paddr=001beacc vaddr=40380000 size=14ad8h ( 84696) load
I (431) esp_image: segment 5: paddr=001d35ac vaddr=50000010 size=00004h ( 4) load
I (437) boot: Loaded app from partition at offset 0x20000
I (438) boot: Disabling RNG early entropy source...
I (452) cpu_start: Unicore app
I (452) cpu_start: Pro cpu up.
I (460) cpu_start: Pro cpu start user code
I (460) cpu_start: cpu freq: 160000000 Hz
I (460) cpu_start: Application information:
I (463) cpu_start: Project name: matter_switch
I (469) cpu_start: App version: d0960b4
I (474) cpu_start: Compile time: Apr 29 2024 09:03:59
I (480) cpu_start: ELF file SHA256: d4fab19c71456fce...
I (486) cpu_start: ESP-IDF: v5.0.6-dirty
I (491) cpu_start: Min chip rev: v0.3
I (496) cpu_start: Max chip rev: v1.99
I (501) cpu_start: Chip rev: v0.4
I (506) heap_init: Initializing. RAM available for dynamic allocation:
I (513) heap_init: At 3FCAA190 len 00032580 (201 KiB): DRAM
I (519) heap_init: At 3FCDC710 len 00002950 (10 KiB): STACK/DRAM
I (526) heap_init: At 50000014 len 00001FD4 (7 KiB): RTCRAM
I (533) spi_flash: detected chip: generic
I (537) spi_flash: flash io: dio
W (541) rmt(legacy): legacy driver is deprecated, please migrate to driver/rmt_tx.h and/or driver/rmt_rx.h
I (552) sleep: Configure to isolate all GPIO pins in sleep state
I (558) sleep: Enable automatic switching of GPIO sleep configuration
I (565) coexist: coexist rom version 9387209
I (570) app_start: Starting scheduler on CPU0
I (575) main_task: Started on CPU0
I (575) main_task: Calling app_main()
I (585) led_driver_ws2812: Initializing light driver
I (585) led_driver_ws2812: led set r:0, g:0, b:0
I (585) gpio: GPIO[9]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:0
I (605) app_matter: Switch created with endpoint_id 1
I (605) pp: pp rom version: 9387209
I (605) net80211: net80211 rom version: 9387209
I (625) wifi:wifi driver task: 3fcb6bd4, prio:23, stack:6656, core=0
I (625) system_api: Base MAC address is not set
I (625) system_api: read default base MAC address from EFUSE
I (635) wifi:wifi firmware version: dbb7b72
I (635) wifi:wifi certification version: v7.0
I (635) wifi:config NVS flash: enabled
I (645) wifi:config nano formating: disabled
I (645) wifi:Init data frame dynamic rx buffer num: 32
I (655) wifi:Init static rx mgmt buffer num: 5
I (655) wifi:Init management short buffer num: 32
I (665) wifi:Init dynamic tx buffer num: 32
I (665) wifi:Init static tx FG buffer num: 2
I (665) wifi:Init static rx buffer size: 1600
I (675) wifi:Init static rx buffer num: 10
I (675) wifi:Init dynamic rx buffer num: 32
I (685) wifi_init: rx ba win: 6
I (685) wifi_init: tcpip mbox: 32
I (685) wifi_init: udp mbox: 6
I (695) wifi_init: tcp mbox: 6
I (695) wifi_init: tcp tx win: 5760
I (695) wifi_init: tcp rx win: 5760
I (705) wifi_init: tcp mss: 1440
I (705) wifi_init: WiFi IRAM OP enabled
I (715) wifi_init: WiFi RX IRAM OP enabled
I (735) chip[DL]: NVS set: chip-counters/reboot-count = 1508 (0x5E4)
I (735) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC)
I (735) BLE_INIT: BT controller compile version [9359a4d]
I (745) BLE_INIT: Bluetooth MAC: ec:da:3b:cc:bd:d6
I (745) phy_init: phy_version 1150,7c3c08f,Jan 24 2024,17:32:21
I (785) NimBLE: GAP procedure initiated: stop advertising.
I (795) CHIP[DL]: BLE host-controller synced
I (1295) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (1295) NimBLE: GAP procedure initiated: advertise;
I (1295) NimBLE: disc_mode=2
I (1295) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (1305) NimBLE:
I (1315) chip[DL]: CHIPoBLE advertising started
I (1315) chip[DL]: Starting ESP WiFi layer
I (1325) wifi:mode : sta (ec:da:3b:cc:bd:d4)
I (1325) wifi:enable tsf
W (1325) wifi:Haven't to connect to a suitable AP now!
I (1335) chip[DL]: Done driving station state, nothing else to do...
W (1335) wifi:Haven't to connect to a suitable AP now!
I (1345) chip[DL]: Done driving station state, nothing else to do...
I (1355) chip[SVR]: Initializing subscription resumption storage...
I (1355) chip[SVR]: Server initializing...
I (1365) chip[TS]: Last Known Good Time: 2023-10-14T01:16:48
I (1365) chip[DMG]: AccessControl: initializing
I (1375) chip[DMG]: Examples::AccessControlDelegate::Init
I (1385) chip[DMG]: AccessControl: setting
I (1385) chip[DMG]: DefaultAclStorage: initializing
I (1395) chip[DMG]: DefaultAclStorage: 0 entries loaded
I (1405) chip[ZCL]: Using ZAP configuration...
I (1405) esp_matter_cluster: Cluster plugin init common callback
I (1405) chip[DMG]: AccessControlCluster: initializing
I (1415) chip[ZCL]: 0x3c1511d8ep 0 clus 0x0000_0030 attr 0x0000_0000 not supported
I (1425) chip[ZCL]: Initiating Admin Commissioning cluster.
I (1435) chip[DIS]: Updating services using commissioning mode 1
I (1435) chip[DIS]: CHIP minimal mDNS started advertising.
I (1445) chip[DIS]: Advertise commission parameter vendorID=4891 productID=2 discriminator=2551/09 cm=1
I (1455) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: EE343A640B9004BF.
I (1465) chip[DIS]: mDNS service published: _matterc._udp
I (1465) chip[IN]: CASE Server enabling CASE session setups
I (1475) chip[SVR]: Joining Multicast groups
I (1485) chip[SVR]: Server Listening...
I (1485) esp_matter_core: Dynamic endpoint 0 added
I (1495) esp_matter_attribute: ** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 0 **
I (1505) esp_matter_attribute: ** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 **
I (1515) esp_matter_attribute: ** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is <invalid type: 0> **
I (1525) esp_matter_core: Dynamic endpoint 1 added
I (1535) chip[DL]: WIFI_EVENT_STA_START
W (1535) wifi:Haven't to connect to a suitable AP now!
I (1545) chip[DL]: Done driving station state, nothing else to do...
I (1545) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (1555) chip[DL]: Device already advertising, stop active advertisement and restart
I (1565) NimBLE: GAP procedure initiated: stop advertising.
I (1575) NimBLE: GAP procedure initiated: advertise;
I (1575) NimBLE: disc_mode=2
I (1585) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (1595) NimBLE:
E (1595) esp_matter_client: failed to notify the bound cluster changed
I (1605) esp_rmaker_work_queue: Work Queue created.
I (1605) esp_mqtt_glue: AWS PPI: ?Platform=APN3|A0|RM|EX00|RMDev|1x0|ECDA3BCCBDD4
I (1615) esp_mqtt_glue: Initialising MQTT
I (1615) esp_rmaker_mqtt_budget: MQTT Budgeting initialised. Default: 100, Max: 1024, Revive count: 1, Revive period: 5
I (1635) esp_rmaker_node: Node ID ----- WWhfctC2WPRRx3jNrvqvqD
I (1635) esp_rmaker_ota_using_params: OTA enabled with Params
I (1645) esp_rmaker_ota: OTA state = 2
I (1645) esp_rmaker_time: Initializing SNTP. Using the SNTP server: pool.ntp.org
assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)
Core 0 register dump:
MEPC : 0x403808bc RA : 0x4038a684 SP : 0x3fcacf20 GP : 0x3fc95400
Stack dump detected
0x403808bc: panic_abort at /mnt/d/ubuntu/esp-idf/components/esp_system/panic.c:425
0x4038a684: __ubsan_include at /mnt/d/ubuntu/esp-idf/components/esp_system/ubsan.c:313
panic_abort (details=details@entry=0x3fcacf78 "assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)") at /mnt/d/ubuntu/esp-idf/components/esp_system/panic.c:425
425 ((volatile int ) 0) = 0; // NOLINT(clang-analyzer-core.NullDereference) should be an invalid operation on targets
0 panic_abort (details=details@entry=0x3fcacf78 "assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)") at /mnt/d/ubuntu/esp-idf/components/esp_system/panic.c:425
1 0x4038a684 in esp_system_abort (details=details@entry=0x3fcacf78 "assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)") at /mnt/d/ubuntu/esp-idf/components/esp_system/esp_system.c:153
2 0x40390ed2 in __assert_func (file=, file@entry=0x3c172744 "", line=, func=, expr=) at /mnt/d/ubuntu/esp-idf/components/newlib/assert.c:78
3 0x4206b75c in sntp_setoperatingmode (operating_mode=operating_mode@entry=0 '\000') at /mnt/d/ubuntu/esp-idf/components/lwip/lwip/src/apps/sntp/sntp.c:733
4 0x42059c84 in esp_rmaker_time_sync_init (config=config@entry=0x0) at /mnt/d/ubuntu/esp-rainmaker/components/rmaker_common/src/time.c:224
5 0x42010216 in esp_rmaker_ota_enable (ota_config=, type=) at /mnt/d/ubuntu/esp-rainmaker/components/esp_rainmaker/src/ota/esp_rmaker_ota.c:713
6 0x42007866 in app_main () at /mnt/d/ubuntu/esp-rainmaker/examples-wenxe/matter/matter_switch/main/app_main.cpp:88
7 0x4213d4ea in main_task (args=) at /mnt/d/ubuntu/esp-idf/components/freertos/app_startup.c:208
8 0x4038a68c in vPortTaskWrapper (pxCode=, pvParameters=) at /mnt/d/ubuntu/esp-idf/components/freertos/FreeRTOS-Kernel/portable/riscv/port.c:127
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fcd5820,len:0x16a8
load:0x403cc710,len:0x95c
load:0x403ce710,len:0x2eac
entry 0x403cc710
I (35) boot: ESP-IDF v5.0.6-dirty 2nd stage bootloader
I (35) boot: compile time 23:47:42
I (35) boot: chip revision: v0.4
I (37) boot.esp32c3: SPI Speed : 80MHz
I (42) boot.esp32c3: SPI Mode : DIO
I (47) boot.esp32c3: SPI Flash Size : 4MB
I (52) boot: Enabling RNG early entropy source...
I (57) boot: Partition Table:
I (61) boot: ## Label Usage Type ST Offset Length
I (68) boot: 0 esp_secure_cert unknown 3f 06 0000d000 00002000
I (75) boot: 1 nvs WiFi data 01 02 00010000 0000c000
I (83) boot: 2 nvs_keys NVS keys 01 04 0001c000 00001000
I (90) boot: 3 otadata OTA data 01 00 0001d000 00002000
I (98) boot: 4 phy_init RF data 01 01 0001f000 00001000
I (105) boot: 5 ota_0 OTA app 00 10 00020000 001e0000
I (113) boot: 6 ota_1 OTA app 00 11 00200000 001e0000
I (120) boot: 7 fctry WiFi data 01 02 003e0000 00006000
I (128) boot: End of partition table
I (132) esp_image: segment 0: paddr=00020020 vaddr=3c140020 size=5d978h (383352) map
I (201) esp_image: segment 1: paddr=0007d9a0 vaddr=3fc94c00 size=02678h ( 9848) load
I (203) esp_image: segment 2: paddr=00080020 vaddr=42000020 size=13de38h (1302072) map
I (410) esp_image: segment 3: paddr=001bde60 vaddr=3fc97278 size=00c64h ( 3172) load
I (411) esp_image: segment 4: paddr=001beacc vaddr=40380000 size=14ad8h ( 84696) load
I (431) esp_image: segment 5: paddr=001d35ac vaddr=50000010 size=00004h ( 4) load
I (437) boot: Loaded app from partition at offset 0x20000
I (438) boot: Disabling RNG early entropy source...
I (452) cpu_start: Unicore app
I (452) cpu_start: Pro cpu up.
I (460) cpu_start: Pro cpu start user code
I (460) cpu_start: cpu freq: 160000000 Hz
I (460) cpu_start: Application information:
I (463) cpu_start: Project name: matter_switch
I (469) cpu_start: App version: d0960b4
I (474) cpu_start: Compile time: Apr 29 2024 09:03:59
I (480) cpu_start: ELF file SHA256: d4fab19c71456fce...
I (486) cpu_start: ESP-IDF: v5.0.6-dirty
I (491) cpu_start: Min chip rev: v0.3
I (496) cpu_start: Max chip rev: v1.99
I (501) cpu_start: Chip rev: v0.4
I (506) heap_init: Initializing. RAM available for dynamic allocation:
I (513) heap_init: At 3FCAA190 len 00032580 (201 KiB): DRAM
I (519) heap_init: At 3FCDC710 len 00002950 (10 KiB): STACK/DRAM
I (526) heap_init: At 50000014 len 00001FD4 (7 KiB): RTCRAM
I (533) spi_flash: detected chip: generic
I (537) spi_flash: flash io: dio
W (541) rmt(legacy): legacy driver is deprecated, please migrate to `driver/rmt_tx.h` and/or `driver/rmt_rx.h`
I (552) sleep: Configure to isolate all GPIO pins in sleep state
I (558) sleep: Enable automatic switching of GPIO sleep configuration
I (565) coexist: coexist rom version 9387209
I (570) app_start: Starting scheduler on CPU0
I (575) main_task: Started on CPU0
I (575) main_task: Calling app_main()
I (585) led_driver_ws2812: Initializing light driver
I (585) led_driver_ws2812: led set r:0, g:0, b:0
I (585) gpio: GPIO[9]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:0
I (605) app_matter: Switch created with endpoint_id 1
I (605) pp: pp rom version: 9387209
I (605) net80211: net80211 rom version: 9387209
I (625) wifi:wifi driver task: 3fcb6bd4, prio:23, stack:6656, core=0
I (625) system_api: Base MAC address is not set
I (625) system_api: read default base MAC address from EFUSE
I (635) wifi:wifi firmware version: dbb7b72
I (635) wifi:wifi certification version: v7.0
I (635) wifi:config NVS flash: enabled
I (645) wifi:config nano formating: disabled
I (645) wifi:Init data frame dynamic rx buffer num: 32
I (655) wifi:Init static rx mgmt buffer num: 5
I (655) wifi:Init management short buffer num: 32
I (665) wifi:Init dynamic tx buffer num: 32
I (665) wifi:Init static tx FG buffer num: 2
I (665) wifi:Init static rx buffer size: 1600
I (675) wifi:Init static rx buffer num: 10
I (675) wifi:Init dynamic rx buffer num: 32
I (685) wifi_init: rx ba win: 6
I (685) wifi_init: tcpip mbox: 32
I (685) wifi_init: udp mbox: 6
I (695) wifi_init: tcp mbox: 6
I (695) wifi_init: tcp tx win: 5760
I (695) wifi_init: tcp rx win: 5760
I (705) wifi_init: tcp mss: 1440
I (705) wifi_init: WiFi IRAM OP enabled
I (715) wifi_init: WiFi RX IRAM OP enabled
I (735) chip[DL]: NVS set: chip-counters/reboot-count = 1508 (0x5E4)
I (735) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC)
I (735) BLE_INIT: BT controller compile version [9359a4d]
I (745) BLE_INIT: Bluetooth MAC: ec:da:3b:cc:bd:d6
I (745) phy_init: phy_version 1150,7c3c08f,Jan 24 2024,17:32:21
I (785) NimBLE: GAP procedure initiated: stop advertising.
I (795) CHIP[DL]: BLE host-controller synced
I (1295) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (1295) NimBLE: GAP procedure initiated: advertise;
I (1295) NimBLE: disc_mode=2
I (1295) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (1305) NimBLE:
I (1315) chip[DL]: CHIPoBLE advertising started
I (1315) chip[DL]: Starting ESP WiFi layer
I (1325) wifi:mode : sta (ec:da:3b:cc:bd:d4)
I (1325) wifi:enable tsf
W (1325) wifi:Haven't to connect to a suitable AP now!
I (1335) chip[DL]: Done driving station state, nothing else to do...
W (1335) wifi:Haven't to connect to a suitable AP now!
I (1345) chip[DL]: Done driving station state, nothing else to do...
I (1355) chip[SVR]: Initializing subscription resumption storage...
I (1355) chip[SVR]: Server initializing...
I (1365) chip[TS]: Last Known Good Time: 2023-10-14T01:16:48
I (1365) chip[DMG]: AccessControl: initializing
I (1375) chip[DMG]: Examples::AccessControlDelegate::Init
I (1385) chip[DMG]: AccessControl: setting
I (1385) chip[DMG]: DefaultAclStorage: initializing
I (1395) chip[DMG]: DefaultAclStorage: 0 entries loaded
I (1405) chip[ZCL]: Using ZAP configuration...
I (1405) esp_matter_cluster: Cluster plugin init common callback
I (1405) chip[DMG]: AccessControlCluster: initializing
I (1415) chip[ZCL]: 0x3c1511d8ep 0 clus 0x0000_0030 attr 0x0000_0000 not supported
I (1425) chip[ZCL]: Initiating Admin Commissioning cluster.
I (1435) chip[DIS]: Updating services using commissioning mode 1
I (1435) chip[DIS]: CHIP minimal mDNS started advertising.
I (1445) chip[DIS]: Advertise commission parameter vendorID=4891 productID=2 discriminator=2551/09 cm=1
I (1455) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: EE343A640B9004BF.
I (1465) chip[DIS]: mDNS service published: _matterc._udp
I (1465) chip[IN]: CASE Server enabling CASE session setups
I (1475) chip[SVR]: Joining Multicast groups
I (1485) chip[SVR]: Server Listening...
I (1485) esp_matter_core: Dynamic endpoint 0 added
I (1495) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 0 **********
I (1505) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 **********
I (1515) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is <invalid type: 0> **********
I (1525) esp_matter_core: Dynamic endpoint 1 added
I (1535) chip[DL]: WIFI_EVENT_STA_START
W (1535) wifi:Haven't to connect to a suitable AP now!
I (1545) chip[DL]: Done driving station state, nothing else to do...
I (1545) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (1555) chip[DL]: Device already advertising, stop active advertisement and restart
I (1565) NimBLE: GAP procedure initiated: stop advertising.
I (1575) NimBLE: GAP procedure initiated: advertise;
I (1575) NimBLE: disc_mode=2
I (1585) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (1595) NimBLE:
E (1595) esp_matter_client: failed to notify the bound cluster changed
I (1605) esp_rmaker_work_queue: Work Queue created.
I (1605) esp_mqtt_glue: AWS PPI: ?Platform=APN3|A0|RM|EX00|RMDev|1x0|ECDA3BCCBDD4
I (1615) esp_mqtt_glue: Initialising MQTT
I (1615) esp_rmaker_mqtt_budget: MQTT Budgeting initialised. Default: 100, Max: 1024, Revive count: 1, Revive period: 5
I (1635) esp_rmaker_node: Node ID ----- WWhfctC2WPRRx3jNrvqvqD
I (1635) esp_rmaker_ota_using_params: OTA enabled with Params
I (1645) esp_rmaker_ota: OTA state = 2
I (1645) esp_rmaker_time: Initializing SNTP. Using the SNTP server: pool.ntp.org
assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)
Core 0 register dump:
MEPC : 0x403808bc RA : 0x4038a684 SP : 0x3fcacf20 GP : 0x3fc95400
Stack dump detected
0x403808bc: panic_abort at /mnt/d/ubuntu/esp-idf/components/esp_system/panic.c:425
0x4038a684: __ubsan_include at /mnt/d/ubuntu/esp-idf/components/esp_system/ubsan.c:313
TP : 0x3fc5046c T0 : 0x37363534 T1 : 0x7271706f T2 : 0x33323130
S0/FP : 0x3fcad0c4 S1 : 0x00000043 A0 : 0x3fcacf78 A1 : 0x3fc9725d
A2 : 0x00000001 A3 : 0x00000029 A4 : 0x00000001 A5 : 0x3fca8000
A6 : 0x7a797877 A7 : 0x76757473 S2 : 0x3fcacf78 S3 : 0x00000001
S4 : 0x3fcacf78 S5 : 0x3c14a000 S6 : 0x00000000 S7 : 0x00000000
S8 : 0x00000000 S9 : 0x00000000 S10 : 0x00000000 S11 : 0x00000000
T3 : 0x6e6d6c6b T4 : 0x6a696867 T5 : 0x66656463 T6 : 0x62613938
MSTATUS : 0x00001801 MTVEC : 0x40380001 MCAUSE : 0x00000007 MTVAL : 0x00000000
0x40380001: _vector_table at ??:?
MHARTID : 0x00000000
Backtrace:
panic_abort (details=details@entry=0x3fcacf78 "assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)") at /mnt/d/ubuntu/esp-idf/components/esp_system/panic.c:425
425 *((volatile int *) 0) = 0; // NOLINT(clang-analyzer-core.NullDereference) should be an invalid operation on targets
#0 panic_abort (details=details@entry=0x3fcacf78 "assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)") at /mnt/d/ubuntu/esp-idf/components/esp_system/panic.c:425
#1 0x4038a684 in esp_system_abort (details=details@entry=0x3fcacf78 "assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)") at /mnt/d/ubuntu/esp-idf/components/esp_system/esp_system.c:153
#2 0x40390ed2 in __assert_func (file=<optimized out>, file@entry=0x3c172744 "", line=<optimized out>, func=<optimized out>, expr=<optimized out>) at /mnt/d/ubuntu/esp-idf/components/newlib/assert.c:78
#3 0x4206b75c in sntp_setoperatingmode (operating_mode=operating_mode@entry=0 '\000') at /mnt/d/ubuntu/esp-idf/components/lwip/lwip/src/apps/sntp/sntp.c:733
#4 0x42059c84 in esp_rmaker_time_sync_init (config=config@entry=0x0) at /mnt/d/ubuntu/esp-rainmaker/components/rmaker_common/src/time.c:224
#5 0x42010216 in esp_rmaker_ota_enable (ota_config=<optimized out>, type=<optimized out>) at /mnt/d/ubuntu/esp-rainmaker/components/esp_rainmaker/src/ota/esp_rmaker_ota.c:713
#6 0x42007866 in app_main () at /mnt/d/ubuntu/esp-rainmaker/examples-wenxe/matter/matter_switch/main/app_main.cpp:88
#7 0x4213d4ea in main_task (args=<error reading variable: value has been optimized out>) at /mnt/d/ubuntu/esp-idf/components/freertos/app_startup.c:208
#8 0x4038a68c in vPortTaskWrapper (pxCode=<optimized out>, pvParameters=<optimized out>) at /mnt/d/ubuntu/esp-idf/components/freertos/FreeRTOS-Kernel/portable/riscv/port.c:127
ELF file SHA256: d4fab19c71456fce
Rebooting...
ESP-ROM:esp32c3-api1-20210207
Build:Feb 7 2021
rst:0x3 (RTC_SW_SYS_RST),boot:0xc (SPI_FAST_FLASH_BOOT)
Saved PC:0x40380688
0x40380688: esp_restart_noos_dig at /mnt/d/ubuntu/esp-idf/components/esp_system/esp_system.c:64 (discriminator 1)
More Information.
I tried ESP-IDF V4.4.4,it can't build correctly.
CMake Error at /mnt/d/ubuntu/esp-idf/tools/cmake/build.cmake:452 (message):
WARNING: Component "idf" not found
WARNING: Component "idf" not found
WARNING: Component "idf" not found
WARNING: Component "idf" not found
WARNING: Component "idf" not found
WARNING: Component "idf" not found
WARNING: Component "idf" not found
ERROR: Because no versions of espressif/mdns match >1.2.0,<1.2.1 ||
And because espressif/mdns (1.2.1) depends on both idf (>=5.0) and idf
(>=5.0), espressif/mdns (>=1.2.0,<1.2.3 || >1.2.3,<1.2.4 || >1.2.4,<1.2.5
|| >1.2.5,<1.3.0 || >1.3.0,<2.0.0) requires idf (>=5.0.0).
And because espressif/mdns (1.2.3) depends on both idf (>=5.0) and idf
(>=5.0), espressif/mdns (>=1.2.0,<1.2.5 || >1.2.5,<1.3.0 || >1.3.0,<2.0.0)
requires idf (>=5.0.0).
And because espressif/mdns (1.2.5) depends on both idf (>=5.0) and idf
(>=5.0), espressif/mdns (>=1.2.0,<2.0.0) requires idf (>=5.0.0).
And because no versions of idf match >=5.0.0
and espressif/esp_rainmaker (1.1.0) depends on espressif/mdns (^1.2.0), espressif/esp_rainmaker is forbidden.
So, because no versions of espressif/esp_rainmaker match >=1.0.0,<1.1.0 ||
1.1.0,<2.0.0
and project depends on espressif/esp_rainmaker (~=1.0), version solving failed.
Answers checklist.
IDF / ESP32-Arduino version.
V5.0.6
Operating System used.
Linux
How did you build your project?
Command line with idf.py
Development Kit.
ESP32-C3-DevKit
What is the expected behavior?
I expect I can run the matter+rainmaker switch example，but it failed.
What is the actual behavior?
I follow the rainmaker docs step by step, after downloading and run, the chip always reboot ,
SPIWP:0xee mode:DIO, clock div:1 load:0x3fcd5820,len:0x16a8 load:0x403cc710,len:0x95c load:0x403ce710,len:0x2eac entry 0x403cc710 I (35) boot: ESP-IDF v5.0.6-dirty 2nd stage bootloader I (35) boot: compile time 23:47:42 I (35) boot: chip revision: v0.4 I (37) boot.esp32c3: SPI Speed : 80MHz I (42) boot.esp32c3: SPI Mode : DIO I (47) boot.esp32c3: SPI Flash Size : 4MB I (52) boot: Enabling RNG early entropy source... I (57) boot: Partition Table: I (61) boot: ## Label Usage Type ST Offset Length I (68) boot: 0 esp_secure_cert unknown 3f 06 0000d000 00002000 I (75) boot: 1 nvs WiFi data 01 02 00010000 0000c000 I (83) boot: 2 nvs_keys NVS keys 01 04 0001c000 00001000 I (90) boot: 3 otadata OTA data 01 00 0001d000 00002000 I (98) boot: 4 phy_init RF data 01 01 0001f000 00001000 I (105) boot: 5 ota_0 OTA app 00 10 00020000 001e0000 I (113) boot: 6 ota_1 OTA app 00 11 00200000 001e0000 I (120) boot: 7 fctry WiFi data 01 02 003e0000 00006000 I (128) boot: End of partition table I (132) esp_image: segment 0: paddr=00020020 vaddr=3c140020 size=5d978h (383352) map I (201) esp_image: segment 1: paddr=0007d9a0 vaddr=3fc94c00 size=02678h ( 9848) load I (203) esp_image: segment 2: paddr=00080020 vaddr=42000020 size=13de38h (1302072) map I (410) esp_image: segment 3: paddr=001bde60 vaddr=3fc97278 size=00c64h ( 3172) load I (411) esp_image: segment 4: paddr=001beacc vaddr=40380000 size=14ad8h ( 84696) load I (431) esp_image: segment 5: paddr=001d35ac vaddr=50000010 size=00004h ( 4) load I (437) boot: Loaded app from partition at offset 0x20000 I (438) boot: Disabling RNG early entropy source... I (452) cpu_start: Unicore app I (452) cpu_start: Pro cpu up. I (460) cpu_start: Pro cpu start user code I (460) cpu_start: cpu freq: 160000000 Hz I (460) cpu_start: Application information: I (463) cpu_start: Project name: matter_switch I (469) cpu_start: App version: d0960b4 I (474) cpu_start: Compile time: Apr 29 2024 09:03:59 I (480) cpu_start: ELF file SHA256: d4fab19c71456fce... I (486) cpu_start: ESP-IDF: v5.0.6-dirty I (491) cpu_start: Min chip rev: v0.3 I (496) cpu_start: Max chip rev: v1.99 I (501) cpu_start: Chip rev: v0.4 I (506) heap_init: Initializing. RAM available for dynamic allocation: I (513) heap_init: At 3FCAA190 len 00032580 (201 KiB): DRAM I (519) heap_init: At 3FCDC710 len 00002950 (10 KiB): STACK/DRAM I (526) heap_init: At 50000014 len 00001FD4 (7 KiB): RTCRAM I (533) spi_flash: detected chip: generic I (537) spi_flash: flash io: dio W (541) rmt(legacy): legacy driver is deprecated, please migrate to
driver/rmt_tx.hand/ordriver/rmt_rx.hI (552) sleep: Configure to isolate all GPIO pins in sleep state I (558) sleep: Enable automatic switching of GPIO sleep configuration I (565) coexist: coexist rom version 9387209 I (570) app_start: Starting scheduler on CPU0 I (575) main_task: Started on CPU0 I (575) main_task: Calling app_main() I (585) led_driver_ws2812: Initializing light driver I (585) led_driver_ws2812: led set r:0, g:0, b:0 I (585) gpio: GPIO[9]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:0 I (605) app_matter: Switch created with endpoint_id 1 I (605) pp: pp rom version: 9387209 I (605) net80211: net80211 rom version: 9387209 I (625) wifi:wifi driver task: 3fcb6bd4, prio:23, stack:6656, core=0 I (625) system_api: Base MAC address is not set I (625) system_api: read default base MAC address from EFUSE I (635) wifi:wifi firmware version: dbb7b72 I (635) wifi:wifi certification version: v7.0 I (635) wifi:config NVS flash: enabled I (645) wifi:config nano formating: disabled I (645) wifi:Init data frame dynamic rx buffer num: 32 I (655) wifi:Init static rx mgmt buffer num: 5 I (655) wifi:Init management short buffer num: 32 I (665) wifi:Init dynamic tx buffer num: 32 I (665) wifi:Init static tx FG buffer num: 2 I (665) wifi:Init static rx buffer size: 1600 I (675) wifi:Init static rx buffer num: 10 I (675) wifi:Init dynamic rx buffer num: 32 I (685) wifi_init: rx ba win: 6 I (685) wifi_init: tcpip mbox: 32 I (685) wifi_init: udp mbox: 6 I (695) wifi_init: tcp mbox: 6 I (695) wifi_init: tcp tx win: 5760 I (695) wifi_init: tcp rx win: 5760 I (705) wifi_init: tcp mss: 1440 I (705) wifi_init: WiFi IRAM OP enabled I (715) wifi_init: WiFi RX IRAM OP enabled I (735) chip[DL]: NVS set: chip-counters/reboot-count = 1508 (0x5E4) I (735) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (735) BLE_INIT: BT controller compile version [9359a4d] I (745) BLE_INIT: Bluetooth MAC: ec:da:3b:cc:bd:d6I (745) phy_init: phy_version 1150,7c3c08f,Jan 24 2024,17:32:21 I (785) NimBLE: GAP procedure initiated: stop advertising.
I (795) CHIP[DL]: BLE host-controller synced I (1295) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (1295) NimBLE: GAP procedure initiated: advertise; I (1295) NimBLE: disc_mode=2 I (1295) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (1305) NimBLE:
I (1315) chip[DL]: CHIPoBLE advertising started I (1315) chip[DL]: Starting ESP WiFi layer I (1325) wifi:mode : sta (ec:da:3b:cc:bd:d4) I (1325) wifi:enable tsf W (1325) wifi:Haven't to connect to a suitable AP now! I (1335) chip[DL]: Done driving station state, nothing else to do... W (1335) wifi:Haven't to connect to a suitable AP now! I (1345) chip[DL]: Done driving station state, nothing else to do... I (1355) chip[SVR]: Initializing subscription resumption storage... I (1355) chip[SVR]: Server initializing... I (1365) chip[TS]: Last Known Good Time: 2023-10-14T01:16:48 I (1365) chip[DMG]: AccessControl: initializing I (1375) chip[DMG]: Examples::AccessControlDelegate::Init I (1385) chip[DMG]: AccessControl: setting I (1385) chip[DMG]: DefaultAclStorage: initializing I (1395) chip[DMG]: DefaultAclStorage: 0 entries loaded I (1405) chip[ZCL]: Using ZAP configuration... I (1405) esp_matter_cluster: Cluster plugin init common callback I (1405) chip[DMG]: AccessControlCluster: initializing I (1415) chip[ZCL]: 0x3c1511d8ep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (1425) chip[ZCL]: Initiating Admin Commissioning cluster. I (1435) chip[DIS]: Updating services using commissioning mode 1 I (1435) chip[DIS]: CHIP minimal mDNS started advertising. I (1445) chip[DIS]: Advertise commission parameter vendorID=4891 productID=2 discriminator=2551/09 cm=1 I (1455) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: EE343A640B9004BF. I (1465) chip[DIS]: mDNS service published: _matterc._udp I (1465) chip[IN]: CASE Server enabling CASE session setups I (1475) chip[SVR]: Joining Multicast groups I (1485) chip[SVR]: Server Listening... I (1485) esp_matter_core: Dynamic endpoint 0 added I (1495) esp_matter_attribute: ** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 0 ** I (1505) esp_matter_attribute: ** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 ** I (1515) esp_matter_attribute: ** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is <invalid type: 0> ** I (1525) esp_matter_core: Dynamic endpoint 1 added I (1535) chip[DL]: WIFI_EVENT_STA_START W (1535) wifi:Haven't to connect to a suitable AP now! I (1545) chip[DL]: Done driving station state, nothing else to do... I (1545) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (1555) chip[DL]: Device already advertising, stop active advertisement and restart I (1565) NimBLE: GAP procedure initiated: stop advertising.
I (1575) NimBLE: GAP procedure initiated: advertise; I (1575) NimBLE: disc_mode=2 I (1585) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (1595) NimBLE:
E (1595) esp_matter_client: failed to notify the bound cluster changed I (1605) esp_rmaker_work_queue: Work Queue created. I (1605) esp_mqtt_glue: AWS PPI: ?Platform=APN3|A0|RM|EX00|RMDev|1x0|ECDA3BCCBDD4 I (1615) esp_mqtt_glue: Initialising MQTT I (1615) esp_rmaker_mqtt_budget: MQTT Budgeting initialised. Default: 100, Max: 1024, Revive count: 1, Revive period: 5 I (1635) esp_rmaker_node: Node ID ----- WWhfctC2WPRRx3jNrvqvqD I (1635) esp_rmaker_ota_using_params: OTA enabled with Params I (1645) esp_rmaker_ota: OTA state = 2 I (1645) esp_rmaker_time: Initializing SNTP. Using the SNTP server: pool.ntp.org
assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!) Core 0 register dump: MEPC : 0x403808bc RA : 0x4038a684 SP : 0x3fcacf20 GP : 0x3fc95400 Stack dump detected 0x403808bc: panic_abort at /mnt/d/ubuntu/esp-idf/components/esp_system/panic.c:425
0x4038a684: __ubsan_include at /mnt/d/ubuntu/esp-idf/components/esp_system/ubsan.c:313
TP : 0x3fc5046c T0 : 0x37363534 T1 : 0x7271706f T2 : 0x33323130 S0/FP : 0x3fcad0c4 S1 : 0x00000043 A0 : 0x3fcacf78 A1 : 0x3fc9725d A2 : 0x00000001 A3 : 0x00000029 A4 : 0x00000001 A5 : 0x3fca8000 A6 : 0x7a797877 A7 : 0x76757473 S2 : 0x3fcacf78 S3 : 0x00000001 S4 : 0x3fcacf78 S5 : 0x3c14a000 S6 : 0x00000000 S7 : 0x00000000 S8 : 0x00000000 S9 : 0x00000000 S10 : 0x00000000 S11 : 0x00000000 T3 : 0x6e6d6c6b T4 : 0x6a696867 T5 : 0x66656463 T6 : 0x62613938 MSTATUS : 0x00001801 MTVEC : 0x40380001 MCAUSE : 0x00000007 MTVAL : 0x00000000 0x40380001: _vector_table at ??:?
MHARTID : 0x00000000
Backtrace:
panic_abort (details=details@entry=0x3fcacf78 "assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)") at /mnt/d/ubuntu/esp-idf/components/esp_system/panic.c:425 425 ((volatile int ) 0) = 0; // NOLINT(clang-analyzer-core.NullDereference) should be an invalid operation on targets
0 panic_abort (details=details@entry=0x3fcacf78 "assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)") at /mnt/d/ubuntu/esp-idf/components/esp_system/panic.c:425
1 0x4038a684 in esp_system_abort (details=details@entry=0x3fcacf78 "assert failed: sntp_setoperatingmode /IDF/components/lwip/lwip/src/apps/sntp/sntp.c:732 (Required to lock TCPIP core functionality!)") at /mnt/d/ubuntu/esp-idf/components/esp_system/esp_system.c:153
2 0x40390ed2 in __assert_func (file=, file@entry=0x3c172744 "", line=, func=, expr=) at /mnt/d/ubuntu/esp-idf/components/newlib/assert.c:78
3 0x4206b75c in sntp_setoperatingmode (operating_mode=operating_mode@entry=0 '\000') at /mnt/d/ubuntu/esp-idf/components/lwip/lwip/src/apps/sntp/sntp.c:733
4 0x42059c84 in esp_rmaker_time_sync_init (config=config@entry=0x0) at /mnt/d/ubuntu/esp-rainmaker/components/rmaker_common/src/time.c:224
5 0x42010216 in esp_rmaker_ota_enable (ota_config=, type=) at /mnt/d/ubuntu/esp-rainmaker/components/esp_rainmaker/src/ota/esp_rmaker_ota.c:713
6 0x42007866 in app_main () at /mnt/d/ubuntu/esp-rainmaker/examples-wenxe/matter/matter_switch/main/app_main.cpp:88
7 0x4213d4ea in main_task (args=) at /mnt/d/ubuntu/esp-idf/components/freertos/app_startup.c:208
8 0x4038a68c in vPortTaskWrapper (pxCode=, pvParameters=) at /mnt/d/ubuntu/esp-idf/components/freertos/FreeRTOS-Kernel/portable/riscv/port.c:127
ELF file SHA256: d4fab19c71456fce
Rebooting... ESP-ROM:esp32c3-api1-20210207 Build:Feb 7 2021 rst:0x3 (RTC_SW_SYS_RST),boot:0xc (SPI_FAST_FLASH_BOOT) Saved PC:0x40380688 0x40380688: esp_restart_noos_dig at /mnt/d/ubuntu/esp-idf/components/esp_system/esp_system.c:64 (discriminator 1) log-20240429.txt
Steps to reproduce.
Debug Logs.
More Information.
I tried ESP-IDF V4.4.4,it can't build correctly. CMake Error at /mnt/d/ubuntu/esp-idf/tools/cmake/build.cmake:452 (message): WARNING: Component "idf" not found
WARNING: Component "idf" not found
WARNING: Component "idf" not found
WARNING: Component "idf" not found
WARNING: Component "idf" not found
WARNING: Component "idf" not found
WARNING: Component "idf" not found
ERROR: Because no versions of espressif/mdns match >1.2.0,<1.2.1 ||
and espressif/mdns (1.2.0) depends on idf (>=5.0), espressif/mdns (>=1.2.0,<1.2.1 || >1.2.1,<1.2.2 || >1.2.2,<1.2.3 || >1.2.3,<1.2.4 || >1.2.4,<1.2.5 || >1.2.5,<1.3.0 || >1.3.0,<2.0.0) requires idf (>=5.0).
And because espressif/mdns (1.2.1) depends on both idf (>=5.0) and idf (>=5.0), espressif/mdns (>=1.2.0,<1.2.3 || >1.2.3,<1.2.4 || >1.2.4,<1.2.5 || >1.2.5,<1.3.0 || >1.3.0,<2.0.0) requires idf (>=5.0.0).
And because espressif/mdns (1.2.3) depends on both idf (>=5.0) and idf (>=5.0), espressif/mdns (>=1.2.0,<1.2.5 || >1.2.5,<1.3.0 || >1.3.0,<2.0.0) requires idf (>=5.0.0).
And because espressif/mdns (1.2.5) depends on both idf (>=5.0) and idf (>=5.0), espressif/mdns (>=1.2.0,<2.0.0) requires idf (>=5.0.0).
And because no versions of idf match >=5.0.0
and espressif/esp_rainmaker (1.1.0) depends on espressif/mdns (^1.2.0), espressif/esp_rainmaker is forbidden.
So, because no versions of espressif/esp_rainmaker match >=1.0.0,<1.1.0 ||
and project depends on espressif/esp_rainmaker (~=1.0), version solving failed.
Call Stack (most recent call first)
and ESP-IDF V5.0.1 alse also build failed.