[TW#27671] Guru Meditation Error on Bluetooth Init when Sysview Trace is Enabled

[markingle]

Environment

• Development Kit: ESP32-Wrover-Kit
• Kit version (WroverKit): [v4]
• Module or chip used: ESP32-WROVER
• IDF version (run git describe --tags to find it): v3.3-dev-278-gcc56734
• Build System: Make
• Compiler version (run xtensa-esp32-elf-gcc --version to find it):xtensa-esp32-elf-gcc (crosstool-NG crosstool-ng-1.22.0-80-g6c4433a) 5.2.0
• Operating System: macOS
• Power Supply: USB

Problem Description

When I enable the SysView Tracing Enable in the menuconfig my application crashes with a Guru Meditation Error after the bluetooth controller inits:

Code snippet:
if (esp_bt_controller_init(&bt_cfg) != ESP_OK) {
ESP_LOGE(BT_AV_TAG, "%s initialize controller failed\n", func);
return;

I need Sysview trace to diagnose another issue related to BT and GPIO performance. Other than the performance issue everything works as long as I do not have sysview trace enabled. See verbose monitor output.

Expected Behavior

I am providing the entire logging session from make monitor....The BT init is in bold.

MONITOR
--- idf_monitor on /dev/cu.usbserial-00002014B 115200 ---
--- Quit: Ctrl+] | Menu: Ctrl+T | Help: Ctrl+T followed by Ctrl+H ---
ets Jun 8 2016 00:22:57

rst:0x1 (POWERON_RESET),boot:0x3e (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:6164
load:0x40078000,len:10168
load:0x40080400,len:6552
entry 0x40080764
I (28) boot: ESP-IDF v3.3-dev-278-gcc56734-dirty 2nd stage bootloader
I (28) boot: compile time 20:28:39
I (31) boot: Enabling RNG early entropy source...
I (35) boot: SPI Speed : 40MHz
I (39) boot: SPI Mode : DIO
I (43) boot: SPI Flash Size : 4MB
I (47) boot: Partition Table:
I (51) boot: ## Label Usage Type ST Offset Length
I (58) boot: 0 nvs WiFi data 01 02 00009000 00006000
I (65) boot: 1 phy_init RF data 01 01 0000f000 00001000
I (73) boot: 2 factory factory app 00 00 00010000 00100000
I (80) boot: End of partition table
I (84) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x3f8d0 (260304) map
I (185) esp_image: segment 1: paddr=0x0004f8f8 vaddr=0x3ff80000 size=0x00000 ( 0) load
I (185) esp_image: segment 2: paddr=0x0004f900 vaddr=0x3ff80000 size=0x00000 ( 0) load
I (191) esp_image: segment 3: paddr=0x0004f908 vaddr=0x3ffbdb60 size=0x00708 ( 1800) load
I (201) esp_image: segment 4: paddr=0x00050018 vaddr=0x400d0018 size=0xa0e80 (659072) map
0x400d0018: _flash_cache_start at ??:?

I (440) esp_image: segment 5: paddr=0x000f0ea0 vaddr=0x3ffbe268 size=0x02f00 ( 12032) load
I (445) esp_image: segment 6: paddr=0x000f3da8 vaddr=0x3ffc1168 size=0x00000 ( 0) load
I (447) esp_image: segment 7: paddr=0x000f3db0 vaddr=0x40080000 size=0x00400 ( 1024) load
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

I (456) esp_image: segment 8: paddr=0x000f41b8 vaddr=0x40080400 size=0x124f0 ( 74992) load
I (496) esp_image: segment 9: paddr=0x001066b0 vaddr=0x400c0000 size=0x00000 ( 0) load
I (496) esp_image: segment 10: paddr=0x001066b8 vaddr=0x50000000 size=0x00000 ( 0) load
I (502) esp_image: segment 11: paddr=0x001066c0 vaddr=0x50000000 size=0x00000 ( 0) load
I (522) boot: Loaded app from partition at offset 0x10000
I (523) boot: Disabling RNG early entropy source...
I (523) cpu_start: Pro cpu up.
I (527) cpu_start: Starting app cpu, entry point is 0x40081120
0x40081120: call_start_cpu1 at /Users/Mark/esp/esp-idf/components/esp32/cpu_start.c:246

I (0) cpu_start: App cpu up.
V (537) memory_layout: reserved range is 0x3f43f8a0 - 0x3f43f8f0
D (543) memory_layout: Checking 12 reserved memory ranges:
D (548) memory_layout: Reserved memory range 0x3ffae000 - 0x3ffae6e0
D (555) memory_layout: Reserved memory range 0x3ffae6e0 - 0x3ffaff10
D (561) memory_layout: Reserved memory range 0x3ffb0000 - 0x3ffb6388
D (568) memory_layout: Reserved memory range 0x3ffb8000 - 0x3ffb9a20
D (574) memory_layout: Reserved memory range 0x3ffbdb28 - 0x3ffbdb5c
D (581) memory_layout: Reserved memory range 0x3ffbdb60 - 0x3ffd0710
D (587) memory_layout: Reserved memory range 0x3ffe0000 - 0x3ffe0440
D (593) memory_layout: Reserved memory range 0x3ffe3f20 - 0x3ffe4350
D (600) memory_layout: Reserved memory range 0x3fff8000 - 0x40000000
D (606) memory_layout: Reserved memory range 0x40070000 - 0x40078000
D (613) memory_layout: Reserved memory range 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

D (619) memory_layout: Reserved memory range 0x40080000 - 0x400928f0
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

D (626) memory_layout: Building list of available memory regions:
V (632) memory_layout: Examining memory region 0x3ffae000 - 0x3ffb0000
V (638) memory_layout: Start of region 0x3ffae000 - 0x3ffb0000 overlaps reserved 0x3ffae000 - 0x3ffae6e0
V (648) memory_layout: Start of region 0x3ffae6e0 - 0x3ffb0000 overlaps reserved 0x3ffae6e0 - 0x3ffaff10
D (657) memory_layout: Available memory region 0x3ffaff10 - 0x3ffb0000
V (664) memory_layout: Examining memory region 0x3ffb0000 - 0x3ffb8000
V (671) memory_layout: Start of region 0x3ffb0000 - 0x3ffb8000 overlaps reserved 0x3ffb0000 - 0x3ffb6388
D (680) memory_layout: Available memory region 0x3ffb6388 - 0x3ffb8000
V (687) memory_layout: Examining memory region 0x3ffb8000 - 0x3ffc0000
V (693) memory_layout: Start of region 0x3ffb8000 - 0x3ffc0000 overlaps reserved 0x3ffb8000 - 0x3ffb9a20
V (703) memory_layout: Region 0x3ffb9a20 - 0x3ffc0000 contains reserved 0x3ffbdb28 - 0x3ffbdb5c
D (712) memory_layout: Available memory region 0x3ffb9a20 - 0x3ffbdb28
V (718) memory_layout: Examining memory region 0x3ffbdb5c - 0x3ffc0000
V (725) memory_layout: End of region 0x3ffbdb5c - 0x3ffc0000 overlaps reserved 0x3ffbdb60 - 0x3ffd0710
D (734) memory_layout: Available memory region 0x3ffbdb5c - 0x3ffbdb60
V (741) memory_layout: Examining memory region 0x3ffc0000 - 0x3ffc2000
V (747) memory_layout: Region 0x3ffc0000 - 0x3ffc2000 inside of reserved 0x3ffbdb60 - 0x3ffd0710
V (756) memory_layout: Examining memory region 0x3ffc2000 - 0x3ffc4000
V (763) memory_layout: Region 0x3ffc2000 - 0x3ffc4000 inside of reserved 0x3ffbdb60 - 0x3ffd0710
V (772) memory_layout: Examining memory region 0x3ffc4000 - 0x3ffc6000
V (778) memory_layout: Region 0x3ffc4000 - 0x3ffc6000 inside of reserved 0x3ffbdb60 - 0x3ffd0710
V (787) memory_layout: Examining memory region 0x3ffc6000 - 0x3ffc8000
V (794) memory_layout: Region 0x3ffc6000 - 0x3ffc8000 inside of reserved 0x3ffbdb60 - 0x3ffd0710
V (803) memory_layout: Examining memory region 0x3ffc8000 - 0x3ffca000
V (809) memory_layout: Region 0x3ffc8000 - 0x3ffca000 inside of reserved 0x3ffbdb60 - 0x3ffd0710
V (818) memory_layout: Examining memory region 0x3ffca000 - 0x3ffcc000
V (825) memory_layout: Region 0x3ffca000 - 0x3ffcc000 inside of reserved 0x3ffbdb60 - 0x3ffd0710
V (834) memory_layout: Examining memory region 0x3ffcc000 - 0x3ffce000
V (840) memory_layout: Region 0x3ffcc000 - 0x3ffce000 inside of reserved 0x3ffbdb60 - 0x3ffd0710
V (849) memory_layout: Examining memory region 0x3ffce000 - 0x3ffd0000
V (856) memory_layout: Region 0x3ffce000 - 0x3ffd0000 inside of reserved 0x3ffbdb60 - 0x3ffd0710
V (864) memory_layout: Examining memory region 0x3ffd0000 - 0x3ffd2000
V (871) memory_layout: Start of region 0x3ffd0000 - 0x3ffd2000 overlaps reserved 0x3ffbdb60 - 0x3ffd0710
D (881) memory_layout: Available memory region 0x3ffd0710 - 0x3ffd2000
V (887) memory_layout: Examining memory region 0x3ffd2000 - 0x3ffd4000
D (894) memory_layout: Available memory region 0x3ffd2000 - 0x3ffd4000
V (900) memory_layout: Examining memory region 0x3ffd4000 - 0x3ffd6000
D (907) memory_layout: Available memory region 0x3ffd4000 - 0x3ffd6000
V (914) memory_layout: Examining memory region 0x3ffd6000 - 0x3ffd8000
D (920) memory_layout: Available memory region 0x3ffd6000 - 0x3ffd8000
V (927) memory_layout: Examining memory region 0x3ffd8000 - 0x3ffda000
D (933) memory_layout: Available memory region 0x3ffd8000 - 0x3ffda000
V (940) memory_layout: Examining memory region 0x3ffda000 - 0x3ffdc000
D (947) memory_layout: Available memory region 0x3ffda000 - 0x3ffdc000
V (953) memory_layout: Examining memory region 0x3ffdc000 - 0x3ffde000
D (960) memory_layout: Available memory region 0x3ffdc000 - 0x3ffde000
V (966) memory_layout: Examining memory region 0x3ffde000 - 0x3ffe0000
D (973) memory_layout: Available memory region 0x3ffde000 - 0x3ffe0000
V (980) memory_layout: Examining memory region 0x3ffe0000 - 0x3ffe4000
V (986) memory_layout: Start of region 0x3ffe0000 - 0x3ffe4000 overlaps reserved 0x3ffe0000 - 0x3ffe0440
V (996) memory_layout: End of region 0x3ffe0440 - 0x3ffe4000 overlaps reserved 0x3ffe3f20 - 0x3ffe4350
D (1005) memory_layout: Available memory region 0x3ffe0440 - 0x3ffe3f20
V (1012) memory_layout: Examining memory region 0x3ffe4000 - 0x3ffe8000
V (1018) memory_layout: Start of region 0x3ffe4000 - 0x3ffe8000 overlaps reserved 0x3ffe3f20 - 0x3ffe4350
D (1028) memory_layout: Available memory region 0x3ffe4350 - 0x3ffe8000
V (1035) memory_layout: Examining memory region 0x3ffe8000 - 0x3fff0000
D (1041) memory_layout: Available memory region 0x3ffe8000 - 0x3fff0000
V (1048) memory_layout: Examining memory region 0x3fff0000 - 0x3fff8000
D (1055) memory_layout: Available memory region 0x3fff0000 - 0x3fff8000
V (1061) memory_layout: Examining memory region 0x3fff8000 - 0x3fffc000
V (1068) memory_layout: Region 0x3fff8000 - 0x3fffc000 inside of reserved 0x3fff8000 - 0x40000000
V (1077) memory_layout: Examining memory region 0x3fffc000 - 0x40000000
V (1084) memory_layout: Region 0x3fffc000 - 0x40000000 inside of reserved 0x3fff8000 - 0x40000000
V (1093) memory_layout: Examining memory region 0x40070000 - 0x40078000
V (1099) memory_layout: Region 0x40070000 - 0x40078000 inside of reserved 0x40070000 - 0x40078000
V (1108) memory_layout: Examining memory region 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1115) memory_layout: Region 0x40078000 - 0x40080000 inside of reserved 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1124) memory_layout: Examining memory region 0x40080000 - 0x40082000
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

