ESP32-S3 Powercycling right after uploading a sketch using Arduino IDE and Arduino Core 2.0.4

[SuGlider]

Board

ESP32-S3

Device Description

ESP32-S3-DevkitC-1 1.0

Hardware Configuration

None

Version

v2.0.4

IDE Name

Arduino IDE 1.8.15

Operating System

Windows 11

Flash frequency

QIO 80MHz

PSRAM enabled

no

Upload speed

Tried 115200 and 921600

Description

I have two, apparently equal, ESP32-S3 boards.
For one of them I can upload any sketch and it works fine right after reset.
The other one keeps reseting after uploading and I can't make it work.

BUT.... when I use IDF examples (HelloWorld and Blink RGB LED) both works fine!
So it seems that something may be wrong with Arduino second-stage bootloader or some boot configuration.

No error message even with DEBUG turned on.

IMPORTANT NOTE: it runs fine with Arduino Core 2.0.3 but fails with 2.0.4 --- REGRESSION

Sketch

// 01.Basics --> BareMinimum Arduino Example

void setup() {
  // put your setup code here, to run once:

}

void loop() {
  // put your main code here, to run repeatedly:

}

Debug Message

ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x1 (POWERON),boot:0x8 (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce3808,len:0x43c
load:0x403c9700,len:0xbec
load:0x403cc700,len:0x2a3c
SHA-256 comparison failed:
Calculated: 3611f330726ad4edf64f62982b0568332a5800bc59425cab90be5379db92c108
Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
Attempting to boot anyway...
entry 0x403c98d8
ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x7 (TG0WDT_SYS_RST),boot:0x8 (SPI_FAST_FLASH_BOOT)
Saved PC:0x403cebce
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce3808,len:0x43c
load:0x403c9700,len:0xbec
load:0x403cc700,len:0x2a3c
SHA-256 comparison failed:
Calculated: 3611f330726ad4edf64f62982b0568332a5800bc59425cab90be5379db92c108
Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
Attempting to boot anyway...
entry 0x403c98d8
ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x7 (TG0WDT_SYS_RST),boot:0x8 (SPI_FAST_FLASH_BOOT)
Saved PC:0x403cebce
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce3808,len:0x43c
load:0x403c9700,len:0xbec
load:0x403cc700,len:0x2a3c
SHA-256 comparison failed:
Calculated: 3611f330726ad4edf64f62982b0568332a5800bc59425cab90be5379db92c108
Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
Attempting to boot anyway...
entry 0x403c98d8
ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x10 (RTCWDT_RTC_RST),boot:0x8 (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce3808,len:0x43c
load:0x403c9700,len:0xbec
load:0x403cc700,len:0x2a3c
SHA-256 comparison failed:
Calculated: 3611f330726ad4edf64f62982b0568332a5800bc59425cab90be5379db92c108
Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
Attempting to boot anyway...
entry 0x403c98d8
ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x7 (TG0WDT_SYS_RST),boot:0x8 (SPI_FAST_FLASH_BOOT)
Saved PC:0x403cebce
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce3808,len:0x43c
load:0x403c9700,len:0xbec
load:0x403cc700,len:0x2a3c
SHA-256 comparison failed:
Calculated: 3611f330726ad4edf64f62982b0568332a5800bc59425cab90be5379db92c108
Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
Attempting to boot anyway...
entry 0x403c98d8
ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x7 (TG0WDT_SYS_RST),boot:0x8 (SPI_FAST_FLASH_BOOT)
Saved PC:0x403cebce
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce3808,len:0x43c
load:0x403c9700,len:0xbec
load:0x403cc700,len:0x2a3c
SHA-256 comparison failed:
Calculated: 3611f330726ad4edf64f62982b0568332a5800bc59425cab90be5379db92c108
Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
Attempting to boot anyway...
entry 0x403c98d8

Other Steps to Reproduce

As simple as that. I get TG0WDT_SYS_RST or RTCWDT_RTC_RST in the second-stage bootloader execution.

I have checked existing issues, online documentation and the Troubleshooting Guide

• I confirm I have checked existing issues, online documentation and Troubleshooting guide.

[SuGlider]

Can it be related to #7125 ?

[SuGlider]

The S3 that works fine has this output and doesn't reset:

ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x1 (POWERON),boot:0x8 (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce3808,len:0x43c
load:0x403c9700,len:0xbec
load:0x403cc700,len:0x2a3c
SHA-256 comparison failed:
Calculated: 3611f330726ad4edf64f62982b0568332a5800bc59425cab90be5379db92c108
Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
Attempting to boot anyway...
entry 0x403c98d8

[SuGlider]

This is the picture of the S3 that works fine:

[SuGlider]

This is the picture of the S3 that fails:

[SuGlider]

Both seem to be exactly the same CHIP! Both have the same output when booting, but one of them keeps restating after 1st stage bootloader.

But, when I run IDF HelloWorld example.... it works perfectly well in both boards.

[SuGlider]

Using IDF 4.4.1, I can run it on Both bards.

Log Output for the "Good board" is

ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x1 (POWERON),boot:0x8 (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fcd0108,len:0x20cc
load:0x403b6000,len:0xd1c
load:0x403ba000,len:0x3454
entry 0x403b6290
I (26) boot: ESP-IDF v4.4.1 2nd stage bootloader
I (26) boot: compile time 10:37:47
D (26) bootloader_flash: non-XMC chip detected by SFDP Read (C8), skip.
D (31) bootloader_flash: mmu set block paddr=0x00000000 (was 0xffffffff)
I (38) boot: chip revision: 0
D (41) boot.esp32s3: magic e9
D (44) boot.esp32s3: segments 03
D (48) boot.esp32s3: spi_mode 02
D (51) boot.esp32s3: spi_speed 0f
D (54) boot.esp32s3: spi_size 01
I (58) boot.esp32s3: Boot SPI Speed : 80MHz
I (63) boot.esp32s3: SPI Mode       : DIO
I (67) boot.esp32s3: SPI Flash Size : 2MB
D (72) boot: Enabling RTCWDT(9000 ms)
I (76) boot: Enabling RNG early entropy source...
D (81) bootloader_flash: mmu set paddr=00000000 count=1 size=c00 src_addr=8000 src_addr_aligned=0
D (90) boot: mapped partition table 0x8000 at 0x3c008000
D (95) flash_parts: partition table verified, 4 entries
I (101) boot: Partition Table:
I (104) boot: ## Label            Usage          Type ST Offset   Length
D (112) boot: load partition table entry 0x3c008000
D (117) boot: type=1 subtype=2
I (120) boot:  0 nvs              WiFi data        01 02 00009000 00006000
D (127) boot: load partition table entry 0x3c008020
D (132) boot: type=1 subtype=1
I (135) boot:  1 phy_init         RF data          01 01 0000f000 00001000
D (143) boot: load partition table entry 0x3c008040
D (148) boot: type=0 subtype=0
I (151) boot:  2 factory          factory app      00 00 00010000 00100000
I (159) boot: End of partition table
D (163) boot: Trying partition index -1 offs 0x10000 size 0x100000
D (169) esp_image: reading image header @ 0x10000
D (174) bootloader_flash: mmu set block paddr=0x00010000 (was 0xffffffff)
D (181) esp_image: image header: 0xe9 0x06 0x02 0x01 40375164
V (187) esp_image: loading segment header 0 at offset 0x10018
V (192) esp_image: segment data length 0x7ae0 data starts 0x10020
V (199) esp_image: segment 0 map_segment 1 segment_data_offs 0x10020 load_addr 0x3c020020
I (207) esp_image: segment 0: paddr=00010020 vaddr=3c020020 size=07ae0h ( 31456) map
D (215) esp_image: free data page_count 0x0000003f
D (220) bootloader_flash: mmu set paddr=00010000 count=1 size=7ae0 src_addr=10020 src_addr_aligned=10000
V (235) esp_image: loading segment header 1 at offset 0x17b00
D (236) bootloader_flash: mmu set block paddr=0x00010000 (was 0xffffffff)
V (242) esp_image: segment data length 0x26f8 data starts 0x17b08
V (248) esp_image: segment 1 map_segment 0 segment_data_offs 0x17b08 load_addr 0x3fc90630
I (257) esp_image: segment 1: paddr=00017b08 vaddr=3fc90630 size=026f8h (  9976) load
D (265) esp_image: free data page_count 0x0000003f
D (270) bootloader_flash: mmu set paddr=00010000 count=1 size=26f8 src_addr=17b08 src_addr_aligned=10000
V (282) esp_image: loading segment header 2 at offset 0x1a200
D (285) bootloader_flash: mmu set block paddr=0x00010000 (was 0xffffffff)
V (292) esp_image: segment data length 0x5e10 data starts 0x1a208
V (298) esp_image: segment 2 map_segment 0 segment_data_offs 0x1a208 load_addr 0x40374000
I (307) esp_image: segment 2: paddr=0001a208 vaddr=40374000 size=05e10h ( 24080) load
D (315) esp_image: free data page_count 0x0000003f
D (320) bootloader_flash: mmu set paddr=00010000 count=2 size=5e10 src_addr=1a208 src_addr_aligned=10000
V (335) esp_image: loading segment header 3 at offset 0x20018
D (335) bootloader_flash: mmu set block paddr=0x00020000 (was 0xffffffff)
V (342) esp_image: segment data length 0x173a0 data starts 0x20020
V (349) esp_image: segment 3 map_segment 1 segment_data_offs 0x20020 load_addr 0x42000020
I (357) esp_image: segment 3: paddr=00020020 vaddr=42000020 size=173a0h ( 95136) map
D (365) esp_image: free data page_count 0x0000003f
D (370) bootloader_flash: mmu set paddr=00020000 count=2 size=173a0 src_addr=20020 src_addr_aligned=20000
V (397) esp_image: loading segment header 4 at offset 0x373c0
D (397) bootloader_flash: mmu set block paddr=0x00030000 (was 0xffffffff)
V (398) esp_image: segment data length 0x6814 data starts 0x373c8
V (404) esp_image: segment 4 map_segment 0 segment_data_offs 0x373c8 load_addr 0x40379e10
I (413) esp_image: segment 4: paddr=000373c8 vaddr=40379e10 size=06814h ( 26644) load
D (421) esp_image: free data page_count 0x0000003f
D (426) bootloader_flash: mmu set paddr=00030000 count=1 size=6814 src_addr=373c8 src_addr_aligned=30000
V (441) esp_image: loading segment header 5 at offset 0x3dbdc
D (442) bootloader_flash: mmu set block paddr=0x00030000 (was 0xffffffff)
V (448) esp_image: segment data length 0x10 data starts 0x3dbe4
V (454) esp_image: segment 5 map_segment 0 segment_data_offs 0x3dbe4 load_addr 0x50000000
I (462) esp_image: segment 5: paddr=0003dbe4 vaddr=50000000 size=00010h (    16) load
D (471) esp_image: free data page_count 0x0000003f
D (476) bootloader_flash: mmu set paddr=00030000 count=1 size=10 src_addr=3dbe4 src_addr_aligned=30000
V (485) esp_image: image start 0x00010000 end of last section 0x0003dbf4
D (492) bootloader_flash: mmu set block paddr=0x00030000 (was 0xffffffff)
D (499) boot: Calculated hash: d0578e0e2f955caf68f5088b1de5ac7e05fd9c0d82ec2b9e19b813c19ffe6569
I (513) boot: Loaded app from partition at offset 0x10000
I (514) boot: Disabling RNG early entropy source...
D (519) boot: Mapping segment 0 as DROM
D (523) boot: Mapping segment 3 as IROM
D (527) boot: calling set_cache_and_start_app
D (531) boot: configure drom and irom and start
V (536) boot: d mmu set paddr=00010000 vaddr=3c020000 size=31456 n=1
V (542) boot: rc=0
V (545) boot: i mmu set paddr=00020000 vaddr=42000000 size=95136 n=2
V (551) boot: rc=0
D (553) boot: start: 0x40375164
I (567) cpu_start: Pro cpu up.
I (568) cpu_start: Starting app cpu, entry point is 0x403750bc
I (0) cpu_start: App cpu up.
I (581) cpu_start: Pro cpu start user code
I (581) cpu_start: cpu freq: 160000000
I (581) cpu_start: Application information:
I (584) cpu_start: Project name:     hello_world
I (589) cpu_start: App version:      1
I (593) cpu_start: Compile time:     Aug 22 2022 10:37:31
I (600) cpu_start: ELF file SHA256:  71c5a3bff93e6ff3...
I (606) cpu_start: ESP-IDF:          v4.4.1
I (610) heap_init: Initializing. RAM available for dynamic allocation:
I (618) heap_init: At 3FC93718 len 0004C8E8 (306 KiB): D/IRAM
I (624) heap_init: At 3FCE0000 len 0000EE34 (59 KiB): STACK/DRAM
I (631) heap_init: At 3FCF0000 len 00008000 (32 KiB): DRAM
I (637) heap_init: At 600FE000 len 00002000 (8 KiB): RTCRAM
I (644) spi_flash: detected chip: gd
I (648) spi_flash: flash io: dio
W (651) spi_flash: Detected size(8192k) larger than the size in the binary image header(2048k). Using the size in the binary image header.
I (665) sleep: Configure to isolate all GPIO pins in sleep state
I (671) sleep: Enable automatic switching of GPIO sleep configuration[⸮

[SuGlider]

IDF 4.4.1 Log Output for the "BAD board" is

ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x1 (POWERON),boot:0x8 (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fcd0108,len:0x20cc
load:0x403b6000,len:0xd1c
load:0x403ba000,len:0x3454
entry 0x403b6290
I (26) boot: ESP-IDF v4.4.1 2nd stage bootloader
I (26) boot: compile time 10:37:47
D (26) bootloader_flash: non-XMC chip detected by SFDP Read (C8), skip.
D (31) bootloader_flash: mmu set block paddr=0x00000000 (was 0xffffffff)
I (38) boot: chip revision: 0
D (43) boot.esp32s3: magic e9
D (44) boot.esp32s3: segments 03
D (48) boot.esp32s3: spi_mode 02
D (51) boot.esp32s3: spi_speed 0f
D (54) boot.esp32s3: spi_size 01
I (58) boot.esp32s3: Boot SPI Speed : 80MHz
I (63) boot.esp32s3: SPI Mode       : DIO
I (67) boot.esp32s3: SPI Flash Size : 2MB
D (72) boot: Enabling RTCWDT(9000 ms)
I (76) boot: Enabling RNG early entropy source...
D (81) bootloader_flash: mmu set paddr=00000000 count=1 size=c00 src_addr=8000 src_addr_aligned=0
D (90) boot: mapped partition table 0x8000 at 0x3c008000
D (95) flash_parts: partition table verified, 4 entries
I (101) boot: Partition Table:
I (104) boot: ## Label            Usage          Type ST Offset   Length
D (112) boot: load partition table entry 0x3c008000
D (117) boot: type=1 subtype=2
I (120) boot:  0 nvs              WiFi data        01 02 00009000 00006000
D (127) boot: load partition table entry 0x3c008020
D (132) boot: type=1 subtype=1
I (135) boot:  1 phy_init         RF data          01 01 0000f000 00001000
D (143) boot: load partition table entry 0x3c008040
D (148) boot: type=0 subtype=0
I (151) boot:  2 factory          factory app      00 00 00010000 00100000
I (159) boot: End of partition table
D (163) boot: Trying partition index -1 offs 0x10000 size 0x100000
D (169) esp_image: reading image header @ 0x10000
D (174) bootloader_flash: mmu set block paddr=0x00010000 (was 0xffffffff)
D (181) esp_image: image header: 0xe9 0x06 0x02 0x01 40375164
V (187) esp_image: loading segment header 0 at offset 0x10018
V (192) esp_image: segment data length 0x7ae0 data starts 0x10020
V (199) esp_image: segment 0 map_segment 1 segment_data_offs 0x10020 load_addr 0x3c020020
I (207) esp_image: segment 0: paddr=00010020 vaddr=3c020020 size=07ae0h ( 31456) map
D (215) esp_image: free data page_count 0x0000003f
D (220) bootloader_flash: mmu set paddr=00010000 count=1 size=7ae0 src_addr=10020 src_addr_aligned=10000
V (235) esp_image: loading segment header 1 at offset 0x17b00
D (236) bootloader_flash: mmu set block paddr=0x00010000 (was 0xffffffff)
V (242) esp_image: segment data length 0x26f8 data starts 0x17b08
V (248) esp_image: segment 1 map_segment 0 segment_data_offs 0x17b08 load_addr 0x3fc90630
I (257) esp_image: segment 1: paddr=00017b08 vaddr=3fc90630 size=026f8h (  9976) load
D (265) esp_image: free data page_count 0x0000003f
D (270) bootloader_flash: mmu set paddr=00010000 count=1 size=26f8 src_addr=17b08 src_addr_aligned=10000
V (282) esp_image: loading segment header 2 at offset 0x1a200
D (285) bootloader_flash: mmu set block paddr=0x00010000 (was 0xffffffff)
V (292) esp_image: segment data length 0x5e10 data starts 0x1a208
V (298) esp_image: segment 2 map_segment 0 segment_data_offs 0x1a208 load_addr 0x40374000
I (307) esp_image: segment 2: paddr=0001a208 vaddr=40374000 size=05e10h ( 24080) load
D (315) esp_image: free data page_count 0x0000003f
D (320) bootloader_flash: mmu set paddr=00010000 count=2 size=5e10 src_addr=1a208 src_addr_aligned=10000
V (335) esp_image: loading segment header 3 at offset 0x20018
D (335) bootloader_flash: mmu set block paddr=0x00020000 (was 0xffffffff)
V (342) esp_image: segment data length 0x173a0 data starts 0x20020
V (349) esp_image: segment 3 map_segment 1 segment_data_offs 0x20020 load_addr 0x42000020
I (357) esp_image: segment 3: paddr=00020020 vaddr=42000020 size=173a0h ( 95136) map
D (365) esp_image: free data page_count 0x0000003f
D (370) bootloader_flash: mmu set paddr=00020000 count=2 size=173a0 src_addr=20020 src_addr_aligned=20000
V (397) esp_image: loading segment header 4 at offset 0x373c0
D (397) bootloader_flash: mmu set block paddr=0x00030000 (was 0xffffffff)
V (398) esp_image: segment data length 0x6814 data starts 0x373c8
V (404) esp_image: segment 4 map_segment 0 segment_data_offs 0x373c8 load_addr 0x40379e10
I (413) esp_image: segment 4: paddr=000373c8 vaddr=40379e10 size=06814h ( 26644) load
D (421) esp_image: free data page_count 0x0000003f
D (426) bootloader_flash: mmu set paddr=00030000 count=1 size=6814 src_addr=373c8 src_addr_aligned=30000
V (441) esp_image: loading segment header 5 at offset 0x3dbdc
D (442) bootloader_flash: mmu set block paddr=0x00030000 (was 0xffffffff)
V (448) esp_image: segment data length 0x10 data starts 0x3dbe4
V (454) esp_image: segment 5 map_segment 0 segment_data_offs 0x3dbe4 load_addr 0x50000000
I (462) esp_image: segment 5: paddr=0003dbe4 vaddr=50000000 size=00010h (    16) load
D (471) esp_image: free data page_count 0x0000003f
D (476) bootloader_flash: mmu set paddr=00030000 count=1 size=10 src_addr=3dbe4 src_addr_aligned=30000
V (485) esp_image: image start 0x00010000 end of last section 0x0003dbf4
D (492) bootloader_flash: mmu set block paddr=0x00030000 (was 0xffffffff)
D (499) boot: Calculated hash: d0578e0e2f955caf68f5088b1de5ac7e05fd9c0d82ec2b9e19b813c19ffe6569
I (513) boot: Loaded app from partition at offset 0x10000
I (514) boot: Disabling RNG early entropy source...
D (519) boot: Mapping segment 0 as DROM
D (523) boot: Mapping segment 3 as IROM
D (527) boot: calling set_cache_and_start_app
D (531) boot: configure drom and irom and start
V (536) boot: d mmu set paddr=00010000 vaddr=3c020000 size=31456 n=1
V (542) boot: rc=0
V (545) boot: i mmu set paddr=00020000 vaddr=42000000 size=95136 n=2
V (551) boot: rc=0
D (553) boot: start: 0x40375164
I (567) cpu_start: Pro cpu up.
I (568) cpu_start: Starting app cpu, entry point is 0x403750bc
I (0) cpu_start: App cpu up.
I (581) cpu_start: Pro cpu start user code
I (581) cpu_start: cpu freq: 160000000
I (581) cpu_start: Application information:
I (584) cpu_start: Project name:     hello_world
I (589) cpu_start: App version:      1
I (593) cpu_start: Compile time:     Aug 22 2022 10:37:31
I (600) cpu_start: ELF file SHA256:  71c5a3bff93e6ff3...
I (605) cpu_start: ESP-IDF:          v4.4.1
I (610) heap_init: Initializing. RAM available for dynamic allocation:
I (618) heap_init: At 3FC93718 len 0004C8E8 (306 KiB): D/IRAM
I (624) heap_init: At 3FCE0000 len 0000EE34 (59 KiB): STACK/DRAM
I (631) heap_init: At 3FCF0000 len 00008000 (32 KiB): DRAM
I (637) heap_init: At 600FE000 len 00002000 (8 KiB): RTCRAM
I (644) spi_flash: detected chip: gd
I (648) spi_flash: flash io: dio
W (651) spi_flash: Detected size(8192k) larger than the size in the binary image header(2048k). Using the size in the binary image header.
I (665) sleep: Configure to isolate all GPIO pins in sleep state
I (671) sleep: Enable automatic switching of GPIO sleep configuration[⸮

[SuGlider]

Comparing IDF output for both boards.... there is no difference. Both work fine under IDF.
But with Arduino.... it doesn't.

[SuGlider]

Testing it with all possible ESP32-S3 Arduino Cores (upload speed minimum at 115200):

• Core 2.0.3: Works FINE!!!!
• Core 2.0.4: Fails as described in the issue

@me-no-dev --- This seems to be a REGRESSION.