Calling otIp6SetEnabled() Crashes Program (TZ-1481)

[Robbie981]

Checklist

• Checked the issue tracker for similar issues to ensure this is not a duplicate
• Read the documentation to confirm the issue is not addressed there and your configuration is set correctly
• Tested with the latest version to ensure the issue hasn't been fixed

How often does this bug occurs?

always

Expected behavior

I am trying to bring the ipv6 interface online by calling otIp6SetEnabled(myOtInstance, true); (should do the exact same thing as running "ifconfig up" in CLI from my understanding)

Actual behavior (suspected bug)

Calling otIp6SetEnabled(myOtInstance, true); right afterxTaskCreate(ot_task_worker, "ot_br_main", 8192, xTaskGetCurrentTaskHandle(), 5, NULL); in border_router_launch.c, the program crashes with following error: assert failed: otPlatAssertFail esp_openthread_misc.c:79 (false). At the point of calling this function an active dataset have been initialized and committed. Full terminal output shown below.

Error logs or terminal output

ELF file SHA256: 396ccfec4

Rebooting...
ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0xc (RTC_SW_CPU_RST),boot:0x8 (SPI_FAST_FLASH_BOOT)
Saved PC:0x40375a41
--- 0x40375a41: esp_restart_noos at /Users/robbie/Desktop/esp32/esp-idf/components/esp_system/port/soc/esp32s3/system_internal.c:160

SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce2820,len:0x15d0
load:0x403c8700,len:0xc80
--- 0x403c8700: _stext at ??:?

load:0x403cb700,len:0x2f54
entry 0x403c8910
--- 0x403c8910: call_start_cpu0 at /Users/robbie/Desktop/esp32/esp-idf/components/bootloader/subproject/main/bootloader_start.c:25

I (24) boot: ESP-IDF v5.5-dev-1053-g1de896d375-dirty 2nd stage bootloader
I (24) boot: compile time Jan 16 2025 00:29:29
I (24) boot: Multicore bootloader
I (25) boot: chip revision: v0.2
I (25) boot: efuse block revision: v1.3
I (25) boot.esp32s3: Boot SPI Speed : 80MHz
I (25) boot.esp32s3: SPI Mode       : DIO
I (25) boot.esp32s3: SPI Flash Size : 4MB
I (25) boot: Enabling RNG early entropy source...
I (26) boot: Partition Table:
I (26) boot: ## Label            Usage          Type ST Offset   Length
I (26) boot:  0 nvs              WiFi data        01 02 00009000 00006000
I (26) boot:  1 otadata          OTA data         01 00 0000f000 00002000
I (27) boot:  2 phy_init         RF data          01 01 00011000 00001000
I (27) boot:  3 ota_0            OTA app          00 10 00020000 00190000
I (28) boot:  4 ota_1            OTA app          00 11 001b0000 00190000
I (28) boot:  5 web_storage      Unknown data     01 82 00340000 00019000
I (28) boot:  6 rcp_fw           Unknown data     01 82 00359000 000a0000
I (29) boot: End of partition table
I (29) esp_image: segment 0: paddr=00020020 vaddr=3c100020 size=469c4h (289220) map
I (81) esp_image: segment 1: paddr=000669ec vaddr=3fc99a00 size=04f30h ( 20272) load
I (85) esp_image: segment 2: paddr=0006b924 vaddr=40374000 size=046f4h ( 18164) load
I (89) esp_image: segment 3: paddr=00070020 vaddr=42000020 size=f4f88h (1003400) map
I (267) esp_image: segment 4: paddr=00164fb0 vaddr=403786f4 size=112c4h ( 70340) load
I (282) esp_image: segment 5: paddr=0017627c vaddr=600fe000 size=0001ch (    28) load
I (291) boot: Loaded app from partition at offset 0x20000
I (291) boot: Disabling RNG early entropy source...
I (292) cpu_start: Multicore app
I (301) cpu_start: Pro cpu start user code
I (301) cpu_start: cpu freq: 160000000 Hz
I (302) app_init: Application information:
I (302) app_init: Project name:     esp_ot_br
I (302) app_init: App version:      v5.4-beta2-14-g9018928-dirty
I (302) app_init: Compile time:     Jan 16 2025 00:28:55
I (302) app_init: ELF file SHA256:  396ccfec4...
I (302) app_init: ESP-IDF:          v5.5-dev-1053-g1de896d375-dirty
I (303) efuse_init: Min chip rev:     v0.0
I (303) efuse_init: Max chip rev:     v0.99 
I (303) efuse_init: Chip rev:         v0.2
I (303) heap_init: Initializing. RAM available for dynamic allocation:
I (304) heap_init: At 3FCAD9C8 len 0003BD48 (239 KiB): RAM
I (304) heap_init: At 3FCE9710 len 00005724 (21 KiB): RAM
I (304) heap_init: At 3FCF0000 len 00008000 (32 KiB): DRAM
I (304) heap_init: At 600FE01C len 00001FCC (7 KiB): RTCRAM
I (305) spi_flash: detected chip: gd
I (305) spi_flash: flash io: dio
W (305) spi_flash: Detected size(8192k) larger than the size in the binary image header(4096k). Using the size in the binary image header.
I (306) sleep_gpio: Configure to isolate all GPIO pins in sleep state
I (307) sleep_gpio: Enable automatic switching of GPIO sleep configuration
I (307) main_task: Started on CPU0
I (317) main_task: Calling app_main()
I (367) RCP_UPDATE: RCP: using update sequence 0
I (367) uart: ESP_INTR_FLAG_IRAM flag not set while CONFIG_UART_ISR_IN_IRAM is enabled, flag updated
I (367) OPENTHREAD: spinel UART interface initialization completed
E (377) OPENTHREAD: Assert failed at /IDF/components/openthread/openthread/src/lib/spinel/radio_spinel.cpp:641
I(377) OPENTHREAD:[I] P-SpinelDrive-: co-processor reset: RESET_POWER_ON
E(377) OPENTHREAD:[C] P-SpinelDrive-: Software reset co-processor successfully

