SD card example in 4 line mode

[marchingband]

The sd card example works for me in 1-line mode, but fails in 4-line mode.
I am using this (very recent) version of the esp-idf:

Code: Select all

commit 79ca95ddf6eb84757774f25ccb29dc0ac91608b4 (HEAD -> master, origin/master, origin/HEAD)
Merge: 9679be19c 5b938199a
Author: Angus Gratton <angus@espressif.com>
Date:   Mon Mar 11 16:35:04 2019 +0800

I have 10k pullup resistors on all the lines as indicated in the readme
I am using a sparkfun sdmmc breakout : https://www.sparkfun.com/products/12941
My SD card is a scandisk 8gb HC 1.
I have burned the fuse to boot with 3.3v flash voltage, and I am pulling GPIO2 LOW during a flash, using a jumper, then disconnecting that jumper when flashing begins.
I have added the line

Code: Select all

host.max_freq_khz = SDMMC_FREQ_PROBING;

here is the log, with verbose set in the menuconfig

Code: Select all

MONITOR
--- idf_monitor on /dev/cu.SLAB_USBtoUART 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:0x3f (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:8988
load:0x40078000,len:12312
load:0x40080400,len:7068
entry 0x4008079c
D (75) bootloader_flash: mmu set block paddr=0x00000000 (was 0xffffffff)
I (36) boot: ESP-IDF v3.3-beta2-53-g79ca95ddf-dirty 2nd stage bootloader
I (36) boot: compile time 03:18:48
D (37) boot: Enabling RTCWDT(9000 ms)
I (41) boot: Enabling RNG early entropy source...
D (46) boot: magic e9
D (48) boot: segments 04
D (51) boot: spi_mode 02
D (54) boot: spi_speed 00
D (56) boot: spi_size 02
I (59) boot: SPI Speed      : 40MHz
I (63) boot: SPI Mode       : DIO
I (67) boot: SPI Flash Size : 4MB
D (71) bootloader_flash: mmu set paddr=00000000 count=1 size=c00 src_addr=8000 src_addr_aligned=0
D (80) boot: mapped partition table 0x8000 at 0x3f408000
D (85) flash_parts: partition table verified, 4 entries
I (91) boot: Partition Table:
I (94) boot: ## Label            Usage          Type ST Offset   Length
D (102) boot: load partition table entry 0x3f408000
D (107) boot: type=1 subtype=2
I (110) boot:  0 nvs              WiFi data        01 02 00009000 00006000
D (117) boot: load partition table entry 0x3f408020
D (122) boot: type=1 subtype=1
I (125) boot:  1 phy_init         RF data          01 01 0000f000 00001000
D (133) boot: load partition table entry 0x3f408040
D (138) boot: type=0 subtype=0
I (141) boot:  2 factory          factory app      00 00 00010000 00100000
I (149) boot: End of partition table
D (153) boot: Trying partition index -1 offs 0x10000 size 0x100000
D (159) esp_image: reading image header @ 0x10000
D (164) bootloader_flash: mmu set block paddr=0x00010000 (was 0xffffffff)
D (171) esp_image: image header: 0xe9 0x06 0x02 0x01 40080f2c
V (176) esp_image: loading segment header 0 at offset 0x10018
V (182) esp_image: segment data length 0x10a4c data starts 0x10020
V (189) esp_image: segment 0 map_segment 1 segment_data_offs 0x10020 load_addr 0x3f400020
I (197) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x10a4c ( 68172) map
D (206) bootloader_flash: mmu set paddr=00010000 count=2 size=10a4c src_addr=10020 src_addr_aligned=10000
V (239) esp_image: loading segment header 1 at offset 0x20a6c
D (239) bootloader_flash: mmu set block paddr=0x00020000 (was 0xffffffff)
V (241) esp_image: segment data length 0x1ec4 data starts 0x20a74
V (247) esp_image: segment 1 map_segment 0 segment_data_offs 0x20a74 load_addr 0x3ffb0000
I (255) esp_image: segment 1: paddr=0x00020a74 vaddr=0x3ffb0000 size=0x01ec4 (  7876) load
D (264) bootloader_flash: mmu set paddr=00020000 count=1 size=1ec4 src_addr=20a74 src_addr_aligned=20000
V (277) esp_image: loading segment header 2 at offset 0x22938
D (280) bootloader_flash: mmu set block paddr=0x00020000 (was 0xffffffff)
V (286) esp_image: segment data length 0x400 data starts 0x22940
V (292) esp_image: segment 2 map_segment 0 segment_data_offs 0x22940 load_addr 0x40080000
0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

I (301) esp_image: segment 2: paddr=0x00022940 vaddr=0x40080000 size=0x00400 (  1024) load
0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

D (310) bootloader_flash: mmu set paddr=00020000 count=1 size=400 src_addr=22940 src_addr_aligned=20000
V (320) esp_image: loading segment header 3 at offset 0x22d40
D (325) bootloader_flash: mmu set block paddr=0x00020000 (was 0xffffffff)
V (332) esp_image: segment data length 0x88b0 data starts 0x22d48
V (338) esp_image: segment 3 map_segment 0 segment_data_offs 0x22d48 load_addr 0x40080400
I (346) esp_image: segment 3: paddr=0x00022d48 vaddr=0x40080400 size=0x088b0 ( 34992) load
D (355) bootloader_flash: mmu set paddr=00020000 count=1 size=88b0 src_addr=22d48 src_addr_aligned=20000
V (379) esp_image: loading segment header 4 at offset 0x2b5f8
D (379) bootloader_flash: mmu set block paddr=0x00020000 (was 0xffffffff)
V (381) esp_image: segment data length 0x4a10 data starts 0x2b600
V (387) esp_image: segment 4 map_segment 0 segment_data_offs 0x2b600 load_addr 0x0
I (395) esp_image: segment 4: paddr=0x0002b600 vaddr=0x00000000 size=0x04a10 ( 18960) 
D (403) bootloader_flash: mmu set paddr=00020000 count=2 size=4a10 src_addr=2b600 src_addr_aligned=20000
V (419) esp_image: loading segment header 5 at offset 0x30010
D (419) bootloader_flash: mmu set block paddr=0x00030000 (was 0xffffffff)
V (425) esp_image: segment data length 0x1d53c data starts 0x30018
V (432) esp_image: segment 5 map_segment 1 segment_data_offs 0x30018 load_addr 0x400d0018
0x400d0018: _flash_cache_start at ??:?

I (440) esp_image: segment 5: paddr=0x00030018 vaddr=0x400d0018 size=0x1d53c (120124) map
0x400d0018: _flash_cache_start at ??:?

D (449) bootloader_flash: mmu set paddr=00030000 count=2 size=1d53c src_addr=30018 src_addr_aligned=30000
V (500) esp_image: image start 0x00010000 end of last section 0x0004d554
D (501) bootloader_flash: mmu set block paddr=0x00040000 (was 0xffffffff)
D (503) esp_image: Calculated hash: f64274499626243014b65385cbf9f0833b1a0e2dc7551aa3f653e0d1c5a0a149
D (512) bootloader_flash: mmu set paddr=00040000 count=1 size=20 src_addr=4d560 src_addr_aligned=40000
D (521) bootloader_flash: mmu set paddr=00040000 count=1 size=20 src_addr=4d560 src_addr_aligned=40000
I (536) boot: Loaded app from partition at offset 0x10000
I (537) boot: Disabling RNG early entropy source...
D (542) boot: Mapping segment 0 as DROM
D (546) boot: Mapping segment 5 as IROM
D (550) boot: calling set_cache_and_start_app
D (555) boot: configure drom and irom and start
V (559) boot: d mmu set paddr=00010000 vaddr=3f400000 size=68172 n=2
V (566) boot: rc=0
V (568) boot: rc=0
V (570) boot: i mmu set paddr=00030000 vaddr=400d0000 size=120124 n=2
V (576) boot: rc=0
V (578) boot: rc=0
D (581) boot: start: 0x40080f2c
0x40080f2c: call_start_cpu0 at /Users/andymarch/esp/esp-idf/components/esp32/cpu_start.c:121

I (584) cpu_start: Pro cpu up.
I (587) cpu_start: Application information:
I (592) cpu_start: Project name:     sd_card
I (597) cpu_start: App version:      v3.3-beta2-53-g79ca95ddf-dirty
I (604) cpu_start: Compile time:     Mar 25 2019 03:18:54
I (610) cpu_start: ELF file SHA256:  5bed43fff66c1127...
I (616) cpu_start: ESP-IDF:          v3.3-beta2-53-g79ca95ddf-dirty
I (623) cpu_start: Starting app cpu, entry point is 0x40080ee0
0x40080ee0: call_start_cpu1 at /Users/andymarch/esp/esp-idf/components/esp32/cpu_start.c:267

I (0) cpu_start: App cpu up.
V (634) memory_layout: reserved range is 0x3f410a44 - 0x3f410a6c
D (639) memory_layout: Checking 7 reserved memory ranges:
D (645) memory_layout: Reserved memory range 0x3ffae000 - 0x3ffae6e0
D (651) memory_layout: Reserved memory range 0x3ffb0000 - 0x3ffb2ff8
D (658) memory_layout: Reserved memory range 0x3ffe0000 - 0x3ffe0440
D (664) memory_layout: Reserved memory range 0x3ffe3f20 - 0x3ffe4350
D (671) memory_layout: Reserved memory range 0x40070000 - 0x40078000
D (677) memory_layout: Reserved memory range 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

D (683) memory_layout: Reserved memory range 0x40080000 - 0x40088caf
0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

D (690) memory_layout: Building list of available memory regions:
V (696) memory_layout: Examining memory region 0x3ffae000 - 0x3ffb0000
V (703) memory_layout: Start of region 0x3ffae000 - 0x3ffb0000 overlaps reserved 0x3ffae000 - 0x3ffae6e0
D (712) memory_layout: Available memory region 0x3ffae6e0 - 0x3ffb0000
V (719) memory_layout: Examining memory region 0x3ffb0000 - 0x3ffb8000
V (725) memory_layout: Start of region 0x3ffb0000 - 0x3ffb8000 overlaps reserved 0x3ffb0000 - 0x3ffb2ff8
D (735) memory_layout: Available memory region 0x3ffb2ff8 - 0x3ffb8000
V (741) memory_layout: Examining memory region 0x3ffb8000 - 0x3ffc0000
D (748) memory_layout: Available memory region 0x3ffb8000 - 0x3ffc0000
V (755) memory_layout: Examining memory region 0x3ffc0000 - 0x3ffc2000
D (761) memory_layout: Available memory region 0x3ffc0000 - 0x3ffc2000
V (768) memory_layout: Examining memory region 0x3ffc2000 - 0x3ffc4000
D (774) memory_layout: Available memory region 0x3ffc2000 - 0x3ffc4000
V (781) memory_layout: Examining memory region 0x3ffc4000 - 0x3ffc6000
D (788) memory_layout: Available memory region 0x3ffc4000 - 0x3ffc6000
V (794) memory_layout: Examining memory region 0x3ffc6000 - 0x3ffc8000
D (801) memory_layout: Available memory region 0x3ffc6000 - 0x3ffc8000
V (807) memory_layout: Examining memory region 0x3ffc8000 - 0x3ffca000
D (814) memory_layout: Available memory region 0x3ffc8000 - 0x3ffca000
V (821) memory_layout: Examining memory region 0x3ffca000 - 0x3ffcc000
D (827) memory_layout: Available memory region 0x3ffca000 - 0x3ffcc000
V (834) memory_layout: Examining memory region 0x3ffcc000 - 0x3ffce000
D (840) memory_layout: Available memory region 0x3ffcc000 - 0x3ffce000
V (847) memory_layout: Examining memory region 0x3ffce000 - 0x3ffd0000
D (854) memory_layout: Available memory region 0x3ffce000 - 0x3ffd0000
V (860) memory_layout: Examining memory region 0x3ffd0000 - 0x3ffd2000
D (867) memory_layout: Available memory region 0x3ffd0000 - 0x3ffd2000
V (873) memory_layout: Examining memory region 0x3ffd2000 - 0x3ffd4000
D (880) memory_layout: Available memory region 0x3ffd2000 - 0x3ffd4000
V (887) memory_layout: Examining memory region 0x3ffd4000 - 0x3ffd6000
D (893) memory_layout: Available memory region 0x3ffd4000 - 0x3ffd6000
V (900) memory_layout: Examining memory region 0x3ffd6000 - 0x3ffd8000
D (906) memory_layout: Available memory region 0x3ffd6000 - 0x3ffd8000
V (913) memory_layout: Examining memory region 0x3ffd8000 - 0x3ffda000
D (920) memory_layout: Available memory region 0x3ffd8000 - 0x3ffda000
V (926) memory_layout: Examining memory region 0x3ffda000 - 0x3ffdc000
D (933) memory_layout: Available memory region 0x3ffda000 - 0x3ffdc000
V (939) memory_layout: Examining memory region 0x3ffdc000 - 0x3ffde000
D (946) memory_layout: Available memory region 0x3ffdc000 - 0x3ffde000
V (953) memory_layout: Examining memory region 0x3ffde000 - 0x3ffe0000
D (959) memory_layout: Available memory region 0x3ffde000 - 0x3ffe0000
V (966) memory_layout: Examining memory region 0x3ffe0000 - 0x3ffe4000
V (972) memory_layout: Start of region 0x3ffe0000 - 0x3ffe4000 overlaps reserved 0x3ffe0000 - 0x3ffe0440
V (982) memory_layout: End of region 0x3ffe0440 - 0x3ffe4000 overlaps reserved 0x3ffe3f20 - 0x3ffe4350
D (991) memory_layout: Available memory region 0x3ffe0440 - 0x3ffe3f20
V (998) memory_layout: Examining memory region 0x3ffe4000 - 0x3ffe8000
V (1004) memory_layout: Start of region 0x3ffe4000 - 0x3ffe8000 overlaps reserved 0x3ffe3f20 - 0x3ffe4350
D (1014) memory_layout: Available memory region 0x3ffe4350 - 0x3ffe8000
V (1021) memory_layout: Examining memory region 0x3ffe8000 - 0x3fff0000
D (1027) memory_layout: Available memory region 0x3ffe8000 - 0x3fff0000
V (1034) memory_layout: Examining memory region 0x3fff0000 - 0x3fff8000
D (1041) memory_layout: Available memory region 0x3fff0000 - 0x3fff8000
V (1048) memory_layout: Examining memory region 0x3fff8000 - 0x3fffc000
D (1054) memory_layout: Available memory region 0x3fff8000 - 0x3fffc000
V (1061) memory_layout: Examining memory region 0x3fffc000 - 0x40000000
D (1068) memory_layout: Available memory region 0x3fffc000 - 0x40000000
V (1074) memory_layout: Examining memory region 0x40070000 - 0x40078000
V (1081) memory_layout: Region 0x40070000 - 0x40078000 inside of reserved 0x40070000 - 0x40078000
V (1090) memory_layout: Examining memory region 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

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

0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

V (1106) memory_layout: Examining memory region 0x40080000 - 0x40082000
0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

0x40082000: _xt_coproc_exc at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1028

V (1112) memory_layout: Region 0x40080000 - 0x40082000 inside of reserved 0x40080000 - 0x40088cb0
0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

0x40082000: _xt_coproc_exc at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1028

0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

V (1121) memory_layout: Examining memory region 0x40082000 - 0x40084000
0x40082000: _xt_coproc_exc at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1028

0x40084000: xRingbufferSend at /Users/andymarch/esp/esp-idf/components/esp_ringbuf/ringbuf.c:992

V (1128) memory_layout: Region 0x40082000 - 0x40084000 inside of reserved 0x40080000 - 0x40088cb0
0x40082000: _xt_coproc_exc at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1028

0x40084000: xRingbufferSend at /Users/andymarch/esp/esp-idf/components/esp_ringbuf/ringbuf.c:992

0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

V (1137) memory_layout: Examining memory region 0x40084000 - 0x40086000
0x40084000: xRingbufferSend at /Users/andymarch/esp/esp-idf/components/esp_ringbuf/ringbuf.c:992

0x40086000: xQueueGetMutexHolder at /Users/andymarch/esp/esp-idf/components/freertos/queue.c:2038

V (1143) memory_layout: Region 0x40084000 - 0x40086000 inside of reserved 0x40080000 - 0x40088cb0
0x40084000: xRingbufferSend at /Users/andymarch/esp/esp-idf/components/esp_ringbuf/ringbuf.c:992

0x40086000: xQueueGetMutexHolder at /Users/andymarch/esp/esp-idf/components/freertos/queue.c:2038

0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

V (1152) memory_layout: Examining memory region 0x40086000 - 0x40088000
0x40086000: xQueueGetMutexHolder at /Users/andymarch/esp/esp-idf/components/freertos/queue.c:2038

0x40088000: _frxt_setup_switch at /Users/andymarch/esp/esp-idf/components/freertos/portasm.S:72

V (1159) memory_layout: Region 0x40086000 - 0x40088000 inside of reserved 0x40080000 - 0x40088cb0
0x40086000: xQueueGetMutexHolder at /Users/andymarch/esp/esp-idf/components/freertos/queue.c:2038

0x40088000: _frxt_setup_switch at /Users/andymarch/esp/esp-idf/components/freertos/portasm.S:72

0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

V (1168) memory_layout: Examining memory region 0x40088000 - 0x4008a000
0x40088000: _frxt_setup_switch at /Users/andymarch/esp/esp-idf/components/freertos/portasm.S:72

V (1175) memory_layout: Start of region 0x40088000 - 0x4008a000 overlaps reserved 0x40080000 - 0x40088cb0
0x40088000: _frxt_setup_switch at /Users/andymarch/esp/esp-idf/components/freertos/portasm.S:72

0x40080000: _WindowOverflow4 at /Users/andymarch/esp/esp-idf/components/freertos/xtensa_vectors.S:1779

D (1184) memory_layout: Available memory region 0x40088cb0 - 0x4008a000
V (1191) memory_layout: Examining memory region 0x4008a000 - 0x4008c000
D (1198) memory_layout: Available memory region 0x4008a000 - 0x4008c000
V (1204) memory_layout: Examining memory region 0x4008c000 - 0x4008e000
D (1211) memory_layout: Available memory region 0x4008c000 - 0x4008e000
V (1218) memory_layout: Examining memory region 0x4008e000 - 0x40090000
D (1224) memory_layout: Available memory region 0x4008e000 - 0x40090000
V (1231) memory_layout: Examining memory region 0x40090000 - 0x40092000
D (1238) memory_layout: Available memory region 0x40090000 - 0x40092000
V (1244) memory_layout: Examining memory region 0x40092000 - 0x40094000
D (1251) memory_layout: Available memory region 0x40092000 - 0x40094000
V (1258) memory_layout: Examining memory region 0x40094000 - 0x40096000
D (1265) memory_layout: Available memory region 0x40094000 - 0x40096000
V (1271) memory_layout: Examining memory region 0x40096000 - 0x40098000
D (1278) memory_layout: Available memory region 0x40096000 - 0x40098000
V (1285) memory_layout: Examining memory region 0x40098000 - 0x4009a000
D (1291) memory_layout: Available memory region 0x40098000 - 0x4009a000
V (1298) memory_layout: Examining memory region 0x4009a000 - 0x4009c000
D (1305) memory_layout: Available memory region 0x4009a000 - 0x4009c000
V (1311) memory_layout: Examining memory region 0x4009c000 - 0x4009e000
D (1318) memory_layout: Available memory region 0x4009c000 - 0x4009e000
V (1325) memory_layout: Examining memory region 0x4009e000 - 0x400a0000
D (1331) memory_layout: Available memory region 0x4009e000 - 0x400a0000
I (1338) heap_init: Initializing. RAM available for dynamic allocation:
D (1345) heap_init: New heap initialised at 0x3ffae6e0
I (1351) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
D (1357) heap_init: New heap initialised at 0x3ffb2ff8
I (1362) heap_init: At 3FFB2FF8 len 0002D008 (180 KiB): DRAM
I (1368) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (1375) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
D (1381) heap_init: New heap initialised at 0x40088cb0
I (1386) heap_init: At 40088CB0 len 00017350 (92 KiB): IRAM
I (1393) cpu_start: Pro cpu start user code
D (1405) clk: RTC_SLOW_CLK calibration value: 3162253
V (72) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (72) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xE
D (77) intr_alloc: Connected src 46 to int 2 (cpu 0)
V (82) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (88) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xC0E
D (96) intr_alloc: Connected src 57 to int 3 (cpu 0)
V (101) esp_dbg_stubs: esp_dbg_stubs_init stubs 3ffb26d4
V (107) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (113) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0x40E
D (121) intr_alloc: Connected src 24 to int 9 (cpu 0)
I (126) cpu_start: Starting scheduler on PRO CPU.
V (0) intr_alloc: esp_intr_alloc_intrstatus (cpu 1): checking args
V (0) 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 (10) cpu_start: Starting scheduler on APP CPU.
D (176) heap_init: New heap initialised at 0x3ffe0440
D (176) heap_init: New heap initialised at 0x3ffe4350
V (186) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (186) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xE
D (186) intr_alloc: Connected src 16 to int 12 (cpu 0)
I (206) example: Initializing SD card
I (206) example: Using SDMMC peripheral
D (216) sdmmc_periph: peripheral version 5342270a, hardware config 03c44c83
V (226) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (226) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xE
D (236) intr_alloc: Connected src 37 to int 13 (cpu 0)
I (236) gpio: GPIO[13]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
D (246) sdmmc_periph: slot=1 host_div=10 card_div=20 freq=400kHz
D (256) sdmmc_periph: slot=1 width=1
V (256) sdmmc_cmd: sending cmd slot=1 op=52 arg=80000c08 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (266) sdmmc_req: make_hw_cmd: opcode=52, rexp=1, crc=1, auto_stop=0
V (276) sdmmc_req: sdmmc_handle_event: event 00000100 00000000, unhandled 00000000 00000000
V (286) sdmmc_req: process_events: state=SENDING_CMD evt=100 dma=0
D (286) sdmmc_req: process_command_response: error 0x107  (status=00000100)
V (296) sdmmc_req: process_events state=SENDING_CMD next_state=SENDING_CMD
V (306) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (316) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (316) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (326) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (336) sdmmc_req: process_events state=IDLE next_state=IDLE
V (336) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (346) sdmmc_cmd: cmd response 00000000 00000000 00000000 00000000 err=0x107 state=0
V (356) sdmmc_io: sdmmc_io_rw_direct: sdmmc_send_cmd returned 0x107
V (356) sdmmc_cmd: sending cmd slot=1 op=0 arg=0 flags=20 data=0x0 blklen=0 datalen=0 timeout=1000
V (366) sdmmc_req: make_hw_cmd: opcode=0, rexp=0, crc=0, auto_stop=0
V (376) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (386) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (386) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (396) sdmmc_req: process_events state=IDLE next_state=IDLE
V (406) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (406) sdmmc_cmd: cmd response 00000000 00000000 00000000 00000000 err=0x0 state=0
V (436) sdmmc_cmd: sending cmd slot=1 op=8 arg=1aa flags=1c30 data=0x0 blklen=0 datalen=0 timeout=1000
V (436) sdmmc_req: make_hw_cmd: opcode=8, rexp=1, crc=1, auto_stop=0
V (436) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (446) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (446) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (456) sdmmc_req: process_events state=IDLE next_state=IDLE
V (466) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (466) sdmmc_cmd: cmd response 000001aa 00000000 00000000 00000000 err=0x0 state=0
D (476) sdmmc_sd: SDHC/SDXC card
V (476) sdmmc_cmd: sending cmd slot=1 op=5 arg=0 flags=1030 data=0x0 blklen=0 datalen=0 timeout=1000
V (486) sdmmc_req: make_hw_cmd: opcode=5, rexp=1, crc=0, auto_stop=0
V (496) sdmmc_req: sdmmc_handle_event: event 00000100 00000000, unhandled 00000000 00000000
V (506) sdmmc_req: process_events: state=SENDING_CMD evt=100 dma=0
D (506) sdmmc_req: process_command_response: error 0x107  (status=00000100)
V (516) sdmmc_req: process_events state=SENDING_CMD next_state=SENDING_CMD
V (526) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (536) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (536) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (546) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (556) sdmmc_req: process_events state=IDLE next_state=IDLE
V (556) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (566) sdmmc_cmd: cmd response 000001aa 00000000 00000000 00000000 err=0x107 state=0
D (576) sdmmc_io: sdmmc_init_io: io_send_op_cond (1) returned 0x107; not IO card
V (576) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (586) sdmmc_req: make_hw_cmd: opcode=55, rexp=1, crc=1, auto_stop=0
V (596) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (606) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (606) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (616) sdmmc_req: process_events state=IDLE next_state=IDLE
V (626) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (626) sdmmc_cmd: cmd response 00400120 00000000 00000000 00000000 err=0x0 state=0
V (636) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=1000
V (646) sdmmc_req: make_hw_cmd: opcode=41, rexp=1, crc=0, auto_stop=0
V (656) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (666) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (666) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (676) sdmmc_req: process_events state=IDLE next_state=IDLE
V (676) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (686) sdmmc_cmd: cmd response 40ff8000 00000000 00000000 00000000 err=0x0 state=0
V (706) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (706) sdmmc_req: make_hw_cmd: opcode=55, rexp=1, crc=1, auto_stop=0
V (716) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (716) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (726) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (736) sdmmc_req: process_events state=IDLE next_state=IDLE
V (736) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (746) sdmmc_cmd: cmd response 00000120 00000000 00000000 00000000 err=0x0 state=0
V (756) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=1000
V (766) sdmmc_req: make_hw_cmd: opcode=41, rexp=1, crc=0, auto_stop=0
V (766) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (776) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (786) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (786) sdmmc_req: process_events state=IDLE next_state=IDLE
V (796) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (806) sdmmc_cmd: cmd response 40ff8000 00000000 00000000 00000000 err=0x0 state=0
V (816) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (816) sdmmc_req: make_hw_cmd: opcode=55, rexp=1, crc=1, auto_stop=0
V (826) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (836) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (836) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (846) sdmmc_req: process_events state=IDLE next_state=IDLE
V (856) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (856) sdmmc_cmd: cmd response 00000120 00000000 00000000 00000000 err=0x0 state=0
V (866) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=1000
V (876) sdmmc_req: make_hw_cmd: opcode=41, rexp=1, crc=0, auto_stop=0
V (886) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (896) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (896) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (906) sdmmc_req: process_events state=IDLE next_state=IDLE
V (906) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (916) sdmmc_cmd: cmd response c0ff8000 00000000 00000000 00000000 err=0x0 state=0
D (926) sdmmc_common: host_ocr=0x40ff8000 card_ocr=0xc0ff8000
D (926) sdmmc_common: sdmmc_card_init: host_ocr=40ff8000, card_ocr=c0ff8000
D (936) sdmmc_init: sdmmc_card_init: card type is SD
V (946) sdmmc_cmd: sending cmd slot=1 op=2 arg=0 flags=1630 data=0x0 blklen=0 datalen=0 timeout=1000
V (956) sdmmc_req: make_hw_cmd: opcode=2, rexp=1, crc=1, auto_stop=0
V (956) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (966) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (976) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (976) sdmmc_req: process_events state=IDLE next_state=IDLE
V (986) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (996) sdmmc_cmd: cmd response c6010aa3 80c82d49 53303847 03534453 err=0x0 state=5
V (996) sdmmc_cmd: sending cmd slot=1 op=3 arg=0 flags=1c30 data=0x0 blklen=0 datalen=0 timeout=1000
V (1006) sdmmc_req: make_hw_cmd: opcode=3, rexp=1, crc=1, auto_stop=0
V (1016) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1026) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1026) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (1036) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1046) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1046) sdmmc_cmd: cmd response aaaa0520 00000000 00000000 00000000 err=0x0 state=2
V (1056) sdmmc_cmd: sending cmd slot=1 op=9 arg=aaaa0000 flags=1600 data=0x0 blklen=0 datalen=0 timeout=1000
V (1066) sdmmc_req: make_hw_cmd: opcode=9, rexp=1, crc=1, auto_stop=0
V (1076) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1086) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1086) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (1096) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1096) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1106) sdmmc_cmd: cmd response 0a4040af 3b377f80 5b590000 400e0032 err=0x0 state=0
V (1116) sdmmc_cmd: sending cmd slot=1 op=7 arg=aaaa0000 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (1126) sdmmc_req: make_hw_cmd: opcode=7, rexp=1, crc=1, auto_stop=0
V (1136) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1136) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1146) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (1156) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1156) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1166) sdmmc_cmd: cmd response 00000700 00000000 00000000 00000000 err=0x0 state=3
V (1176) sdmmc_cmd: sending cmd slot=1 op=55 arg=aaaa0000 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (1186) sdmmc_req: make_hw_cmd: opcode=55, rexp=1, crc=1, auto_stop=0
V (1186) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1196) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1206) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (1206) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1216) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1226) sdmmc_cmd: cmd response 00000920 00000000 00000000 00000000 err=0x0 state=4
V (1236) sdmmc_cmd: sending cmd slot=1 op=51 arg=0 flags=1c50 data=0x3ffb3aec blklen=8 datalen=8 timeout=1000
V (1246) sdmmc_req: make_hw_cmd: opcode=51, rexp=1, crc=1, auto_stop=0
V (1246) sdmmc_req: fill 4 desc=0 rem=0 next=1 last=1 sz=8
V (1256) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1266) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1266) sdmmc_req: process_events state=SENDING_CMD next_state=SENDIND_DATA
V (1276) sdmmc_req: process_events state=SENDIND_DATA next_state=SENDIND_DATA
V (1286) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1286) sdmmc_req: sdmmc_handle_event: event 00000008 00000002, unhandled 00000000 00000000
V (1296) sdmmc_req: process_events: state=SENDIND_DATA evt=8 dma=2
V (1306) sdmmc_req: process_events state=SENDIND_DATA next_state=IDLE
V (1306) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1316) sdmmc_req: sdmmc_handle_event: events unhandled: 00000008 00000000
V (1326) sdmmc_cmd: cmd response 00000920 00000000 00000000 00000000 err=0x0 state=4
V (1336) sdmmc_cmd: sending cmd slot=1 op=13 arg=aaaa0000 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (1346) sdmmc_req: make_hw_cmd: opcode=13, rexp=1, crc=1, auto_stop=0
V (1346) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1356) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1366) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (1366) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1376) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1386) sdmmc_cmd: cmd response 00000900 00000000 00000000 00000000 err=0x0 state=4
V (1386) sdmmc_cmd: sending cmd slot=1 op=55 arg=aaaa0000 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (1396) sdmmc_req: make_hw_cmd: opcode=55, rexp=1, crc=1, auto_stop=0
V (1406) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1416) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1416) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (1426) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1436) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1436) sdmmc_cmd: cmd response 00000920 00000000 00000000 00000000 err=0x0 state=4
V (1446) sdmmc_cmd: sending cmd slot=1 op=6 arg=2 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (1456) sdmmc_req: make_hw_cmd: opcode=6, rexp=1, crc=1, auto_stop=0
V (1466) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1476) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1476) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (1486) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1486) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1496) sdmmc_cmd: cmd response 00000920 00000000 00000000 00000000 err=0x0 state=4
D (1506) sdmmc_common: sdmmc_init_host_bus_width: using 4-bit bus
D (1516) sdmmc_periph: slot=1 width=4
D (1516) sdmmc_common: sdmmc_init_host_frequency: using 400 kHz bus frequency
V (1526) sdmmc_cmd: sending cmd slot=1 op=55 arg=aaaa0000 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (1536) sdmmc_req: make_hw_cmd: opcode=55, rexp=1, crc=1, auto_stop=0
V (1536) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1546) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1556) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (1556) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1566) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1576) sdmmc_cmd: cmd response 00000920 00000000 00000000 00000000 err=0x0 state=4
V (1586) sdmmc_cmd: sending cmd slot=1 op=51 arg=0 flags=1c50 data=0x3ffb3aec blklen=8 datalen=8 timeout=1000
V (1596) sdmmc_req: make_hw_cmd: opcode=51, rexp=1, crc=1, auto_stop=0
V (1596) sdmmc_req: fill 4 desc=0 rem=0 next=1 last=1 sz=8
V (1606) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1616) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1616) sdmmc_req: process_events state=SENDING_CMD next_state=SENDIND_DATA
V (1626) sdmmc_req: process_events state=SENDIND_DATA next_state=SENDIND_DATA
V (1636) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1636) sdmmc_req: sdmmc_handle_event: event 00000008 00000002, unhandled 00000000 00000000
V (1646) sdmmc_req: process_events: state=SENDIND_DATA evt=8 dma=2
V (1656) sdmmc_req: process_events state=SENDIND_DATA next_state=IDLE
V (1656) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1666) sdmmc_req: sdmmc_handle_event: events unhandled: 00000008 00000000
V (1676) sdmmc_cmd: cmd response 00000920 00000000 00000000 00000000 err=0x0 state=4
D (1686) vfs_fat_sdmmc: using pdrv=0
V (1686) sdmmc_cmd: sending cmd slot=1 op=17 arg=0 flags=1c50 data=0x3ffb6d84 blklen=512 datalen=512 timeout=1000
V (1696) sdmmc_req: make_hw_cmd: opcode=17, rexp=1, crc=1, auto_stop=0
V (1706) sdmmc_req: fill 4 desc=0 rem=0 next=1 last=1 sz=512
V (1706) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1716) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1726) sdmmc_req: process_events state=SENDING_CMD next_state=SENDIND_DATA
V (1726) sdmmc_req: process_events state=SENDIND_DATA next_state=SENDIND_DATA
V (1736) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1746) sdmmc_req: sdmmc_handle_event: event 00000008 00000002, unhandled 00000000 00000000
V (1756) sdmmc_req: process_events: state=SENDIND_DATA evt=8 dma=2
V (1756) sdmmc_req: process_events state=SENDIND_DATA next_state=IDLE
V (1766) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1766) sdmmc_req: sdmmc_handle_event: events unhandled: 00000008 00000000
V (1776) sdmmc_cmd: cmd response 00000900 00000000 00000000 00000000 err=0x0 state=4
V (1786) sdmmc_cmd: sending cmd slot=1 op=13 arg=aaaa0000 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=1000
V (1796) sdmmc_req: make_hw_cmd: opcode=13, rexp=1, crc=1, auto_stop=0
V (1806) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1806) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1816) sdmmc_req: process_events state=SENDING_CMD next_state=IDLE
V (1826) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1826) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1836) sdmmc_cmd: cmd response 00000900 00000000 00000000 00000000 err=0x0 state=4
V (1846) sdmmc_cmd: sending cmd slot=1 op=17 arg=3f flags=1c50 data=0x3ffb6d84 blklen=512 datalen=512 timeout=1000
V (1856) sdmmc_req: make_hw_cmd: opcode=17, rexp=1, crc=1, auto_stop=0
V (1856) sdmmc_req: fill 4 desc=0 rem=0 next=1 last=1 sz=512
V (1866) sdmmc_req: sdmmc_handle_event: event 00000004 00000000, unhandled 00000000 00000000
V (1876) sdmmc_req: process_events: state=SENDING_CMD evt=4 dma=0
V (1886) sdmmc_req: process_events state=SENDING_CMD next_state=SENDIND_DATA
V (1886) sdmmc_req: process_events state=SENDIND_DATA next_state=SENDIND_DATA
V (1896) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1906) sdmmc_req: sdmmc_handle_event: event 00000080 00000000, unhandled 00000000 00000000
V (1906) sdmmc_req: process_events: state=SENDIND_DATA evt=80 dma=0
D (1916) sdmmc_req: process_data_status: error 0x109 (status=00000080)
V (1926) sdmmc_req: process_events state=SENDIND_DATA next_state=SENDIND_DATA
V (1936) sdmmc_req: sdmmc_handle_event: events unhandled: 00000000 00000000
V (1936) sdmmc_req: sdmmc_handle_event: event 00000008 00000002, unhandled 00000000 00000000
V (1946) sdmmc_req: process_events: state=SENDIND_DATA evt=8 dma=2
V (1956) sdmmc_req: process_events state=SENDIND_DATA next_state=IDLE
V (1956) sdmmc_req: process_events state=IDLE next_state=IDLE
V (1966) sdmmc_req: sdmmc_handle_event: events unhandled: 00000008 00000000
V (1976) sdmmc_cmd: cmd response 00000900 00000000 00000000 00000000 err=0x109 state=4
E (1976) sdmmc_cmd: sdmmc_read_sectors_dma: sdmmc_send_cmd returned 0x109
E (1986) diskio_sdmmc: sdmmc_read_blocks failed (265)
W (1996) vfs_fat_sdmmc: failed to mount card (13)
V (1996) intr_alloc: esp_intr_free: Disabling int, killing handler
E (2006) example: Failed to mount filesystem. If you want the card to be formatted, set format_if_mount_failed = true.