0x40082000: esp_restart_noos at /Users/Mark/esp/esp-idf/components/esp32/system_api.c:172

V (1131) memory_layout: Region 0x40080000 - 0x40082000 inside of reserved 0x40080000 - 0x400928f0
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

0x40082000: esp_restart_noos at /Users/Mark/esp/esp-idf/components/esp32/system_api.c:172

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1140) memory_layout: Examining memory region 0x40082000 - 0x40084000
0x40082000: esp_restart_noos at /Users/Mark/esp/esp-idf/components/esp32/system_api.c:172

0x40084000: spi_flash_mmap_pages at /Users/Mark/esp/esp-idf/components/spi_flash/flash_mmap.c:319

V (1146) memory_layout: Region 0x40082000 - 0x40084000 inside of reserved 0x40080000 - 0x400928f0
0x40082000: esp_restart_noos at /Users/Mark/esp/esp-idf/components/esp32/system_api.c:172

0x40084000: spi_flash_mmap_pages at /Users/Mark/esp/esp-idf/components/spi_flash/flash_mmap.c:319

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1155) memory_layout: Examining memory region 0x40084000 - 0x40086000
0x40084000: spi_flash_mmap_pages at /Users/Mark/esp/esp-idf/components/spi_flash/flash_mmap.c:319