assert failed: otPlatAssertFail esp_openthread_misc.c:79 (false)


Backtrace: 0x40375afd:0x3fcb0960 0x4037d36d:0x3fcb0980 0x40384911:0x3fcb09a0 0x420829c6:0x3fcb0ac0 0x420620f2:0x3fcb0af0 0x420624b8:0x3fcb0b10 0x42063015:0x3fcb0b40 0x4206354f:0x3fcb0ba0 0x42040485:0x3fcb0bc0 0x42049b88:0x3fcb0be0 0x4204761a:0x3fcb0c00 0x420601c3:0x3fcb0c20 0x42044182:0x3fcb0c40 0x42014e25:0x3fcb0c60 0x42008dbd:0x3fcb0c90 0x420f2f9b:0x3fcb0df0
.........--- 0x40375afd: panic_abort at /Users/robbie/Desktop/esp32/esp-idf/components/esp_system/panic.c:454
0x4037d36d: esp_system_abort at /Users/robbie/Desktop/esp32/esp-idf/components/esp_system/port/esp_system_chip.c:92
0x40384911: __assert_func at /Users/robbie/Desktop/esp32/esp-idf/components/newlib/src/assert.c:80
0x420829c6: otPlatAssertFail at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/src/port/esp_openthread_misc.c:79
0x420620f2: ot::Spinel::RadioSpinel::GetSpinelDriver() const at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/lib/spinel/radio_spinel.cpp:641 (discriminator 1)
 (inlined by) ot::Spinel::RadioSpinel::GetSpinelDriver() const at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/lib/spinel/radio_spinel.cpp:639 (discriminator 1)
0x420624b8: ot::Spinel::RadioSpinel::RequestV(unsigned long, unsigned long, char const*, __va_list_tag) at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/lib/spinel/radio_spinel.cpp:1450
0x42063015: ot::Spinel::RadioSpinel::RequestWithExpectedCommandV(unsigned long, unsigned long, unsigned long, char const*, __va_list_tag) at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/lib/spinel/radio_spinel.cpp:1520
 (inlined by) ot::Spinel::RadioSpinel::Set(unsigned long, char const*, ...) at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/lib/spinel/radio_spinel.cpp:1335
0x4206354f: ot::Spinel::RadioSpinel::Enable(otInstance*) at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/lib/spinel/radio_spinel.cpp:1752
0x42040485: otPlatRadioEnable at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/src/port/esp_openthread_radio_spinel.cpp:213
0x42049b88: ot::Radio::Enable() at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/core/radio/radio.hpp:887 (discriminator 1)
 (inlined by) ot::Mac::SubMac::Enable() at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/core/mac/sub_mac.cpp:201 (discriminator 1)
0x4204761a: ot::Mac::Links::Enable() at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/core/mac/mac_links.hpp:400
 (inlined by) ot::Mac::Mac::SetEnabled(bool) at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/core/mac/mac.cpp:131
0x420601c3: ot::ThreadNetif::Up() at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/core/thread/thread_netif.cpp:51 (discriminator 1)
0x42044182: otIp6SetEnabled at /Users/robbie/Desktop/esp32/esp-idf/components/openthread/openthread/src/core/api/ip6_api.cpp:51 (discriminator 1)
0x42014e25: thread_network_start at /Users/robbie/Desktop/esp32/esp-thread-br/my_projects/common/thread_border_router/src/border_router_launch.c:211
 (inlined by) launch_openthread_border_router at /Users/robbie/Desktop/esp32/esp-thread-br/my_projects/common/thread_border_router/src/border_router_launch.c:280
0x42008dbd: app_main at /Users/robbie/Desktop/esp32/esp-thread-br/my_projects/basic_thread_border_router/main/esp_ot_br.c:109
0x420f2f9b: main_task at /Users/robbie/Desktop/esp32/esp-idf/components/freertos/app_startup.c:208

Steps to reproduce the behavior

1. set up project normally and build
2. flash and monitor esp32 border router
3. program crashes on boot

Project release version

latest

System architecture

ARM 64-bit (Apple M1/M2, Raspberry Pi 4/5)

Operating system

MacOS

Operating system version

macOS Sequoia

Shell

ZSH

Additional context

No response

[zwx1995esp]

Did you take the openthread lock before you call the API otIp6SetEnabled ?

[Robbie981]

Problem resolved, thank you