system exception occurs...

[mzimmers]

...seemingly at random, after the system has been booted and operational but in a quiet state. I get this:

Guru Meditation Error: Core 0 panic'ed (LoadProhibited). Exception was unhandled.
0x4008d09d: pvTaskIncrementMutexHeldCount at C:/esp-idf-release-v3.2/components/freertos/tasks.c:4631

All my tasks are created at startup, and I don't explicitly use mutexes, so it must be something I'm doing indirectly. Any suggestions on what to look for?

[ESP_Angus]

Hi mzimmers,

Do you get a stack trace as well at this exception? Can you post the full panic handler output, please?

What IDF version are you using?


Angus

[mzimmers]

Hi Angus -

I've been unable to reproduce that particular error. Now, though, I'm getting this after a period of running:

Code: Select all

A   :0fc7 A   :0f88 A   :0000 A   :007c5 fb8
A0  :00000 A1  :0a7ef A2  :0a7ef A3  :00000 b8
ECAD:00000 LE  :000c4 LN  :000c47 LON :00000
akrc:008860f7f0x419f5:x3fb82005330f80
A   :0fb4f A   :0018 A   :00004 A   :0fd0
A0  :0000a5 A1  :0fb50 A2  :008c7 A3  :0fa0
ECAD:00000 LE  :000c2 LN  :000c2 LON :0ffff
ets Jun  8 2016 00:22:573fb x40d5:x3fb x40d0ax000

rst:0x8 (TG1WDT_SYS_RESET),boot:0x32 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0018,len:4
load:0x3fff001c,len:5892
load:0x40078000,len:9308
load:0x40080400,len:5724
entry 0x40080724
W (29) boot: PRO CPU has been reset by WDT.
W (29) boot: WDT reset info: PRO CPU PC=0x40081461
0x40081461: uart_tx_wait_idle at C:/esp-idf-release-v3.2/components/esp32/system_api.c:170

W (29) boot: WDT reset info: APP CPU PC=0x40090483
0x40090483: panicHandler at C:/esp-idf-release-v3.2/components/esp32/panic.c:649

I (35) boot: ESP-IDF v3.2-dev-518-g020ade65-dirty 2nd stage bootloader
I (42) boot: compile time 10:15:25
I (59) boot: Enabling RNG early entropy source...
I (59) boot: SPI Speed      : 40MHz
I (59) boot: SPI Mode       : DIO
I (62) boot: SPI Flash Size : 4MB
I (66) boot: Partition Table:
I (70) boot: ## Label            Usage          Type ST Offset   Length
I (77) boot:  0 nvs              WiFi data        01 02 00009000 00004000
I (84) boot:  1 otadata          OTA data         01 00 0000d000 00002000
I (92) boot:  2 phy_init         RF data          01 01 0000f000 00001000
I (99) boot:  3 factory          factory app      00 00 00010000 00100000
I (107) boot:  4 ota_0            OTA app          00 10 00110000 00100000
I (114) boot:  5 ota_1            OTA app          00 11 00210000 00100000
I (122) boot: End of partition table
I (126) boot: Defaulting to factory image
I (131) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x2f7d4 (194516) map
I (208) esp_image: segment 1: paddr=0x0003f7fc vaddr=0x3ffc0000 size=0x00814 (  2068) load
I (209) esp_image: segment 2: paddr=0x00040018 vaddr=0x400d0018 size=0xab6a8 (702120) map
0x400d0018: _flash_cache_start at ??:?

I (460) esp_image: segment 3: paddr=0x000eb6c8 vaddr=0x3ffc0814 size=0x02fa8 ( 12200) load
I (465) esp_image: segment 4: paddr=0x000ee678 vaddr=0x3ffc37bc size=0x00000 (     0) load
I (467) esp_image: segment 5: paddr=0x000ee680 vaddr=0x40080000 size=0x00400 (  1024) load
0x40080000: _WindowOverflow4 at C:/esp-idf-release-v3.2/components/freertos/xtensa_vectors.S:1685

I (476) esp_image: segment 6: paddr=0x000eea88 vaddr=0x40080400 size=0x1213c ( 74044) load
I (516) esp_image: segment 7: paddr=0x00100bcc vaddr=0x400c0000 size=0x00000 (     0) load
I (516) esp_image: segment 8: paddr=0x00100bd4 vaddr=0x50000000 size=0x00000 (     0) load
I (533) boot: Loaded app from partition at offset 0x10000
I (533) boot: Disabling RNG early entropy source...
I (534) cpu_start: Pro cpu up.
I (538) cpu_start: Starting app cpu, entry point is 0x40081164
0x40081164: call_start_cpu1 at C:/esp-idf-release-v3.2/components/esp32/cpu_start.c:225

I (0) cpu_start: App cpu up.
I (548) heap_init: Initializing. RAM available for dynamic allocation:
I (555) heap_init: At 3FFAE6E0 len 00011920 (70 KiB): DRAM
I (561) heap_init: At 3FFCF160 len 00010EA0 (67 KiB): DRAM
I (567) heap_init: At 3FFE0440 len 00003BC0 (14 KiB): D/IRAM
I (574) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
I (581) heap_init: At 4009253C len 0000DAC4 (54 KiB): IRAM
I (586) cpu_start: Pro cpu start user code

And that's where it stops. I never get to my app_main() when this happens.

[chegewara]

There is one thing:

W (29) boot: WDT reset info: PRO CPU PC=0x40081461
0x40081461: uart_tx_wait_idle at C:/esp-idf-release-v3.2/components/esp32/system_api.c:170

W (29) boot: WDT reset info: APP CPU PC=0x40090483
0x40090483: panicHandler at C:/esp-idf-release-v3.2/components/esp32/panic.c:649

[mzimmers]

Hi chegewara - just to be clear, it isn't that the logging stops; the entire processor seems to stop. My app doesn't start.

Verbosity is set to info.

[chegewara]

Yes, ive read logs one more time and correct my answer. I also found similar logs with WDT in bootloader:

W (31) boot: PRO CPU has been reset by WDT.
W (31) boot: WDT reset info: PRO CPU PC=0x4008fc06
0x4008fc06: commonErrorHandler at /home/peter/esp/esp-idf-v3.1/components/esp32/panic.c:649

W (31) boot: WDT reset info: APP CPU PC=0x4008fc46
0x4008fc46: panicHandler at /home/peter/esp/esp-idf-v3.1/components/esp32/panic.c:649

in this topic:
https://esp32.com/viewtopic.php?f=13&t=7406