0x40086000: ld_sched_link_in_sniff at ??:?

V (1162) memory_layout: Region 0x40084000 - 0x40086000 inside of reserved 0x40080000 - 0x400928f0
0x40084000: spi_flash_mmap_pages at /Users/Mark/esp/esp-idf/components/spi_flash/flash_mmap.c:319

0x40086000: ld_sched_link_in_sniff at ??:?

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1171) memory_layout: Examining memory region 0x40086000 - 0x40088000
0x40086000: ld_sched_link_in_sniff at ??:?

0x40088000: r_rwbt_isr at ??:?

V (1178) memory_layout: Region 0x40086000 - 0x40088000 inside of reserved 0x40080000 - 0x400928f0
0x40086000: ld_sched_link_in_sniff at ??:?

0x40088000: r_rwbt_isr at ??:?

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1186) memory_layout: Examining memory region 0x40088000 - 0x4008a000
0x40088000: r_rwbt_isr at ??:?

0x4008a000: coex_timer_ts_start_disalarm at ??:?

V (1193) memory_layout: Region 0x40088000 - 0x4008a000 inside of reserved 0x40080000 - 0x400928f0
0x40088000: r_rwbt_isr at ??:?

0x4008a000: coex_timer_ts_start_disalarm at ??:?

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1202) memory_layout: Examining memory region 0x4008a000 - 0x4008c000
0x4008a000: coex_timer_ts_start_disalarm at ??:?

0x4008c000: esp_rom_spiflash_erase_sector at /Users/Mark/esp/esp-idf/components/spi_flash/spi_flash_rom_patch.c:451

V (1209) memory_layout: Region 0x4008a000 - 0x4008c000 inside of reserved 0x40080000 - 0x400928f0
0x4008a000: coex_timer_ts_start_disalarm at ??:?

0x4008c000: esp_rom_spiflash_erase_sector at /Users/Mark/esp/esp-idf/components/spi_flash/spi_flash_rom_patch.c:451

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1218) memory_layout: Examining memory region 0x4008c000 - 0x4008e000
0x4008c000: esp_rom_spiflash_erase_sector at /Users/Mark/esp/esp-idf/components/spi_flash/spi_flash_rom_patch.c:451

0x4008e000: coex_bt_high_prio at /home/qgu/git_tree/chip7.1_rtc/board_code/app_test/pp/rtc/bt_bb.c:383

V (1224) memory_layout: Region 0x4008c000 - 0x4008e000 inside of reserved 0x40080000 - 0x400928f0
0x4008c000: esp_rom_spiflash_erase_sector at /Users/Mark/esp/esp-idf/components/spi_flash/spi_flash_rom_patch.c:451

0x4008e000: coex_bt_high_prio at /home/qgu/git_tree/chip7.1_rtc/board_code/app_test/pp/rtc/bt_bb.c:383

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1233) memory_layout: Examining memory region 0x4008e000 - 0x40090000
0x4008e000: coex_bt_high_prio at /home/qgu/git_tree/chip7.1_rtc/board_code/app_test/pp/rtc/bt_bb.c:383

0x40090000: prvAddCurrentTaskToDelayedList at /Users/Mark/esp/esp-idf/components/freertos/tasks.c:3537

V (1240) memory_layout: Region 0x4008e000 - 0x40090000 inside of reserved 0x40080000 - 0x400928f0
0x4008e000: coex_bt_high_prio at /home/qgu/git_tree/chip7.1_rtc/board_code/app_test/pp/rtc/bt_bb.c:383

0x40090000: prvAddCurrentTaskToDelayedList at /Users/Mark/esp/esp-idf/components/freertos/tasks.c:3537

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1249) memory_layout: Examining memory region 0x40090000 - 0x40092000
0x40090000: prvAddCurrentTaskToDelayedList at /Users/Mark/esp/esp-idf/components/freertos/tasks.c:3537

0x40092000: get_prev_free_block at /Users/Mark/esp/esp-idf/components/heap/multi_heap.c:377

V (1256) memory_layout: Region 0x40090000 - 0x40092000 inside of reserved 0x40080000 - 0x400928f0
0x40090000: prvAddCurrentTaskToDelayedList at /Users/Mark/esp/esp-idf/components/freertos/tasks.c:3537

0x40092000: get_prev_free_block at /Users/Mark/esp/esp-idf/components/heap/multi_heap.c:377

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1265) memory_layout: Examining memory region 0x40092000 - 0x40094000
0x40092000: get_prev_free_block at /Users/Mark/esp/esp-idf/components/heap/multi_heap.c:377

V (1271) memory_layout: Start of region 0x40092000 - 0x40094000 overlaps reserved 0x40080000 - 0x400928f0
0x40092000: get_prev_free_block at /Users/Mark/esp/esp-idf/components/heap/multi_heap.c:377

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