Others have reported using 4-line mode successfully, but I cannot figure out what the issue is here.
My only guess is the line

Code: Select all

GPIO[13]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0

indicates that something is changing the setting for GPIO13 and perhaps that is interfering.

[ESP_igrr]

0x109 is CRC error. This means that the communication is somewhat working, but not quite. This is usually related to voltage levels, noise, or long wiring. Suggested debugging approach is to use an oscilloscope to capture signals on D0-D3 lines, compare the voltage levels, see if the waveforms look reasonable w.r.t. the clock line.

[marchingband]

I should add that I am using a new esp32 devkitc v4 wroom module, an official one through Mouser.
The example code explicitly sets GPIO13 pullup, but, the line I mentioned in the monitor output indicates that the pullup is not engaged. This commit of the IDF seems related to JTAG programming, and 13 is indeed a JTAG pin ... perhaps I'm making connections where there are none.
I will look with an oscilloscope today and report back thank you.

[marchingband]

also please note, I edited my initial post to show verbose output (it wasn't before), if that causes confusion I apologize, I will add comments rather then editing next time.

[marchingband]

I have scoped the outputs and they all appear as expected.
What would be the next step to debug this?

[ESP_igrr]

DevKitC does not have an on-board SD card slot... Can you describe how you are connecting the SD card to DevKitC?
It might also be worth checking voltage on GPIO12, as it is pulled down on DevKitC, but needs to be pulled up for SD card operation.

[marchingband]

I am connecting the SD Breakout using shortest possible wires, and a bread board.
The voltage on GPIO12 behaves the same as all the other data lines when looking at the scope, except immediately after reset it is pulled down by about 1V (steady at about 2V) for a few ms.

As per the example readme I burned the fuses using

Code: Select all

components/esptool_py/esptool/espefuse.py set_flash_voltage 3.3V

The identical setup works very well with 1-line mode, even with

Code: Select all

host.max_freq_khz = SDMMC_FREQ_HIGHSPEED;

or even

Code: Select all

host.max_freq_khz = SDMMC_FREQ_52M;

When you say GPIO12 is pulled-down on devkitc, do you mean with an external pull-down resistor? I do not see this indicated in the schematic.

[marchingband]

based on another thread I found on this forum, I tried removing both the pullup and sdcard connection from GPIO12 during reset, leaving GPIO12 completely unattached to any external circuitry, and keeping the pullup on the sdcard pin D2. I inserted a long pause into the code before sd card init process, and re-attaching the circuit to GPIO12 during this pause.

This actually works, but I am confused what this indicates, obviously I need a work-around for this.

can anyone help me find a solution that will allow me to leave my circuit attached? I have no clue what is going on.

thank you so much for all the help.

[marchingband]

correction.
The above procedure worked simply because I was placing my hand on the SD CARD metal enclosure during the process. Its success has nothing to do with attaching and re-attaching the wires, with the normal circuit, and a finger on the SDCARD enclosure, everything works perfectly, so, it does indeed indicate to me that it is a signal integrity issue. I will debug further and report back.

[marchingband]

Happy to report that after transferring my circuit from breadboard to a protoboard, the sd_card example now works in 4-line mode. I am getting about double the read speed with small random sector reads of 512 bytes, compared to 1-line mode.
I still occasionally get an error code 109, but i expect this to not occur once the circuit is on a proper pcb, with shorter lines.