unused code hangs program

[chromebin]

Thanks for the reply.

As a reminder, the first thing user code does is print something.

> Are you able to post a full project that (at least sometimes) exhibits this problem, somewhere?

Hopefully that won't be needed.

Below all output on verbose (should have remembered that):

Code: Select all

Wrote 3072 bytes (160 compressed) at 0x00008000 in 0.0 seconds (effective 1572.7 kbit/s)...
Hash of data verified.

Leaving...
Hard resetting via RTS pin...
MONITOR
--- idf_monitor on COM4 115200 ---
--- Quit: Ctrl+] | Menu: Ctrl+T | Help: Ctrl+T followed by Ctrl+H ---
etsRչ ╗016:22:MSH
. (POWE%UMT),bo0x1BSPI_TMQ}1║SH_BOj
c˥ͥ: 0, *]A0xee
ckE0x00,E0A0,d_0x0bcs0_0xbhd_d.0,wp_dr0C둕DIO, ckdZj
l+0x&blen:SH0x3fflen:83j
loa0000,10LNH+0x48040b╹6Mj
e0‚¢
D Ɋ) bo_flaK╗jՁset bkpa0x000╗(wasxfff+j5
I (35) boot: ESP-IDF v3.2-dev-518-g020ade65 2nd stage bootloader
I (35) boot: compile time 08:07:37
I (35) boot: Enabling RNG early entropy source...
D (41) boot: magic e9
D (43) boot: segments 04
D (46) boot: spi_mode 02
D (48) boot: spi_speed 0f
D (51) boot: spi_size 02
I (54) boot: SPI Speed      : 80MHz
I (58) boot: SPI Mode       : DIO
I (62) boot: SPI Flash Size : 4MB
D (66) bootloader_flash: mmu set paddr=00000000 count=1
D (71) boot: mapped partition table 0x8000 at 0x3f408000
D (77) flash_parts: partition table verified, 8 entries
I (82) boot: Partition Table:
I (85) boot: ## Label            Usage          Type ST Offset   Length
D (93) boot: load partition table entry 0x3f408000
D (98) boot: type=1 subtype=2
I (101) boot:  0 nvs              WiFi data        01 02 00009000 00004000
D (108) boot: load partition table entry 0x3f408020
D (113) boot: type=1 subtype=0
I (116) boot:  1 otadata          OTA data         01 00 0000d000 00002000
D (124) boot: load partition table entry 0x3f408040
D (129) boot: type=1 subtype=1
I (132) boot:  2 phy_init         RF data          01 01 0000f000 00001000
D (139) boot: load partition table entry 0x3f408060
D (144) boot: type=0 subtype=0
I (147) boot:  3 factory          factory app      00 00 00010000 000e1000
D (155) boot: load partition table entry 0x3f408080
D (160) boot: type=0 subtype=10
I (163) boot:  4 ota_0            OTA app          00 10 00100000 000e1000
D (171) boot: load partition table entry 0x3f4080a0
D (176) boot: type=0 subtype=11
I (179) boot:  5 ota_1            OTA app          00 11 001f0000 000e1000
D (186) boot: load partition table entry 0x3f4080c0
D (191) boot: type=1 subtype=82
I (195) boot:  6 storage          Unknown data     01 82 002d1000 0012c000
I (202) boot: End of partition table
D (206) boot: OTA data offset 0xd000
D (210) bootloader_flash: mmu set paddr=00000000 count=1
D (215) boot: OTA sequence values A 0xffffffff B 0xffffffff
D (221) boot: OTA sequence numbers both empty (all-0xFF) or partition table does
 not have bootable ota_apps (app_count=2)
I (232) boot: Defaulting to factory image
D (237) boot: Trying partition index -1 offs 0x10000 size 0xe1000
D (243) esp_image: reading image header @ 0x10000
D (248) bootloader_flash: mmu set block paddr=0x00010000 (was 0xffffffff)
D (255) esp_image: image header: 0xe9 0x09 0x02 0x03 40080e50
V (260) esp_image: loading segment header 0 at offset 0x10018
V (266) esp_image: segment data length 0x2ca2c data starts 0x10020
V (272) esp_image: segment 0 map_segment 1 segment_data_offs 0x10020 load_addr 0
x3f400020
I (281) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x2ca2c (18
2828) map
D (290) bootloader_flash: mmu set paddr=00010000 count=3
V (348) esp_image: loading segment header 1 at offset 0x3ca4c
D (348) bootloader_flash: mmu set block paddr=0x00030000 (was 0xffffffff)
V (350) esp_image: segment data length 0x35bc data starts 0x3ca54
V (356) esp_image: segment 1 map_segment 0 segment_data_offs 0x3ca54 load_addr 0
x3ffb0000
I (364) esp_image: segment 1: paddr=0x0003ca54 vaddr=0x3ffb0000 size=0x035bc ( 1
3756) load
D (373) bootloader_flash: mmu set paddr=00030000 count=2
V (383) esp_image: loading segment header 2 at offset 0x40010
D (384) bootloader_flash: mmu set block paddr=0x00040000 (was 0xffffffff)
V (391) esp_image: segment data length 0x797a4 data starts 0x40018
V (397) esp_image: segment 2 map_segment 1 segment_data_offs 0x40018 load_addr 0
x400d0018
0x400d0018: _stext at ??:?

I (406) esp_image: segment 2: paddr=0x00040018 vaddr=0x400d0018 size=0x797a4 (49
7572) map
0x400d0018: _stext at ??:?

D (414) bootloader_flash: mmu set paddr=00040000 count=8
V (565) esp_image: loading segment header 3 at offset 0xb97bc
D (565) bootloader_flash: mmu set block paddr=0x000b0000 (was 0xffffffff)
V (566) esp_image: segment data length 0x1c80 data starts 0xb97c4
V (572) esp_image: segment 3 map_segment 0 segment_data_offs 0xb97c4 load_addr 0
x3ffb35bc
I (580) esp_image: segment 3: paddr=0x000b97c4 vaddr=0x3ffb35bc size=0x01c80 (
7296) load
D (589) bootloader_flash: mmu set paddr=000b0000 count=1
V (597) esp_image: loading segment header 4 at offset 0xbb444
D (601) bootloader_flash: mmu set block paddr=0x000b0000 (was 0xffffffff)
V (607) esp_image: segment data length 0x0 data starts 0xbb44c
V (613) esp_image: segment 4 map_segment 0 segment_data_offs 0xbb44c load_addr 0
x3ffb523c
I (622) esp_image: segment 4: paddr=0x000bb44c vaddr=0x3ffb523c size=0x00000 (
   0) load
D (631) bootloader_flash: mmu set paddr=000b0000 count=1
V (636) esp_image: loading segment header 5 at offset 0xbb44c
D (642) bootloader_flash: mmu set block paddr=0x000b0000 (was 0xffffffff)
V (649) esp_image: segment data length 0x400 data starts 0xbb454
V (655) esp_image: segment 5 map_segment 0 segment_data_offs 0xbb454 load_addr 0
x40080000
0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

I (663) esp_image: segment 5: paddr=0x000bb454 vaddr=0x40080000 size=0x00400 (
1024) load
0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

D (672) bootloader_flash: mmu set paddr=000b0000 count=1
V (678) esp_image: loading segment header 6 at offset 0xbb854
D (683) bootloader_flash: mmu set block paddr=0x000b0000 (was 0xffffffff)
V (690) esp_image: segment data length 0xfe64 data starts 0xbb85c
V (696) esp_image: segment 6 map_segment 0 segment_data_offs 0xbb85c load_addr 0
x40080400
I (704) esp_image: segment 6: paddr=0x000bb85c vaddr=0x40080400 size=0x0fe64 ( 6
5124) load
D (713) bootloader_flash: mmu set paddr=000b0000 count=2
V (742) esp_image: loading segment header 7 at offset 0xcb6c0
D (742) bootloader_flash: mmu set block paddr=0x000c0000 (was 0xffffffff)
V (743) esp_image: segment data length 0x0 data starts 0xcb6c8
V (749) esp_image: segment 7 map_segment 0 segment_data_offs 0xcb6c8 load_addr 0
x400c0000
I (757) esp_image: segment 7: paddr=0x000cb6c8 vaddr=0x400c0000 size=0x00000 (
   0) load
D (766) bootloader_flash: mmu set paddr=000c0000 count=1
V (772) esp_image: loading segment header 8 at offset 0xcb6c8
D (778) bootloader_flash: mmu set block paddr=0x000c0000 (was 0xffffffff)
V (784) esp_image: segment data length 0x0 data starts 0xcb6d0
V (790) esp_image: segment 8 map_segment 0 segment_data_offs 0xcb6d0 load_addr 0
x50000000
I (799) esp_image: segment 8: paddr=0x000cb6d0 vaddr=0x50000000 size=0x00000 (
   0) load
D (808) bootloader_flash: mmu set paddr=000c0000 count=1
V (813) esp_image: image start 0x00010000 end of last section 0x000cb6d0
D (820) bootloader_flash: mmu set block paddr=0x000c0000 (was 0xffffffff)
D (827) esp_image: Calculated hash: 4643567abf858c0679b7126579c73d306fb4265884f0
3bd23ff0a12c9ea88854
D (836) bootloader_flash: mmu set paddr=000c0000 count=1
D (841) bootloader_flash: mmu set paddr=000c0000 count=1
I (857) boot: Loaded app from partition at offset 0x10000
I (857) boot: Disabling RNG early entropy source...
D (858) boot: Mapping segment 0 as IROM
D (862) boot: Mapping segment 2 as DROM
D (866) boot: calling set_cache_and_start_app
D (870) boot: configure drom and irom and start
V (875) boot: d mmu set paddr=00040000 vaddr=400d0000 size=497572 n=8
V (882) boot: rc=0
V (884) boot: rc=0
V (886) boot: i mmu set paddr=00010000 vaddr=3f400000 size=182828 n=3
V (892) boot: rc=0
V (894) boot: rc=0
D (896) boot: start: 0x40080e50
0x40080e50: call_start_cpu0 at C:/msys32/home/user/esp/esp-idf/components/esp32/
cpu_start.c:114

I (900) cpu_start: Pro cpu up.
I (903) cpu_start: Single core mode
I (908) heap_init: Initializing. RAM available for dynamic allocation:
I (915) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
I (921) heap_init: At 3FFC3480 len 0001CB80 (114 KiB): DRAM
I (927) heap_init: At 3FFE0440 len 00003BC0 (14 KiB): D/IRAM
I (933) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
I (940) heap_init: At 40090264 len 0000FD9C (63 KiB): IRAM
I (946) cpu_start: Pro cpu start user code

[chromebin]

What is happening??

I don't know, but my guess would be silent memory corruption somewhere else which is sometimes breaking everything when you call settimeofday().

You probably implied this, but I have no evidence of settimeofday() actually being called, it is only linked in.

And no, I have no memory allocations, but I'll disable wifi just to see if that makes a difference.

Also, it seems the ESP WROOM stays cool (it isn't busy).

I can't get it to print anything, even with a ets_delay_us() after the print stmt.

[chromebin]

And no, I have no memory allocations, but I'll disable wifi just to see if that makes a difference.

I tried, no difference. Bad idea I guess. To make a real difference I would have to purge the project of anything Wifi. That would mean reducing the project, which also implies getting to a state that I can send it, as per your request.

But my employer is in a hurry, so my only option for now is to ignore settimeofday() and implement something similar myself using the uptime. As far as |I know using settimeofday() isn't critical, maybe so for TLS (which we will be using in the future)?

I'll see if I can reduce the project after hours.

[ESP_igrr]

It seems that you have enabled verbose log level for the bootloader, but not for the app? This is a separate setting under menconfig > component config > log. Please give it a try.

[chromebin]

This is the output:

Code: Select all

$ clear; cd ~/projects/xxxxxx/project; make flash -j ESPPORT=COM4; make monitor -j ESPPORT=COM4
GENCONFIG
Building partitions from /home/user/projects/xxxxxx/project/partitions.csv...
  ....
esptool.py v2.5.0
Flashing binaries to serial port COM4 (app at offset 0x10000 )...
esptool.py v2.5.0
Serial port COM4
Connecting.....
Chip is ESP32D0WDQ6 (revision 1)
Features: WiFi, BT, Dual Core, 240MHz, VRef calibration in efuse
MAC: 80:7d:3a:a4:4b:cc
Uploading stub...
Running stub...
Stub running...
Changing baud rate to 921600
Changed.
Configuring flash size...
Auto-detected Flash size: 4MB
Compressed 8192 bytes to 31...
Wrote 8192 bytes (31 compressed) at 0x0000d000 in 0.0 seconds (effective 21272.1 kbit/s)...
Hash of data verified.
Flash params set to 0x022f
Compressed 25152 bytes to 14271...
Wrote 25152 bytes (14271 compressed) at 0x00001000 in 0.2 seconds (effective 1116.2 kbit/s)...
Hash of data verified.
Compressed 780624 bytes to 446705...
Wrote 780624 bytes (446705 compressed) at 0x00010000 in 7.3 seconds (effective 853.4 kbit/s)...
Hash of data verified.
Compressed 3072 bytes to 160...
Wrote 3072 bytes (160 compressed) at 0x00008000 in 0.0 seconds (effective 4655.4 kbit/s)...
Hash of data verified.

Leaving...
Hard resetting via RTS pin...
MONITOR
--- idf_monitor on COM4 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:0x17 (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:1
load:0x3fff0018,len:4
load:0x3fff001c,len:8300
load:0x40078000,len:10192
load:0x40080400,len:6560
entry 0x40080784
D (61) bootloader_flash: mmu set block paddr=0x00000000 (was 0xffffffff)
I (35) boot: ESP-IDF v3.2-dev-518-g020ade65 2nd stage bootloader
I (35) boot: compile time 14:04:22
I (35) boot: Enabling RNG early entropy source...
D (41) boot: magic e9
D (43) boot: segments 04
D (46) boot: spi_mode 02
D (48) boot: spi_speed 0f
D (51) boot: spi_size 02
I (54) boot: SPI Speed      : 80MHz
I (58) boot: SPI Mode       : DIO
I (62) boot: SPI Flash Size : 4MB
D (66) bootloader_flash: mmu set paddr=00000000 count=1
D (71) boot: mapped partition table 0x8000 at 0x3f408000
D (77) flash_parts: partition table verified, 8 entries
I (82) boot: Partition Table:
I (86) boot: ## Label            Usage          Type ST Offset   Length
D (93) boot: load partition table entry 0x3f408000
D (98) boot: type=1 subtype=2
I (101) boot:  0 nvs              WiFi data        01 02 00009000 00004000
D (108) boot: load partition table entry 0x3f408020
D (113) boot: type=1 subtype=0
I (116) boot:  1 otadata          OTA data         01 00 0000d000 00002000
D (124) boot: load partition table entry 0x3f408040
D (129) boot: type=1 subtype=1
I (132) boot:  2 phy_init         RF data          01 01 0000f000 00001000
D (140) boot: load partition table entry 0x3f408060
D (145) boot: type=0 subtype=0
I (148) boot:  3 factory          factory app      00 00 00010000 000e1000
D (155) boot: load partition table entry 0x3f408080
D (160) boot: type=0 subtype=10
I (163) boot:  4 ota_0            OTA app          00 10 00100000 000e1000
D (171) boot: load partition table entry 0x3f4080a0
D (176) boot: type=0 subtype=11
I (179) boot:  5 ota_1            OTA app          00 11 001f0000 000e1000
D (187) boot: load partition table entry 0x3f4080c0
D (192) boot: type=1 subtype=82
I (195) boot:  6 storage          Unknown data     01 82 002d1000 0012c000
I (202) boot: End of partition table
D (207) boot: OTA data offset 0xd000
D (210) bootloader_flash: mmu set paddr=00000000 count=1
D (216) boot: OTA sequence values A 0xffffffff B 0xffffffff
D (221) boot: OTA sequence numbers both empty (all-0xFF) or partition table does
 not have bootable ota_apps (app_count=2)
I (232) boot: Defaulting to factory image
D (237) boot: Trying partition index -1 offs 0x10000 size 0xe1000
D (243) esp_image: reading image header @ 0x10000
D (248) bootloader_flash: mmu set block paddr=0x00010000 (was 0xffffffff)
D (255) esp_image: image header: 0xe9 0x09 0x02 0x03 40080e60
V (261) esp_image: loading segment header 0 at offset 0x10018
V (266) esp_image: segment data length 0x2e808 data starts 0x10020
V (273) esp_image: segment 0 map_segment 1 segment_data_offs 0x10020 load_addr 0
x3f400020
I (281) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x2e808 (19
0472) map
D (290) bootloader_flash: mmu set paddr=00010000 count=3
V (351) esp_image: loading segment header 1 at offset 0x3e828
D (351) bootloader_flash: mmu set block paddr=0x00030000 (was 0xffffffff)
V (352) esp_image: segment data length 0x17e0 data starts 0x3e830
V (358) esp_image: segment 1 map_segment 0 segment_data_offs 0x3e830 load_addr 0
x3ffb0000
I (367) esp_image: segment 1: paddr=0x0003e830 vaddr=0x3ffb0000 size=0x017e0 (
6112) load
D (375) bootloader_flash: mmu set paddr=00030000 count=2
V (383) esp_image: loading segment header 2 at offset 0x40010
D (387) bootloader_flash: mmu set block paddr=0x00040000 (was 0xffffffff)
V (394) esp_image: segment data length 0x7abd0 data starts 0x40018
V (400) esp_image: segment 2 map_segment 1 segment_data_offs 0x40018 load_addr 0
x400d0018
0x400d0018: _stext at ??:?

I (408) esp_image: segment 2: paddr=0x00040018 vaddr=0x400d0018 size=0x7abd0 (50
2736) map
0x400d0018: _stext at ??:?

D (417) bootloader_flash: mmu set paddr=00040000 count=8
V (568) esp_image: loading segment header 3 at offset 0xbabe8
D (569) bootloader_flash: mmu set block paddr=0x000b0000 (was 0xffffffff)
V (570) esp_image: segment data length 0x3a80 data starts 0xbabf0
V (576) esp_image: segment 3 map_segment 0 segment_data_offs 0xbabf0 load_addr 0
x3ffb17e0
I (584) esp_image: segment 3: paddr=0x000babf0 vaddr=0x3ffb17e0 size=0x03a80 ( 1
4976) load
D (593) bootloader_flash: mmu set paddr=000b0000 count=1
V (604) esp_image: loading segment header 4 at offset 0xbe670
D (604) bootloader_flash: mmu set block paddr=0x000b0000 (was 0xffffffff)
V (611) esp_image: segment data length 0x0 data starts 0xbe678
V (617) esp_image: segment 4 map_segment 0 segment_data_offs 0xbe678 load_addr 0
x3ffb5260
I (625) esp_image: segment 4: paddr=0x000be678 vaddr=0x3ffb5260 size=0x00000 (
   0) load
D (634) bootloader_flash: mmu set paddr=000b0000 count=1
V (640) esp_image: loading segment header 5 at offset 0xbe678
D (646) bootloader_flash: mmu set block paddr=0x000b0000 (was 0xffffffff)
V (652) esp_image: segment data length 0x400 data starts 0xbe680
V (659) esp_image: segment 5 map_segment 0 segment_data_offs 0xbe680 load_addr 0
x40080000
0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

I (667) esp_image: segment 5: paddr=0x000be680 vaddr=0x40080000 size=0x00400 (
1024) load
0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

D (676) bootloader_flash: mmu set paddr=000b0000 count=1
V (682) esp_image: loading segment header 6 at offset 0xbea80
D (687) bootloader_flash: mmu set block paddr=0x000b0000 (was 0xffffffff)
V (694) esp_image: segment data length 0xfe90 data starts 0xbea88
V (700) esp_image: segment 6 map_segment 0 segment_data_offs 0xbea88 load_addr 0
x40080400
I (708) esp_image: segment 6: paddr=0x000bea88 vaddr=0x40080400 size=0x0fe90 ( 6
5168) load
D (717) bootloader_flash: mmu set paddr=000b0000 count=2
V (746) esp_image: loading segment header 7 at offset 0xce918
D (746) bootloader_flash: mmu set block paddr=0x000c0000 (was 0xffffffff)
V (747) esp_image: segment data length 0x0 data starts 0xce920
V (753) esp_image: segment 7 map_segment 0 segment_data_offs 0xce920 load_addr 0
x400c0000
I (761) esp_image: segment 7: paddr=0x000ce920 vaddr=0x400c0000 size=0x00000 (
   0) load
D (770) bootloader_flash: mmu set paddr=000c0000 count=1
V (776) esp_image: loading segment header 8 at offset 0xce920
D (782) bootloader_flash: mmu set block paddr=0x000c0000 (was 0xffffffff)
V (788) esp_image: segment data length 0x0 data starts 0xce928
V (794) esp_image: segment 8 map_segment 0 segment_data_offs 0xce928 load_addr 0
x50000000
I (803) esp_image: segment 8: paddr=0x000ce928 vaddr=0x50000000 size=0x00000 (
   0) load
D (811) bootloader_flash: mmu set paddr=000c0000 count=1
V (817) esp_image: image start 0x00010000 end of last section 0x000ce928
D (824) bootloader_flash: mmu set block paddr=0x000c0000 (was 0xffffffff)
D (831) esp_image: Calculated hash: 9541ec2e0ad6013ce1b313c2b055a65f7e43e103a176
64cee725eff697193a1e
D (840) bootloader_flash: mmu set paddr=000c0000 count=1
D (845) bootloader_flash: mmu set paddr=000c0000 count=1
I (861) boot: Loaded app from partition at offset 0x10000
I (861) boot: Disabling RNG early entropy source...
D (862) boot: Mapping segment 0 as IROM
D (866) boot: Mapping segment 2 as DROM
D (870) boot: calling set_cache_and_start_app
D (874) boot: configure drom and irom and start
V (879) boot: d mmu set paddr=00040000 vaddr=400d0000 size=502736 n=8
V (885) boot: rc=0
V (888) boot: rc=0
V (890) boot: i mmu set paddr=00010000 vaddr=3f400000 size=190472 n=3
V (896) boot: rc=0
V (898) boot: rc=0
D (900) boot: start: 0x40080e60
0x40080e60: call_start_cpu0 at C:/msys32/home/user/esp/esp-idf/components/esp32/
cpu_start.c:114

I (904) cpu_start: Pro cpu up.
I (907) cpu_start: Single core mode
V (911) memory_layout: reserved range is 0x3f42e800 - 0x3f42e828
D (917) memory_layout: Checking 7 reserved memory ranges:
D (923) memory_layout: Reserved memory range 0x3ffae000 - 0x3ffae6e0
D (929) memory_layout: Reserved memory range 0x3ffb0000 - 0x3ffc3478
D (936) memory_layout: Reserved memory range 0x3ffe0000 - 0x3ffe0440
D (942) memory_layout: Reserved memory range 0x3ffe4000 - 0x3ffe4350
D (949) memory_layout: Reserved memory range 0x40070000 - 0x40078000
D (955) memory_layout: Reserved memory range 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

D (961) memory_layout: Reserved memory range 0x40080000 - 0x40090290
0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

D (968) memory_layout: Building list of available memory regions:
V (974) memory_layout: Examining memory region 0x3ffae000 - 0x3ffb0000
V (981) memory_layout: Start of region 0x3ffae000 - 0x3ffb0000 overlaps reserved
 0x3ffae000 - 0x3ffae6e0
D (990) memory_layout: Available memory region 0x3ffae6e0 - 0x3ffb0000
V (997) memory_layout: Examining memory region 0x3ffb0000 - 0x3ffb8000
V (1003) memory_layout: Region 0x3ffb0000 - 0x3ffb8000 inside of reserved 0x3ffb
0000 - 0x3ffc3478
V (1012) memory_layout: Examining memory region 0x3ffb8000 - 0x3ffc0000
V (1019) memory_layout: Region 0x3ffb8000 - 0x3ffc0000 inside of reserved 0x3ffb
0000 - 0x3ffc3478
V (1028) memory_layout: Examining memory region 0x3ffc0000 - 0x3ffc2000
V (1035) memory_layout: Region 0x3ffc0000 - 0x3ffc2000 inside of reserved 0x3ffb
0000 - 0x3ffc3478
V (1044) memory_layout: Examining memory region 0x3ffc2000 - 0x3ffc4000
V (1050) memory_layout: Start of region 0x3ffc2000 - 0x3ffc4000 overlaps reserve
d 0x3ffb0000 - 0x3ffc3478
D (1060) memory_layout: Available memory region 0x3ffc3478 - 0x3ffc4000
V (1067) memory_layout: Examining memory region 0x3ffc4000 - 0x3ffc6000
D (1073) memory_layout: Available memory region 0x3ffc4000 - 0x3ffc6000
V (1080) memory_layout: Examining memory region 0x3ffc6000 - 0x3ffc8000
D (1087) memory_layout: Available memory region 0x3ffc6000 - 0x3ffc8000
V (1093) memory_layout: Examining memory region 0x3ffc8000 - 0x3ffca000
D (1100) memory_layout: Available memory region 0x3ffc8000 - 0x3ffca000
V (1107) memory_layout: Examining memory region 0x3ffca000 - 0x3ffcc000
D (1113) memory_layout: Available memory region 0x3ffca000 - 0x3ffcc000
V (1120) memory_layout: Examining memory region 0x3ffcc000 - 0x3ffce000
D (1127) memory_layout: Available memory region 0x3ffcc000 - 0x3ffce000
V (1133) memory_layout: Examining memory region 0x3ffce000 - 0x3ffd0000
D (1140) memory_layout: Available memory region 0x3ffce000 - 0x3ffd0000
V (1147) memory_layout: Examining memory region 0x3ffd0000 - 0x3ffd2000
D (1153) memory_layout: Available memory region 0x3ffd0000 - 0x3ffd2000
V (1160) memory_layout: Examining memory region 0x3ffd2000 - 0x3ffd4000
D (1167) memory_layout: Available memory region 0x3ffd2000 - 0x3ffd4000
V (1174) memory_layout: Examining memory region 0x3ffd4000 - 0x3ffd6000
D (1180) memory_layout: Available memory region 0x3ffd4000 - 0x3ffd6000
V (1187) memory_layout: Examining memory region 0x3ffd6000 - 0x3ffd8000
D (1194) memory_layout: Available memory region 0x3ffd6000 - 0x3ffd8000
V (1200) memory_layout: Examining memory region 0x3ffd8000 - 0x3ffda000
D (1207) memory_layout: Available memory region 0x3ffd8000 - 0x3ffda000
V (1214) memory_layout: Examining memory region 0x3ffda000 - 0x3ffdc000
D (1220) memory_layout: Available memory region 0x3ffda000 - 0x3ffdc000
V (1227) memory_layout: Examining memory region 0x3ffdc000 - 0x3ffde000
D (1234) memory_layout: Available memory region 0x3ffdc000 - 0x3ffde000
V (1240) memory_layout: Examining memory region 0x3ffde000 - 0x3ffe0000
D (1247) memory_layout: Available memory region 0x3ffde000 - 0x3ffe0000
V (1254) memory_layout: Examining memory region 0x3ffe0000 - 0x3ffe4000
V (1260) memory_layout: Start of region 0x3ffe0000 - 0x3ffe4000 overlaps reserve
d 0x3ffe0000 - 0x3ffe0440
D (1270) memory_layout: Available memory region 0x3ffe0440 - 0x3ffe4000
V (1277) memory_layout: Examining memory region 0x3ffe4000 - 0x3ffe8000
V (1283) memory_layout: Start of region 0x3ffe4000 - 0x3ffe8000 overlaps reserve
d 0x3ffe4000 - 0x3ffe4350
D (1293) memory_layout: Available memory region 0x3ffe4350 - 0x3ffe8000
V (1300) memory_layout: Examining memory region 0x3ffe8000 - 0x3fff0000
D (1306) memory_layout: Available memory region 0x3ffe8000 - 0x3fff0000
V (1313) memory_layout: Examining memory region 0x3fff0000 - 0x3fff8000
D (1320) memory_layout: Available memory region 0x3fff0000 - 0x3fff8000
V (1326) memory_layout: Examining memory region 0x3fff8000 - 0x3fffc000
D (1333) memory_layout: Available memory region 0x3fff8000 - 0x3fffc000
V (1340) memory_layout: Examining memory region 0x3fffc000 - 0x40000000
D (1347) memory_layout: Available memory region 0x3fffc000 - 0x40000000
V (1353) memory_layout: Examining memory region 0x40070000 - 0x40078000
V (1360) memory_layout: Region 0x40070000 - 0x40078000 inside of reserved 0x4007
0000 - 0x40078000
V (1369) memory_layout: Examining memory region 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

V (1376) memory_layout: Region 0x40078000 - 0x40080000 inside of reserved 0x4007
8000 - 0x40080000
0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

V (1384) memory_layout: Examining memory region 0x40080000 - 0x40082000
0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

0x40082000: _xt_lowint1 at C:/msys32/home/user/esp/esp-idf/components/freertos/x
tensa_vectors.S:1105

V (1391) memory_layout: Region 0x40080000 - 0x40082000 inside of reserved 0x4008
0000 - 0x40090290
0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

0x40082000: _xt_lowint1 at C:/msys32/home/user/esp/esp-idf/components/freertos/x
tensa_vectors.S:1105

0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

V (1400) memory_layout: Examining memory region 0x40082000 - 0x40084000
0x40082000: _xt_lowint1 at C:/msys32/home/user/esp/esp-idf/components/freertos/x
tensa_vectors.S:1105

0x40084000: spi_intr at C:/msys32/home/user/esp/esp-idf/components/driver/spi_ma
ster.c:432

V (1407) memory_layout: Region 0x40082000 - 0x40084000 inside of reserved 0x4008
0000 - 0x40090290
0x40082000: _xt_lowint1 at C:/msys32/home/user/esp/esp-idf/components/freertos/x
tensa_vectors.S:1105

0x40084000: spi_intr at C:/msys32/home/user/esp/esp-idf/components/driver/spi_ma
ster.c:432

0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

V (1416) memory_layout: Examining memory region 0x40084000 - 0x40086000
0x40084000: spi_intr at C:/msys32/home/user/esp/esp-idf/components/driver/spi_ma
ster.c:432

0x40086000: ppTxProtoProc at ??:?

V (1422) memory_layout: Region 0x40084000 - 0x40086000 inside of reserved 0x4008
0000 - 0x40090290
0x40084000: spi_intr at C:/msys32/home/user/esp/esp-idf/components/driver/spi_ma
ster.c:432

0x40086000: ppTxProtoProc at ??:?

0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

V (1431) memory_layout: Examining memory region 0x40086000 - 0x40088000
0x40086000: ppTxProtoProc at ??:?

0x40088000: rcUpdateTxDoneAmpdu2 at ??:?

V (1438) memory_layout: Region 0x40086000 - 0x40088000 inside of reserved 0x4008
0000 - 0x40090290
0x40086000: ppTxProtoProc at ??:?

0x40088000: rcUpdateTxDoneAmpdu2 at ??:?

0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

V (1447) memory_layout: Examining memory region 0x40088000 - 0x4008a000
0x40088000: rcUpdateTxDoneAmpdu2 at ??:?

0x4008a000: ram_write_gain_mem at /home/cff/gittree/chip7.1_phy/chip_7.1/board_c
ode/app_test/pp/phy/phy_chip_v7.c:1215

V (1454) memory_layout: Region 0x40088000 - 0x4008a000 inside of reserved 0x4008
0000 - 0x40090290
0x40088000: rcUpdateTxDoneAmpdu2 at ??:?

0x4008a000: ram_write_gain_mem at /home/cff/gittree/chip7.1_phy/chip_7.1/board_c
ode/app_test/pp/phy/phy_chip_v7.c:1215

0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

V (1463) memory_layout: Examining memory region 0x4008a000 - 0x4008c000
0x4008a000: ram_write_gain_mem at /home/cff/gittree/chip7.1_phy/chip_7.1/board_c
ode/app_test/pp/phy/phy_chip_v7.c:1215

0x4008c000: vTaskPlaceOnEventList at C:/msys32/home/user/esp/esp-idf/components/
freertos/tasks.c:3564

V (1469) memory_layout: Region 0x4008a000 - 0x4008c000 inside of reserved 0x4008
0000 - 0x40090290
0x4008a000: ram_write_gain_mem at /home/cff/gittree/chip7.1_phy/chip_7.1/board_c
ode/app_test/pp/phy/phy_chip_v7.c:1215

0x4008c000: vTaskPlaceOnEventList at C:/msys32/home/user/esp/esp-idf/components/
freertos/tasks.c:3564

0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

V (1478) memory_layout: Examining memory region 0x4008c000 - 0x4008e000
0x4008c000: vTaskPlaceOnEventList at C:/msys32/home/user/esp/esp-idf/components/
freertos/tasks.c:3564

0x4008e000: get_block at C:/msys32/home/user/esp/esp-idf/components/heap/multi_h
eap.c:377
 (inlined by) multi_heap_free_impl at C:/msys32/home/user/esp/esp-idf/components
/heap/multi_heap.c:465

V (1485) memory_layout: Region 0x4008c000 - 0x4008e000 inside of reserved 0x4008
0000 - 0x40090290
0x4008c000: vTaskPlaceOnEventList at C:/msys32/home/user/esp/esp-idf/components/
freertos/tasks.c:3564

0x4008e000: get_block at C:/msys32/home/user/esp/esp-idf/components/heap/multi_h
eap.c:377
 (inlined by) multi_heap_free_impl at C:/msys32/home/user/esp/esp-idf/components
/heap/multi_heap.c:465

0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

V (1494) memory_layout: Examining memory region 0x4008e000 - 0x40090000
0x4008e000: get_block at C:/msys32/home/user/esp/esp-idf/components/heap/multi_h
eap.c:377
 (inlined by) multi_heap_free_impl at C:/msys32/home/user/esp/esp-idf/components
/heap/multi_heap.c:465

0x40090000: esp_rom_spiflash_read at C:/msys32/home/user/esp/esp-idf/components/
spi_flash/spi_flash_rom_patch.c:539

V (1501) memory_layout: Region 0x4008e000 - 0x40090000 inside of reserved 0x4008
0000 - 0x40090290
0x4008e000: get_block at C:/msys32/home/user/esp/esp-idf/components/heap/multi_h
eap.c:377
 (inlined by) multi_heap_free_impl at C:/msys32/home/user/esp/esp-idf/components
/heap/multi_heap.c:465

0x40090000: esp_rom_spiflash_read at C:/msys32/home/user/esp/esp-idf/components/
spi_flash/spi_flash_rom_patch.c:539

0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

V (1509) memory_layout: Examining memory region 0x40090000 - 0x40092000
0x40090000: esp_rom_spiflash_read at C:/msys32/home/user/esp/esp-idf/components/
spi_flash/spi_flash_rom_patch.c:539

V (1516) memory_layout: Start of region 0x40090000 - 0x40092000 overlaps reserve
d 0x40080000 - 0x40090290
0x40090000: esp_rom_spiflash_read at C:/msys32/home/user/esp/esp-idf/components/
spi_flash/spi_flash_rom_patch.c:539

0x40080000: _WindowOverflow4 at C:/msys32/home/user/esp/esp-idf/components/freer
tos/xtensa_vectors.S:1685

D (1526) memory_layout: Available memory region 0x40090290 - 0x40092000
V (1532) memory_layout: Examining memory region 0x40092000 - 0x40094000
D (1539) memory_layout: Available memory region 0x40092000 - 0x40094000
V (1546) memory_layout: Examining memory region 0x40094000 - 0x40096000
D (1553) memory_layout: Available memory region 0x40094000 - 0x40096000
V (1559) memory_layout: Examining memory region 0x40096000 - 0x40098000
D (1566) memory_layout: Available memory region 0x40096000 - 0x40098000
V (1573) memory_layout: Examining memory region 0x40098000 - 0x4009a000
D (1579) memory_layout: Available memory region 0x40098000 - 0x4009a000
V (1586) memory_layout: Examining memory region 0x4009a000 - 0x4009c000
D (1593) memory_layout: Available memory region 0x4009a000 - 0x4009c000
V (1599) memory_layout: Examining memory region 0x4009c000 - 0x4009e000
D (1606) memory_layout: Available memory region 0x4009c000 - 0x4009e000
V (1613) memory_layout: Examining memory region 0x4009e000 - 0x400a0000
D (1619) memory_layout: Available memory region 0x4009e000 - 0x400a0000
I (1626) heap_init: Initializing. RAM available for dynamic allocation:
D (1633) heap_init: New heap initialised at 0x3ffae6e0
I (1639) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
D (1645) heap_init: New heap initialised at 0x3ffc3478
I (1650) heap_init: At 3FFC3478 len 0001CB88 (114 KiB): DRAM
I (1656) heap_init: At 3FFE0440 len 00003BC0 (14 KiB): D/IRAM
I (1663) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
D (1669) heap_init: New heap initialised at 0x40090290
I (1674) heap_init: At 40090290 len 0000FD70 (63 KiB): IRAM
I (1681) cpu_start: Pro cpu start user code

[ESP_Angus]

Boot log looks normal to me.

If you can't post the full project source, would you be able to PM one of us a link to the output .elf, .map and .bin files for the project?

Thanks,

Angus