D (1281) memory_layout: Available memory region 0x400928f0 - 0x40094000
V (1288) memory_layout: Examining memory region 0x40094000 - 0x40096000
D (1294) memory_layout: Available memory region 0x40094000 - 0x40096000
V (1301) memory_layout: Examining memory region 0x40096000 - 0x40098000
D (1308) memory_layout: Available memory region 0x40096000 - 0x40098000
V (1314) memory_layout: Examining memory region 0x40098000 - 0x4009a000
D (1321) memory_layout: Available memory region 0x40098000 - 0x4009a000
V (1328) memory_layout: Examining memory region 0x4009a000 - 0x4009c000
D (1334) memory_layout: Available memory region 0x4009a000 - 0x4009c000
V (1341) memory_layout: Examining memory region 0x4009c000 - 0x4009e000
D (1348) memory_layout: Available memory region 0x4009c000 - 0x4009e000
V (1354) memory_layout: Examining memory region 0x4009e000 - 0x400a0000
D (1361) memory_layout: Available memory region 0x4009e000 - 0x400a0000
I (1368) heap_init: Initializing. RAM available for dynamic allocation:
D (1375) heap_init: New heap initialised at 0x3ffaff10
I (1380) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM
D (1386) heap_init: New heap initialised at 0x3ffb6388
I (1392) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM
D (1398) heap_init: New heap initialised at 0x3ffb9a20
I (1403) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM
I (1409) heap_init: At 3FFBDB5C len 00000004 (0 KiB): DRAM
D (1415) heap_init: New heap initialised at 0x3ffd0710
I (1421) heap_init: At 3FFD0710 len 0000F8F0 (62 KiB): DRAM
I (1427) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (1433) heap_init: At 3FFE4350 len 00013CB0 (79 KiB): D/IRAM
D (1440) heap_init: New heap initialised at 0x400928f0
I (1445) heap_init: At 400928F0 len 0000D710 (53 KiB): IRAM
I (1451) cpu_start: Pro cpu start user code
D (1463) clk: RTC_SLOW_CLK calibration value: 3198707
V (131) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (131) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xE
D (136) intr_alloc: Connected src 46 to int 2 (cpu 0)
V (141) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (147) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xC0E
D (155) intr_alloc: Connected src 57 to int 3 (cpu 0)
I (160) esp_apptrace: Initialized TRAX on CPU0
V (165) esp_dbg_stubs: esp_dbg_stubs_init stubs 3ffce210
V (172) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (177) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0x40E
D (185) intr_alloc: Connected src 24 to int 9 (cpu 0)
I (191) cpu_start: Starting scheduler on PRO CPU.
I (535) esp_apptrace: Initialized TRAX on CPU1
V (0) intr_alloc: esp_intr_alloc_intrstatus (cpu 1): checking args
V (10) intr_alloc: esp_intr_alloc_intrstatus (cpu 1): Args okay. Resulting flags 0x40E
D (10) intr_alloc: Connected src 25 to int 2 (cpu 1)
I (20) cpu_start: Starting scheduler on APP CPU.
D (246) heap_init: New heap initialised at 0x3ffe0440
D (256) heap_init: New heap initialised at 0x3ffe4350
V (256) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (256) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xE
D (256) intr_alloc: Connected src 16 to int 12 (cpu 0)
D (286) nvs: nvs_flash_init_custom partition=nvs start=9 count=6
I (316) BTDM_INIT: BT controller compile version [6eb4acf]
D (316) BTDM_INIT: .data initialise [0x3ffae6e0] <== [0x4000d890]
D (316) BTDM_INIT: .bss initialise [0x3ffb0000] - [0x3ffb09a8]
D (326) BTDM_INIT: .bss initialise [0x3ffb09a8] - [0x3ffb1ddc]
D (336) BTDM_INIT: .bss initialise [0x3ffb1ddc] - [0x3ffb2730]
D (336) BTDM_INIT: .bss initialise [0x3ffb2730] - [0x3ffb6388]
D (346) BTDM_INIT: .bss initialise [0x3ffb8000] - [0x3ffb9a20]
D (346) BTDM_INIT: .bss initialise [0x3ffbdb28] - [0x3ffbdb5c]
I (356) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (366) phy_init: loading PHY init data from application binary
D (376) nvs: nvs_open_from_partition phy 0
D (376) nvs: nvs_get cal_version 4
D (376) nvs: nvs_get_str_or_blob cal_mac
D (386) nvs: nvs_get_str_or_blob cal_data
D (396) nvs: nvs_close 1
I (456) phy: phy_version: 4000, b6198fa, Sep 3 2018, 15:11:06, 0, 0
D (936) nvs: nvs_open_from_partition bt_config.conf 1
D (936) nvs: nvs_get_str_or_blob bt_cfg_key0
D (936) nvs: nvs_get_str_or_blob bt_cfg_key0
D (936) nvs: nvs_close 2
D (936) nvs: nvs_open_from_partition bt_config.conf 1
D (946) nvs: nvs_set_blob bt_cfg_key0 216
D (956) nvs: nvs_close 3
D (976) ledc: LEDC_PWM CHANNEL 0|GPIO 23|Duty 0000|Time 0
D (1976) ledc: LEDC_PWM CHANNEL 0|GPIO 23|Duty 0000|Time 0
D (2976) ledc: LEDC_PWM CHANNEL 0|GPIO 23|Duty 0000|Time 0
Determining frequency average on start up
Start calibration....pass #10
Startup Calibration Pulse Counter is on - Core: 0
First counter value for calibration:0
Second counter value for calibration:0
Count Difference: 0.000000
Start calibration....pass #10
Startup Calibration Pulse Counter is on - Core: 0
First counter value for calibration:0
Second counter value for calibration:0
Count Difference: 0.000000
Start calibration....pass #10
Startup Calibration Pulse Counter is on - Core: 0
First counter value for calibration:0
Second counter value for calibration:0
Count Difference: 0.000000

Actual Behavior

The Guru error is in bold and its immediately after the nvs function

MONITOR
--- idf_monitor on /dev/cu.usbserial-00002014B 115200 ---
--- Quit: Ctrl+] | Menu: Ctrl+T | Help: Ctrl+T followed by Ctrl+H ---
/?ets Jun 8 2016 00:22:57

rst:0x1 (POWERON_RESET),boot:0x3e (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:6164
load:0x40078000,len:10168
load:0x40080400,len:6552
entry 0x40080764
I (28) boot: ESP-IDF v3.3-dev-278-gcc56734-dirty 2nd stage bootloader
I (28) boot: compile time 20:55:09
I (31) boot: Enabling RNG early entropy source...
I (35) boot: SPI Speed : 40MHz
I (39) boot: SPI Mode : DIO
I (43) boot: SPI Flash Size : 4MB
I (47) boot: Partition Table:
I (51) boot: ## Label Usage Type ST Offset Length
I (58) boot: 0 nvs WiFi data 01 02 00009000 00006000
I (65) boot: 1 phy_init RF data 01 01 0000f000 00001000
I (73) boot: 2 factory factory app 00 00 00010000 00100000
I (80) boot: End of partition table
I (85) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x40880 (264320) map
I (186) esp_image: segment 1: paddr=0x000508a8 vaddr=0x3ff80000 size=0x00000 ( 0) load
I (186) esp_image: segment 2: paddr=0x000508b0 vaddr=0x3ff80000 size=0x00000 ( 0) load
I (193) esp_image: segment 3: paddr=0x000508b8 vaddr=0x3ffbdb60 size=0x02ca4 ( 11428) load
I (206) esp_image: segment 4: paddr=0x00053564 vaddr=0x3ffc0804 size=0x00000 ( 0) load
I (211) esp_image: segment 5: paddr=0x0005356c vaddr=0x40080000 size=0x00400 ( 1024) load
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

I (220) esp_image: segment 6: paddr=0x00053974 vaddr=0x40080400 size=0x0c69c ( 50844) load
I (250) esp_image: segment 7: paddr=0x00060018 vaddr=0x400d0018 size=0xa31cc (668108) map
0x400d0018: _flash_cache_start at ??:?

I (484) esp_image: segment 8: paddr=0x001031ec vaddr=0x4008ca9c size=0x05a20 ( 23072) load
0x4008ca9c: reconfigureAllWdts at /Users/Mark/esp/esp-idf/components/esp32/panic.c:680

I (494) esp_image: segment 9: paddr=0x00108c14 vaddr=0x400c0000 size=0x00000 ( 0) load
I (494) esp_image: segment 10: paddr=0x00108c1c vaddr=0x50000000 size=0x00000 ( 0) load
I (502) esp_image: segment 11: paddr=0x00108c24 vaddr=0x50000000 size=0x00000 ( 0) load
I (521) boot: Loaded app from partition at offset 0x10000
I (522) boot: Disabling RNG early entropy source...
I (523) cpu_start: Pro cpu up.
I (526) cpu_start: Starting app cpu, entry point is 0x400810f4
0x400810f4: call_start_cpu1 at /Users/Mark/esp/esp-idf/components/esp32/cpu_start.c:246

I (0) cpu_start: App cpu up.
V (536) memory_layout: reserved range is 0x3f440850 - 0x3f4408a0
D (542) memory_layout: Checking 12 reserved memory ranges:
D (548) memory_layout: Reserved memory range 0x3ffae000 - 0x3ffae6e0
D (554) memory_layout: Reserved memory range 0x3ffae6e0 - 0x3ffaff10
D (561) memory_layout: Reserved memory range 0x3ffb0000 - 0x3ffb6388
D (567) memory_layout: Reserved memory range 0x3ffb8000 - 0x3ffb9a20
D (574) memory_layout: Reserved memory range 0x3ffbdb28 - 0x3ffbdb5c
D (580) memory_layout: Reserved memory range 0x3ffbdb60 - 0x3ffd0530
D (586) memory_layout: Reserved memory range 0x3ffe0000 - 0x3ffe0440
D (593) memory_layout: Reserved memory range 0x3ffe3f20 - 0x3ffe4350
D (599) memory_layout: Reserved memory range 0x3fff8000 - 0x40000000
D (606) memory_layout: Reserved memory range 0x40070000 - 0x40078000
D (612) memory_layout: Reserved memory range 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

D (618) memory_layout: Reserved memory range 0x40080000 - 0x400924bc
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

D (625) memory_layout: Building list of available memory regions:
V (631) memory_layout: Examining memory region 0x3ffae000 - 0x3ffb0000
V (638) memory_layout: Start of region 0x3ffae000 - 0x3ffb0000 overlaps reserved 0x3ffae000 - 0x3ffae6e0
V (647) memory_layout: Start of region 0x3ffae6e0 - 0x3ffb0000 overlaps reserved 0x3ffae6e0 - 0x3ffaff10
D (657) memory_layout: Available memory region 0x3ffaff10 - 0x3ffb0000
V (663) memory_layout: Examining memory region 0x3ffb0000 - 0x3ffb8000
V (670) memory_layout: Start of region 0x3ffb0000 - 0x3ffb8000 overlaps reserved 0x3ffb0000 - 0x3ffb6388
D (680) memory_layout: Available memory region 0x3ffb6388 - 0x3ffb8000
V (686) memory_layout: Examining memory region 0x3ffb8000 - 0x3ffc0000
V (693) memory_layout: Start of region 0x3ffb8000 - 0x3ffc0000 overlaps reserved 0x3ffb8000 - 0x3ffb9a20
V (702) memory_layout: Region 0x3ffb9a20 - 0x3ffc0000 contains reserved 0x3ffbdb28 - 0x3ffbdb5c
D (711) memory_layout: Available memory region 0x3ffb9a20 - 0x3ffbdb28
V (718) memory_layout: Examining memory region 0x3ffbdb5c - 0x3ffc0000
V (724) memory_layout: End of region 0x3ffbdb5c - 0x3ffc0000 overlaps reserved 0x3ffbdb60 - 0x3ffd0530
D (734) memory_layout: Available memory region 0x3ffbdb5c - 0x3ffbdb60
V (740) memory_layout: Examining memory region 0x3ffc0000 - 0x3ffc2000
V (747) memory_layout: Region 0x3ffc0000 - 0x3ffc2000 inside of reserved 0x3ffbdb60 - 0x3ffd0530
V (756) memory_layout: Examining memory region 0x3ffc2000 - 0x3ffc4000
V (762) memory_layout: Region 0x3ffc2000 - 0x3ffc4000 inside of reserved 0x3ffbdb60 - 0x3ffd0530
V (771) memory_layout: Examining memory region 0x3ffc4000 - 0x3ffc6000
V (778) memory_layout: Region 0x3ffc4000 - 0x3ffc6000 inside of reserved 0x3ffbdb60 - 0x3ffd0530
V (787) memory_layout: Examining memory region 0x3ffc6000 - 0x3ffc8000
V (793) memory_layout: Region 0x3ffc6000 - 0x3ffc8000 inside of reserved 0x3ffbdb60 - 0x3ffd0530
V (802) memory_layout: Examining memory region 0x3ffc8000 - 0x3ffca000
V (809) memory_layout: Region 0x3ffc8000 - 0x3ffca000 inside of reserved 0x3ffbdb60 - 0x3ffd0530
V (817) memory_layout: Examining memory region 0x3ffca000 - 0x3ffcc000
V (824) memory_layout: Region 0x3ffca000 - 0x3ffcc000 inside of reserved 0x3ffbdb60 - 0x3ffd0530
V (833) memory_layout: Examining memory region 0x3ffcc000 - 0x3ffce000
V (840) memory_layout: Region 0x3ffcc000 - 0x3ffce000 inside of reserved 0x3ffbdb60 - 0x3ffd0530
V (848) memory_layout: Examining memory region 0x3ffce000 - 0x3ffd0000
V (855) memory_layout: Region 0x3ffce000 - 0x3ffd0000 inside of reserved 0x3ffbdb60 - 0x3ffd0530
V (864) memory_layout: Examining memory region 0x3ffd0000 - 0x3ffd2000
V (870) memory_layout: Start of region 0x3ffd0000 - 0x3ffd2000 overlaps reserved 0x3ffbdb60 - 0x3ffd0530
D (880) memory_layout: Available memory region 0x3ffd0530 - 0x3ffd2000
V (887) memory_layout: Examining memory region 0x3ffd2000 - 0x3ffd4000
D (893) memory_layout: Available memory region 0x3ffd2000 - 0x3ffd4000
V (900) memory_layout: Examining memory region 0x3ffd4000 - 0x3ffd6000
D (906) memory_layout: Available memory region 0x3ffd4000 - 0x3ffd6000
V (913) memory_layout: Examining memory region 0x3ffd6000 - 0x3ffd8000
D (920) memory_layout: Available memory region 0x3ffd6000 - 0x3ffd8000
V (926) memory_layout: Examining memory region 0x3ffd8000 - 0x3ffda000
D (933) memory_layout: Available memory region 0x3ffd8000 - 0x3ffda000
V (939) memory_layout: Examining memory region 0x3ffda000 - 0x3ffdc000
D (946) memory_layout: Available memory region 0x3ffda000 - 0x3ffdc000
V (953) memory_layout: Examining memory region 0x3ffdc000 - 0x3ffde000
D (959) memory_layout: Available memory region 0x3ffdc000 - 0x3ffde000
V (966) memory_layout: Examining memory region 0x3ffde000 - 0x3ffe0000
D (972) memory_layout: Available memory region 0x3ffde000 - 0x3ffe0000
V (979) memory_layout: Examining memory region 0x3ffe0000 - 0x3ffe4000
V (986) memory_layout: Start of region 0x3ffe0000 - 0x3ffe4000 overlaps reserved 0x3ffe0000 - 0x3ffe0440
V (995) memory_layout: End of region 0x3ffe0440 - 0x3ffe4000 overlaps reserved 0x3ffe3f20 - 0x3ffe4350
D (1004) memory_layout: Available memory region 0x3ffe0440 - 0x3ffe3f20
V (1011) memory_layout: Examining memory region 0x3ffe4000 - 0x3ffe8000
V (1018) memory_layout: Start of region 0x3ffe4000 - 0x3ffe8000 overlaps reserved 0x3ffe3f20 - 0x3ffe4350
D (1027) memory_layout: Available memory region 0x3ffe4350 - 0x3ffe8000
V (1034) memory_layout: Examining memory region 0x3ffe8000 - 0x3fff0000
D (1041) memory_layout: Available memory region 0x3ffe8000 - 0x3fff0000
V (1047) memory_layout: Examining memory region 0x3fff0000 - 0x3fff8000
D (1054) memory_layout: Available memory region 0x3fff0000 - 0x3fff8000
V (1061) memory_layout: Examining memory region 0x3fff8000 - 0x3fffc000
V (1068) memory_layout: Region 0x3fff8000 - 0x3fffc000 inside of reserved 0x3fff8000 - 0x40000000
V (1076) memory_layout: Examining memory region 0x3fffc000 - 0x40000000
V (1083) memory_layout: Region 0x3fffc000 - 0x40000000 inside of reserved 0x3fff8000 - 0x40000000
V (1092) memory_layout: Examining memory region 0x40070000 - 0x40078000
V (1099) memory_layout: Region 0x40070000 - 0x40078000 inside of reserved 0x40070000 - 0x40078000
V (1108) memory_layout: Examining memory region 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1114) memory_layout: Region 0x40078000 - 0x40080000 inside of reserved 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1123) memory_layout: Examining memory region 0x40080000 - 0x40082000
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

0x40082000: esp_restart_noos at /Users/Mark/esp/esp-idf/components/esp32/system_api.c:172

V (1130) memory_layout: Region 0x40080000 - 0x40082000 inside of reserved 0x40080000 - 0x400924bc
0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

0x40082000: esp_restart_noos at /Users/Mark/esp/esp-idf/components/esp32/system_api.c:172

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1139) memory_layout: Examining memory region 0x40082000 - 0x40084000
0x40082000: esp_restart_noos at /Users/Mark/esp/esp-idf/components/esp32/system_api.c:172

0x40084000: spi_flash_mmap_pages at /Users/Mark/esp/esp-idf/components/spi_flash/flash_mmap.c:319

V (1146) memory_layout: Region 0x40082000 - 0x40084000 inside of reserved 0x40080000 - 0x400924bc
0x40082000: esp_restart_noos at /Users/Mark/esp/esp-idf/components/esp32/system_api.c:172

0x40084000: spi_flash_mmap_pages at /Users/Mark/esp/esp-idf/components/spi_flash/flash_mmap.c:319

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1155) memory_layout: Examining memory region 0x40084000 - 0x40086000
0x40084000: spi_flash_mmap_pages at /Users/Mark/esp/esp-idf/components/spi_flash/flash_mmap.c:319

0x40086000: r_ld_fm_prog_push at ??:?

V (1161) memory_layout: Region 0x40084000 - 0x40086000 inside of reserved 0x40080000 - 0x400924bc
0x40084000: spi_flash_mmap_pages at /Users/Mark/esp/esp-idf/components/spi_flash/flash_mmap.c:319

0x40086000: r_ld_fm_prog_push at ??:?

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1170) memory_layout: Examining memory region 0x40086000 - 0x40088000
0x40086000: r_ld_fm_prog_push at ??:?

0x40088000: r_rwbt_isr at ??:?

V (1177) memory_layout: Region 0x40086000 - 0x40088000 inside of reserved 0x40080000 - 0x400924bc
0x40086000: r_ld_fm_prog_push at ??:?

0x40088000: r_rwbt_isr at ??:?

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1186) memory_layout: Examining memory region 0x40088000 - 0x4008a000
0x40088000: r_rwbt_isr at ??:?

0x4008a000: coex_timer_ts_end_disalarm at ??:?

V (1193) memory_layout: Region 0x40088000 - 0x4008a000 inside of reserved 0x40080000 - 0x400924bc
0x40088000: r_rwbt_isr at ??:?

0x4008a000: coex_timer_ts_end_disalarm at ??:?

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1201) memory_layout: Examining memory region 0x4008a000 - 0x4008c000
0x4008a000: coex_timer_ts_end_disalarm at ??:?

0x4008c000: esp_rom_spiflash_erase_block at /Users/Mark/esp/esp-idf/components/spi_flash/spi_flash_rom_patch.c:429

V (1208) memory_layout: Region 0x4008a000 - 0x4008c000 inside of reserved 0x40080000 - 0x400924bc
0x4008a000: coex_timer_ts_end_disalarm at ??:?

0x4008c000: esp_rom_spiflash_erase_block at /Users/Mark/esp/esp-idf/components/spi_flash/spi_flash_rom_patch.c:429

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1217) memory_layout: Examining memory region 0x4008c000 - 0x4008e000
0x4008c000: esp_rom_spiflash_erase_block at /Users/Mark/esp/esp-idf/components/spi_flash/spi_flash_rom_patch.c:429

0x4008e000: rtc_clk_bbpll_configure at /Users/Mark/esp/esp-idf/components/soc/esp32/rtc_clk.c:474

V (1224) memory_layout: Region 0x4008c000 - 0x4008e000 inside of reserved 0x40080000 - 0x400924bc
0x4008c000: esp_rom_spiflash_erase_block at /Users/Mark/esp/esp-idf/components/spi_flash/spi_flash_rom_patch.c:429

0x4008e000: rtc_clk_bbpll_configure at /Users/Mark/esp/esp-idf/components/soc/esp32/rtc_clk.c:474

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1233) memory_layout: Examining memory region 0x4008e000 - 0x40090000
0x4008e000: rtc_clk_bbpll_configure at /Users/Mark/esp/esp-idf/components/soc/esp32/rtc_clk.c:474

0x40090000: vTaskEnterCritical at /Users/Mark/esp/esp-idf/components/freertos/tasks.c:3537

V (1239) memory_layout: Region 0x4008e000 - 0x40090000 inside of reserved 0x40080000 - 0x400924bc
0x4008e000: rtc_clk_bbpll_configure at /Users/Mark/esp/esp-idf/components/soc/esp32/rtc_clk.c:474

0x40090000: vTaskEnterCritical at /Users/Mark/esp/esp-idf/components/freertos/tasks.c:3537

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1248) memory_layout: Examining memory region 0x40090000 - 0x40092000
0x40090000: vTaskEnterCritical at /Users/Mark/esp/esp-idf/components/freertos/tasks.c:3537

0x40092000: multi_heap_malloc_impl at /Users/Mark/esp/esp-idf/components/heap/multi_heap.c:377

V (1255) memory_layout: Region 0x40090000 - 0x40092000 inside of reserved 0x40080000 - 0x400924bc
0x40090000: vTaskEnterCritical at /Users/Mark/esp/esp-idf/components/freertos/tasks.c:3537

0x40092000: multi_heap_malloc_impl at /Users/Mark/esp/esp-idf/components/heap/multi_heap.c:377

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

V (1264) memory_layout: Examining memory region 0x40092000 - 0x40094000
0x40092000: multi_heap_malloc_impl at /Users/Mark/esp/esp-idf/components/heap/multi_heap.c:377

V (1271) memory_layout: Start of region 0x40092000 - 0x40094000 overlaps reserved 0x40080000 - 0x400924bc
0x40092000: multi_heap_malloc_impl at /Users/Mark/esp/esp-idf/components/heap/multi_heap.c:377

0x40080000: _WindowOverflow4 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1685

D (1280) memory_layout: Available memory region 0x400924bc - 0x40094000
V (1287) memory_layout: Examining memory region 0x40094000 - 0x40096000
D (1294) memory_layout: Available memory region 0x40094000 - 0x40096000
V (1300) memory_layout: Examining memory region 0x40096000 - 0x40098000
D (1307) memory_layout: Available memory region 0x40096000 - 0x40098000
V (1314) memory_layout: Examining memory region 0x40098000 - 0x4009a000
D (1320) memory_layout: Available memory region 0x40098000 - 0x4009a000
V (1327) memory_layout: Examining memory region 0x4009a000 - 0x4009c000
D (1334) memory_layout: Available memory region 0x4009a000 - 0x4009c000
V (1340) memory_layout: Examining memory region 0x4009c000 - 0x4009e000
D (1347) memory_layout: Available memory region 0x4009c000 - 0x4009e000
V (1354) memory_layout: Examining memory region 0x4009e000 - 0x400a0000
D (1361) memory_layout: Available memory region 0x4009e000 - 0x400a0000
I (1367) heap_init: Initializing. RAM available for dynamic allocation:
D (1375) heap_init: New heap initialised at 0x3ffaff10
I (1380) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM
D (1386) heap_init: New heap initialised at 0x3ffb6388
I (1391) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM
D (1397) heap_init: New heap initialised at 0x3ffb9a20
I (1402) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM
I (1409) heap_init: At 3FFBDB5C len 00000004 (0 KiB): DRAM
D (1415) heap_init: New heap initialised at 0x3ffd0530
I (1420) heap_init: At 3FFD0530 len 0000FAD0 (62 KiB): DRAM
I (1426) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (1433) heap_init: At 3FFE4350 len 00013CB0 (79 KiB): D/IRAM
D (1439) heap_init: New heap initialised at 0x400924bc
I (1444) heap_init: At 400924BC len 0000DB44 (54 KiB): IRAM
I (1451) cpu_start: Pro cpu start user code
D (1463) clk: RTC_SLOW_CLK calibration value: 3213478
V (130) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (130) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xE
D (135) intr_alloc: Connected src 46 to int 2 (cpu 0)
V (141) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (147) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xC0E
D (155) intr_alloc: Connected src 57 to int 3 (cpu 0)
I (160) esp_apptrace: Initialized TRAX on CPU0
V (165) esp_dbg_stubs: esp_dbg_stubs_init stubs 3ffcd8b0
V (171) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (177) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0x40E
D (185) intr_alloc: Connected src 24 to int 9 (cpu 0)
I (190) cpu_start: Starting scheduler on PRO CPU.
I (535) esp_apptrace: Initialized TRAX on CPU1
V (0) intr_alloc: esp_intr_alloc_intrstatus (cpu 1): checking args
V (10) intr_alloc: esp_intr_alloc_intrstatus (cpu 1): Args okay. Resulting flags 0x40E
D (10) intr_alloc: Connected src 25 to int 2 (cpu 1)
I (20) cpu_start: Starting scheduler on APP CPU.
D (245) heap_init: New heap initialised at 0x3ffe0440
D (255) heap_init: New heap initialised at 0x3ffe4350
V (255) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (255) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xE
D (255) intr_alloc: Connected src 16 to int 12 (cpu 0)
D (285) nvs: nvs_flash_init_custom partition=nvs start=9 count=6
Guru Meditation Error: Core 0 panic'ed (Cache disabled but cached memory region accessed)
Core 0 register dump:
PC : 0x400e8a0c PS : 0x00060034 A0 : 0x4009158c A1 : 0x3ffbe2b0
0x400e8a0c: SEGGER_SYSVIEW_RecordEnterISR at /Users/Mark/esp/esp-idf/components/app_trace/sys_view/SEGGER/SEGGER_SYSVIEW.c:364

0x4009158c: _frxt_timer_int at /Users/Mark/esp/esp-idf/components/freertos/portasm.S:313

A2 : 0x00000003 A3 : 0x00060723 A4 : 0x40082631 A5 : 0x3ffbb248
0x40082631: _xt_lowint1 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1098

A6 : 0x00000000 A7 : 0x00000000 A8 : 0x8008eaf8 A9 : 0x3ffbbea0
A10 : 0x00000005 A11 : 0x00000000 A12 : 0x00060723 A13 : 0x00060523
A14 : 0x00000000 A15 : 0x00000004 SAR : 0x00000019 EXCCAUSE: 0x00000007
EXCVADDR: 0x00000000 LBEG : 0x4000164d LEND : 0x40001667 LCOUNT : 0xfffffffc
Core 0 was running in ISR context:
EPC1 : 0x400814f7 EPC2 : 0x00000000 EPC3 : 0x00000000 EPC4 : 0x400e8a0c
0x400814f7: esp_dport_access_reg_read at /Users/Mark/esp/esp-idf/components/esp32/dport_access.c:270

0x400e8a0c: SEGGER_SYSVIEW_RecordEnterISR at /Users/Mark/esp/esp-idf/components/app_trace/sys_view/SEGGER/SEGGER_SYSVIEW.c:364

Backtrace: 0x400e8a0c:0x3ffbe2b0 0x40091589:0x3ffbe2d0 0x40082692:0x3ffbe2e0 0x400814f4:0x00000000
0x400e8a0c: SEGGER_SYSVIEW_RecordEnterISR at /Users/Mark/esp/esp-idf/components/app_trace/sys_view/SEGGER/SEGGER_SYSVIEW.c:364

0x40091589: _frxt_timer_int at /Users/Mark/esp/esp-idf/components/freertos/portasm.S:303

0x40082692: _xt_lowint1 at /Users/Mark/esp/esp-idf/components/freertos/xtensa_vectors.S:1105

0x400814f4: esp_dport_access_reg_read at /Users/Mark/esp/esp-idf/components/esp32/dport_access.c:257

Guru Meditation Error: Core 0 panic'ed (IllegalInstruction). Exception was unhandled.
Core 0 register dump:
PC : 0x400e8fb2 PS : 0x00060035 A0 : 0x8008cf6d A1 : 0x3ffbe190
0x400e8fb2: SEGGER_RTT_ESP32_FlushNoLock at /Users/Mark/esp/esp-idf/components/app_trace/sys_view/esp32/SEGGER_RTT_esp32.c:59

A2 : 0x3ffbe1f0 A3 : 0x383fc000 A4 : 0x00000020 A5 : 0x3ffbe1d0
A6 : 0x3ffbfb78 A7 : 0x3ffbfb98 A8 : 0x8008cd38 A9 : 0x3ff60000
A10 : 0xffffffff A11 : 0xffffffff A12 : 0x00000000 A13 : 0x00000018
A14 : 0x00000004 A15 : 0x3ffbbe50 SAR : 0x0000000a EXCCAUSE: 0x00000000
EXCVADDR: 0x00000000 LBEG : 0x4000c2e0 LEND : 0x4000c2f6 LCOUNT : 0x00000000
Core 0 was running in ISR context:
EPC1 : 0x400e8fb2 EPC2 : 0x00000000 EPC3 : 0x00000000 EPC4 : 0x400e8a0c
0x400e8fb2: SEGGER_RTT_ESP32_FlushNoLock at /Users/Mark/esp/esp-idf/components/app_trace/sys_view/esp32/SEGGER_RTT_esp32.c:59

0x400e8a0c: SEGGER_SYSVIEW_RecordEnterISR at /Users/Mark/esp/esp-idf/components/app_trace/sys_view/SEGGER/SEGGER_SYSVIEW.c:364

Backtrace: 0x400e8fb2:0x3ffbe190 0x4008cf6a:0x3ffbe1b0 0x400821f6:0x3ffbe1f0
0x400e8fb2: SEGGER_RTT_ESP32_FlushNoLock at /Users/Mark/esp/esp-idf/components/app_trace/sys_view/esp32/SEGGER_RTT_esp32.c:59

0x4008cf6a: panicHandler at /Users/Mark/esp/esp-idf/components/esp32/panic.c:680

0x400821f6: xt_highint4 at /Users/Mark/esp/esp-idf/components/esp32/dport_panic_highint_hdl.S:116

Guru Meditation Error: Core 0 panic'ed (IllegalInstruction). Exception was unhandled.
Core 0 register dump:
PC : 0x400e8fb2 PS : 0x00060033 A0 : 0x8008cff9 A1 : 0x3ffbe090
0x400e8fb2: SEGGER_RTT_ESP32_FlushNoLock at /Users/Mark/esp/esp-idf/components/app_trace/sys_view/esp32/SEGGER_RTT_esp32.c:59

A2 : 0x3ffbe0d0 A3 : 0x383fc000 A4 : 0x8008cc60 A5 : 0x3ffbe0f0
A6 : 0x00000006 A7 : 0x3fffffff A8 : 0x8008cd38 A9 : 0x3ff60000
A10 : 0xffffffff A11 : 0xffffffff A12 : 0x00000000 A13 : 0x00000018
A14 : 0x00000004 A15 : 0x00000004 SAR : 0x00000014 EXCCAUSE: 0x00000000
EXCVADDR: 0x00000000 LBEG : 0x4000c2e0 LEND : 0x4000c2f6 LCOUNT : 0x00000000
Core 0 was running in ISR context:
EPC1 : 0x400e8fb2 EPC2 : 0x00000000 EPC3 : 0x00000000 EPC4 : 0x400e8a0c
0x400e8fb2: SEGGER_RTT_ESP32_FlushNoLock at /Users/Mark/esp/esp-idf/components/app_trace/sys_view/esp32/SEGGER_RTT_esp32.c:59

0x400e8a0c: SEGGER_SYSVIEW_RecordEnterISR at /Users/Mark/esp/esp-idf/components/app_trace/sys_view/SEGGER/SEGGER_SYSVIEW.c:364

Backtrace: 0x400e8fb2:0x3ffbe090 0x4008cff6:0x3ffbe0b0 0x4008241e:0x3ffbe0d0 0x400e8faf:0x3ffbe190 0x400e8faf:0x3ffbe1b0 0x400821f6:0x3ffbe1f0

Steps to repropduce

1. step1
2. ...

// It helps if you attach a picture of your setup/wiring here.

Code to reproduce this issue

    // Initialize NVS.
    esp_err_t ret = nvs_flash_init();
    if (ret == ESP_ERR_NVS_NO_FREE_PAGES) {
       ESP_ERROR_CHECK(nvs_flash_erase());
        ret = nvs_flash_init();
    }
    ESP_ERROR_CHECK( ret );

    esp_bt_controller_config_t bt_cfg = BT_CONTROLLER_INIT_CONFIG_DEFAULT();

    if (esp_bt_controller_init(&bt_cfg) != ESP_OK) {
        ESP_LOGE(BT_AV_TAG, "%s initialize controller failed\n", __func__);
        return;
    }

    if (esp_bt_controller_enable(ESP_BT_MODE_BTDM) != ESP_OK) {
        ESP_LOGE(BT_AV_TAG, "%s enable controller failed\n", __func__);
        return;
    }

    if (esp_bluedroid_init() != ESP_OK) {
        ESP_LOGE(BT_AV_TAG, "%s initialize bluedroid failed\n", __func__);
        return;
    }

    if (esp_bluedroid_enable() != ESP_OK) {
        ESP_LOGE(BT_AV_TAG, "%s enable bluedroid failed\n", __func__);
        return;
    }

// If your code is longer than 30 lines, GIST is preferred.

Debug Logs

See above

Other items if possible

For some reason I cannot attach files from my Mac. I will be glad to email anything that is needed.
Thank you!!

[ ] sdkconfig file (attach the sdkconfig file from your project folder)

• elf file in the build folder (note this may contain all the code details and symbols of your project.)
• coredump (This provides stacks of tasks.)

[igrr]

Looks like SEGGER_SYSVIEW_RecordEnterISR hasn't been placed into IRAM. Thanks for the report, will fix.

[markingle]

Thank you! I meet with a customer Saturday to demo the code. It would be great if the code could be pushed before then. I appreciate the help!

[igrr]

Could you please try the following patch?

3835.patch.zip

[markingle]

Thank you for the quick response! I will test later this evening.

[markingle]

Unfortunately I am still getting the same error...is there another step that I need to complete?

[markingle]

@igrr I did more troubleshooting with the a2dp_source in the examples directory. Its basically the same version of my code. Once I enabled System trace in menuconfig it produces the same results.

[igrr]

Okay, so you might be seeing a different issue than the one the fix is for. I haven't noticed it initially because it doesn't happen with CMake based build system, and ended up fixing a different issue.
The issue which happens with Make based build system might take some time to analyze, so if this is urgent you might want to try building using CMake (idf.py build) with the above patch applied.

[markingle]

Okay I am not familiar with Cmake but I’ll give it a try tomorrow. Thanks for your help!

[igrr]

Never mind, seems like this was a simple one to fix. Please find updated patch attached. Should work with make now as well.
0001-app_trace-require-timer-driver-to-be-in-IRAM-fix-com.patch.zip

[markingle]

I am having trouble getting the patch to apply. I will work on it more later today. Here is a screenshot of the output from the git apply...

[igrr]

Make sure you aren't applying it on top of the previous patch. The latest one combines both fixes and should apply on top of master.

[markingle]

Yes I pulled the latest from master. I also did the submodule update --init --recursive. Should I not run the submodule update?

[markingle]

I resolved the problem applying the patch. It has been installed and both versions of a2dp_source are working properly. Thank you for your help and quick response! I'll close this issue.

[igrr]

Thanks for feedback. Will keep open until the patch is merged into master.